{"level":"warn","ts":"2026-03-14T01:30:01.820702Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-14T01:30:01.820787Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.250:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.250:2380","--initial-cluster=instance=https://199.204.45.250:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.250:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.250: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-03-14T01:30:01.820854Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-14T01:30:01.820867Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.250:2380"]} {"level":"info","ts":"2026-03-14T01:30:01.820896Z","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-03-14T01:30:01.821546Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.250:2379"]} {"level":"info","ts":"2026-03-14T01:30:01.821617Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.250:2380"],"listen-peer-urls":["https://199.204.45.250:2380"],"advertise-client-urls":["https://199.204.45.250:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.250:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.250: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-03-14T01:30:01.826534Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.730303ms"} {"level":"info","ts":"2026-03-14T01:30:01.831956Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d98d5f2c752cc52d","cluster-id":"c785b4aaf4bd3b11"} {"level":"info","ts":"2026-03-14T01:30:01.832024Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d switched to configuration voters=()"} {"level":"info","ts":"2026-03-14T01:30:01.832067Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became follower at term 0"} {"level":"info","ts":"2026-03-14T01:30:01.832077Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d98d5f2c752cc52d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-14T01:30:01.832089Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became follower at term 1"} {"level":"info","ts":"2026-03-14T01:30:01.832125Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d switched to configuration voters=(15676290522495632685)"} {"level":"warn","ts":"2026-03-14T01:30:01.84051Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-14T01:30:01.842611Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-14T01:30:01.844052Z","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-03-14T01:30:01.847019Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d98d5f2c752cc52d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-14T01:30:01.847169Z","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-03-14T01:30:01.847264Z","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-03-14T01:30:01.847277Z","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-03-14T01:30:01.8476Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d98d5f2c752cc52d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-14T01:30:01.850632Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d switched to configuration voters=(15676290522495632685)"} {"level":"info","ts":"2026-03-14T01:30:01.850764Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c785b4aaf4bd3b11","local-member-id":"d98d5f2c752cc52d","added-peer-id":"d98d5f2c752cc52d","added-peer-peer-urls":["https://199.204.45.250:2380"]} {"level":"info","ts":"2026-03-14T01:30:01.853283Z","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-03-14T01:30:01.853488Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d98d5f2c752cc52d","initial-advertise-peer-urls":["https://199.204.45.250:2380"],"listen-peer-urls":["https://199.204.45.250:2380"],"advertise-client-urls":["https://199.204.45.250:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.250:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-14T01:30:01.853519Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-14T01:30:01.853859Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.250:2380"} {"level":"info","ts":"2026-03-14T01:30:01.853898Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.250:2380"} {"level":"info","ts":"2026-03-14T01:30:02.632931Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d is starting a new election at term 1"} {"level":"info","ts":"2026-03-14T01:30:02.632979Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became pre-candidate at term 1"} {"level":"info","ts":"2026-03-14T01:30:02.633002Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d received MsgPreVoteResp from d98d5f2c752cc52d at term 1"} {"level":"info","ts":"2026-03-14T01:30:02.633013Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became candidate at term 2"} {"level":"info","ts":"2026-03-14T01:30:02.633134Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d received MsgVoteResp from d98d5f2c752cc52d at term 2"} {"level":"info","ts":"2026-03-14T01:30:02.633148Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became leader at term 2"} {"level":"info","ts":"2026-03-14T01:30:02.633156Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d98d5f2c752cc52d elected leader d98d5f2c752cc52d at term 2"} {"level":"info","ts":"2026-03-14T01:30:02.799004Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-14T01:30:02.799087Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-14T01:30:02.799093Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-14T01:30:02.799678Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d98d5f2c752cc52d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.250:2379]}","request-path":"/0/members/d98d5f2c752cc52d/attributes","cluster-id":"c785b4aaf4bd3b11","publish-timeout":"7s"} {"level":"info","ts":"2026-03-14T01:30:02.799799Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-14T01:30:02.799826Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-14T01:30:02.802167Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.250:2379"} {"level":"info","ts":"2026-03-14T01:30:02.803568Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-14T01:30:02.870511Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c785b4aaf4bd3b11","local-member-id":"d98d5f2c752cc52d","cluster-version":"3.5"} {"level":"info","ts":"2026-03-14T01:30:02.870657Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-14T01:30:02.870723Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-03-14T01:30:03.332175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.486637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-14T01:30:03.332262Z","caller":"traceutil/trace.go:171","msg":"trace[1289579293] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1; }","duration":"309.65871ms","start":"2026-03-14T01:30:03.022589Z","end":"2026-03-14T01:30:03.332248Z","steps":["trace[1289579293] 'range keys from in-memory index tree' (duration: 309.388004ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:03.332298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:30:03.022567Z","time spent":"309.722503ms","remote":"127.0.0.1:39920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" limit:10000 "} {"level":"warn","ts":"2026-03-14T01:30:03.332298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.060769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-14T01:30:03.332361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.624206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-14T01:30:03.332384Z","caller":"traceutil/trace.go:171","msg":"trace[135265445] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1; }","duration":"349.654878ms","start":"2026-03-14T01:30:02.982722Z","end":"2026-03-14T01:30:03.332377Z","steps":["trace[135265445] 'count revisions from in-memory index tree' (duration: 349.601956ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:30:03.332375Z","caller":"traceutil/trace.go:171","msg":"trace[969234855] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1; }","duration":"350.181331ms","start":"2026-03-14T01:30:02.982179Z","end":"2026-03-14T01:30:03.33236Z","steps":["trace[969234855] 'range keys from in-memory index tree' (duration: 350.007617ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:03.332402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:30:02.982719Z","time spent":"349.678119ms","remote":"127.0.0.1:39900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-03-14T01:30:03.332414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:30:02.982172Z","time spent":"350.231873ms","remote":"127.0.0.1:39900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" limit:10000 "} {"level":"warn","ts":"2026-03-14T01:30:03.332557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.331092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-14T01:30:03.332606Z","caller":"traceutil/trace.go:171","msg":"trace[1016034505] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1; }","duration":"390.385984ms","start":"2026-03-14T01:30:02.942209Z","end":"2026-03-14T01:30:03.332595Z","steps":["trace[1016034505] 'count revisions from in-memory index tree' (duration: 390.262071ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:03.332643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:30:02.942201Z","time spent":"390.433155ms","remote":"127.0.0.1:39888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-03-14T01:30:03.332736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.398194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-14T01:30:03.332767Z","caller":"traceutil/trace.go:171","msg":"trace[2138486448] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1; }","duration":"390.429915ms","start":"2026-03-14T01:30:02.942329Z","end":"2026-03-14T01:30:03.332759Z","steps":["trace[2138486448] 'range keys from in-memory index tree' (duration: 390.330293ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:03.332794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:30:02.942311Z","time spent":"390.474916ms","remote":"127.0.0.1:39888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" limit:10000 "} {"level":"warn","ts":"2026-03-14T01:30:03.33281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.91391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-14T01:30:03.33284Z","caller":"traceutil/trace.go:171","msg":"trace[724577895] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1; }","duration":"338.94397ms","start":"2026-03-14T01:30:02.993886Z","end":"2026-03-14T01:30:03.33283Z","steps":["trace[724577895] 'count revisions from in-memory index tree' (duration: 338.678953ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:03.33288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:30:02.993858Z","time spent":"339.015052ms","remote":"127.0.0.1:39904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-03-14T01:30:03.332879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.105716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-14T01:30:03.332916Z","caller":"traceutil/trace.go:171","msg":"trace[1219704160] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1; }","duration":"378.170898ms","start":"2026-03-14T01:30:02.954735Z","end":"2026-03-14T01:30:03.332906Z","steps":["trace[1219704160] 'count revisions from in-memory index tree' (duration: 378.059765ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:03.332949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:30:02.954713Z","time spent":"378.228569ms","remote":"127.0.0.1:39894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-03-14T01:30:03.333033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.091184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-14T01:30:03.333123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.327404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-14T01:30:03.333159Z","caller":"traceutil/trace.go:171","msg":"trace[761985305] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1; }","duration":"365.380456ms","start":"2026-03-14T01:30:02.967768Z","end":"2026-03-14T01:30:03.333149Z","steps":["trace[761985305] 'range keys from in-memory index tree' (duration: 365.275703ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:03.333187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:30:02.967756Z","time spent":"365.422397ms","remote":"127.0.0.1:39896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" limit:10000 "} {"level":"warn","ts":"2026-03-14T01:30:03.333411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.498069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-14T01:30:03.333442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.548761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-14T01:30:03.333492Z","caller":"traceutil/trace.go:171","msg":"trace[946426841] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1; }","duration":"365.656124ms","start":"2026-03-14T01:30:02.967817Z","end":"2026-03-14T01:30:03.333473Z","steps":["trace[946426841] 'count revisions from in-memory index tree' (duration: 365.420347ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:03.333503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.836153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-14T01:30:03.333523Z","caller":"traceutil/trace.go:171","msg":"trace[484125703] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1; }","duration":"141.641243ms","start":"2026-03-14T01:30:03.19186Z","end":"2026-03-14T01:30:03.333501Z","steps":["trace[484125703] 'range keys from in-memory index tree' (duration: 141.49075ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:30:03.333536Z","caller":"traceutil/trace.go:171","msg":"trace[2107884426] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1; }","duration":"125.887065ms","start":"2026-03-14T01:30:03.20764Z","end":"2026-03-14T01:30:03.333527Z","steps":["trace[2107884426] 'range keys from in-memory index tree' (duration: 125.7241ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:03.333539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:30:02.967801Z","time spent":"365.726255ms","remote":"127.0.0.1:39896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-03-14T01:30:03.333443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.907652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-14T01:30:03.333683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.800939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-14T01:30:03.333704Z","caller":"traceutil/trace.go:171","msg":"trace[811500420] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1; }","duration":"112.177719ms","start":"2026-03-14T01:30:03.22151Z","end":"2026-03-14T01:30:03.333688Z","steps":["trace[811500420] 'count revisions from in-memory index tree' (duration: 111.85853ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:30:03.333715Z","caller":"traceutil/trace.go:171","msg":"trace[1224494785] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1; }","duration":"180.84426ms","start":"2026-03-14T01:30:03.15286Z","end":"2026-03-14T01:30:03.333705Z","steps":["trace[1224494785] 'range keys from in-memory index tree' (duration: 180.739378ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:30:03.333784Z","caller":"traceutil/trace.go:171","msg":"trace[1429509462] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1; }","duration":"339.155545ms","start":"2026-03-14T01:30:02.993907Z","end":"2026-03-14T01:30:03.333062Z","steps":["trace[1429509462] 'range keys from in-memory index tree' (duration: 339.034923ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:03.333833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.321417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-14T01:30:03.33387Z","caller":"traceutil/trace.go:171","msg":"trace[1162843534] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1; }","duration":"170.369468ms","start":"2026-03-14T01:30:03.163484Z","end":"2026-03-14T01:30:03.333853Z","steps":["trace[1162843534] 'range keys from in-memory index tree' (duration: 170.231866ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:03.333906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.260855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-14T01:30:03.333944Z","caller":"traceutil/trace.go:171","msg":"trace[2033428810] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1; }","duration":"325.307496ms","start":"2026-03-14T01:30:03.008625Z","end":"2026-03-14T01:30:03.333933Z","steps":["trace[2033428810] 'range keys from in-memory index tree' (duration: 325.221014ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:03.333977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:30:03.008618Z","time spent":"325.349857ms","remote":"127.0.0.1:39912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":0,"response size":28,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" limit:10000 "} {"level":"warn","ts":"2026-03-14T01:30:03.334007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.277636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-14T01:30:03.334046Z","caller":"traceutil/trace.go:171","msg":"trace[1585528445] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1; }","duration":"170.355168ms","start":"2026-03-14T01:30:03.163681Z","end":"2026-03-14T01:30:03.334036Z","steps":["trace[1585528445] 'count revisions from in-memory index tree' (duration: 170.221305ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:03.334235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.076429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-14T01:30:03.334279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.834897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-14T01:30:03.334311Z","caller":"traceutil/trace.go:171","msg":"trace[224946594] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1; }","duration":"311.871638ms","start":"2026-03-14T01:30:03.022427Z","end":"2026-03-14T01:30:03.334298Z","steps":["trace[224946594] 'count revisions from in-memory index tree' (duration: 311.806646ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:30:03.334283Z","caller":"traceutil/trace.go:171","msg":"trace[1440782845] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1; }","duration":"156.16139ms","start":"2026-03-14T01:30:03.178111Z","end":"2026-03-14T01:30:03.334272Z","steps":["trace[1440782845] 'count revisions from in-memory index tree' (duration: 156.020026ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:03.334359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:30:03.022422Z","time spent":"311.927989ms","remote":"127.0.0.1:39920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-03-14T01:30:03.334397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.858687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-14T01:30:03.334434Z","caller":"traceutil/trace.go:171","msg":"trace[1016705818] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1; }","duration":"112.936999ms","start":"2026-03-14T01:30:03.221486Z","end":"2026-03-14T01:30:03.334423Z","steps":["trace[1016705818] 'range keys from in-memory index tree' (duration: 112.803675ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:03.334506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.293443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-14T01:30:03.334546Z","caller":"traceutil/trace.go:171","msg":"trace[280201165] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1; }","duration":"156.358955ms","start":"2026-03-14T01:30:03.178174Z","end":"2026-03-14T01:30:03.334533Z","steps":["trace[280201165] 'range keys from in-memory index tree' (duration: 156.223261ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:03.332849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.109776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-14T01:30:03.334643Z","caller":"traceutil/trace.go:171","msg":"trace[460682822] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1; }","duration":"379.936393ms","start":"2026-03-14T01:30:02.954696Z","end":"2026-03-14T01:30:03.334632Z","steps":["trace[460682822] 'range keys from in-memory index tree' (duration: 378.037674ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:03.334672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.930517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-14T01:30:03.334712Z","caller":"traceutil/trace.go:171","msg":"trace[1906485805] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1; }","duration":"142.972197ms","start":"2026-03-14T01:30:03.191732Z","end":"2026-03-14T01:30:03.334704Z","steps":["trace[1906485805] 'count revisions from in-memory index tree' (duration: 142.864886ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:03.333476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.824514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-14T01:30:03.334882Z","caller":"traceutil/trace.go:171","msg":"trace[39251138] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1; }","duration":"127.264711ms","start":"2026-03-14T01:30:03.207603Z","end":"2026-03-14T01:30:03.334867Z","steps":["trace[39251138] 'count revisions from in-memory index tree' (duration: 125.70228ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:03.333855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:30:02.993895Z","time spent":"339.940906ms","remote":"127.0.0.1:39904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" limit:10000 "} {"level":"warn","ts":"2026-03-14T01:30:03.334243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.399359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-14T01:30:03.335218Z","caller":"traceutil/trace.go:171","msg":"trace[935372244] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1; }","duration":"326.377075ms","start":"2026-03-14T01:30:03.008823Z","end":"2026-03-14T01:30:03.3352Z","steps":["trace[935372244] 'count revisions from in-memory index tree' (duration: 325.309365ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:03.335269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:30:03.008816Z","time spent":"326.432585ms","remote":"127.0.0.1:39912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":28,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-03-14T01:30:03.335371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.377458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-14T01:30:03.334681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:30:02.954684Z","time spent":"379.985175ms","remote":"127.0.0.1:39894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" limit:10000 "} {"level":"info","ts":"2026-03-14T01:30:03.335411Z","caller":"traceutil/trace.go:171","msg":"trace[883427785] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1; }","duration":"266.42593ms","start":"2026-03-14T01:30:03.068972Z","end":"2026-03-14T01:30:03.335398Z","steps":["trace[883427785] 'count revisions from in-memory index tree' (duration: 266.316987ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:03.335584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.578164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-14T01:30:03.335616Z","caller":"traceutil/trace.go:171","msg":"trace[451033250] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1; }","duration":"303.633605ms","start":"2026-03-14T01:30:03.031975Z","end":"2026-03-14T01:30:03.335609Z","steps":["trace[451033250] 'range keys from in-memory index tree' (duration: 303.498941ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:03.335644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:30:03.031968Z","time spent":"303.668556ms","remote":"127.0.0.1:39936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":28,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" limit:10000 "} {"level":"warn","ts":"2026-03-14T01:30:03.335774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.656546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-14T01:30:03.335806Z","caller":"traceutil/trace.go:171","msg":"trace[1417807685] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1; }","duration":"303.700867ms","start":"2026-03-14T01:30:03.032097Z","end":"2026-03-14T01:30:03.335798Z","steps":["trace[1417807685] 'count revisions from in-memory index tree' (duration: 303.573543ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:03.335832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:30:03.032089Z","time spent":"303.735728ms","remote":"127.0.0.1:39936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":28,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-03-14T01:30:03.335954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.20412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-14T01:30:03.335986Z","caller":"traceutil/trace.go:171","msg":"trace[1724185015] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1; }","duration":"294.260041ms","start":"2026-03-14T01:30:03.041718Z","end":"2026-03-14T01:30:03.335978Z","steps":["trace[1724185015] 'count revisions from in-memory index tree' (duration: 294.131578ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:03.33611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.105348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-14T01:30:03.336192Z","caller":"traceutil/trace.go:171","msg":"trace[1633311115] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1; }","duration":"294.152329ms","start":"2026-03-14T01:30:03.041981Z","end":"2026-03-14T01:30:03.336133Z","steps":["trace[1633311115] 'range keys from in-memory index tree' (duration: 294.065766ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:03.33631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.966767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-14T01:30:03.336343Z","caller":"traceutil/trace.go:171","msg":"trace[1879717611] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1; }","duration":"281.009658ms","start":"2026-03-14T01:30:03.055326Z","end":"2026-03-14T01:30:03.336336Z","steps":["trace[1879717611] 'count revisions from in-memory index tree' (duration: 280.939616ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:03.336507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.187623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-14T01:30:03.336545Z","caller":"traceutil/trace.go:171","msg":"trace[484837841] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1; }","duration":"281.254084ms","start":"2026-03-14T01:30:03.055281Z","end":"2026-03-14T01:30:03.336535Z","steps":["trace[484837841] 'range keys from in-memory index tree' (duration: 281.108291ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:03.337156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.201564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-14T01:30:03.337188Z","caller":"traceutil/trace.go:171","msg":"trace[448238999] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1; }","duration":"207.239165ms","start":"2026-03-14T01:30:03.129936Z","end":"2026-03-14T01:30:03.337175Z","steps":["trace[448238999] 'range keys from in-memory index tree' (duration: 207.172413ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:03.337285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.290458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-14T01:30:03.337301Z","caller":"traceutil/trace.go:171","msg":"trace[1619726704] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1; }","duration":"268.308299ms","start":"2026-03-14T01:30:03.068989Z","end":"2026-03-14T01:30:03.337297Z","steps":["trace[1619726704] 'range keys from in-memory index tree' (duration: 268.222046ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:03.337642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.990267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-14T01:30:03.337683Z","caller":"traceutil/trace.go:171","msg":"trace[1716232412] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1; }","duration":"222.043709ms","start":"2026-03-14T01:30:03.115632Z","end":"2026-03-14T01:30:03.337675Z","steps":["trace[1716232412] 'count revisions from in-memory index tree' (duration: 221.948426ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:03.337795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.047299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-14T01:30:03.337815Z","caller":"traceutil/trace.go:171","msg":"trace[1526725169] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1; }","duration":"222.09231ms","start":"2026-03-14T01:30:03.115718Z","end":"2026-03-14T01:30:03.33781Z","steps":["trace[1526725169] 'range keys from in-memory index tree' (duration: 221.957227ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:03.338108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.257504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-14T01:30:03.338138Z","caller":"traceutil/trace.go:171","msg":"trace[988193510] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1; }","duration":"195.285554ms","start":"2026-03-14T01:30:03.142842Z","end":"2026-03-14T01:30:03.338128Z","steps":["trace[988193510] 'range keys from in-memory index tree' (duration: 195.234864ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:03.33822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.177409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-14T01:30:03.338235Z","caller":"traceutil/trace.go:171","msg":"trace[1273402226] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1; }","duration":"208.203559ms","start":"2026-03-14T01:30:03.130027Z","end":"2026-03-14T01:30:03.338231Z","steps":["trace[1273402226] 'count revisions from in-memory index tree' (duration: 208.153799ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:03.338456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.665015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-14T01:30:03.338478Z","caller":"traceutil/trace.go:171","msg":"trace[1680576781] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1; }","duration":"195.698295ms","start":"2026-03-14T01:30:03.142774Z","end":"2026-03-14T01:30:03.338472Z","steps":["trace[1680576781] 'count revisions from in-memory index tree' (duration: 195.572523ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:03.338604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.247919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-14T01:30:03.338672Z","caller":"traceutil/trace.go:171","msg":"trace[333870006] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1; }","duration":"185.85931ms","start":"2026-03-14T01:30:03.1528Z","end":"2026-03-14T01:30:03.33866Z","steps":["trace[333870006] 'count revisions from in-memory index tree' (duration: 179.186887ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:30:04.215656Z","caller":"traceutil/trace.go:171","msg":"trace[1053025360] linearizableReadLoop","detail":"{readStateIndex:5; appliedIndex:4; }","duration":"223.23642ms","start":"2026-03-14T01:30:03.992399Z","end":"2026-03-14T01:30:04.215635Z","steps":["trace[1053025360] 'read index received' (duration: 223.096126ms)","trace[1053025360] 'applied index is now lower than readState.Index' (duration: 139.154µs)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:30:04.215709Z","caller":"traceutil/trace.go:171","msg":"trace[782879763] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"223.2973ms","start":"2026-03-14T01:30:03.992377Z","end":"2026-03-14T01:30:04.215674Z","steps":["trace[782879763] 'process raft request' (duration: 223.087176ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:04.215813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.394004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-14T01:30:04.215852Z","caller":"traceutil/trace.go:171","msg":"trace[79442916] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:0; response_revision:2; }","duration":"223.453675ms","start":"2026-03-14T01:30:03.992388Z","end":"2026-03-14T01:30:04.215842Z","steps":["trace[79442916] 'agreement among raft nodes before linearized reading' (duration: 223.354472ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:30:04.260443Z","caller":"traceutil/trace.go:171","msg":"trace[886958604] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"186.485846ms","start":"2026-03-14T01:30:04.073927Z","end":"2026-03-14T01:30:04.260413Z","steps":["trace[886958604] 'process raft request' (duration: 186.381874ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:30:04.260542Z","caller":"traceutil/trace.go:171","msg":"trace[1682977589] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"186.673382ms","start":"2026-03-14T01:30:04.073841Z","end":"2026-03-14T01:30:04.260514Z","steps":["trace[1682977589] 'process raft request' (duration: 186.338783ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:30:04.2607Z","caller":"traceutil/trace.go:171","msg":"trace[1233410968] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"186.608219ms","start":"2026-03-14T01:30:04.074078Z","end":"2026-03-14T01:30:04.260687Z","steps":["trace[1233410968] 'process raft request' (duration: 186.290121ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:30:04.26126Z","caller":"traceutil/trace.go:171","msg":"trace[1566374717] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"186.481927ms","start":"2026-03-14T01:30:04.074752Z","end":"2026-03-14T01:30:04.261234Z","steps":["trace[1566374717] 'process raft request' (duration: 186.097707ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:30:04.261468Z","caller":"traceutil/trace.go:171","msg":"trace[1336611257] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"186.489756ms","start":"2026-03-14T01:30:04.074825Z","end":"2026-03-14T01:30:04.261315Z","steps":["trace[1336611257] 'process raft request' (duration: 186.335813ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:04.261973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.725929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-14T01:30:04.262018Z","caller":"traceutil/trace.go:171","msg":"trace[1757117185] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:0; response_revision:10; }","duration":"268.769341ms","start":"2026-03-14T01:30:03.993233Z","end":"2026-03-14T01:30:04.262002Z","steps":["trace[1757117185] 'agreement among raft nodes before linearized reading' (duration: 268.725409ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:30:04.26164Z","caller":"traceutil/trace.go:171","msg":"trace[578239212] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"178.357666ms","start":"2026-03-14T01:30:04.083268Z","end":"2026-03-14T01:30:04.261626Z","steps":["trace[578239212] 'process raft request' (duration: 177.953175ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:30:04.261652Z","caller":"traceutil/trace.go:171","msg":"trace[2083271271] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"186.397035ms","start":"2026-03-14T01:30:04.07524Z","end":"2026-03-14T01:30:04.261637Z","steps":["trace[2083271271] 'process raft request' (duration: 185.950443ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:04.261779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.219625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-14T01:30:04.262363Z","caller":"traceutil/trace.go:171","msg":"trace[846547181] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:10; }","duration":"160.845803ms","start":"2026-03-14T01:30:04.101505Z","end":"2026-03-14T01:30:04.262351Z","steps":["trace[846547181] 'agreement among raft nodes before linearized reading' (duration: 160.119844ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:30:04.261816Z","caller":"traceutil/trace.go:171","msg":"trace[1503555514] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"176.900478ms","start":"2026-03-14T01:30:04.084898Z","end":"2026-03-14T01:30:04.261798Z","steps":["trace[1503555514] 'process raft request' (duration: 176.348464ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:04.261831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.307406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-14T01:30:04.262586Z","caller":"traceutil/trace.go:171","msg":"trace[926767665] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"137.079645ms","start":"2026-03-14T01:30:04.125497Z","end":"2026-03-14T01:30:04.262577Z","steps":["trace[926767665] 'agreement among raft nodes before linearized reading' (duration: 136.316736ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:04.261875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.849556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-14T01:30:04.262764Z","caller":"traceutil/trace.go:171","msg":"trace[605083034] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"187.7814ms","start":"2026-03-14T01:30:04.074968Z","end":"2026-03-14T01:30:04.262749Z","steps":["trace[605083034] 'agreement among raft nodes before linearized reading' (duration: 186.890726ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:04.261889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.288623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-14T01:30:04.262974Z","caller":"traceutil/trace.go:171","msg":"trace[1578733636] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"221.377641ms","start":"2026-03-14T01:30:04.041561Z","end":"2026-03-14T01:30:04.262939Z","steps":["trace[1578733636] 'agreement among raft nodes before linearized reading' (duration: 220.284113ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:04.261909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.746378ms","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-03-14T01:30:04.263064Z","caller":"traceutil/trace.go:171","msg":"trace[1058887027] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:10; }","duration":"186.927887ms","start":"2026-03-14T01:30:04.076113Z","end":"2026-03-14T01:30:04.263041Z","steps":["trace[1058887027] 'agreement among raft nodes before linearized reading' (duration: 185.775848ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:04.261937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.894145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.250\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-14T01:30:04.263208Z","caller":"traceutil/trace.go:171","msg":"trace[969197314] range","detail":"{range_begin:/registry/masterleases/199.204.45.250; range_end:; response_count:0; response_revision:10; }","duration":"191.186198ms","start":"2026-03-14T01:30:04.072013Z","end":"2026-03-14T01:30:04.263199Z","steps":["trace[969197314] 'agreement among raft nodes before linearized reading' (duration: 189.908714ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:30:06.091968Z","caller":"traceutil/trace.go:171","msg":"trace[1343438988] transaction","detail":"{read_only:false; response_revision:243; number_of_response:1; }","duration":"136.041829ms","start":"2026-03-14T01:30:05.955903Z","end":"2026-03-14T01:30:06.091945Z","steps":["trace[1343438988] 'process raft request' (duration: 135.911975ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:30:06.096736Z","caller":"traceutil/trace.go:171","msg":"trace[958962723] transaction","detail":"{read_only:false; response_revision:244; number_of_response:1; }","duration":"114.966592ms","start":"2026-03-14T01:30:05.981735Z","end":"2026-03-14T01:30:06.096701Z","steps":["trace[958962723] 'process raft request' (duration: 114.827378ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:30:33.882602Z","caller":"traceutil/trace.go:171","msg":"trace[617696519] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"228.887631ms","start":"2026-03-14T01:30:33.653687Z","end":"2026-03-14T01:30:33.882574Z","steps":["trace[617696519] 'process raft request' (duration: 228.690516ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:37.721951Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14208184928108342394,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-14T01:30:37.931917Z","caller":"traceutil/trace.go:171","msg":"trace[171680425] linearizableReadLoop","detail":"{readStateIndex:543; appliedIndex:542; }","duration":"710.603756ms","start":"2026-03-14T01:30:37.221282Z","end":"2026-03-14T01:30:37.931886Z","steps":["trace[171680425] 'read index received' (duration: 710.40673ms)","trace[171680425] 'applied index is now lower than readState.Index' (duration: 195.656µs)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:30:37.932093Z","caller":"traceutil/trace.go:171","msg":"trace[1382303067] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"719.567058ms","start":"2026-03-14T01:30:37.212496Z","end":"2026-03-14T01:30:37.932063Z","steps":["trace[1382303067] 'process raft request' (duration: 719.223619ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:37.932159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.049535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T01:30:37.932244Z","caller":"traceutil/trace.go:171","msg":"trace[76006044] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:522; }","duration":"143.185798ms","start":"2026-03-14T01:30:37.78904Z","end":"2026-03-14T01:30:37.932225Z","steps":["trace[76006044] 'agreement among raft nodes before linearized reading' (duration: 143.061915ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:37.932451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.085797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-03-14T01:30:37.932632Z","caller":"traceutil/trace.go:171","msg":"trace[195939225] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:522; }","duration":"711.364444ms","start":"2026-03-14T01:30:37.221245Z","end":"2026-03-14T01:30:37.932609Z","steps":["trace[195939225] 'agreement among raft nodes before linearized reading' (duration: 710.965804ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:37.932691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:30:37.221228Z","time spent":"711.442457ms","remote":"127.0.0.1:40042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-14T01:30:37.932814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:30:37.212459Z","time spent":"719.678942ms","remote":"127.0.0.1:40042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T01:30:42.25222Z","caller":"traceutil/trace.go:171","msg":"trace[229467897] linearizableReadLoop","detail":"{readStateIndex:556; appliedIndex:555; }","duration":"295.70453ms","start":"2026-03-14T01:30:41.956479Z","end":"2026-03-14T01:30:42.252184Z","steps":["trace[229467897] 'read index received' (duration: 295.412652ms)","trace[229467897] 'applied index is now lower than readState.Index' (duration: 290.258µs)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:30:42.252263Z","caller":"traceutil/trace.go:171","msg":"trace[147376584] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"487.810431ms","start":"2026-03-14T01:30:41.764412Z","end":"2026-03-14T01:30:42.252223Z","steps":["trace[147376584] 'process raft request' (duration: 487.534015ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:42.252459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:30:41.764363Z","time spent":"487.991835ms","remote":"127.0.0.1:39936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T01:30:42.252614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.509954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-14T01:30:42.25266Z","caller":"traceutil/trace.go:171","msg":"trace[517786774] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:534; }","duration":"295.629047ms","start":"2026-03-14T01:30:41.957016Z","end":"2026-03-14T01:30:42.252645Z","steps":["trace[517786774] 'agreement among raft nodes before linearized reading' (duration: 295.379411ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:42.252681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.575426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-14T01:30:42.252769Z","caller":"traceutil/trace.go:171","msg":"trace[1618910084] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:534; }","duration":"292.705311ms","start":"2026-03-14T01:30:41.960039Z","end":"2026-03-14T01:30:42.252744Z","steps":["trace[1618910084] 'agreement among raft nodes before linearized reading' (duration: 292.504676ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:42.252943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.467989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-14T01:30:42.253214Z","caller":"traceutil/trace.go:171","msg":"trace[1454999994] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:534; }","duration":"296.776477ms","start":"2026-03-14T01:30:41.956424Z","end":"2026-03-14T01:30:42.253201Z","steps":["trace[1454999994] 'agreement among raft nodes before linearized reading' (duration: 295.955506ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:30:42.493509Z","caller":"traceutil/trace.go:171","msg":"trace[1419463496] linearizableReadLoop","detail":"{readStateIndex:559; appliedIndex:557; }","duration":"102.287483ms","start":"2026-03-14T01:30:42.391197Z","end":"2026-03-14T01:30:42.493485Z","steps":["trace[1419463496] 'read index received' (duration: 101.969776ms)","trace[1419463496] 'applied index is now lower than readState.Index' (duration: 316.787µs)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:30:42.493548Z","caller":"traceutil/trace.go:171","msg":"trace[540884899] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"233.84397ms","start":"2026-03-14T01:30:42.259676Z","end":"2026-03-14T01:30:42.49352Z","steps":["trace[540884899] 'process raft request' (duration: 233.558853ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:30:42.493582Z","caller":"traceutil/trace.go:171","msg":"trace[1561209445] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"233.813389ms","start":"2026-03-14T01:30:42.259746Z","end":"2026-03-14T01:30:42.493559Z","steps":["trace[1561209445] 'process raft request' (duration: 233.680036ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:42.493722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.511128ms","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-03-14T01:30:42.493763Z","caller":"traceutil/trace.go:171","msg":"trace[2010104926] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:537; }","duration":"102.611672ms","start":"2026-03-14T01:30:42.391142Z","end":"2026-03-14T01:30:42.493753Z","steps":["trace[2010104926] 'agreement among raft nodes before linearized reading' (duration: 102.442978ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:43.512139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.466848ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-14T01:30:43.512217Z","caller":"traceutil/trace.go:171","msg":"trace[813609105] linearizableReadLoop","detail":"{readStateIndex:560; appliedIndex:559; }","duration":"721.670691ms","start":"2026-03-14T01:30:42.790533Z","end":"2026-03-14T01:30:43.512204Z","steps":["trace[813609105] 'read index received' (duration: 283.029039ms)","trace[813609105] 'applied index is now lower than readState.Index' (duration: 438.640952ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T01:30:43.512271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.750662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T01:30:43.512287Z","caller":"traceutil/trace.go:171","msg":"trace[2113619781] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:538; }","duration":"721.838586ms","start":"2026-03-14T01:30:42.790443Z","end":"2026-03-14T01:30:43.512282Z","steps":["trace[2113619781] 'agreement among raft nodes before linearized reading' (duration: 721.792604ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:30:43.512315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:30:42.79038Z","time spent":"721.927298ms","remote":"127.0.0.1:39740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-14T01:30:43.512436Z","caller":"traceutil/trace.go:171","msg":"trace[733408405] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"1.012431833s","start":"2026-03-14T01:30:42.49997Z","end":"2026-03-14T01:30:43.512402Z","steps":["trace[733408405] 'process raft request' (duration: 573.537673ms)","trace[733408405] 'compare' (duration: 438.361446ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T01:30:43.512665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:30:42.499947Z","time spent":"1.012563897s","remote":"127.0.0.1:40042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T01:30:45.148201Z","caller":"traceutil/trace.go:171","msg":"trace[1141202292] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"241.175682ms","start":"2026-03-14T01:30:44.907002Z","end":"2026-03-14T01:30:45.148177Z","steps":["trace[1141202292] 'process raft request' (duration: 241.051469ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:30:48.003975Z","caller":"traceutil/trace.go:171","msg":"trace[1109901999] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"107.003477ms","start":"2026-03-14T01:30:47.896932Z","end":"2026-03-14T01:30:48.003935Z","steps":["trace[1109901999] 'process raft request' (duration: 106.834222ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:01.042598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.408358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-14T01:31:01.042745Z","caller":"traceutil/trace.go:171","msg":"trace[1423521324] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:718; }","duration":"463.615572ms","start":"2026-03-14T01:31:00.579093Z","end":"2026-03-14T01:31:01.042709Z","steps":["trace[1423521324] 'range keys from in-memory index tree' (duration: 463.15384ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:01.04279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.454317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-03-14T01:31:01.04283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:31:00.579066Z","time spent":"463.742186ms","remote":"127.0.0.1:40042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-14T01:31:01.042844Z","caller":"traceutil/trace.go:171","msg":"trace[201799326] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:718; }","duration":"461.493418ms","start":"2026-03-14T01:31:00.581328Z","end":"2026-03-14T01:31:01.042821Z","steps":["trace[201799326] 'range keys from in-memory index tree' (duration: 461.195619ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:01.042815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.946207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-14T01:31:01.042893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:31:00.579989Z","time spent":"462.894473ms","remote":"127.0.0.1:40042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-14T01:31:01.042904Z","caller":"traceutil/trace.go:171","msg":"trace[187052928] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:718; }","duration":"604.07548ms","start":"2026-03-14T01:31:00.438809Z","end":"2026-03-14T01:31:01.042884Z","steps":["trace[187052928] 'range keys from in-memory index tree' (duration: 603.705101ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:01.042948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:31:00.438792Z","time spent":"604.146052ms","remote":"127.0.0.1:40042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-14T01:31:01.042634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.182652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T01:31:01.043101Z","caller":"traceutil/trace.go:171","msg":"trace[1751421248] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:718; }","duration":"253.702497ms","start":"2026-03-14T01:31:00.789377Z","end":"2026-03-14T01:31:01.04308Z","steps":["trace[1751421248] 'range keys from in-memory index tree' (duration: 253.050428ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:01.557171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.497656ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-14T01:31:01.557293Z","caller":"traceutil/trace.go:171","msg":"trace[451203487] linearizableReadLoop","detail":"{readStateIndex:745; appliedIndex:744; }","duration":"512.160136ms","start":"2026-03-14T01:31:01.045107Z","end":"2026-03-14T01:31:01.557267Z","steps":["trace[451203487] 'read index received' (duration: 50.535736ms)","trace[451203487] 'applied index is now lower than readState.Index' (duration: 461.62239ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T01:31:01.557498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.363811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T01:31:01.557573Z","caller":"traceutil/trace.go:171","msg":"trace[145136322] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:718; }","duration":"512.495095ms","start":"2026-03-14T01:31:01.045058Z","end":"2026-03-14T01:31:01.557553Z","steps":["trace[145136322] 'agreement among raft nodes before linearized reading' (duration: 512.344082ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:01.557614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:31:01.045032Z","time spent":"512.574147ms","remote":"127.0.0.1:39726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-14T01:31:01.560038Z","caller":"traceutil/trace.go:171","msg":"trace[1127099550] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"512.536367ms","start":"2026-03-14T01:31:01.04744Z","end":"2026-03-14T01:31:01.559977Z","steps":["trace[1127099550] 'process raft request' (duration: 512.239999ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:01.560082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.61647ms","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-03-14T01:31:01.560057Z","caller":"traceutil/trace.go:171","msg":"trace[477667828] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"512.362692ms","start":"2026-03-14T01:31:01.047628Z","end":"2026-03-14T01:31:01.55999Z","steps":["trace[477667828] 'process raft request' (duration: 512.213318ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:31:01.56017Z","caller":"traceutil/trace.go:171","msg":"trace[1510813650] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:721; }","duration":"327.755254ms","start":"2026-03-14T01:31:01.232396Z","end":"2026-03-14T01:31:01.560151Z","steps":["trace[1510813650] 'agreement among raft nodes before linearized reading' (duration: 327.541768ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:31:01.560086Z","caller":"traceutil/trace.go:171","msg":"trace[971049161] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"512.124215ms","start":"2026-03-14T01:31:01.047934Z","end":"2026-03-14T01:31:01.560058Z","steps":["trace[971049161] 'process raft request' (duration: 511.962552ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:01.560223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:31:01.232373Z","time spent":"327.840907ms","remote":"127.0.0.1:40042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-14T01:31:01.560319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:31:01.047422Z","time spent":"512.743341ms","remote":"127.0.0.1:40042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T01:31:01.560413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:31:01.047622Z","time spent":"512.607328ms","remote":"127.0.0.1:40042","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-03-14T01:31:01.560481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:31:01.047907Z","time spent":"512.353271ms","remote":"127.0.0.1:40042","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-03-14T01:31:26.213128Z","caller":"traceutil/trace.go:171","msg":"trace[1606282637] linearizableReadLoop","detail":"{readStateIndex:827; appliedIndex:826; }","duration":"285.209515ms","start":"2026-03-14T01:31:25.927893Z","end":"2026-03-14T01:31:26.213103Z","steps":["trace[1606282637] 'read index received' (duration: 285.056081ms)","trace[1606282637] 'applied index is now lower than readState.Index' (duration: 152.144µs)"],"step_count":2} {"level":"warn","ts":"2026-03-14T01:31:26.213345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.43734ms","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-03-14T01:31:26.213439Z","caller":"traceutil/trace.go:171","msg":"trace[456363576] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:795; }","duration":"285.597154ms","start":"2026-03-14T01:31:25.927831Z","end":"2026-03-14T01:31:26.213428Z","steps":["trace[456363576] 'agreement among raft nodes before linearized reading' (duration: 285.373869ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:31:26.213316Z","caller":"traceutil/trace.go:171","msg":"trace[1759958868] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"315.513786ms","start":"2026-03-14T01:31:25.897771Z","end":"2026-03-14T01:31:26.213285Z","steps":["trace[1759958868] 'process raft request' (duration: 315.186138ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:26.213846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:31:25.897756Z","time spent":"315.797834ms","remote":"127.0.0.1:40042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T01:31:27.910891Z","caller":"traceutil/trace.go:171","msg":"trace[1506667156] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"104.225898ms","start":"2026-03-14T01:31:27.806635Z","end":"2026-03-14T01:31:27.910861Z","steps":["trace[1506667156] 'process raft request' (duration: 101.058715ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:31.201913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.225883ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-14T01:31:31.905523Z","caller":"traceutil/trace.go:171","msg":"trace[1757873914] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"127.232817ms","start":"2026-03-14T01:31:31.778263Z","end":"2026-03-14T01:31:31.905496Z","steps":["trace[1757873914] 'process raft request' (duration: 127.043451ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:31:34.463661Z","caller":"traceutil/trace.go:171","msg":"trace[544004601] transaction","detail":"{read_only:false; response_revision:818; number_of_response:1; }","duration":"116.56793ms","start":"2026-03-14T01:31:34.347065Z","end":"2026-03-14T01:31:34.463633Z","steps":["trace[544004601] 'process raft request' (duration: 116.413385ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:31:39.432289Z","caller":"traceutil/trace.go:171","msg":"trace[620386557] transaction","detail":"{read_only:false; response_revision:865; number_of_response:1; }","duration":"146.408276ms","start":"2026-03-14T01:31:39.28585Z","end":"2026-03-14T01:31:39.432259Z","steps":["trace[620386557] 'process raft request' (duration: 146.122288ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:31:39.432332Z","caller":"traceutil/trace.go:171","msg":"trace[2085689833] transaction","detail":"{read_only:false; response_revision:866; number_of_response:1; }","duration":"146.107339ms","start":"2026-03-14T01:31:39.286196Z","end":"2026-03-14T01:31:39.432303Z","steps":["trace[2085689833] 'process raft request' (duration: 145.965555ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:31:39.432716Z","caller":"traceutil/trace.go:171","msg":"trace[1687576856] transaction","detail":"{read_only:false; response_revision:867; number_of_response:1; }","duration":"141.15245ms","start":"2026-03-14T01:31:39.291537Z","end":"2026-03-14T01:31:39.43269Z","steps":["trace[1687576856] 'process raft request' (duration: 140.726379ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:31:39.433035Z","caller":"traceutil/trace.go:171","msg":"trace[1051972647] transaction","detail":"{read_only:false; response_revision:868; number_of_response:1; }","duration":"141.361036ms","start":"2026-03-14T01:31:39.291648Z","end":"2026-03-14T01:31:39.433009Z","steps":["trace[1051972647] 'process raft request' (duration: 140.984006ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:31:39.433065Z","caller":"traceutil/trace.go:171","msg":"trace[1211861596] linearizableReadLoop","detail":"{readStateIndex:902; appliedIndex:898; }","duration":"146.409247ms","start":"2026-03-14T01:31:39.28661Z","end":"2026-03-14T01:31:39.433019Z","steps":["trace[1211861596] 'read index received' (duration: 73.458346ms)","trace[1211861596] 'applied index is now lower than readState.Index' (duration: 72.948961ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:31:39.433179Z","caller":"traceutil/trace.go:171","msg":"trace[1162005718] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"138.277415ms","start":"2026-03-14T01:31:39.294885Z","end":"2026-03-14T01:31:39.433162Z","steps":["trace[1162005718] 'process raft request' (duration: 137.986448ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:39.433391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.726585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-7kjf6\" ","response":"range_response_count:1 size:9695"} {"level":"info","ts":"2026-03-14T01:31:39.433438Z","caller":"traceutil/trace.go:171","msg":"trace[1198047893] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-7kjf6; range_end:; response_count:1; response_revision:869; }","duration":"146.859109ms","start":"2026-03-14T01:31:39.286567Z","end":"2026-03-14T01:31:39.433426Z","steps":["trace[1198047893] 'agreement among raft nodes before linearized reading' (duration: 146.650933ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:39.438677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.525811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/ceph-csi-rbd-provisioner\" ","response":"range_response_count:1 size:10872"} {"level":"info","ts":"2026-03-14T01:31:39.438783Z","caller":"traceutil/trace.go:171","msg":"trace[1085022715] range","detail":"{range_begin:/registry/deployments/kube-system/ceph-csi-rbd-provisioner; range_end:; response_count:1; response_revision:870; }","duration":"143.680085ms","start":"2026-03-14T01:31:39.295081Z","end":"2026-03-14T01:31:39.438761Z","steps":["trace[1085022715] 'agreement among raft nodes before linearized reading' (duration: 143.237034ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:31:39.664076Z","caller":"traceutil/trace.go:171","msg":"trace[1070943677] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"219.677357ms","start":"2026-03-14T01:31:39.444361Z","end":"2026-03-14T01:31:39.664038Z","steps":["trace[1070943677] 'process raft request' (duration: 162.637431ms)","trace[1070943677] 'compare' (duration: 56.619785ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:31:39.664073Z","caller":"traceutil/trace.go:171","msg":"trace[1956106333] linearizableReadLoop","detail":"{readStateIndex:909; appliedIndex:907; }","duration":"204.985483ms","start":"2026-03-14T01:31:39.459062Z","end":"2026-03-14T01:31:39.664048Z","steps":["trace[1956106333] 'read index received' (duration: 147.872345ms)","trace[1956106333] 'applied index is now lower than readState.Index' (duration: 57.111758ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T01:31:39.664297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.212229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/ceph-csi-rbd-provisioner-69cb97fb6\" ","response":"range_response_count:1 size:10843"} {"level":"info","ts":"2026-03-14T01:31:39.664352Z","caller":"traceutil/trace.go:171","msg":"trace[1392306875] range","detail":"{range_begin:/registry/replicasets/kube-system/ceph-csi-rbd-provisioner-69cb97fb6; range_end:; response_count:1; response_revision:873; }","duration":"205.330703ms","start":"2026-03-14T01:31:39.459005Z","end":"2026-03-14T01:31:39.664336Z","steps":["trace[1392306875] 'agreement among raft nodes before linearized reading' (duration: 205.134018ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:31:39.664409Z","caller":"traceutil/trace.go:171","msg":"trace[1582962822] transaction","detail":"{read_only:false; response_revision:873; number_of_response:1; }","duration":"216.490094ms","start":"2026-03-14T01:31:39.447901Z","end":"2026-03-14T01:31:39.664391Z","steps":["trace[1582962822] 'process raft request' (duration: 216.060734ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:39.664639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.458337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:6 size:363678"} {"level":"info","ts":"2026-03-14T01:31:39.664697Z","caller":"traceutil/trace.go:171","msg":"trace[1263626883] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:6; response_revision:873; }","duration":"177.58057ms","start":"2026-03-14T01:31:39.487104Z","end":"2026-03-14T01:31:39.664685Z","steps":["trace[1263626883] 'agreement among raft nodes before linearized reading' (duration: 177.231861ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:39.66477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.593119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ceph-csi-rbd-nodeplugin\" ","response":"range_response_count:1 size:751"} {"level":"warn","ts":"2026-03-14T01:31:39.664811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.434071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ceph-csi-rbd-nodeplugin\" ","response":"range_response_count:1 size:751"} {"level":"info","ts":"2026-03-14T01:31:39.664847Z","caller":"traceutil/trace.go:171","msg":"trace[37529184] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ceph-csi-rbd-nodeplugin; range_end:; response_count:1; response_revision:873; }","duration":"134.704271ms","start":"2026-03-14T01:31:39.530122Z","end":"2026-03-14T01:31:39.664827Z","steps":["trace[37529184] 'agreement among raft nodes before linearized reading' (duration: 134.477895ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:31:39.664919Z","caller":"traceutil/trace.go:171","msg":"trace[560017869] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ceph-csi-rbd-nodeplugin; range_end:; response_count:1; response_revision:873; }","duration":"135.594824ms","start":"2026-03-14T01:31:39.529293Z","end":"2026-03-14T01:31:39.664887Z","steps":["trace[560017869] 'agreement among raft nodes before linearized reading' (duration: 135.332718ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:39.930759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.860078ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-14T01:31:39.931076Z","caller":"traceutil/trace.go:171","msg":"trace[1777735837] linearizableReadLoop","detail":"{readStateIndex:911; appliedIndex:910; }","duration":"259.175867ms","start":"2026-03-14T01:31:39.671878Z","end":"2026-03-14T01:31:39.931053Z","steps":["trace[1777735837] 'read index received' (duration: 28.240216ms)","trace[1777735837] 'applied index is now lower than readState.Index' (duration: 230.931441ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:31:39.931114Z","caller":"traceutil/trace.go:171","msg":"trace[1740085386] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"257.948174ms","start":"2026-03-14T01:31:39.673132Z","end":"2026-03-14T01:31:39.93108Z","steps":["trace[1740085386] 'process raft request' (duration: 257.77969ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:31:39.931154Z","caller":"traceutil/trace.go:171","msg":"trace[737292372] transaction","detail":"{read_only:false; response_revision:875; number_of_response:1; }","duration":"259.351131ms","start":"2026-03-14T01:31:39.671737Z","end":"2026-03-14T01:31:39.931088Z","steps":["trace[737292372] 'process raft request' (duration: 122.053041ms)","trace[737292372] 'compare' (duration: 136.687093ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T01:31:39.931306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.421243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-68lq5\" ","response":"range_response_count:1 size:10774"} {"level":"info","ts":"2026-03-14T01:31:39.931344Z","caller":"traceutil/trace.go:171","msg":"trace[1798774788] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-68lq5; range_end:; response_count:1; response_revision:876; }","duration":"259.461554ms","start":"2026-03-14T01:31:39.67187Z","end":"2026-03-14T01:31:39.931332Z","steps":["trace[1798774788] 'agreement among raft nodes before linearized reading' (duration: 259.28863ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:39.931494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.228212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ceph-csi-rbd-provisioner\" ","response":"range_response_count:1 size:754"} {"level":"info","ts":"2026-03-14T01:31:39.932179Z","caller":"traceutil/trace.go:171","msg":"trace[875409833] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ceph-csi-rbd-provisioner; range_end:; response_count:1; response_revision:876; }","duration":"197.976301ms","start":"2026-03-14T01:31:39.734178Z","end":"2026-03-14T01:31:39.932155Z","steps":["trace[875409833] 'agreement among raft nodes before linearized reading' (duration: 197.110208ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:39.931567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.922666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:6 size:363678"} {"level":"info","ts":"2026-03-14T01:31:39.932478Z","caller":"traceutil/trace.go:171","msg":"trace[1647232262] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:6; response_revision:876; }","duration":"158.81989ms","start":"2026-03-14T01:31:39.773596Z","end":"2026-03-14T01:31:39.932415Z","steps":["trace[1647232262] 'agreement among raft nodes before linearized reading' (duration: 157.715041ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:39.931617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.794412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T01:31:39.933019Z","caller":"traceutil/trace.go:171","msg":"trace[1691368501] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:876; }","duration":"144.20461ms","start":"2026-03-14T01:31:39.788796Z","end":"2026-03-14T01:31:39.933001Z","steps":["trace[1691368501] 'agreement among raft nodes before linearized reading' (duration: 142.799383ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:39.931806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.215681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ceph-csi-rbd-provisioner\" ","response":"range_response_count:1 size:754"} {"level":"info","ts":"2026-03-14T01:31:39.93325Z","caller":"traceutil/trace.go:171","msg":"trace[89009899] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ceph-csi-rbd-provisioner; range_end:; response_count:1; response_revision:876; }","duration":"198.67685ms","start":"2026-03-14T01:31:39.734546Z","end":"2026-03-14T01:31:39.933223Z","steps":["trace[89009899] 'agreement among raft nodes before linearized reading' (duration: 197.110198ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:40.565454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.037291ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-14T01:31:40.56576Z","caller":"traceutil/trace.go:171","msg":"trace[119696165] linearizableReadLoop","detail":"{readStateIndex:915; appliedIndex:913; }","duration":"611.675617ms","start":"2026-03-14T01:31:39.95406Z","end":"2026-03-14T01:31:40.565736Z","steps":["trace[119696165] 'read index received' (duration: 307.928377ms)","trace[119696165] 'applied index is now lower than readState.Index' (duration: 303.74575ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:31:40.565857Z","caller":"traceutil/trace.go:171","msg":"trace[345447548] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"621.041411ms","start":"2026-03-14T01:31:39.944769Z","end":"2026-03-14T01:31:40.565811Z","steps":["trace[345447548] 'process raft request' (duration: 317.288692ms)","trace[345447548] 'compare' (duration: 302.804594ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:31:40.56607Z","caller":"traceutil/trace.go:171","msg":"trace[1751671724] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"620.865765ms","start":"2026-03-14T01:31:39.945156Z","end":"2026-03-14T01:31:40.566021Z","steps":["trace[1751671724] 'process raft request' (duration: 620.482405ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:40.566132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:31:39.944763Z","time spent":"621.214275ms","remote":"127.0.0.1:40258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11206,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T01:31:40.566275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.30651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-03-14T01:31:40.566284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:31:39.945149Z","time spent":"621.035091ms","remote":"127.0.0.1:39936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12627,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T01:31:40.566327Z","caller":"traceutil/trace.go:171","msg":"trace[2121977664] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:879; }","duration":"582.410423ms","start":"2026-03-14T01:31:39.983902Z","end":"2026-03-14T01:31:40.566313Z","steps":["trace[2121977664] 'agreement among raft nodes before linearized reading' (duration: 582.201638ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:40.566372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:31:39.98388Z","time spent":"582.480235ms","remote":"127.0.0.1:40042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-14T01:31:40.566759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.449462ms","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-03-14T01:31:40.566822Z","caller":"traceutil/trace.go:171","msg":"trace[1881435616] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:879; }","duration":"552.539745ms","start":"2026-03-14T01:31:40.014267Z","end":"2026-03-14T01:31:40.566807Z","steps":["trace[1881435616] 'agreement among raft nodes before linearized reading' (duration: 552.326949ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:40.56683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.11931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-14T01:31:40.566862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:31:40.014255Z","time spent":"552.597245ms","remote":"127.0.0.1:40042","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":"info","ts":"2026-03-14T01:31:40.566869Z","caller":"traceutil/trace.go:171","msg":"trace[522323418] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:879; }","duration":"207.215603ms","start":"2026-03-14T01:31:40.359642Z","end":"2026-03-14T01:31:40.566858Z","steps":["trace[522323418] 'agreement among raft nodes before linearized reading' (duration: 207.057729ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:40.567032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.924329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/ceph-csi-rbd-provisioner-69cb97fb6\" ","response":"range_response_count:1 size:10888"} {"level":"info","ts":"2026-03-14T01:31:40.56811Z","caller":"traceutil/trace.go:171","msg":"trace[1561780892] range","detail":"{range_begin:/registry/replicasets/kube-system/ceph-csi-rbd-provisioner-69cb97fb6; range_end:; response_count:1; response_revision:879; }","duration":"614.050378ms","start":"2026-03-14T01:31:39.954033Z","end":"2026-03-14T01:31:40.568083Z","steps":["trace[1561780892] 'agreement among raft nodes before linearized reading' (duration: 611.955423ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:40.568264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:31:39.95402Z","time spent":"614.219922ms","remote":"127.0.0.1:40270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":10912,"request content":"key:\"/registry/replicasets/kube-system/ceph-csi-rbd-provisioner-69cb97fb6\" "} {"level":"info","ts":"2026-03-14T01:31:40.799125Z","caller":"traceutil/trace.go:171","msg":"trace[1258622367] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"224.742739ms","start":"2026-03-14T01:31:40.574354Z","end":"2026-03-14T01:31:40.799096Z","steps":["trace[1258622367] 'process raft request' (duration: 224.644957ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:31:40.799343Z","caller":"traceutil/trace.go:171","msg":"trace[1073558412] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"225.332173ms","start":"2026-03-14T01:31:40.574Z","end":"2026-03-14T01:31:40.799332Z","steps":["trace[1073558412] 'process raft request' (duration: 224.799709ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:31:40.799494Z","caller":"traceutil/trace.go:171","msg":"trace[2098635595] linearizableReadLoop","detail":"{readStateIndex:919; appliedIndex:916; }","duration":"221.996367ms","start":"2026-03-14T01:31:40.57748Z","end":"2026-03-14T01:31:40.799477Z","steps":["trace[2098635595] 'read index received' (duration: 59.356047ms)","trace[2098635595] 'applied index is now lower than readState.Index' (duration: 162.63825ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:31:40.799621Z","caller":"traceutil/trace.go:171","msg":"trace[1171880432] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"225.070298ms","start":"2026-03-14T01:31:40.574535Z","end":"2026-03-14T01:31:40.799606Z","steps":["trace[1171880432] 'process raft request' (duration: 224.515433ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:40.799714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.684798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-14T01:31:40.799798Z","caller":"traceutil/trace.go:171","msg":"trace[648973639] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:883; }","duration":"206.788161ms","start":"2026-03-14T01:31:40.592993Z","end":"2026-03-14T01:31:40.799782Z","steps":["trace[648973639] 'agreement among raft nodes before linearized reading' (duration: 206.647547ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:40.799955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.45852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/ceph-csi-rbd-provisioner-69cb97fb6\" ","response":"range_response_count:1 size:10888"} {"level":"info","ts":"2026-03-14T01:31:40.800018Z","caller":"traceutil/trace.go:171","msg":"trace[275674996] range","detail":"{range_begin:/registry/replicasets/kube-system/ceph-csi-rbd-provisioner-69cb97fb6; range_end:; response_count:1; response_revision:883; }","duration":"222.562992ms","start":"2026-03-14T01:31:40.577439Z","end":"2026-03-14T01:31:40.800002Z","steps":["trace[275674996] 'agreement among raft nodes before linearized reading' (duration: 222.335276ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:41.045215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.219799ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-14T01:31:41.045322Z","caller":"traceutil/trace.go:171","msg":"trace[2001561316] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"112.836622ms","start":"2026-03-14T01:31:40.932468Z","end":"2026-03-14T01:31:41.045304Z","steps":["trace[2001561316] 'compare' (duration: 111.070676ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:31:41.257417Z","caller":"traceutil/trace.go:171","msg":"trace[342680455] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"204.04612ms","start":"2026-03-14T01:31:41.053297Z","end":"2026-03-14T01:31:41.257343Z","steps":["trace[342680455] 'process raft request' (duration: 127.874904ms)","trace[342680455] 'compare' (duration: 74.989225ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:31:42.753022Z","caller":"traceutil/trace.go:171","msg":"trace[600091886] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"107.685417ms","start":"2026-03-14T01:31:42.645314Z","end":"2026-03-14T01:31:42.753Z","steps":["trace[600091886] 'process raft request' (duration: 107.508162ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:43.012187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.173324ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-14T01:31:43.012407Z","caller":"traceutil/trace.go:171","msg":"trace[1336998518] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"203.66858ms","start":"2026-03-14T01:31:42.80872Z","end":"2026-03-14T01:31:43.012389Z","steps":["trace[1336998518] 'process raft request' (duration: 203.629419ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:31:43.012413Z","caller":"traceutil/trace.go:171","msg":"trace[520152214] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"204.512112ms","start":"2026-03-14T01:31:42.807872Z","end":"2026-03-14T01:31:43.012384Z","steps":["trace[520152214] 'process raft request' (duration: 79.056661ms)","trace[520152214] 'compare' (duration: 125.013619ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:31:43.012496Z","caller":"traceutil/trace.go:171","msg":"trace[2125002983] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"203.875235ms","start":"2026-03-14T01:31:42.808582Z","end":"2026-03-14T01:31:43.012457Z","steps":["trace[2125002983] 'process raft request' (duration: 203.718701ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:47.091702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.402555715s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.250\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-14T01:31:47.091784Z","caller":"traceutil/trace.go:171","msg":"trace[1156150223] range","detail":"{range_begin:/registry/masterleases/199.204.45.250; range_end:; response_count:1; response_revision:897; }","duration":"1.402669367s","start":"2026-03-14T01:31:45.689096Z","end":"2026-03-14T01:31:47.091765Z","steps":["trace[1156150223] 'range keys from in-memory index tree' (duration: 1.402401831s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:47.0918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.610588539s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-14T01:31:47.091863Z","caller":"traceutil/trace.go:171","msg":"trace[1255189468] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:897; }","duration":"1.610667s","start":"2026-03-14T01:31:45.481182Z","end":"2026-03-14T01:31:47.091849Z","steps":["trace[1255189468] 'count revisions from in-memory index tree' (duration: 1.610506645s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:47.091892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:31:45.481129Z","time spent":"1.610755423s","remote":"127.0.0.1:40316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":104,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-03-14T01:31:47.091914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.066025592s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-14T01:31:47.091983Z","caller":"traceutil/trace.go:171","msg":"trace[555169389] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:897; }","duration":"1.066129503s","start":"2026-03-14T01:31:46.025835Z","end":"2026-03-14T01:31:47.091964Z","steps":["trace[555169389] 'range keys from in-memory index tree' (duration: 1.065846706s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:47.092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.304015316s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-14T01:31:47.092022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:31:46.025817Z","time spent":"1.066195755s","remote":"127.0.0.1:40042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-14T01:31:47.092035Z","caller":"traceutil/trace.go:171","msg":"trace[860450029] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:897; }","duration":"1.304073397s","start":"2026-03-14T01:31:45.787952Z","end":"2026-03-14T01:31:47.092026Z","steps":["trace[860450029] 'range keys from in-memory index tree' (duration: 1.303961164s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:47.092064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:31:45.787937Z","time spent":"1.304120688s","remote":"127.0.0.1:39726","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-03-14T01:31:47.091822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:31:45.689081Z","time spent":"1.402732028s","remote":"127.0.0.1:39786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.204.45.250\" "} {"level":"info","ts":"2026-03-14T01:31:47.092806Z","caller":"traceutil/trace.go:171","msg":"trace[661799418] linearizableReadLoop","detail":"{readStateIndex:935; appliedIndex:934; }","duration":"313.847192ms","start":"2026-03-14T01:31:46.778914Z","end":"2026-03-14T01:31:47.092761Z","steps":["trace[661799418] 'read index received' (duration: 287.876875ms)","trace[661799418] 'applied index is now lower than readState.Index' (duration: 25.968297ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:31:47.093049Z","caller":"traceutil/trace.go:171","msg":"trace[1158186820] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"421.58925ms","start":"2026-03-14T01:31:46.671447Z","end":"2026-03-14T01:31:47.093036Z","steps":["trace[1158186820] 'process raft request' (duration: 395.414148ms)","trace[1158186820] 'compare' (duration: 24.55647ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T01:31:47.093095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.174431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-14T01:31:47.093133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:31:46.671415Z","time spent":"421.670084ms","remote":"127.0.0.1:40042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T01:31:47.093135Z","caller":"traceutil/trace.go:171","msg":"trace[1047948685] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:898; }","duration":"314.252842ms","start":"2026-03-14T01:31:46.778871Z","end":"2026-03-14T01:31:47.093124Z","steps":["trace[1047948685] 'agreement among raft nodes before linearized reading' (duration: 314.131169ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:47.093173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:31:46.778852Z","time spent":"314.314303ms","remote":"127.0.0.1:40042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-14T01:31:47.491715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:31:47.09481Z","time spent":"396.895216ms","remote":"127.0.0.1:39786","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-03-14T01:31:47.499481Z","caller":"traceutil/trace.go:171","msg":"trace[259484095] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"402.383679ms","start":"2026-03-14T01:31:47.097074Z","end":"2026-03-14T01:31:47.499457Z","steps":["trace[259484095] 'process raft request' (duration: 402.308857ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:31:47.499484Z","caller":"traceutil/trace.go:171","msg":"trace[546924188] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"402.845182ms","start":"2026-03-14T01:31:47.096604Z","end":"2026-03-14T01:31:47.499449Z","steps":["trace[546924188] 'process raft request' (duration: 402.647617ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:47.499632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:31:47.097059Z","time spent":"402.493752ms","remote":"127.0.0.1:40042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T01:31:47.499677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:31:47.096572Z","time spent":"403.011656ms","remote":"127.0.0.1:40042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T01:31:47.499799Z","caller":"traceutil/trace.go:171","msg":"trace[1878216228] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"402.075212ms","start":"2026-03-14T01:31:47.097705Z","end":"2026-03-14T01:31:47.499781Z","steps":["trace[1878216228] 'process raft request' (duration: 401.714563ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:47.499909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:31:47.097685Z","time spent":"402.158685ms","remote":"127.0.0.1:40042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T01:31:47.500024Z","caller":"traceutil/trace.go:171","msg":"trace[492074619] linearizableReadLoop","detail":"{readStateIndex:941; appliedIndex:935; }","duration":"398.677454ms","start":"2026-03-14T01:31:47.101336Z","end":"2026-03-14T01:31:47.500014Z","steps":["trace[492074619] 'read index received' (duration: 374.688098ms)","trace[492074619] 'applied index is now lower than readState.Index' (duration: 23.988316ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:31:47.500016Z","caller":"traceutil/trace.go:171","msg":"trace[1630347030] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"401.840435ms","start":"2026-03-14T01:31:47.098163Z","end":"2026-03-14T01:31:47.500004Z","steps":["trace[1630347030] 'process raft request' (duration: 401.735643ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:31:47.500047Z","caller":"traceutil/trace.go:171","msg":"trace[278219551] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"402.144243ms","start":"2026-03-14T01:31:47.09786Z","end":"2026-03-14T01:31:47.500005Z","steps":["trace[278219551] 'process raft request' (duration: 401.822295ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:47.500109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:31:47.098151Z","time spent":"401.898407ms","remote":"127.0.0.1:40042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T01:31:47.500167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.032983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-14T01:31:47.50012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.786146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T01:31:47.500195Z","caller":"traceutil/trace.go:171","msg":"trace[1348629876] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:904; }","duration":"338.102045ms","start":"2026-03-14T01:31:47.162085Z","end":"2026-03-14T01:31:47.500188Z","steps":["trace[1348629876] 'agreement among raft nodes before linearized reading' (duration: 338.060833ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:31:47.50021Z","caller":"traceutil/trace.go:171","msg":"trace[1553454674] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:904; }","duration":"398.887389ms","start":"2026-03-14T01:31:47.101303Z","end":"2026-03-14T01:31:47.500191Z","steps":["trace[1553454674] 'agreement among raft nodes before linearized reading' (duration: 398.759015ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:47.50024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:31:47.097837Z","time spent":"402.303467ms","remote":"127.0.0.1:40042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T01:31:47.500256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:31:47.10129Z","time spent":"398.956391ms","remote":"127.0.0.1:39740","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-03-14T01:31:47.500218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:31:47.162067Z","time spent":"338.143876ms","remote":"127.0.0.1:40084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"info","ts":"2026-03-14T01:31:59.747742Z","caller":"traceutil/trace.go:171","msg":"trace[289298375] linearizableReadLoop","detail":"{readStateIndex:973; appliedIndex:972; }","duration":"180.035952ms","start":"2026-03-14T01:31:59.56767Z","end":"2026-03-14T01:31:59.747706Z","steps":["trace[289298375] 'read index received' (duration: 179.710814ms)","trace[289298375] 'applied index is now lower than readState.Index' (duration: 323.888µs)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:31:59.747937Z","caller":"traceutil/trace.go:171","msg":"trace[44162009] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"195.025414ms","start":"2026-03-14T01:31:59.552897Z","end":"2026-03-14T01:31:59.747922Z","steps":["trace[44162009] 'process raft request' (duration: 194.595753ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:59.748204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.1666ms","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-03-14T01:31:59.748292Z","caller":"traceutil/trace.go:171","msg":"trace[234720632] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:933; }","duration":"141.288494ms","start":"2026-03-14T01:31:59.606981Z","end":"2026-03-14T01:31:59.748269Z","steps":["trace[234720632] 'agreement among raft nodes before linearized reading' (duration: 141.049317ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:59.748615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.306429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-03-14T01:31:59.748638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.210217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-14T01:31:59.748652Z","caller":"traceutil/trace.go:171","msg":"trace[1941351947] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:933; }","duration":"132.362621ms","start":"2026-03-14T01:31:59.616279Z","end":"2026-03-14T01:31:59.748642Z","steps":["trace[1941351947] 'agreement among raft nodes before linearized reading' (duration: 132.268289ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:31:59.748692Z","caller":"traceutil/trace.go:171","msg":"trace[783634227] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:933; }","duration":"132.322769ms","start":"2026-03-14T01:31:59.616352Z","end":"2026-03-14T01:31:59.748675Z","steps":["trace[783634227] 'agreement among raft nodes before linearized reading' (duration: 132.122033ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:31:59.748855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.192735ms","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-03-14T01:31:59.74889Z","caller":"traceutil/trace.go:171","msg":"trace[255589435] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:933; }","duration":"181.272825ms","start":"2026-03-14T01:31:59.567607Z","end":"2026-03-14T01:31:59.74888Z","steps":["trace[255589435] 'agreement among raft nodes before linearized reading' (duration: 181.173922ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:32:00.29496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.839328ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-14T01:32:00.295062Z","caller":"traceutil/trace.go:171","msg":"trace[942233523] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"541.172438ms","start":"2026-03-14T01:31:59.753869Z","end":"2026-03-14T01:32:00.295042Z","steps":["trace[942233523] 'process raft request' (duration: 109.174315ms)","trace[942233523] 'compare' (duration: 431.732005ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T01:32:00.29512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:31:59.753844Z","time spent":"541.248721ms","remote":"127.0.0.1:40042","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-03-14T01:32:00.302873Z","caller":"traceutil/trace.go:171","msg":"trace[420090077] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"548.612662ms","start":"2026-03-14T01:31:59.75424Z","end":"2026-03-14T01:32:00.302853Z","steps":["trace[420090077] 'process raft request' (duration: 548.325404ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:32:00.302975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:31:59.754215Z","time spent":"548.706114ms","remote":"127.0.0.1:40042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T01:32:00.302972Z","caller":"traceutil/trace.go:171","msg":"trace[821013262] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"548.094099ms","start":"2026-03-14T01:31:59.754861Z","end":"2026-03-14T01:32:00.302955Z","steps":["trace[821013262] 'process raft request' (duration: 547.811981ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:32:00.30308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:31:59.754851Z","time spent":"548.179471ms","remote":"127.0.0.1:40042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T01:32:00.303148Z","caller":"traceutil/trace.go:171","msg":"trace[670769060] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"547.940185ms","start":"2026-03-14T01:31:59.755203Z","end":"2026-03-14T01:32:00.303143Z","steps":["trace[670769060] 'process raft request' (duration: 547.706689ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:32:00.303177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:31:59.75519Z","time spent":"547.970935ms","remote":"127.0.0.1:40042","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-03-14T01:32:00.303295Z","caller":"traceutil/trace.go:171","msg":"trace[1120859556] linearizableReadLoop","detail":"{readStateIndex:977; appliedIndex:973; }","duration":"513.591729ms","start":"2026-03-14T01:31:59.789677Z","end":"2026-03-14T01:32:00.303268Z","steps":["trace[1120859556] 'read index received' (duration: 73.460885ms)","trace[1120859556] 'applied index is now lower than readState.Index' (duration: 440.129354ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:32:00.303373Z","caller":"traceutil/trace.go:171","msg":"trace[1018995524] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"455.134345ms","start":"2026-03-14T01:31:59.848232Z","end":"2026-03-14T01:32:00.303367Z","steps":["trace[1018995524] 'process raft request' (duration: 454.861497ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:32:00.303405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:31:59.84821Z","time spent":"455.178346ms","remote":"127.0.0.1:40042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T01:32:00.303472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.497332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-14T01:32:00.303469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.773004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T01:32:00.303501Z","caller":"traceutil/trace.go:171","msg":"trace[1179802544] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:938; }","duration":"214.571003ms","start":"2026-03-14T01:32:00.088923Z","end":"2026-03-14T01:32:00.303494Z","steps":["trace[1179802544] 'agreement among raft nodes before linearized reading' (duration: 214.518502ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:32:00.303521Z","caller":"traceutil/trace.go:171","msg":"trace[527229084] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:938; }","duration":"513.883256ms","start":"2026-03-14T01:31:59.78962Z","end":"2026-03-14T01:32:00.303503Z","steps":["trace[527229084] 'agreement among raft nodes before linearized reading' (duration: 513.793933ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:32:00.303562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:31:59.789594Z","time spent":"513.95549ms","remote":"127.0.0.1:39740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-14T01:32:04.007388Z","caller":"traceutil/trace.go:171","msg":"trace[1744120058] linearizableReadLoop","detail":"{readStateIndex:999; appliedIndex:998; }","duration":"220.051606ms","start":"2026-03-14T01:32:03.787313Z","end":"2026-03-14T01:32:04.007365Z","steps":["trace[1744120058] 'read index received' (duration: 219.868062ms)","trace[1744120058] 'applied index is now lower than readState.Index' (duration: 177.404µs)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:32:04.007474Z","caller":"traceutil/trace.go:171","msg":"trace[1300692837] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"225.279574ms","start":"2026-03-14T01:32:03.782167Z","end":"2026-03-14T01:32:04.007447Z","steps":["trace[1300692837] 'process raft request' (duration: 225.042198ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:32:04.007557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.228631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T01:32:04.0076Z","caller":"traceutil/trace.go:171","msg":"trace[1002875234] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:958; }","duration":"220.312453ms","start":"2026-03-14T01:32:03.787276Z","end":"2026-03-14T01:32:04.007588Z","steps":["trace[1002875234] 'agreement among raft nodes before linearized reading' (duration: 220.221051ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:32:04.007731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.913067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-14T01:32:04.007815Z","caller":"traceutil/trace.go:171","msg":"trace[1001542932] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:958; }","duration":"181.05265ms","start":"2026-03-14T01:32:03.826738Z","end":"2026-03-14T01:32:04.007791Z","steps":["trace[1001542932] 'agreement among raft nodes before linearized reading' (duration: 180.823144ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:32:04.30253Z","caller":"traceutil/trace.go:171","msg":"trace[82532170] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"288.543462ms","start":"2026-03-14T01:32:04.013967Z","end":"2026-03-14T01:32:04.30251Z","steps":["trace[82532170] 'process raft request' (duration: 229.124393ms)","trace[82532170] 'compare' (duration: 59.274515ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:32:04.302655Z","caller":"traceutil/trace.go:171","msg":"trace[1268968533] linearizableReadLoop","detail":"{readStateIndex:1001; appliedIndex:999; }","duration":"219.360289ms","start":"2026-03-14T01:32:04.083273Z","end":"2026-03-14T01:32:04.302634Z","steps":["trace[1268968533] 'read index received' (duration: 159.785236ms)","trace[1268968533] 'applied index is now lower than readState.Index' (duration: 59.574243ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T01:32:04.302793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.517062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-14T01:32:04.302819Z","caller":"traceutil/trace.go:171","msg":"trace[1382246946] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:960; }","duration":"219.598565ms","start":"2026-03-14T01:32:04.083213Z","end":"2026-03-14T01:32:04.302812Z","steps":["trace[1382246946] 'agreement among raft nodes before linearized reading' (duration: 219.543313ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:32:04.302917Z","caller":"traceutil/trace.go:171","msg":"trace[1639782411] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"287.30901ms","start":"2026-03-14T01:32:04.015602Z","end":"2026-03-14T01:32:04.302911Z","steps":["trace[1639782411] 'process raft request' (duration: 286.868908ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:32:04.30293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.640684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-7kjf6\" ","response":"range_response_count:1 size:11061"} {"level":"info","ts":"2026-03-14T01:32:04.302957Z","caller":"traceutil/trace.go:171","msg":"trace[272432431] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-7kjf6; range_end:; response_count:1; response_revision:960; }","duration":"217.708495ms","start":"2026-03-14T01:32:04.08524Z","end":"2026-03-14T01:32:04.302949Z","steps":["trace[272432431] 'agreement among raft nodes before linearized reading' (duration: 217.589812ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:32:05.452397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"777.89896ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-14T01:32:05.45261Z","caller":"traceutil/trace.go:171","msg":"trace[1845787301] linearizableReadLoop","detail":"{readStateIndex:1003; appliedIndex:1001; }","duration":"1.137908785s","start":"2026-03-14T01:32:04.314686Z","end":"2026-03-14T01:32:05.452595Z","steps":["trace[1845787301] 'read index received' (duration: 359.702088ms)","trace[1845787301] 'applied index is now lower than readState.Index' (duration: 778.205807ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:32:05.452691Z","caller":"traceutil/trace.go:171","msg":"trace[1774829681] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"1.138505681s","start":"2026-03-14T01:32:04.314176Z","end":"2026-03-14T01:32:05.452682Z","steps":["trace[1774829681] 'process raft request' (duration: 1.138318705s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:32:05.452755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:32:04.31416Z","time spent":"1.138560461s","remote":"127.0.0.1:39936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11375,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T01:32:05.452758Z","caller":"traceutil/trace.go:171","msg":"trace[905711265] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"1.142663438s","start":"2026-03-14T01:32:04.310068Z","end":"2026-03-14T01:32:05.452732Z","steps":["trace[905711265] 'process raft request' (duration: 364.356758ms)","trace[905711265] 'compare' (duration: 777.256694ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T01:32:05.452896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:32:04.310047Z","time spent":"1.142777051s","remote":"127.0.0.1:40042","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-03-14T01:32:05.453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.138311816s","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-03-14T01:32:05.453054Z","caller":"traceutil/trace.go:171","msg":"trace[635900596] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:962; }","duration":"1.138369946s","start":"2026-03-14T01:32:04.314667Z","end":"2026-03-14T01:32:05.453037Z","steps":["trace[635900596] 'agreement among raft nodes before linearized reading' (duration: 1.138275904s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:32:05.45289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.136276891s","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":"warn","ts":"2026-03-14T01:32:05.453105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:32:04.314658Z","time spent":"1.138438008s","remote":"127.0.0.1:40042","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-03-14T01:32:05.453128Z","caller":"traceutil/trace.go:171","msg":"trace[611823943] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:962; }","duration":"1.13656038s","start":"2026-03-14T01:32:04.316556Z","end":"2026-03-14T01:32:05.453116Z","steps":["trace[611823943] 'agreement among raft nodes before linearized reading' (duration: 1.136147729s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:32:05.453169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:32:04.316549Z","time spent":"1.136607372s","remote":"127.0.0.1:40042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-14T01:32:05.453172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"678.927519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-14T01:32:05.453202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.135647366s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-14T01:32:05.453229Z","caller":"traceutil/trace.go:171","msg":"trace[1144936769] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:962; }","duration":"679.041613ms","start":"2026-03-14T01:32:04.774176Z","end":"2026-03-14T01:32:05.453217Z","steps":["trace[1144936769] 'agreement among raft nodes before linearized reading' (duration: 678.902229ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:32:05.453239Z","caller":"traceutil/trace.go:171","msg":"trace[661781653] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:962; }","duration":"1.135698728s","start":"2026-03-14T01:32:04.31753Z","end":"2026-03-14T01:32:05.453229Z","steps":["trace[661781653] 'agreement among raft nodes before linearized reading' (duration: 1.135561674s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:32:05.453264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:32:04.774156Z","time spent":"679.099314ms","remote":"127.0.0.1:40080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":29,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-03-14T01:32:05.453263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.257442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-14T01:32:05.453273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:32:04.317524Z","time spent":"1.135742298s","remote":"127.0.0.1:40042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-14T01:32:05.4533Z","caller":"traceutil/trace.go:171","msg":"trace[23503647] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:962; }","duration":"665.330215ms","start":"2026-03-14T01:32:04.787957Z","end":"2026-03-14T01:32:05.453287Z","steps":["trace[23503647] 'agreement among raft nodes before linearized reading' (duration: 665.281293ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:32:05.453325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:32:04.787936Z","time spent":"665.383656ms","remote":"127.0.0.1:39740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-14T01:32:05.674811Z","caller":"traceutil/trace.go:171","msg":"trace[1426133176] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"210.279401ms","start":"2026-03-14T01:32:05.464495Z","end":"2026-03-14T01:32:05.674774Z","steps":["trace[1426133176] 'process raft request' (duration: 111.591079ms)","trace[1426133176] 'compare' (duration: 98.469617ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:32:05.67485Z","caller":"traceutil/trace.go:171","msg":"trace[403221503] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"209.411379ms","start":"2026-03-14T01:32:05.465415Z","end":"2026-03-14T01:32:05.674826Z","steps":["trace[403221503] 'process raft request' (duration: 209.331607ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:32:05.674992Z","caller":"traceutil/trace.go:171","msg":"trace[1863320008] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"205.778735ms","start":"2026-03-14T01:32:05.469193Z","end":"2026-03-14T01:32:05.674971Z","steps":["trace[1863320008] 'process raft request' (duration: 205.690302ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:32:05.675044Z","caller":"traceutil/trace.go:171","msg":"trace[177053256] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"210.458086ms","start":"2026-03-14T01:32:05.464552Z","end":"2026-03-14T01:32:05.67501Z","steps":["trace[177053256] 'process raft request' (duration: 210.149918ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:32:05.675135Z","caller":"traceutil/trace.go:171","msg":"trace[633766269] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"209.612964ms","start":"2026-03-14T01:32:05.465501Z","end":"2026-03-14T01:32:05.675114Z","steps":["trace[633766269] 'process raft request' (duration: 209.286255ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:32:05.907754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.687371ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-14T01:32:05.908002Z","caller":"traceutil/trace.go:171","msg":"trace[875973598] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"197.848919ms","start":"2026-03-14T01:32:05.710124Z","end":"2026-03-14T01:32:05.907973Z","steps":["trace[875973598] 'process raft request' (duration: 90.85905ms)","trace[875973598] 'compare' (duration: 106.561358ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:32:05.908061Z","caller":"traceutil/trace.go:171","msg":"trace[1293991749] linearizableReadLoop","detail":"{readStateIndex:1011; appliedIndex:1010; }","duration":"119.052535ms","start":"2026-03-14T01:32:05.788948Z","end":"2026-03-14T01:32:05.908001Z","steps":["trace[1293991749] 'read index received' (duration: 12.027435ms)","trace[1293991749] 'applied index is now lower than readState.Index' (duration: 107.0192ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T01:32:05.908835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.866715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T01:32:05.908937Z","caller":"traceutil/trace.go:171","msg":"trace[156738544] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:970; }","duration":"119.992378ms","start":"2026-03-14T01:32:05.788909Z","end":"2026-03-14T01:32:05.908901Z","steps":["trace[156738544] 'agreement among raft nodes before linearized reading' (duration: 119.224928ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:32:06.612194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.08868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-14T01:32:06.612263Z","caller":"traceutil/trace.go:171","msg":"trace[617936286] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:970; }","duration":"306.212603ms","start":"2026-03-14T01:32:06.306036Z","end":"2026-03-14T01:32:06.612249Z","steps":["trace[617936286] 'range keys from in-memory index tree' (duration: 305.936636ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:32:06.612294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:32:06.306015Z","time spent":"306.271734ms","remote":"127.0.0.1:40042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-14T01:32:06.896675Z","caller":"traceutil/trace.go:171","msg":"trace[427225832] linearizableReadLoop","detail":"{readStateIndex:1013; appliedIndex:1012; }","duration":"107.543514ms","start":"2026-03-14T01:32:06.789079Z","end":"2026-03-14T01:32:06.896622Z","steps":["trace[427225832] 'read index received' (duration: 107.276786ms)","trace[427225832] 'applied index is now lower than readState.Index' (duration: 265.868µs)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:32:06.896746Z","caller":"traceutil/trace.go:171","msg":"trace[1138619815] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"281.066968ms","start":"2026-03-14T01:32:06.615653Z","end":"2026-03-14T01:32:06.89672Z","steps":["trace[1138619815] 'process raft request' (duration: 280.77426ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:32:06.896787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.690607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T01:32:06.896825Z","caller":"traceutil/trace.go:171","msg":"trace[1628673802] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:971; }","duration":"107.770309ms","start":"2026-03-14T01:32:06.789044Z","end":"2026-03-14T01:32:06.896814Z","steps":["trace[1628673802] 'agreement among raft nodes before linearized reading' (duration: 107.698358ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:32:07.074838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.138632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-14T01:32:07.074918Z","caller":"traceutil/trace.go:171","msg":"trace[607486988] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:971; }","duration":"120.272865ms","start":"2026-03-14T01:32:06.954627Z","end":"2026-03-14T01:32:07.0749Z","steps":["trace[607486988] 'count revisions from in-memory index tree' (duration: 120.055751ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:32:08.951197Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14208184928108343406,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-14T01:32:09.17362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.49367967s","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-03-14T01:32:09.173693Z","caller":"traceutil/trace.go:171","msg":"trace[557089751] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:973; }","duration":"1.493790462s","start":"2026-03-14T01:32:07.679885Z","end":"2026-03-14T01:32:09.173676Z","steps":["trace[557089751] 'range keys from in-memory index tree' (duration: 1.493485465s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:32:09.173768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:32:07.67982Z","time spent":"1.493906236s","remote":"127.0.0.1:40042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-14T01:32:09.173789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.493095644s","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-03-14T01:32:09.173837Z","caller":"traceutil/trace.go:171","msg":"trace[468836536] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:973; }","duration":"1.493162177s","start":"2026-03-14T01:32:07.68066Z","end":"2026-03-14T01:32:09.173822Z","steps":["trace[468836536] 'range keys from in-memory index tree' (duration: 1.492979872s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:32:09.173869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:32:07.68065Z","time spent":"1.493211727s","remote":"127.0.0.1:40042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-14T01:32:09.173507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.494143031s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-14T01:32:09.174286Z","caller":"traceutil/trace.go:171","msg":"trace[245350040] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:973; }","duration":"1.494958314s","start":"2026-03-14T01:32:07.67929Z","end":"2026-03-14T01:32:09.174249Z","steps":["trace[245350040] 'range keys from in-memory index tree' (duration: 1.493885535s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:32:09.174325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:32:07.679264Z","time spent":"1.495049645s","remote":"127.0.0.1:40042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-14T01:32:09.219709Z","caller":"traceutil/trace.go:171","msg":"trace[588839541] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"1.100623733s","start":"2026-03-14T01:32:08.119034Z","end":"2026-03-14T01:32:09.219657Z","steps":["trace[588839541] 'process raft request' (duration: 1.100445299s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:32:09.219845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:32:08.119013Z","time spent":"1.100768946s","remote":"127.0.0.1:39828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":787,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-14T01:32:09.219859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"769.730537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T01:32:09.219696Z","caller":"traceutil/trace.go:171","msg":"trace[544339267] linearizableReadLoop","detail":"{readStateIndex:1016; appliedIndex:1015; }","duration":"769.526531ms","start":"2026-03-14T01:32:08.450116Z","end":"2026-03-14T01:32:09.219642Z","steps":["trace[544339267] 'read index received' (duration: 768.940656ms)","trace[544339267] 'applied index is now lower than readState.Index' (duration: 584.715µs)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:32:09.219919Z","caller":"traceutil/trace.go:171","msg":"trace[1596010196] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:974; }","duration":"769.840279ms","start":"2026-03-14T01:32:08.450055Z","end":"2026-03-14T01:32:09.219895Z","steps":["trace[1596010196] 'agreement among raft nodes before linearized reading' (duration: 769.704276ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:32:09.219958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:32:08.450035Z","time spent":"769.913651ms","remote":"127.0.0.1:40042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-03-14T01:32:09.248931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.17923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-14T01:32:09.249029Z","caller":"traceutil/trace.go:171","msg":"trace[1306539282] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:977; }","duration":"149.346164ms","start":"2026-03-14T01:32:09.099668Z","end":"2026-03-14T01:32:09.249014Z","steps":["trace[1306539282] 'agreement among raft nodes before linearized reading' (duration: 149.155829ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:32:09.24899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.259697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T01:32:09.249205Z","caller":"traceutil/trace.go:171","msg":"trace[158769507] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:977; }","duration":"463.511063ms","start":"2026-03-14T01:32:08.785665Z","end":"2026-03-14T01:32:09.249176Z","steps":["trace[158769507] 'agreement among raft nodes before linearized reading' (duration: 463.259647ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:32:09.249311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:32:08.785646Z","time spent":"463.611206ms","remote":"127.0.0.1:39740","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-03-14T01:32:09.249024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.229384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-14T01:32:09.249396Z","caller":"traceutil/trace.go:171","msg":"trace[746908854] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:977; }","duration":"339.641705ms","start":"2026-03-14T01:32:08.909736Z","end":"2026-03-14T01:32:09.249377Z","steps":["trace[746908854] 'agreement among raft nodes before linearized reading' (duration: 339.168133ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:32:09.249448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:32:08.909713Z","time spent":"339.722667ms","remote":"127.0.0.1:40042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-14T01:32:15.53308Z","caller":"traceutil/trace.go:171","msg":"trace[88388288] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"257.546044ms","start":"2026-03-14T01:32:15.2755Z","end":"2026-03-14T01:32:15.533046Z","steps":["trace[88388288] 'process raft request' (duration: 257.349189ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:32:15.577806Z","caller":"traceutil/trace.go:171","msg":"trace[1087652308] linearizableReadLoop","detail":"{readStateIndex:1076; appliedIndex:1073; }","duration":"227.968464ms","start":"2026-03-14T01:32:15.349809Z","end":"2026-03-14T01:32:15.577778Z","steps":["trace[1087652308] 'read index received' (duration: 183.066773ms)","trace[1087652308] 'applied index is now lower than readState.Index' (duration: 44.900821ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:32:15.577987Z","caller":"traceutil/trace.go:171","msg":"trace[1214670293] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"300.256777ms","start":"2026-03-14T01:32:15.277715Z","end":"2026-03-14T01:32:15.577972Z","steps":["trace[1214670293] 'process raft request' (duration: 299.840257ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:32:15.578113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:32:15.277651Z","time spent":"300.370471ms","remote":"127.0.0.1:40042","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-03-14T01:32:15.578198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.379634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-14T01:32:15.578266Z","caller":"traceutil/trace.go:171","msg":"trace[265654697] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1033; }","duration":"228.454826ms","start":"2026-03-14T01:32:15.349794Z","end":"2026-03-14T01:32:15.578248Z","steps":["trace[265654697] 'agreement among raft nodes before linearized reading' (duration: 228.289701ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:32:15.578443Z","caller":"traceutil/trace.go:171","msg":"trace[998217724] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"300.467223ms","start":"2026-03-14T01:32:15.277964Z","end":"2026-03-14T01:32:15.578431Z","steps":["trace[998217724] 'process raft request' (duration: 299.760354ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:32:15.578464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.036888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-03-14T01:32:15.578499Z","caller":"traceutil/trace.go:171","msg":"trace[1855721227] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1033; }","duration":"133.106931ms","start":"2026-03-14T01:32:15.445382Z","end":"2026-03-14T01:32:15.578489Z","steps":["trace[1855721227] 'agreement among raft nodes before linearized reading' (duration: 132.980918ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:32:15.578526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:32:15.277819Z","time spent":"300.646938ms","remote":"127.0.0.1:40042","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-03-14T01:32:16.720185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.084358369s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-14T01:32:16.720536Z","caller":"traceutil/trace.go:171","msg":"trace[132755718] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"1.137570187s","start":"2026-03-14T01:32:15.582926Z","end":"2026-03-14T01:32:16.720496Z","steps":["trace[132755718] 'process raft request' (duration: 52.408857ms)","trace[132755718] 'compare' (duration: 1.084240116s)"],"step_count":2} {"level":"warn","ts":"2026-03-14T01:32:16.720687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:32:15.5829Z","time spent":"1.13770668s","remote":"127.0.0.1:40042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T01:32:16.720825Z","caller":"traceutil/trace.go:171","msg":"trace[1224230910] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"1.136609532s","start":"2026-03-14T01:32:15.58419Z","end":"2026-03-14T01:32:16.7208Z","steps":["trace[1224230910] 'process raft request' (duration: 1.136300724s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:32:16.720963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:32:15.584168Z","time spent":"1.136723154s","remote":"127.0.0.1:40042","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-03-14T01:32:16.721009Z","caller":"traceutil/trace.go:171","msg":"trace[1608118571] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"1.136469688s","start":"2026-03-14T01:32:15.584528Z","end":"2026-03-14T01:32:16.720997Z","steps":["trace[1608118571] 'process raft request' (duration: 1.136031057s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:32:16.721069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:32:15.584518Z","time spent":"1.136518619s","remote":"127.0.0.1:40042","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-03-14T01:32:16.720982Z","caller":"traceutil/trace.go:171","msg":"trace[709493173] linearizableReadLoop","detail":"{readStateIndex:1079; appliedIndex:1076; }","duration":"1.066804642s","start":"2026-03-14T01:32:15.65407Z","end":"2026-03-14T01:32:16.720875Z","steps":["trace[709493173] 'read index received' (duration: 33.324558ms)","trace[709493173] 'applied index is now lower than readState.Index' (duration: 1.033477904s)"],"step_count":2} {"level":"warn","ts":"2026-03-14T01:32:16.721387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.067309984s","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-03-14T01:32:16.721438Z","caller":"traceutil/trace.go:171","msg":"trace[924659525] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1036; }","duration":"1.067417028s","start":"2026-03-14T01:32:15.654007Z","end":"2026-03-14T01:32:16.721424Z","steps":["trace[924659525] 'agreement among raft nodes before linearized reading' (duration: 1.067249153s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:32:16.721475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:32:15.653985Z","time spent":"1.067482989s","remote":"127.0.0.1:40042","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-03-14T01:32:16.721649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.03030221s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.250\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-14T01:32:16.721752Z","caller":"traceutil/trace.go:171","msg":"trace[1377790608] range","detail":"{range_begin:/registry/masterleases/199.204.45.250; range_end:; response_count:1; response_revision:1036; }","duration":"1.030438044s","start":"2026-03-14T01:32:15.6913Z","end":"2026-03-14T01:32:16.721738Z","steps":["trace[1377790608] 'agreement among raft nodes before linearized reading' (duration: 1.030050373s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:32:16.721881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:32:15.691281Z","time spent":"1.030586147s","remote":"127.0.0.1:39786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.204.45.250\" "} {"level":"warn","ts":"2026-03-14T01:32:17.289865Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14208184928108343516,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-14T01:32:17.790873Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14208184928108343516,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-14T01:32:17.911789Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.18837232s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-14T01:32:17.978333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"867.389422ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T01:32:17.979789Z","caller":"traceutil/trace.go:171","msg":"trace[1665034484] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1036; }","duration":"868.85539ms","start":"2026-03-14T01:32:17.110913Z","end":"2026-03-14T01:32:17.979769Z","steps":["trace[1665034484] 'range keys from in-memory index tree' (duration: 867.373622ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:32:17.979104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:32:16.723315Z","time spent":"1.255783248s","remote":"127.0.0.1:39786","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-03-14T01:32:17.986387Z","caller":"traceutil/trace.go:171","msg":"trace[1761785229] linearizableReadLoop","detail":"{readStateIndex:1081; appliedIndex:1079; }","duration":"1.196973633s","start":"2026-03-14T01:32:16.789346Z","end":"2026-03-14T01:32:17.98632Z","steps":["trace[1761785229] 'read index received' (duration: 1.122636066s)","trace[1761785229] 'applied index is now lower than readState.Index' (duration: 74.336357ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:32:17.986514Z","caller":"traceutil/trace.go:171","msg":"trace[653972974] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"1.260738547s","start":"2026-03-14T01:32:16.725764Z","end":"2026-03-14T01:32:17.986503Z","steps":["trace[653972974] 'process raft request' (duration: 1.260330087s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:32:17.986626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:32:16.725743Z","time spent":"1.260798638s","remote":"127.0.0.1:40042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T01:32:17.986648Z","caller":"traceutil/trace.go:171","msg":"trace[589344131] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"102.831601ms","start":"2026-03-14T01:32:17.883779Z","end":"2026-03-14T01:32:17.986611Z","steps":["trace[589344131] 'process raft request' (duration: 102.494543ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:32:17.986889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.197541109s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T01:32:17.986922Z","caller":"traceutil/trace.go:171","msg":"trace[510579977] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1038; }","duration":"1.197611381s","start":"2026-03-14T01:32:16.789302Z","end":"2026-03-14T01:32:17.986913Z","steps":["trace[510579977] 'agreement among raft nodes before linearized reading' (duration: 1.197529069s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:32:17.986952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:32:16.789282Z","time spent":"1.197662743s","remote":"127.0.0.1:39740","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-03-14T01:32:17.995833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.346786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-14T01:32:17.995878Z","caller":"traceutil/trace.go:171","msg":"trace[2119510237] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1039; }","duration":"413.438218ms","start":"2026-03-14T01:32:17.58243Z","end":"2026-03-14T01:32:17.995868Z","steps":["trace[2119510237] 'agreement among raft nodes before linearized reading' (duration: 413.301735ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:32:17.995901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:32:17.58241Z","time spent":"413.485859ms","remote":"127.0.0.1:40042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-14T01:32:17.996018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.075750515s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"warn","ts":"2026-03-14T01:32:17.99605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.312375ms","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-03-14T01:32:17.99611Z","caller":"traceutil/trace.go:171","msg":"trace[442339693] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1039; }","duration":"1.075919369s","start":"2026-03-14T01:32:16.920162Z","end":"2026-03-14T01:32:17.996081Z","steps":["trace[442339693] 'agreement among raft nodes before linearized reading' (duration: 1.075603571s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:32:17.996165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:32:16.920143Z","time spent":"1.076012721s","remote":"127.0.0.1:40042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":559,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-03-14T01:32:17.996154Z","caller":"traceutil/trace.go:171","msg":"trace[100947362] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1039; }","duration":"413.459908ms","start":"2026-03-14T01:32:17.582675Z","end":"2026-03-14T01:32:17.996135Z","steps":["trace[100947362] 'agreement among raft nodes before linearized reading' (duration: 413.038057ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:32:17.996295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:32:17.582664Z","time spent":"413.584451ms","remote":"127.0.0.1:40042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-14T01:32:17.996301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.796311ms","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-03-14T01:32:17.996338Z","caller":"traceutil/trace.go:171","msg":"trace[1350184877] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1039; }","duration":"458.904984ms","start":"2026-03-14T01:32:17.537424Z","end":"2026-03-14T01:32:17.996329Z","steps":["trace[1350184877] 'agreement among raft nodes before linearized reading' (duration: 458.666928ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:32:17.996362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:32:17.537398Z","time spent":"458.958894ms","remote":"127.0.0.1:40042","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-03-14T01:32:20.146535Z","caller":"traceutil/trace.go:171","msg":"trace[1400706436] linearizableReadLoop","detail":"{readStateIndex:1093; appliedIndex:1092; }","duration":"132.980967ms","start":"2026-03-14T01:32:20.013532Z","end":"2026-03-14T01:32:20.146513Z","steps":["trace[1400706436] 'read index received' (duration: 132.839802ms)","trace[1400706436] 'applied index is now lower than readState.Index' (duration: 140.475µs)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:32:20.146625Z","caller":"traceutil/trace.go:171","msg":"trace[171186051] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"134.188028ms","start":"2026-03-14T01:32:20.01239Z","end":"2026-03-14T01:32:20.146578Z","steps":["trace[171186051] 'process raft request' (duration: 133.981162ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:32:20.146722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.424416ms","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-03-14T01:32:20.146749Z","caller":"traceutil/trace.go:171","msg":"trace[1663698360] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1049; }","duration":"131.530389ms","start":"2026-03-14T01:32:20.01521Z","end":"2026-03-14T01:32:20.146741Z","steps":["trace[1663698360] 'agreement among raft nodes before linearized reading' (duration: 131.402296ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:32:20.146846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.289054ms","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-03-14T01:32:20.147007Z","caller":"traceutil/trace.go:171","msg":"trace[1959914310] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1049; }","duration":"133.48825ms","start":"2026-03-14T01:32:20.0135Z","end":"2026-03-14T01:32:20.146988Z","steps":["trace[1959914310] 'agreement among raft nodes before linearized reading' (duration: 133.090489ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:33:17.136105Z","caller":"traceutil/trace.go:171","msg":"trace[1634720052] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"113.931003ms","start":"2026-03-14T01:33:17.022147Z","end":"2026-03-14T01:33:17.136078Z","steps":["trace[1634720052] 'process raft request' (duration: 42.732105ms)","trace[1634720052] 'compare' (duration: 71.038383ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:33:17.136278Z","caller":"traceutil/trace.go:171","msg":"trace[1320177387] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"114.030105ms","start":"2026-03-14T01:33:17.022219Z","end":"2026-03-14T01:33:17.13625Z","steps":["trace[1320177387] 'process raft request' (duration: 113.806899ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:33:20.751658Z","caller":"traceutil/trace.go:171","msg":"trace[1302977537] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"157.016718ms","start":"2026-03-14T01:33:20.594609Z","end":"2026-03-14T01:33:20.751626Z","steps":["trace[1302977537] 'process raft request' (duration: 107.721901ms)","trace[1302977537] 'compare' (duration: 49.171474ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:33:22.718446Z","caller":"traceutil/trace.go:171","msg":"trace[1445487955] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"102.30856ms","start":"2026-03-14T01:33:22.616102Z","end":"2026-03-14T01:33:22.71841Z","steps":["trace[1445487955] 'process raft request' (duration: 44.099621ms)","trace[1445487955] 'compare' (duration: 57.891591ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:34:48.622897Z","caller":"traceutil/trace.go:171","msg":"trace[182971988] linearizableReadLoop","detail":"{readStateIndex:1728; appliedIndex:1727; }","duration":"172.201552ms","start":"2026-03-14T01:34:48.450671Z","end":"2026-03-14T01:34:48.622873Z","steps":["trace[182971988] 'read index received' (duration: 171.973456ms)","trace[182971988] 'applied index is now lower than readState.Index' (duration: 227.186µs)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:34:48.622966Z","caller":"traceutil/trace.go:171","msg":"trace[358938403] transaction","detail":"{read_only:false; response_revision:1654; number_of_response:1; }","duration":"237.767167ms","start":"2026-03-14T01:34:48.385168Z","end":"2026-03-14T01:34:48.622935Z","steps":["trace[358938403] 'process raft request' (duration: 237.546241ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:34:48.623094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.409928ms","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-03-14T01:34:48.623745Z","caller":"traceutil/trace.go:171","msg":"trace[179173435] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1654; }","duration":"173.060895ms","start":"2026-03-14T01:34:48.450654Z","end":"2026-03-14T01:34:48.623715Z","steps":["trace[179173435] 'agreement among raft nodes before linearized reading' (duration: 172.316176ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T01:34:48.948654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.324005ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-14T01:34:48.948778Z","caller":"traceutil/trace.go:171","msg":"trace[761915555] linearizableReadLoop","detail":"{readStateIndex:1730; appliedIndex:1729; }","duration":"159.618143ms","start":"2026-03-14T01:34:48.789138Z","end":"2026-03-14T01:34:48.948756Z","steps":["trace[761915555] 'read index received' (duration: 37.061µs)","trace[761915555] 'applied index is now lower than readState.Index' (duration: 159.579382ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T01:34:48.948901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.763086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T01:34:48.948949Z","caller":"traceutil/trace.go:171","msg":"trace[1126746831] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1656; }","duration":"159.842789ms","start":"2026-03-14T01:34:48.789091Z","end":"2026-03-14T01:34:48.948934Z","steps":["trace[1126746831] 'agreement among raft nodes before linearized reading' (duration: 159.719465ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:34:48.948939Z","caller":"traceutil/trace.go:171","msg":"trace[570368882] transaction","detail":"{read_only:false; response_revision:1656; number_of_response:1; }","duration":"256.947877ms","start":"2026-03-14T01:34:48.691945Z","end":"2026-03-14T01:34:48.948892Z","steps":["trace[570368882] 'process raft request' (duration: 84.273823ms)","trace[570368882] 'compare' (duration: 172.185262ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T01:35:15.020603Z","caller":"traceutil/trace.go:171","msg":"trace[189701188] transaction","detail":"{read_only:false; response_revision:1784; number_of_response:1; }","duration":"111.679349ms","start":"2026-03-14T01:35:14.908901Z","end":"2026-03-14T01:35:15.02058Z","steps":["trace[189701188] 'process raft request' (duration: 111.529096ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T01:35:18.329492Z","caller":"traceutil/trace.go:171","msg":"trace[389944876] transaction","detail":"{read_only:false; response_revision:1801; number_of_response:1; }","duration":"461.963638ms","start":"2026-03-14T01:35:17.867483Z","end":"2026-03-14T01:35:18.329447Z","steps":["trace[389944876] 'process raft request' (duration: 366.715567ms)","trace[389944876] 'compare' (duration: 94.998945ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T01:35:18.33006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T01:35:17.867475Z","time spent":"462.399999ms","remote":"127.0.0.1:40042","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-03-14T01:35:27.680156Z","caller":"traceutil/trace.go:171","msg":"trace[890279619] transaction","detail":"{read_only:false; response_revision:1843; number_of_response:1; }","duration":"119.72971ms","start":"2026-03-14T01:35:27.560397Z","end":"2026-03-14T01:35:27.680127Z","steps":["trace[890279619] 'process raft request' (duration: 119.34552ms)"],"step_count":1}