{"level":"warn","ts":"2026-04-24T15:09:51.699952Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-24T15:09:51.700283Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.246: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.246:2380","--initial-cluster=instance=https://199.204.45.246:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.246:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.246: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-04-24T15:09:51.700417Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-24T15:09:51.700428Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.246:2380"]} {"level":"info","ts":"2026-04-24T15:09:51.700461Z","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-04-24T15:09:51.70151Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"]} {"level":"info","ts":"2026-04-24T15:09:51.7016Z","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.246:2380"],"listen-peer-urls":["https://199.204.45.246:2380"],"advertise-client-urls":["https://199.204.45.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.246: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-04-24T15:09:51.709943Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.472606ms"} {"level":"info","ts":"2026-04-24T15:09:51.723789Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ab97322ebdd79664","cluster-id":"87bc89380c3dc2c"} {"level":"info","ts":"2026-04-24T15:09:51.723988Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=()"} {"level":"info","ts":"2026-04-24T15:09:51.724048Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became follower at term 0"} {"level":"info","ts":"2026-04-24T15:09:51.724098Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ab97322ebdd79664 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-24T15:09:51.724118Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became follower at term 1"} {"level":"info","ts":"2026-04-24T15:09:51.724269Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=(12364406478303893092)"} {"level":"warn","ts":"2026-04-24T15:09:51.733185Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-24T15:09:51.734694Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-24T15:09:51.73618Z","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-04-24T15:09:51.738986Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ab97322ebdd79664","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-24T15:09:51.742028Z","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-04-24T15:09:51.742143Z","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-04-24T15:09:51.742004Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ab97322ebdd79664","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-24T15:09:51.742156Z","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-04-24T15:09:51.74459Z","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-04-24T15:09:51.744829Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ab97322ebdd79664","initial-advertise-peer-urls":["https://199.204.45.246:2380"],"listen-peer-urls":["https://199.204.45.246:2380"],"advertise-client-urls":["https://199.204.45.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-24T15:09:51.74486Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-24T15:09:51.744944Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=(12364406478303893092)"} {"level":"info","ts":"2026-04-24T15:09:51.745058Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"87bc89380c3dc2c","local-member-id":"ab97322ebdd79664","added-peer-id":"ab97322ebdd79664","added-peer-peer-urls":["https://199.204.45.246:2380"]} {"level":"info","ts":"2026-04-24T15:09:51.74516Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.246:2380"} {"level":"info","ts":"2026-04-24T15:09:51.745173Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.246:2380"} {"level":"info","ts":"2026-04-24T15:09:51.825638Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 is starting a new election at term 1"} {"level":"info","ts":"2026-04-24T15:09:51.825734Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-24T15:09:51.82578Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 received MsgPreVoteResp from ab97322ebdd79664 at term 1"} {"level":"info","ts":"2026-04-24T15:09:51.825799Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became candidate at term 2"} {"level":"info","ts":"2026-04-24T15:09:51.825807Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 received MsgVoteResp from ab97322ebdd79664 at term 2"} {"level":"info","ts":"2026-04-24T15:09:51.825819Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became leader at term 2"} {"level":"info","ts":"2026-04-24T15:09:51.825829Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ab97322ebdd79664 elected leader ab97322ebdd79664 at term 2"} {"level":"info","ts":"2026-04-24T15:09:51.833896Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-24T15:09:51.835064Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ab97322ebdd79664","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.246:2379]}","request-path":"/0/members/ab97322ebdd79664/attributes","cluster-id":"87bc89380c3dc2c","publish-timeout":"7s"} {"level":"info","ts":"2026-04-24T15:09:51.835074Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-24T15:09:51.835148Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-24T15:09:51.836629Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-24T15:09:51.83668Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-24T15:09:51.837188Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"87bc89380c3dc2c","local-member-id":"ab97322ebdd79664","cluster-version":"3.5"} {"level":"info","ts":"2026-04-24T15:09:51.837269Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-24T15:09:51.837296Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-24T15:09:51.840013Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-24T15:09:51.840637Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.246:2379"} {"level":"warn","ts":"2026-04-24T15:09:55.114793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.662005ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-24T15:09:55.114929Z","caller":"traceutil/trace.go:171","msg":"trace[820641800] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:75; }","duration":"170.418615ms","start":"2026-04-24T15:09:54.944493Z","end":"2026-04-24T15:09:55.114911Z","steps":["trace[820641800] 'read index received' (duration: 13.871834ms)","trace[820641800] 'applied index is now lower than readState.Index' (duration: 156.545091ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T15:09:55.115181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.684766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-24T15:09:55.115217Z","caller":"traceutil/trace.go:171","msg":"trace[728498297] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:0; response_revision:72; }","duration":"170.770368ms","start":"2026-04-24T15:09:54.944435Z","end":"2026-04-24T15:09:55.115206Z","steps":["trace[728498297] 'agreement among raft nodes before linearized reading' (duration: 170.610257ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:09:55.115225Z","caller":"traceutil/trace.go:171","msg":"trace[530548832] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"171.031919ms","start":"2026-04-24T15:09:54.944027Z","end":"2026-04-24T15:09:55.115059Z","steps":["trace[530548832] 'process raft request' (duration: 14.414587ms)","trace[530548832] 'compare' (duration: 155.492754ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T15:09:55.396408Z","caller":"traceutil/trace.go:171","msg":"trace[1253155394] transaction","detail":"{read_only:false; response_revision:82; number_of_response:1; }","duration":"112.823794ms","start":"2026-04-24T15:09:55.283559Z","end":"2026-04-24T15:09:55.396383Z","steps":["trace[1253155394] 'process raft request' (duration: 71.404433ms)","trace[1253155394] 'compare' (duration: 41.303671ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T15:09:55.567709Z","caller":"traceutil/trace.go:171","msg":"trace[190115628] transaction","detail":"{read_only:false; response_revision:84; number_of_response:1; }","duration":"132.247393ms","start":"2026-04-24T15:09:55.435419Z","end":"2026-04-24T15:09:55.567666Z","steps":["trace[190115628] 'process raft request' (duration: 121.614348ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:09:55.774213Z","caller":"traceutil/trace.go:171","msg":"trace[310179500] transaction","detail":"{read_only:false; response_revision:88; number_of_response:1; }","duration":"104.708135ms","start":"2026-04-24T15:09:55.669457Z","end":"2026-04-24T15:09:55.774166Z","steps":["trace[310179500] 'process raft request' (duration: 101.271455ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:09:56.32306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.426478ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-24T15:09:56.323218Z","caller":"traceutil/trace.go:171","msg":"trace[1120942749] transaction","detail":"{read_only:false; response_revision:101; number_of_response:1; }","duration":"182.702859ms","start":"2026-04-24T15:09:56.140491Z","end":"2026-04-24T15:09:56.323193Z","steps":["trace[1120942749] 'process raft request' (duration: 28.0344ms)","trace[1120942749] 'compare' (duration: 154.239067ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T15:10:22.399905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.354032ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-24T15:10:22.400082Z","caller":"traceutil/trace.go:171","msg":"trace[19820711] linearizableReadLoop","detail":"{readStateIndex:476; appliedIndex:475; }","duration":"121.356311ms","start":"2026-04-24T15:10:22.278702Z","end":"2026-04-24T15:10:22.400058Z","steps":["trace[19820711] 'read index received' (duration: 7.783155ms)","trace[19820711] 'applied index is now lower than readState.Index' (duration: 113.571445ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T15:10:22.400411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.693669ms","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-04-24T15:10:22.40044Z","caller":"traceutil/trace.go:171","msg":"trace[36475486] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:463; }","duration":"121.774498ms","start":"2026-04-24T15:10:22.278657Z","end":"2026-04-24T15:10:22.400431Z","steps":["trace[36475486] 'agreement among raft nodes before linearized reading' (duration: 121.489465ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:10:22.535389Z","caller":"traceutil/trace.go:171","msg":"trace[922798769] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"151.015099ms","start":"2026-04-24T15:10:22.384308Z","end":"2026-04-24T15:10:22.535323Z","steps":["trace[922798769] 'process raft request' (duration: 148.834346ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:10:22.535964Z","caller":"traceutil/trace.go:171","msg":"trace[1155393250] linearizableReadLoop","detail":"{readStateIndex:477; appliedIndex:476; }","duration":"135.837241ms","start":"2026-04-24T15:10:22.400112Z","end":"2026-04-24T15:10:22.53595Z","steps":["trace[1155393250] 'read index received' (duration: 131.175553ms)","trace[1155393250] 'applied index is now lower than readState.Index' (duration: 4.659798ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T15:10:22.536185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.778399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T15:10:22.536223Z","caller":"traceutil/trace.go:171","msg":"trace[1199807198] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:465; }","duration":"174.888902ms","start":"2026-04-24T15:10:22.361323Z","end":"2026-04-24T15:10:22.536212Z","steps":["trace[1199807198] 'agreement among raft nodes before linearized reading' (duration: 174.678939ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:10:22.536419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.335937ms","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-04-24T15:10:22.536455Z","caller":"traceutil/trace.go:171","msg":"trace[18593605] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:465; }","duration":"145.388633ms","start":"2026-04-24T15:10:22.391057Z","end":"2026-04-24T15:10:22.536446Z","steps":["trace[18593605] 'agreement among raft nodes before linearized reading' (duration: 145.302424ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:10:22.536425Z","caller":"traceutil/trace.go:171","msg":"trace[218503848] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"131.937327ms","start":"2026-04-24T15:10:22.404453Z","end":"2026-04-24T15:10:22.53639Z","steps":["trace[218503848] 'process raft request' (duration: 130.776508ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:10:23.385523Z","caller":"traceutil/trace.go:171","msg":"trace[1215537860] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"417.437897ms","start":"2026-04-24T15:10:22.968051Z","end":"2026-04-24T15:10:23.385489Z","steps":["trace[1215537860] 'process raft request' (duration: 417.280949ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:10:23.385738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:10:22.968003Z","time spent":"417.6449ms","remote":"127.0.0.1:58826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-24T15:10:23.397422Z","caller":"traceutil/trace.go:171","msg":"trace[677825133] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"418.890828ms","start":"2026-04-24T15:10:22.978495Z","end":"2026-04-24T15:10:23.397386Z","steps":["trace[677825133] 'process raft request' (duration: 418.251817ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:10:23.39753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:10:22.97849Z","time spent":"418.986219ms","remote":"127.0.0.1:58826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-24T15:10:23.39771Z","caller":"traceutil/trace.go:171","msg":"trace[1928125863] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"410.036094ms","start":"2026-04-24T15:10:22.98766Z","end":"2026-04-24T15:10:23.397696Z","steps":["trace[1928125863] 'process raft request' (duration: 409.129393ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:10:23.39777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:10:22.987651Z","time spent":"410.093181ms","remote":"127.0.0.1:58826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-24T15:10:23.39778Z","caller":"traceutil/trace.go:171","msg":"trace[1994437425] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"426.435938ms","start":"2026-04-24T15:10:22.971334Z","end":"2026-04-24T15:10:23.39777Z","steps":["trace[1994437425] 'process raft request' (duration: 425.235594ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:10:23.397831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:10:22.971308Z","time spent":"426.498515ms","remote":"127.0.0.1:58826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-24T15:10:23.397911Z","caller":"traceutil/trace.go:171","msg":"trace[1662260359] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"409.938774ms","start":"2026-04-24T15:10:22.98796Z","end":"2026-04-24T15:10:23.397899Z","steps":["trace[1662260359] 'process raft request' (duration: 408.912049ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:10:23.397955Z","caller":"traceutil/trace.go:171","msg":"trace[579133568] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"422.946069ms","start":"2026-04-24T15:10:22.974998Z","end":"2026-04-24T15:10:23.397945Z","steps":["trace[579133568] 'process raft request' (duration: 421.651854ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:10:23.397995Z","caller":"traceutil/trace.go:171","msg":"trace[1572897196] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"409.825692ms","start":"2026-04-24T15:10:22.988162Z","end":"2026-04-24T15:10:23.397987Z","steps":["trace[1572897196] 'process raft request' (duration: 408.755182ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:10:23.398011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:10:22.974987Z","time spent":"422.998995ms","remote":"127.0.0.1:58826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-24T15:10:23.398041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:10:22.988129Z","time spent":"409.892818ms","remote":"127.0.0.1:58826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-24T15:10:23.398126Z","caller":"traceutil/trace.go:171","msg":"trace[233501172] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"392.659833ms","start":"2026-04-24T15:10:23.005454Z","end":"2026-04-24T15:10:23.398114Z","steps":["trace[233501172] 'process raft request' (duration: 391.530056ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:10:23.398174Z","caller":"traceutil/trace.go:171","msg":"trace[1123661883] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"420.131577ms","start":"2026-04-24T15:10:22.978031Z","end":"2026-04-24T15:10:23.398162Z","steps":["trace[1123661883] 'process raft request' (duration: 418.671284ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:10:23.398176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:10:23.005417Z","time spent":"392.738351ms","remote":"127.0.0.1:58826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-24T15:10:23.39821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:10:22.978007Z","time spent":"420.187993ms","remote":"127.0.0.1:58826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-24T15:10:23.397963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:10:22.987954Z","time spent":"409.985979ms","remote":"127.0.0.1:58826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-24T15:10:23.398407Z","caller":"traceutil/trace.go:171","msg":"trace[1818361072] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"391.727959ms","start":"2026-04-24T15:10:23.006671Z","end":"2026-04-24T15:10:23.398399Z","steps":["trace[1818361072] 'process raft request' (duration: 390.362807ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:10:23.398449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:10:23.006648Z","time spent":"391.785495ms","remote":"127.0.0.1:58826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-24T15:10:23.398507Z","caller":"traceutil/trace.go:171","msg":"trace[2037390934] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"380.435764ms","start":"2026-04-24T15:10:23.018065Z","end":"2026-04-24T15:10:23.3985Z","steps":["trace[2037390934] 'process raft request' (duration: 379.047559ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:10:23.398656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:10:23.018049Z","time spent":"380.472218ms","remote":"127.0.0.1:58826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-24T15:10:23.39874Z","caller":"traceutil/trace.go:171","msg":"trace[2080253208] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"223.660355ms","start":"2026-04-24T15:10:23.175072Z","end":"2026-04-24T15:10:23.398733Z","steps":["trace[2080253208] 'process raft request' (duration: 222.208673ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:10:24.244308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.522114ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-24T15:10:24.244437Z","caller":"traceutil/trace.go:171","msg":"trace[589278238] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"543.931129ms","start":"2026-04-24T15:10:23.700482Z","end":"2026-04-24T15:10:24.244413Z","steps":["trace[589278238] 'process raft request' (duration: 153.756753ms)","trace[589278238] 'compare' (duration: 388.854738ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T15:10:24.244511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:10:23.700463Z","time spent":"544.015688ms","remote":"127.0.0.1:59052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T15:10:24.248647Z","caller":"traceutil/trace.go:171","msg":"trace[243933473] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"522.531089ms","start":"2026-04-24T15:10:23.726088Z","end":"2026-04-24T15:10:24.248619Z","steps":["trace[243933473] 'process raft request' (duration: 522.433059ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:10:24.249058Z","caller":"traceutil/trace.go:171","msg":"trace[26918368] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"541.943077ms","start":"2026-04-24T15:10:23.707099Z","end":"2026-04-24T15:10:24.249042Z","steps":["trace[26918368] 'process raft request' (duration: 541.173532ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:10:24.249138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:10:23.707077Z","time spent":"542.019205ms","remote":"127.0.0.1:58826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65190,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T15:10:24.249405Z","caller":"traceutil/trace.go:171","msg":"trace[953587420] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"526.977764ms","start":"2026-04-24T15:10:23.722399Z","end":"2026-04-24T15:10:24.249377Z","steps":["trace[953587420] 'process raft request' (duration: 526.04709ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:10:24.24947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:10:23.722378Z","time spent":"527.060824ms","remote":"127.0.0.1:58826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64392,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-24T15:10:24.248871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:10:23.726079Z","time spent":"522.657943ms","remote":"127.0.0.1:58826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":59616,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T15:10:24.529248Z","caller":"traceutil/trace.go:171","msg":"trace[1184399833] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"193.397329ms","start":"2026-04-24T15:10:24.335801Z","end":"2026-04-24T15:10:24.529198Z","steps":["trace[1184399833] 'process raft request' (duration: 189.956887ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:10:24.534272Z","caller":"traceutil/trace.go:171","msg":"trace[126473813] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"185.855081ms","start":"2026-04-24T15:10:24.348394Z","end":"2026-04-24T15:10:24.534249Z","steps":["trace[126473813] 'process raft request' (duration: 185.008397ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:10:24.546198Z","caller":"traceutil/trace.go:171","msg":"trace[888342813] linearizableReadLoop","detail":"{readStateIndex:536; appliedIndex:534; }","duration":"182.992873ms","start":"2026-04-24T15:10:24.363068Z","end":"2026-04-24T15:10:24.546061Z","steps":["trace[888342813] 'read index received' (duration: 162.768895ms)","trace[888342813] 'applied index is now lower than readState.Index' (duration: 20.222548ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T15:10:24.546387Z","caller":"traceutil/trace.go:171","msg":"trace[1912074097] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"167.13743ms","start":"2026-04-24T15:10:24.379234Z","end":"2026-04-24T15:10:24.546372Z","steps":["trace[1912074097] 'process raft request' (duration: 154.704108ms)","trace[1912074097] 'compare' (duration: 10.133827ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T15:10:24.547225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.102637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T15:10:24.547301Z","caller":"traceutil/trace.go:171","msg":"trace[693008710] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:519; }","duration":"184.250214ms","start":"2026-04-24T15:10:24.363036Z","end":"2026-04-24T15:10:24.547286Z","steps":["trace[693008710] 'agreement among raft nodes before linearized reading' (duration: 183.917147ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:10:25.748248Z","caller":"traceutil/trace.go:171","msg":"trace[200183558] linearizableReadLoop","detail":"{readStateIndex:563; appliedIndex:562; }","duration":"209.361825ms","start":"2026-04-24T15:10:25.538863Z","end":"2026-04-24T15:10:25.748225Z","steps":["trace[200183558] 'read index received' (duration: 208.918715ms)","trace[200183558] 'applied index is now lower than readState.Index' (duration: 441.98µs)"],"step_count":2} {"level":"warn","ts":"2026-04-24T15:10:25.748445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.562987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/udproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:31126"} {"level":"info","ts":"2026-04-24T15:10:25.748472Z","caller":"traceutil/trace.go:171","msg":"trace[248163576] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/udproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:545; }","duration":"209.647327ms","start":"2026-04-24T15:10:25.538817Z","end":"2026-04-24T15:10:25.748464Z","steps":["trace[248163576] 'agreement among raft nodes before linearized reading' (duration: 209.49494ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:10:25.748734Z","caller":"traceutil/trace.go:171","msg":"trace[905836395] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"220.208351ms","start":"2026-04-24T15:10:25.528518Z","end":"2026-04-24T15:10:25.748727Z","steps":["trace[905836395] 'process raft request' (duration: 219.339584ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:10:25.757062Z","caller":"traceutil/trace.go:171","msg":"trace[694414092] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"186.436736ms","start":"2026-04-24T15:10:25.570603Z","end":"2026-04-24T15:10:25.757039Z","steps":["trace[694414092] 'process raft request' (duration: 186.241215ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:10:25.968294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.826472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/clienttrafficpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:65436"} {"level":"info","ts":"2026-04-24T15:10:25.968433Z","caller":"traceutil/trace.go:171","msg":"trace[1973068503] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/clienttrafficpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:547; }","duration":"111.995845ms","start":"2026-04-24T15:10:25.856408Z","end":"2026-04-24T15:10:25.968404Z","steps":["trace[1973068503] 'range keys from in-memory index tree' (duration: 111.562727ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:10:28.428787Z","caller":"traceutil/trace.go:171","msg":"trace[1207573357] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"128.247939ms","start":"2026-04-24T15:10:28.300508Z","end":"2026-04-24T15:10:28.428756Z","steps":["trace[1207573357] 'process raft request' (duration: 127.991403ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:10:28.506341Z","caller":"traceutil/trace.go:171","msg":"trace[1412577490] linearizableReadLoop","detail":"{readStateIndex:578; appliedIndex:576; }","duration":"145.443783ms","start":"2026-04-24T15:10:28.360873Z","end":"2026-04-24T15:10:28.506317Z","steps":["trace[1412577490] 'read index received' (duration: 66.478025ms)","trace[1412577490] 'applied index is now lower than readState.Index' (duration: 78.964428ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T15:10:28.506475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.594036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T15:10:28.5065Z","caller":"traceutil/trace.go:171","msg":"trace[1102841781] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:559; }","duration":"145.711308ms","start":"2026-04-24T15:10:28.360779Z","end":"2026-04-24T15:10:28.506491Z","steps":["trace[1102841781] 'agreement among raft nodes before linearized reading' (duration: 145.618876ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:10:28.506863Z","caller":"traceutil/trace.go:171","msg":"trace[972200092] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"149.376728ms","start":"2026-04-24T15:10:28.357448Z","end":"2026-04-24T15:10:28.506825Z","steps":["trace[972200092] 'process raft request' (duration: 144.287158ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:10:29.629236Z","caller":"traceutil/trace.go:171","msg":"trace[1291461416] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"105.887522ms","start":"2026-04-24T15:10:29.523225Z","end":"2026-04-24T15:10:29.629112Z","steps":["trace[1291461416] 'process raft request' (duration: 104.812979ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:10:29.633314Z","caller":"traceutil/trace.go:171","msg":"trace[954673094] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"103.480761ms","start":"2026-04-24T15:10:29.529814Z","end":"2026-04-24T15:10:29.633295Z","steps":["trace[954673094] 'process raft request' (duration: 102.949179ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:10:31.777103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.464797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T15:10:31.777209Z","caller":"traceutil/trace.go:171","msg":"trace[1806015317] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:581; }","duration":"416.63404ms","start":"2026-04-24T15:10:31.36055Z","end":"2026-04-24T15:10:31.777184Z","steps":["trace[1806015317] 'range keys from in-memory index tree' (duration: 416.385135ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:10:31.777277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:10:31.360532Z","time spent":"416.734142ms","remote":"127.0.0.1:58794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-24T15:10:31.778187Z","caller":"traceutil/trace.go:171","msg":"trace[2102893866] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"151.090496ms","start":"2026-04-24T15:10:31.627055Z","end":"2026-04-24T15:10:31.778145Z","steps":["trace[2102893866] 'process raft request' (duration: 131.780146ms)","trace[2102893866] 'compare' (duration: 18.555664ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T15:10:36.286754Z","caller":"traceutil/trace.go:171","msg":"trace[1500239222] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"127.673006ms","start":"2026-04-24T15:10:36.159055Z","end":"2026-04-24T15:10:36.286728Z","steps":["trace[1500239222] 'process raft request' (duration: 127.327039ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:10:47.385863Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836960051491828298,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-24T15:10:47.421051Z","caller":"traceutil/trace.go:171","msg":"trace[1608129919] linearizableReadLoop","detail":"{readStateIndex:763; appliedIndex:762; }","duration":"535.457354ms","start":"2026-04-24T15:10:46.885566Z","end":"2026-04-24T15:10:47.421024Z","steps":["trace[1608129919] 'read index received' (duration: 535.259519ms)","trace[1608129919] 'applied index is now lower than readState.Index' (duration: 196.435µs)"],"step_count":2} {"level":"info","ts":"2026-04-24T15:10:47.421211Z","caller":"traceutil/trace.go:171","msg":"trace[1029012875] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"889.008473ms","start":"2026-04-24T15:10:46.532164Z","end":"2026-04-24T15:10:47.421172Z","steps":["trace[1029012875] 'process raft request' (duration: 888.689686ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:10:47.421347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.706039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-24T15:10:47.421395Z","caller":"traceutil/trace.go:171","msg":"trace[1271171865] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:740; }","duration":"535.77484ms","start":"2026-04-24T15:10:46.885601Z","end":"2026-04-24T15:10:47.421376Z","steps":["trace[1271171865] 'agreement among raft nodes before linearized reading' (duration: 535.581566ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:10:47.421368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.76451ms","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-04-24T15:10:47.421392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:10:46.532149Z","time spent":"889.130375ms","remote":"127.0.0.1:59052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T15:10:47.421435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:10:46.885599Z","time spent":"535.828211ms","remote":"127.0.0.1:59052","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-04-24T15:10:47.421472Z","caller":"traceutil/trace.go:171","msg":"trace[1655666944] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:740; }","duration":"535.916072ms","start":"2026-04-24T15:10:46.885526Z","end":"2026-04-24T15:10:47.421442Z","steps":["trace[1655666944] 'agreement among raft nodes before linearized reading' (duration: 535.632516ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:10:47.421499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.600839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.246\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-04-24T15:10:47.421534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:10:46.885513Z","time spent":"536.011665ms","remote":"127.0.0.1:59052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-24T15:10:47.42158Z","caller":"traceutil/trace.go:171","msg":"trace[1286998693] range","detail":"{range_begin:/registry/masterleases/199.204.45.246; range_end:; response_count:1; response_revision:740; }","duration":"273.707632ms","start":"2026-04-24T15:10:47.14785Z","end":"2026-04-24T15:10:47.421558Z","steps":["trace[1286998693] 'agreement among raft nodes before linearized reading' (duration: 273.491207ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:10:47.421614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.554299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-ljgrm\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-04-24T15:10:47.421649Z","caller":"traceutil/trace.go:171","msg":"trace[368041089] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-ljgrm; range_end:; response_count:1; response_revision:740; }","duration":"371.629421ms","start":"2026-04-24T15:10:47.050007Z","end":"2026-04-24T15:10:47.421636Z","steps":["trace[368041089] 'agreement among raft nodes before linearized reading' (duration: 371.540409ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:10:47.421676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:10:47.049985Z","time spent":"371.684852ms","remote":"127.0.0.1:58974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-ljgrm\" "} {"level":"warn","ts":"2026-04-24T15:10:47.421737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.109447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-24T15:10:47.421768Z","caller":"traceutil/trace.go:171","msg":"trace[85713115] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:740; }","duration":"536.142768ms","start":"2026-04-24T15:10:46.885615Z","end":"2026-04-24T15:10:47.421758Z","steps":["trace[85713115] 'agreement among raft nodes before linearized reading' (duration: 536.052236ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:10:47.421794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:10:46.885613Z","time spent":"536.174589ms","remote":"127.0.0.1:59052","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-04-24T15:11:19.72957Z","caller":"traceutil/trace.go:171","msg":"trace[91784361] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"124.357194ms","start":"2026-04-24T15:11:19.605185Z","end":"2026-04-24T15:11:19.729542Z","steps":["trace[91784361] 'process raft request' (duration: 124.301383ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:11:19.729716Z","caller":"traceutil/trace.go:171","msg":"trace[53657007] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"125.827136ms","start":"2026-04-24T15:11:19.603805Z","end":"2026-04-24T15:11:19.729632Z","steps":["trace[53657007] 'process raft request' (duration: 125.167602ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:11:33.861254Z","caller":"traceutil/trace.go:171","msg":"trace[307614313] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"169.133578ms","start":"2026-04-24T15:11:33.692098Z","end":"2026-04-24T15:11:33.861232Z","steps":["trace[307614313] 'process raft request' (duration: 168.986485ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:14:12.844288Z","caller":"traceutil/trace.go:171","msg":"trace[1878934184] transaction","detail":"{read_only:false; response_revision:1512; number_of_response:1; }","duration":"271.87701ms","start":"2026-04-24T15:14:12.572372Z","end":"2026-04-24T15:14:12.844249Z","steps":["trace[1878934184] 'process raft request' (duration: 271.692615ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:14:13.370534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.948458ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-24T15:14:13.370805Z","caller":"traceutil/trace.go:171","msg":"trace[946612361] transaction","detail":"{read_only:false; response_revision:1513; number_of_response:1; }","duration":"145.954963ms","start":"2026-04-24T15:14:13.224832Z","end":"2026-04-24T15:14:13.370787Z","steps":["trace[946612361] 'process raft request' (duration: 145.840101ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:14:13.370814Z","caller":"traceutil/trace.go:171","msg":"trace[1570441703] linearizableReadLoop","detail":"{readStateIndex:1579; appliedIndex:1577; }","duration":"693.056416ms","start":"2026-04-24T15:14:12.677727Z","end":"2026-04-24T15:14:13.370784Z","steps":["trace[1570441703] 'read index received' (duration: 166.481483ms)","trace[1570441703] 'applied index is now lower than readState.Index' (duration: 526.571353ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T15:14:13.371078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"693.331954ms","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-04-24T15:14:13.371107Z","caller":"traceutil/trace.go:171","msg":"trace[987619836] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1513; }","duration":"693.396385ms","start":"2026-04-24T15:14:12.677703Z","end":"2026-04-24T15:14:13.371099Z","steps":["trace[987619836] 'agreement among raft nodes before linearized reading' (duration: 693.216341ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:14:13.371134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:14:12.677692Z","time spent":"693.433896ms","remote":"127.0.0.1:59052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-24T15:14:13.371345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.048201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-24T15:14:13.371394Z","caller":"traceutil/trace.go:171","msg":"trace[1369252001] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1513; }","duration":"661.121842ms","start":"2026-04-24T15:14:12.710261Z","end":"2026-04-24T15:14:13.371383Z","steps":["trace[1369252001] 'agreement among raft nodes before linearized reading' (duration: 660.972759ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:14:13.371412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.398601ms","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-04-24T15:14:13.371437Z","caller":"traceutil/trace.go:171","msg":"trace[398399504] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1513; }","duration":"467.484983ms","start":"2026-04-24T15:14:12.903945Z","end":"2026-04-24T15:14:13.37143Z","steps":["trace[398399504] 'agreement among raft nodes before linearized reading' (duration: 467.419542ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:14:13.371434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:14:12.710252Z","time spent":"661.172714ms","remote":"127.0.0.1:59052","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-04-24T15:14:13.371466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:14:12.903925Z","time spent":"467.533544ms","remote":"127.0.0.1:59052","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-04-24T15:14:13.371641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.416533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-24T15:14:13.371679Z","caller":"traceutil/trace.go:171","msg":"trace[1427490832] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1513; }","duration":"637.488825ms","start":"2026-04-24T15:14:12.734181Z","end":"2026-04-24T15:14:13.37167Z","steps":["trace[1427490832] 'agreement among raft nodes before linearized reading' (duration: 637.397063ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:14:13.371713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:14:12.734158Z","time spent":"637.548425ms","remote":"127.0.0.1:59052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-24T15:14:37.347572Z","caller":"traceutil/trace.go:171","msg":"trace[2121315134] linearizableReadLoop","detail":"{readStateIndex:1662; appliedIndex:1661; }","duration":"188.087383ms","start":"2026-04-24T15:14:37.159456Z","end":"2026-04-24T15:14:37.347543Z","steps":["trace[2121315134] 'read index received' (duration: 187.887128ms)","trace[2121315134] 'applied index is now lower than readState.Index' (duration: 197.435µs)"],"step_count":2} {"level":"warn","ts":"2026-04-24T15:14:37.347827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.353289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.246\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-24T15:14:37.347881Z","caller":"traceutil/trace.go:171","msg":"trace[1036467991] range","detail":"{range_begin:/registry/masterleases/199.204.45.246; range_end:; response_count:1; response_revision:1591; }","duration":"188.474483ms","start":"2026-04-24T15:14:37.159393Z","end":"2026-04-24T15:14:37.347867Z","steps":["trace[1036467991] 'agreement among raft nodes before linearized reading' (duration: 188.265237ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:14:37.347954Z","caller":"traceutil/trace.go:171","msg":"trace[1930691592] transaction","detail":"{read_only:false; response_revision:1591; number_of_response:1; }","duration":"385.24082ms","start":"2026-04-24T15:14:36.962661Z","end":"2026-04-24T15:14:37.347902Z","steps":["trace[1930691592] 'process raft request' (duration: 384.744898ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:14:37.348309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:14:36.962606Z","time spent":"385.520626ms","remote":"127.0.0.1:59052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T15:14:37.699045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:14:37.349355Z","time spent":"349.684435ms","remote":"127.0.0.1:58818","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-24T15:14:37.76126Z","caller":"traceutil/trace.go:171","msg":"trace[1147003634] linearizableReadLoop","detail":"{readStateIndex:1664; appliedIndex:1662; }","duration":"191.893768ms","start":"2026-04-24T15:14:37.569336Z","end":"2026-04-24T15:14:37.761229Z","steps":["trace[1147003634] 'read index received' (duration: 98.714556ms)","trace[1147003634] 'applied index is now lower than readState.Index' (duration: 93.177842ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T15:14:37.761414Z","caller":"traceutil/trace.go:171","msg":"trace[1195739995] transaction","detail":"{read_only:false; response_revision:1592; number_of_response:1; }","duration":"199.040068ms","start":"2026-04-24T15:14:37.56234Z","end":"2026-04-24T15:14:37.76138Z","steps":["trace[1195739995] 'process raft request' (duration: 198.694091ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:14:37.761533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.196064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-24T15:14:37.761585Z","caller":"traceutil/trace.go:171","msg":"trace[519322221] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1592; }","duration":"192.299537ms","start":"2026-04-24T15:14:37.569272Z","end":"2026-04-24T15:14:37.761572Z","steps":["trace[519322221] 'agreement among raft nodes before linearized reading' (duration: 192.151523ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:14:38.034053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.082597ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-24T15:14:38.034563Z","caller":"traceutil/trace.go:171","msg":"trace[1098571685] transaction","detail":"{read_only:false; response_revision:1594; number_of_response:1; }","duration":"267.818435ms","start":"2026-04-24T15:14:37.766719Z","end":"2026-04-24T15:14:38.034537Z","steps":["trace[1098571685] 'process raft request' (duration: 267.721843ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:14:38.035009Z","caller":"traceutil/trace.go:171","msg":"trace[370587429] linearizableReadLoop","detail":"{readStateIndex:1665; appliedIndex:1664; }","duration":"273.602014ms","start":"2026-04-24T15:14:37.761389Z","end":"2026-04-24T15:14:38.034991Z","steps":["trace[370587429] 'read index received' (duration: 95.478963ms)","trace[370587429] 'applied index is now lower than readState.Index' (duration: 178.120341ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T15:14:38.035113Z","caller":"traceutil/trace.go:171","msg":"trace[1222489566] transaction","detail":"{read_only:false; response_revision:1593; number_of_response:1; }","duration":"334.833363ms","start":"2026-04-24T15:14:37.700266Z","end":"2026-04-24T15:14:38.035099Z","steps":["trace[1222489566] 'process raft request' (duration: 156.591119ms)","trace[1222489566] 'compare' (duration: 176.886343ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T15:14:38.035361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.464314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-24T15:14:38.035451Z","caller":"traceutil/trace.go:171","msg":"trace[89478370] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1594; }","duration":"432.598747ms","start":"2026-04-24T15:14:37.60283Z","end":"2026-04-24T15:14:38.035428Z","steps":["trace[89478370] 'agreement among raft nodes before linearized reading' (duration: 432.391342ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:14:38.035499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:14:37.602811Z","time spent":"432.679179ms","remote":"127.0.0.1:59052","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-04-24T15:14:38.035733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.880175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-24T15:14:38.03577Z","caller":"traceutil/trace.go:171","msg":"trace[230763297] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1594; }","duration":"403.933796ms","start":"2026-04-24T15:14:37.631826Z","end":"2026-04-24T15:14:38.03576Z","steps":["trace[230763297] 'agreement among raft nodes before linearized reading' (duration: 403.868435ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:14:38.035799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:14:37.631819Z","time spent":"403.972318ms","remote":"127.0.0.1:59166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":30,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-04-24T15:14:38.036008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.216026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-24T15:14:38.036044Z","caller":"traceutil/trace.go:171","msg":"trace[1332209817] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1594; }","duration":"427.270938ms","start":"2026-04-24T15:14:37.608763Z","end":"2026-04-24T15:14:38.036034Z","steps":["trace[1332209817] 'agreement among raft nodes before linearized reading' (duration: 427.181926ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:14:38.036074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:14:37.608754Z","time spent":"427.310659ms","remote":"127.0.0.1:59052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-24T15:14:38.036122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:14:37.700241Z","time spent":"334.904675ms","remote":"127.0.0.1:58818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T15:14:38.036497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.967885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T15:14:38.036541Z","caller":"traceutil/trace.go:171","msg":"trace[1661014581] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1594; }","duration":"224.044007ms","start":"2026-04-24T15:14:37.812488Z","end":"2026-04-24T15:14:38.036532Z","steps":["trace[1661014581] 'agreement among raft nodes before linearized reading' (duration: 223.979616ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:14:38.036737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.439667ms","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-04-24T15:14:38.036774Z","caller":"traceutil/trace.go:171","msg":"trace[493631355] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1594; }","duration":"438.503889ms","start":"2026-04-24T15:14:37.598261Z","end":"2026-04-24T15:14:38.036765Z","steps":["trace[493631355] 'agreement among raft nodes before linearized reading' (duration: 438.410117ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:14:38.036801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:14:37.598244Z","time spent":"438.55002ms","remote":"127.0.0.1:59052","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-04-24T15:14:38.540473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.959455ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-24T15:14:38.540608Z","caller":"traceutil/trace.go:171","msg":"trace[1616876740] transaction","detail":"{read_only:false; response_revision:1595; number_of_response:1; }","duration":"498.416408ms","start":"2026-04-24T15:14:38.042171Z","end":"2026-04-24T15:14:38.540587Z","steps":["trace[1616876740] 'process raft request' (duration: 342.246899ms)","trace[1616876740] 'compare' (duration: 155.828272ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T15:14:38.540679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:14:38.042159Z","time spent":"498.48995ms","remote":"127.0.0.1:59052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T15:14:38.548294Z","caller":"traceutil/trace.go:171","msg":"trace[1099067196] transaction","detail":"{read_only:false; response_revision:1596; number_of_response:1; }","duration":"505.884886ms","start":"2026-04-24T15:14:38.042395Z","end":"2026-04-24T15:14:38.54828Z","steps":["trace[1099067196] 'process raft request' (duration: 505.775883ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:14:38.548381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:14:38.042385Z","time spent":"505.953647ms","remote":"127.0.0.1:59052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T15:14:38.548522Z","caller":"traceutil/trace.go:171","msg":"trace[492175040] transaction","detail":"{read_only:false; response_revision:1597; number_of_response:1; }","duration":"505.68793ms","start":"2026-04-24T15:14:38.042824Z","end":"2026-04-24T15:14:38.548512Z","steps":["trace[492175040] 'process raft request' (duration: 505.419884ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:14:38.548609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.298667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T15:14:38.548659Z","caller":"traceutil/trace.go:171","msg":"trace[426535677] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1597; }","duration":"188.40776ms","start":"2026-04-24T15:14:38.360241Z","end":"2026-04-24T15:14:38.548648Z","steps":["trace[426535677] 'agreement among raft nodes before linearized reading' (duration: 188.316688ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:14:38.548612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:14:38.042814Z","time spent":"505.763592ms","remote":"127.0.0.1:59052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T15:14:38.5485Z","caller":"traceutil/trace.go:171","msg":"trace[861938602] linearizableReadLoop","detail":"{readStateIndex:1669; appliedIndex:1666; }","duration":"188.167845ms","start":"2026-04-24T15:14:38.360294Z","end":"2026-04-24T15:14:38.548462Z","steps":["trace[861938602] 'read index received' (duration: 24.134549ms)","trace[861938602] 'applied index is now lower than readState.Index' (duration: 164.031076ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T15:14:39.474995Z","caller":"traceutil/trace.go:171","msg":"trace[1177912455] transaction","detail":"{read_only:false; response_revision:1598; number_of_response:1; }","duration":"119.502191ms","start":"2026-04-24T15:14:39.355458Z","end":"2026-04-24T15:14:39.47496Z","steps":["trace[1177912455] 'process raft request' (duration: 119.292687ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:14:39.667398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.463676ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-24T15:14:39.66748Z","caller":"traceutil/trace.go:171","msg":"trace[1960172354] linearizableReadLoop","detail":"{readStateIndex:1671; appliedIndex:1669; }","duration":"308.612898ms","start":"2026-04-24T15:14:39.358853Z","end":"2026-04-24T15:14:39.667466Z","steps":["trace[1960172354] 'read index received' (duration: 115.917041ms)","trace[1960172354] 'applied index is now lower than readState.Index' (duration: 192.694377ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T15:14:39.667527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.679349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T15:14:39.667546Z","caller":"traceutil/trace.go:171","msg":"trace[1845753265] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1599; }","duration":"308.72862ms","start":"2026-04-24T15:14:39.35881Z","end":"2026-04-24T15:14:39.667538Z","steps":["trace[1845753265] 'agreement among raft nodes before linearized reading' (duration: 308.69256ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:14:39.667568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:14:39.358792Z","time spent":"308.770171ms","remote":"127.0.0.1:58800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-24T15:14:39.667592Z","caller":"traceutil/trace.go:171","msg":"trace[1977306993] transaction","detail":"{read_only:false; response_revision:1599; number_of_response:1; }","duration":"309.966148ms","start":"2026-04-24T15:14:39.357586Z","end":"2026-04-24T15:14:39.667552Z","steps":["trace[1977306993] 'process raft request' (duration: 158.276177ms)","trace[1977306993] 'compare' (duration: 151.386343ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T15:14:39.667749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:14:39.357559Z","time spent":"310.10625ms","remote":"127.0.0.1:59052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T15:14:39.987508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.657009ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-24T15:14:39.987624Z","caller":"traceutil/trace.go:171","msg":"trace[397116799] transaction","detail":"{read_only:false; response_revision:1600; number_of_response:1; }","duration":"220.075159ms","start":"2026-04-24T15:14:39.767528Z","end":"2026-04-24T15:14:39.987603Z","steps":["trace[397116799] 'process raft request' (duration: 81.215445ms)","trace[397116799] 'compare' (duration: 138.372113ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T15:14:40.225264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.99401ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-24T15:14:40.225431Z","caller":"traceutil/trace.go:171","msg":"trace[349740515] transaction","detail":"{read_only:false; response_revision:1601; number_of_response:1; }","duration":"179.477681ms","start":"2026-04-24T15:14:40.045928Z","end":"2026-04-24T15:14:40.225405Z","steps":["trace[349740515] 'process raft request' (duration: 77.213925ms)","trace[349740515] 'compare' (duration: 101.744395ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T15:14:40.965878Z","caller":"traceutil/trace.go:171","msg":"trace[1171134495] linearizableReadLoop","detail":"{readStateIndex:1674; appliedIndex:1673; }","duration":"413.089341ms","start":"2026-04-24T15:14:40.552758Z","end":"2026-04-24T15:14:40.965848Z","steps":["trace[1171134495] 'read index received' (duration: 412.897717ms)","trace[1171134495] 'applied index is now lower than readState.Index' (duration: 189.944µs)"],"step_count":2} {"level":"info","ts":"2026-04-24T15:14:40.965962Z","caller":"traceutil/trace.go:171","msg":"trace[2130246137] transaction","detail":"{read_only:false; response_revision:1602; number_of_response:1; }","duration":"416.102348ms","start":"2026-04-24T15:14:40.54981Z","end":"2026-04-24T15:14:40.965912Z","steps":["trace[2130246137] 'process raft request' (duration: 415.866463ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:14:40.966275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:14:40.549779Z","time spent":"416.300483ms","remote":"127.0.0.1:59052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T15:14:40.966462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.949318ms","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-04-24T15:14:40.966549Z","caller":"traceutil/trace.go:171","msg":"trace[1787368292] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1602; }","duration":"413.177833ms","start":"2026-04-24T15:14:40.55335Z","end":"2026-04-24T15:14:40.966528Z","steps":["trace[1787368292] 'agreement among raft nodes before linearized reading' (duration: 412.683482ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:14:40.966604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:14:40.553339Z","time spent":"413.252064ms","remote":"127.0.0.1:59052","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-04-24T15:14:40.966806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.021151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-24T15:14:40.9669Z","caller":"traceutil/trace.go:171","msg":"trace[220951743] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1602; }","duration":"414.132454ms","start":"2026-04-24T15:14:40.552745Z","end":"2026-04-24T15:14:40.966877Z","steps":["trace[220951743] 'agreement among raft nodes before linearized reading' (duration: 413.282215ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:14:40.966992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:14:40.552695Z","time spent":"414.276307ms","remote":"127.0.0.1:59052","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-04-24T15:14:41.861249Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836960051491830467,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-24T15:14:41.996151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.572105ms","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-04-24T15:14:41.996292Z","caller":"traceutil/trace.go:171","msg":"trace[1470824548] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1602; }","duration":"299.723088ms","start":"2026-04-24T15:14:41.696552Z","end":"2026-04-24T15:14:41.996275Z","steps":["trace[1470824548] 'range keys from in-memory index tree' (duration: 299.546894ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:14:41.996931Z","caller":"traceutil/trace.go:171","msg":"trace[1971351871] transaction","detail":"{read_only:false; response_revision:1603; number_of_response:1; }","duration":"1.018409749s","start":"2026-04-24T15:14:40.97849Z","end":"2026-04-24T15:14:41.996899Z","steps":["trace[1971351871] 'process raft request' (duration: 932.164441ms)","trace[1971351871] 'compare' (duration: 85.792008ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T15:14:41.997071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:14:40.97847Z","time spent":"1.018527012s","remote":"127.0.0.1:59052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T15:14:42.083656Z","caller":"traceutil/trace.go:171","msg":"trace[2028872829] linearizableReadLoop","detail":"{readStateIndex:1676; appliedIndex:1674; }","duration":"723.325545ms","start":"2026-04-24T15:14:41.3603Z","end":"2026-04-24T15:14:42.083625Z","steps":["trace[2028872829] 'read index received' (duration: 550.276447ms)","trace[2028872829] 'applied index is now lower than readState.Index' (duration: 173.047718ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T15:14:42.083825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"723.512089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T15:14:42.083841Z","caller":"traceutil/trace.go:171","msg":"trace[812506331] transaction","detail":"{read_only:false; response_revision:1604; number_of_response:1; }","duration":"1.101557817s","start":"2026-04-24T15:14:40.982244Z","end":"2026-04-24T15:14:42.083802Z","steps":["trace[812506331] 'process raft request' (duration: 1.10124343s)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:14:42.083927Z","caller":"traceutil/trace.go:171","msg":"trace[1831196824] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1604; }","duration":"723.658592ms","start":"2026-04-24T15:14:41.3602Z","end":"2026-04-24T15:14:42.083859Z","steps":["trace[1831196824] 'agreement among raft nodes before linearized reading' (duration: 723.54579ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:14:42.08398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:14:41.360175Z","time spent":"723.791824ms","remote":"127.0.0.1:58794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-24T15:14:42.084094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:14:40.982226Z","time spent":"1.101719991s","remote":"127.0.0.1:59052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T15:14:42.20438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.691696ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-24T15:14:42.204567Z","caller":"traceutil/trace.go:171","msg":"trace[1340853176] linearizableReadLoop","detail":"{readStateIndex:1677; appliedIndex:1676; }","duration":"120.81263ms","start":"2026-04-24T15:14:42.083726Z","end":"2026-04-24T15:14:42.204539Z","steps":["trace[1340853176] 'read index received' (duration: 12.713625ms)","trace[1340853176] 'applied index is now lower than readState.Index' (duration: 108.097175ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T15:14:42.20478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.93856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-24T15:14:42.204842Z","caller":"traceutil/trace.go:171","msg":"trace[272518616] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1605; }","duration":"533.062422ms","start":"2026-04-24T15:14:41.671767Z","end":"2026-04-24T15:14:42.20483Z","steps":["trace[272518616] 'agreement among raft nodes before linearized reading' (duration: 532.835677ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:14:42.204877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:14:41.671738Z","time spent":"533.131094ms","remote":"127.0.0.1:59052","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-04-24T15:14:42.205009Z","caller":"traceutil/trace.go:171","msg":"trace[822679835] transaction","detail":"{read_only:false; response_revision:1605; number_of_response:1; }","duration":"210.523744ms","start":"2026-04-24T15:14:41.994027Z","end":"2026-04-24T15:14:42.20455Z","steps":["trace[822679835] 'process raft request' (duration: 102.513811ms)","trace[822679835] 'compare' (duration: 107.482671ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T15:14:43.026135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.029899ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-24T15:14:43.026255Z","caller":"traceutil/trace.go:171","msg":"trace[1683968145] linearizableReadLoop","detail":"{readStateIndex:1680; appliedIndex:1679; }","duration":"323.560052ms","start":"2026-04-24T15:14:42.702674Z","end":"2026-04-24T15:14:43.026234Z","steps":["trace[1683968145] 'read index received' (duration: 456.86µs)","trace[1683968145] 'applied index is now lower than readState.Index' (duration: 323.101402ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T15:14:43.026453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.774546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-24T15:14:43.026528Z","caller":"traceutil/trace.go:171","msg":"trace[1013588677] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1607; }","duration":"323.969771ms","start":"2026-04-24T15:14:42.702543Z","end":"2026-04-24T15:14:43.026513Z","steps":["trace[1013588677] 'agreement among raft nodes before linearized reading' (duration: 323.813667ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:14:43.026594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:14:42.70251Z","time spent":"324.067783ms","remote":"127.0.0.1:59118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":16,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-04-24T15:14:44.588328Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836960051491830487,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-24T15:14:45.0892Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836960051491830487,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-24T15:14:45.450466Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.440662165s","expected-duration":"1s"} {"level":"info","ts":"2026-04-24T15:14:45.450925Z","caller":"traceutil/trace.go:171","msg":"trace[1984354597] transaction","detail":"{read_only:false; response_revision:1609; number_of_response:1; }","duration":"1.441218428s","start":"2026-04-24T15:14:44.009678Z","end":"2026-04-24T15:14:45.450896Z","steps":["trace[1984354597] 'process raft request' (duration: 1.441040624s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:14:45.45108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:14:44.00965Z","time spent":"1.44133882s","remote":"127.0.0.1:59052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T15:14:46.600848Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.150125823s","expected-duration":"1s"} {"level":"info","ts":"2026-04-24T15:14:46.601375Z","caller":"traceutil/trace.go:171","msg":"trace[1224229604] linearizableReadLoop","detail":"{readStateIndex:1684; appliedIndex:1681; }","duration":"2.513336518s","start":"2026-04-24T15:14:44.088008Z","end":"2026-04-24T15:14:46.601344Z","steps":["trace[1224229604] 'read index received' (duration: 1.362751674s)","trace[1224229604] 'applied index is now lower than readState.Index' (duration: 1.150583613s)"],"step_count":2} {"level":"info","ts":"2026-04-24T15:14:46.601494Z","caller":"traceutil/trace.go:171","msg":"trace[1225742505] transaction","detail":"{read_only:false; response_revision:1611; number_of_response:1; }","duration":"2.027371497s","start":"2026-04-24T15:14:44.5741Z","end":"2026-04-24T15:14:46.601471Z","steps":["trace[1225742505] 'process raft request' (duration: 2.027192173s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:14:46.601633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:14:44.574076Z","time spent":"2.027470759s","remote":"127.0.0.1:59052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T15:14:46.601677Z","caller":"traceutil/trace.go:171","msg":"trace[758056143] transaction","detail":"{read_only:false; response_revision:1610; number_of_response:1; }","duration":"2.373173895s","start":"2026-04-24T15:14:44.228455Z","end":"2026-04-24T15:14:46.601629Z","steps":["trace[758056143] 'process raft request' (duration: 2.372594302s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:14:46.60174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.472623718s","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-04-24T15:14:46.601851Z","caller":"traceutil/trace.go:171","msg":"trace[1414582400] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1611; }","duration":"2.472806192s","start":"2026-04-24T15:14:44.129024Z","end":"2026-04-24T15:14:46.601831Z","steps":["trace[1414582400] 'agreement among raft nodes before linearized reading' (duration: 2.472530556s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:14:46.601874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:14:44.228431Z","time spent":"2.373337029s","remote":"127.0.0.1:59052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T15:14:46.601902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:14:44.129004Z","time spent":"2.472886674s","remote":"127.0.0.1:59288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":93,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-04-24T15:14:46.601957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.760275597s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-24T15:14:46.602045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.237994257s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-24T15:14:46.602016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.32327687s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-24T15:14:46.603379Z","caller":"traceutil/trace.go:171","msg":"trace[1277331709] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1611; }","duration":"2.3246646s","start":"2026-04-24T15:14:44.278678Z","end":"2026-04-24T15:14:46.603342Z","steps":["trace[1277331709] 'agreement among raft nodes before linearized reading' (duration: 2.323178428s)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:14:46.603374Z","caller":"traceutil/trace.go:171","msg":"trace[2125930582] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1611; }","duration":"1.239379267s","start":"2026-04-24T15:14:45.363962Z","end":"2026-04-24T15:14:46.603341Z","steps":["trace[2125930582] 'agreement among raft nodes before linearized reading' (duration: 1.238049428s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:14:46.603491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:14:44.278657Z","time spent":"2.324773122s","remote":"127.0.0.1:59052","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-04-24T15:14:46.603468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:14:45.363939Z","time spent":"1.23950843s","remote":"127.0.0.1:58800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-24T15:14:46.603483Z","caller":"traceutil/trace.go:171","msg":"trace[1613951499] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1611; }","duration":"1.760470822s","start":"2026-04-24T15:14:44.841572Z","end":"2026-04-24T15:14:46.602043Z","steps":["trace[1613951499] 'agreement among raft nodes before linearized reading' (duration: 1.760117764s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:14:46.603511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.515494805s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-24T15:14:46.603579Z","caller":"traceutil/trace.go:171","msg":"trace[510661096] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1611; }","duration":"2.515567768s","start":"2026-04-24T15:14:44.08798Z","end":"2026-04-24T15:14:46.603548Z","steps":["trace[510661096] 'agreement among raft nodes before linearized reading' (duration: 2.513470771s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:14:46.603582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:14:44.841552Z","time spent":"1.762003137s","remote":"127.0.0.1:58894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":5,"response size":30,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-04-24T15:14:46.603605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:14:44.087968Z","time spent":"2.515629659s","remote":"127.0.0.1:59052","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-04-24T15:14:46.603983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.376666964s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-24T15:14:46.60401Z","caller":"traceutil/trace.go:171","msg":"trace[161169435] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1611; }","duration":"2.376733925s","start":"2026-04-24T15:14:44.227267Z","end":"2026-04-24T15:14:46.604001Z","steps":["trace[161169435] 'agreement among raft nodes before linearized reading' (duration: 2.376556471s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:14:46.604037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:14:44.227252Z","time spent":"2.376778877s","remote":"127.0.0.1:59052","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-04-24T15:14:46.60434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.496903163s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-24T15:14:46.604367Z","caller":"traceutil/trace.go:171","msg":"trace[2002128037] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1611; }","duration":"1.496961135s","start":"2026-04-24T15:14:45.107396Z","end":"2026-04-24T15:14:46.604357Z","steps":["trace[2002128037] 'agreement among raft nodes before linearized reading' (duration: 1.496506165s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:14:46.604396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:14:45.107383Z","time spent":"1.497007725s","remote":"127.0.0.1:59052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-24T15:14:46.926933Z","caller":"traceutil/trace.go:171","msg":"trace[1123855019] transaction","detail":"{read_only:false; response_revision:1613; number_of_response:1; }","duration":"315.329877ms","start":"2026-04-24T15:14:46.611539Z","end":"2026-04-24T15:14:46.926869Z","steps":["trace[1123855019] 'process raft request' (duration: 313.013875ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:14:46.927321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:14:46.611525Z","time spent":"315.708296ms","remote":"127.0.0.1:59052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T15:14:46.927434Z","caller":"traceutil/trace.go:171","msg":"trace[336144565] linearizableReadLoop","detail":"{readStateIndex:1688; appliedIndex:1684; }","duration":"311.533312ms","start":"2026-04-24T15:14:46.615884Z","end":"2026-04-24T15:14:46.927417Z","steps":["trace[336144565] 'read index received' (duration: 8.414248ms)","trace[336144565] 'applied index is now lower than readState.Index' (duration: 303.118184ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T15:14:46.927536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.635154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T15:14:46.927571Z","caller":"traceutil/trace.go:171","msg":"trace[2142867196] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1615; }","duration":"311.689645ms","start":"2026-04-24T15:14:46.61587Z","end":"2026-04-24T15:14:46.927559Z","steps":["trace[2142867196] 'agreement among raft nodes before linearized reading' (duration: 311.613393ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:14:46.927599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:14:46.615855Z","time spent":"311.738976ms","remote":"127.0.0.1:58800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-24T15:14:46.927602Z","caller":"traceutil/trace.go:171","msg":"trace[235611732] transaction","detail":"{read_only:false; response_revision:1614; number_of_response:1; }","duration":"314.450247ms","start":"2026-04-24T15:14:46.613096Z","end":"2026-04-24T15:14:46.927546Z","steps":["trace[235611732] 'process raft request' (duration: 313.907405ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:14:46.927711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:14:46.613053Z","time spent":"314.628861ms","remote":"127.0.0.1:59052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T15:14:46.927791Z","caller":"traceutil/trace.go:171","msg":"trace[831368050] transaction","detail":"{read_only:false; response_revision:1615; number_of_response:1; }","duration":"314.57354ms","start":"2026-04-24T15:14:46.613185Z","end":"2026-04-24T15:14:46.927758Z","steps":["trace[831368050] 'process raft request' (duration: 314.11845ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:14:46.927988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:14:46.613179Z","time spent":"314.690502ms","remote":"127.0.0.1:59052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T15:15:01.478271Z","caller":"traceutil/trace.go:171","msg":"trace[1454078025] transaction","detail":"{read_only:false; response_revision:1712; number_of_response:1; }","duration":"436.042394ms","start":"2026-04-24T15:15:01.042001Z","end":"2026-04-24T15:15:01.478043Z","steps":["trace[1454078025] 'process raft request' (duration: 435.85438ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:15:01.47845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:15:01.041968Z","time spent":"436.375871ms","remote":"127.0.0.1:59052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T15:15:01.50806Z","caller":"traceutil/trace.go:171","msg":"trace[1837662954] transaction","detail":"{read_only:false; response_revision:1713; number_of_response:1; }","duration":"463.059048ms","start":"2026-04-24T15:15:01.044968Z","end":"2026-04-24T15:15:01.508027Z","steps":["trace[1837662954] 'process raft request' (duration: 462.885034ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:15:01.508282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:15:01.044947Z","time spent":"463.203482ms","remote":"127.0.0.1:59052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T15:15:01.508318Z","caller":"traceutil/trace.go:171","msg":"trace[1359140280] linearizableReadLoop","detail":"{readStateIndex:1792; appliedIndex:1789; }","duration":"142.558972ms","start":"2026-04-24T15:15:01.365733Z","end":"2026-04-24T15:15:01.508292Z","steps":["trace[1359140280] 'read index received' (duration: 112.086821ms)","trace[1359140280] 'applied index is now lower than readState.Index' (duration: 30.470311ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T15:15:01.508364Z","caller":"traceutil/trace.go:171","msg":"trace[114676886] transaction","detail":"{read_only:false; response_revision:1714; number_of_response:1; }","duration":"462.632399ms","start":"2026-04-24T15:15:01.045703Z","end":"2026-04-24T15:15:01.508335Z","steps":["trace[114676886] 'process raft request' (duration: 462.268761ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:15:01.508424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.684545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-24T15:15:01.508448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:15:01.045694Z","time spent":"462.71231ms","remote":"127.0.0.1:59052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T15:15:01.50847Z","caller":"traceutil/trace.go:171","msg":"trace[892491875] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1714; }","duration":"142.94286ms","start":"2026-04-24T15:15:01.365512Z","end":"2026-04-24T15:15:01.508454Z","steps":["trace[892491875] 'agreement among raft nodes before linearized reading' (duration: 142.863569ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:15:05.363763Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836960051491830741,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-24T15:15:05.500014Z","caller":"traceutil/trace.go:171","msg":"trace[176211118] linearizableReadLoop","detail":"{readStateIndex:1801; appliedIndex:1800; }","duration":"636.266269ms","start":"2026-04-24T15:15:04.863727Z","end":"2026-04-24T15:15:05.499993Z","steps":["trace[176211118] 'read index received' (duration: 636.168727ms)","trace[176211118] 'applied index is now lower than readState.Index' (duration: 96.382µs)"],"step_count":2} {"level":"info","ts":"2026-04-24T15:15:05.500137Z","caller":"traceutil/trace.go:171","msg":"trace[1060824018] transaction","detail":"{read_only:false; response_revision:1722; number_of_response:1; }","duration":"696.720451ms","start":"2026-04-24T15:15:04.803374Z","end":"2026-04-24T15:15:05.500094Z","steps":["trace[1060824018] 'process raft request' (duration: 696.485116ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:15:05.50021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.479964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-24T15:15:05.500317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.710047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T15:15:05.500361Z","caller":"traceutil/trace.go:171","msg":"trace[2075871906] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1722; }","duration":"636.671918ms","start":"2026-04-24T15:15:04.86367Z","end":"2026-04-24T15:15:05.500342Z","steps":["trace[2075871906] 'agreement among raft nodes before linearized reading' (duration: 636.466424ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:15:05.500394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:15:04.803358Z","time spent":"696.903705ms","remote":"127.0.0.1:59052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T15:15:05.50043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:15:04.863647Z","time spent":"636.76639ms","remote":"127.0.0.1:59052","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-04-24T15:15:05.50043Z","caller":"traceutil/trace.go:171","msg":"trace[2070945516] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1722; }","duration":"137.872151ms","start":"2026-04-24T15:15:05.362526Z","end":"2026-04-24T15:15:05.500399Z","steps":["trace[2070945516] 'agreement among raft nodes before linearized reading' (duration: 137.690137ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:15:17.228317Z","caller":"traceutil/trace.go:171","msg":"trace[827571384] transaction","detail":"{read_only:false; response_revision:1761; number_of_response:1; }","duration":"101.397877ms","start":"2026-04-24T15:15:17.126869Z","end":"2026-04-24T15:15:17.228266Z","steps":["trace[827571384] 'process raft request' (duration: 101.112769ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:15:17.484669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.988034ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-24T15:15:17.484868Z","caller":"traceutil/trace.go:171","msg":"trace[1504994833] linearizableReadLoop","detail":"{readStateIndex:1844; appliedIndex:1843; }","duration":"123.233754ms","start":"2026-04-24T15:15:17.361592Z","end":"2026-04-24T15:15:17.484826Z","steps":["trace[1504994833] 'read index received' (duration: 1.853501ms)","trace[1504994833] 'applied index is now lower than readState.Index' (duration: 121.378183ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T15:15:17.485004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.423098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T15:15:17.485147Z","caller":"traceutil/trace.go:171","msg":"trace[1974364582] transaction","detail":"{read_only:false; response_revision:1762; number_of_response:1; }","duration":"230.94153ms","start":"2026-04-24T15:15:17.25414Z","end":"2026-04-24T15:15:17.485082Z","steps":["trace[1974364582] 'process raft request' (duration: 109.378464ms)","trace[1974364582] 'compare' (duration: 120.600225ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T15:15:17.485221Z","caller":"traceutil/trace.go:171","msg":"trace[319475862] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1762; }","duration":"123.696694ms","start":"2026-04-24T15:15:17.361506Z","end":"2026-04-24T15:15:17.485203Z","steps":["trace[319475862] 'agreement among raft nodes before linearized reading' (duration: 123.414868ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:15:17.968064Z","caller":"traceutil/trace.go:171","msg":"trace[1886786504] linearizableReadLoop","detail":"{readStateIndex:1845; appliedIndex:1844; }","duration":"413.581573ms","start":"2026-04-24T15:15:17.554453Z","end":"2026-04-24T15:15:17.968035Z","steps":["trace[1886786504] 'read index received' (duration: 413.304317ms)","trace[1886786504] 'applied index is now lower than readState.Index' (duration: 276.476µs)"],"step_count":2} {"level":"warn","ts":"2026-04-24T15:15:17.968636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.694651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-24T15:15:17.968692Z","caller":"traceutil/trace.go:171","msg":"trace[1615230489] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1763; }","duration":"390.791914ms","start":"2026-04-24T15:15:17.577886Z","end":"2026-04-24T15:15:17.968678Z","steps":["trace[1615230489] 'agreement among raft nodes before linearized reading' (duration: 390.554649ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:15:17.968736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:15:17.577874Z","time spent":"390.853035ms","remote":"127.0.0.1:59052","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-04-24T15:15:17.968815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.509362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-24T15:15:17.968916Z","caller":"traceutil/trace.go:171","msg":"trace[910446274] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1763; }","duration":"375.640815ms","start":"2026-04-24T15:15:17.593253Z","end":"2026-04-24T15:15:17.968893Z","steps":["trace[910446274] 'agreement among raft nodes before linearized reading' (duration: 375.339948ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:15:17.968966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:15:17.593246Z","time spent":"375.711406ms","remote":"127.0.0.1:59052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-24T15:15:17.969053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.876735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-24T15:15:17.969101Z","caller":"traceutil/trace.go:171","msg":"trace[1450492515] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1763; }","duration":"386.945787ms","start":"2026-04-24T15:15:17.582145Z","end":"2026-04-24T15:15:17.969091Z","steps":["trace[1450492515] 'agreement among raft nodes before linearized reading' (duration: 386.835085ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:15:17.969113Z","caller":"traceutil/trace.go:171","msg":"trace[1165848957] transaction","detail":"{read_only:false; response_revision:1763; number_of_response:1; }","duration":"432.413163ms","start":"2026-04-24T15:15:17.536687Z","end":"2026-04-24T15:15:17.9691Z","steps":["trace[1165848957] 'process raft request' (duration: 431.129185ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:15:17.969137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:15:17.582135Z","time spent":"386.996198ms","remote":"127.0.0.1:59052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-24T15:15:17.969214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:15:17.536666Z","time spent":"432.484164ms","remote":"127.0.0.1:59052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T15:15:17.969268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.81206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-24T15:15:17.9693Z","caller":"traceutil/trace.go:171","msg":"trace[1018043180] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1763; }","duration":"414.862222ms","start":"2026-04-24T15:15:17.554429Z","end":"2026-04-24T15:15:17.969291Z","steps":["trace[1018043180] 'agreement among raft nodes before linearized reading' (duration: 414.78144ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:15:17.969331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:15:17.554416Z","time spent":"414.908682ms","remote":"127.0.0.1:59052","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-04-24T15:15:17.969491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.942087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-24T15:15:17.971538Z","caller":"traceutil/trace.go:171","msg":"trace[1493650273] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1763; }","duration":"336.346106ms","start":"2026-04-24T15:15:17.633259Z","end":"2026-04-24T15:15:17.969605Z","steps":["trace[1493650273] 'agreement among raft nodes before linearized reading' (duration: 335.298853ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:15:17.971636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:15:17.633248Z","time spent":"338.369242ms","remote":"127.0.0.1:59052","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-04-24T15:15:18.335552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.225738ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-24T15:15:18.335847Z","caller":"traceutil/trace.go:171","msg":"trace[56802578] transaction","detail":"{read_only:false; response_revision:1764; number_of_response:1; }","duration":"361.412687ms","start":"2026-04-24T15:15:17.974392Z","end":"2026-04-24T15:15:18.335805Z","steps":["trace[56802578] 'process raft request' (duration: 52.821931ms)","trace[56802578] 'compare' (duration: 307.779518ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T15:15:18.336022Z","caller":"traceutil/trace.go:171","msg":"trace[1965133548] transaction","detail":"{read_only:false; response_revision:1766; number_of_response:1; }","duration":"360.853835ms","start":"2026-04-24T15:15:17.975142Z","end":"2026-04-24T15:15:18.335995Z","steps":["trace[1965133548] 'process raft request' (duration: 360.62682ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:15:18.336049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:15:17.974363Z","time spent":"361.586391ms","remote":"127.0.0.1:59052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T15:15:18.336097Z","caller":"traceutil/trace.go:171","msg":"trace[749057266] transaction","detail":"{read_only:false; response_revision:1767; number_of_response:1; }","duration":"359.569056ms","start":"2026-04-24T15:15:17.976517Z","end":"2026-04-24T15:15:18.336086Z","steps":["trace[749057266] 'process raft request' (duration: 359.29963ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:15:18.335907Z","caller":"traceutil/trace.go:171","msg":"trace[1442441472] transaction","detail":"{read_only:false; response_revision:1768; number_of_response:1; }","duration":"357.384337ms","start":"2026-04-24T15:15:17.978504Z","end":"2026-04-24T15:15:18.335888Z","steps":["trace[1442441472] 'process raft request' (duration: 357.344227ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:15:18.336148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:15:17.975135Z","time spent":"360.936076ms","remote":"127.0.0.1:59052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T15:15:18.336166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:15:17.97651Z","time spent":"359.619297ms","remote":"127.0.0.1:59052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T15:15:18.336154Z","caller":"traceutil/trace.go:171","msg":"trace[792132754] transaction","detail":"{read_only:false; response_revision:1765; number_of_response:1; }","duration":"361.026518ms","start":"2026-04-24T15:15:17.975072Z","end":"2026-04-24T15:15:18.336098Z","steps":["trace[792132754] 'process raft request' (duration: 360.611059ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:15:18.336291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:15:17.978496Z","time spent":"357.656033ms","remote":"127.0.0.1:59052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T15:15:18.336388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:15:17.97505Z","time spent":"361.207343ms","remote":"127.0.0.1:59052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T15:15:20.400545Z","caller":"traceutil/trace.go:171","msg":"trace[1088839872] transaction","detail":"{read_only:false; response_revision:1769; number_of_response:1; }","duration":"425.09089ms","start":"2026-04-24T15:15:19.975419Z","end":"2026-04-24T15:15:20.40051Z","steps":["trace[1088839872] 'process raft request' (duration: 424.812794ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:15:20.401128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:15:19.975379Z","time spent":"425.266914ms","remote":"127.0.0.1:59052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T15:15:20.423525Z","caller":"traceutil/trace.go:171","msg":"trace[1567313786] linearizableReadLoop","detail":"{readStateIndex:1852; appliedIndex:1850; }","duration":"188.36619ms","start":"2026-04-24T15:15:20.235128Z","end":"2026-04-24T15:15:20.423494Z","steps":["trace[1567313786] 'read index received' (duration: 165.152681ms)","trace[1567313786] 'applied index is now lower than readState.Index' (duration: 23.211199ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T15:15:20.423916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.748522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-24T15:15:20.424031Z","caller":"traceutil/trace.go:171","msg":"trace[2048903216] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1770; }","duration":"160.948297ms","start":"2026-04-24T15:15:20.263045Z","end":"2026-04-24T15:15:20.423993Z","steps":["trace[2048903216] 'agreement among raft nodes before linearized reading' (duration: 160.709782ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:15:20.424139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.012974ms","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-04-24T15:15:20.424204Z","caller":"traceutil/trace.go:171","msg":"trace[1661297378] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1770; }","duration":"189.117376ms","start":"2026-04-24T15:15:20.235072Z","end":"2026-04-24T15:15:20.424189Z","steps":["trace[1661297378] 'agreement among raft nodes before linearized reading' (duration: 189.022724ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:15:20.425254Z","caller":"traceutil/trace.go:171","msg":"trace[1824256515] transaction","detail":"{read_only:false; response_revision:1770; number_of_response:1; }","duration":"192.174685ms","start":"2026-04-24T15:15:20.233062Z","end":"2026-04-24T15:15:20.425236Z","steps":["trace[1824256515] 'process raft request' (duration: 190.249432ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:15:20.550126Z","caller":"traceutil/trace.go:171","msg":"trace[1061390279] transaction","detail":"{read_only:false; response_revision:1772; number_of_response:1; }","duration":"112.450003ms","start":"2026-04-24T15:15:20.437646Z","end":"2026-04-24T15:15:20.550096Z","steps":["trace[1061390279] 'process raft request' (duration: 112.190327ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:15:20.550649Z","caller":"traceutil/trace.go:171","msg":"trace[427257776] transaction","detail":"{read_only:false; response_revision:1775; number_of_response:1; }","duration":"112.199409ms","start":"2026-04-24T15:15:20.438389Z","end":"2026-04-24T15:15:20.550589Z","steps":["trace[427257776] 'process raft request' (duration: 112.147607ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:15:20.550734Z","caller":"traceutil/trace.go:171","msg":"trace[2012419114] transaction","detail":"{read_only:false; response_revision:1773; number_of_response:1; }","duration":"112.978946ms","start":"2026-04-24T15:15:20.437738Z","end":"2026-04-24T15:15:20.550716Z","steps":["trace[2012419114] 'process raft request' (duration: 112.31175ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:15:20.550887Z","caller":"traceutil/trace.go:171","msg":"trace[790297925] transaction","detail":"{read_only:false; response_revision:1774; number_of_response:1; }","duration":"113.076128ms","start":"2026-04-24T15:15:20.437793Z","end":"2026-04-24T15:15:20.550869Z","steps":["trace[790297925] 'process raft request' (duration: 112.640817ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:15:26.692424Z","caller":"traceutil/trace.go:171","msg":"trace[1178171917] transaction","detail":"{read_only:false; response_revision:1840; number_of_response:1; }","duration":"101.345885ms","start":"2026-04-24T15:15:26.591056Z","end":"2026-04-24T15:15:26.692402Z","steps":["trace[1178171917] 'process raft request' (duration: 101.261263ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:15:26.692452Z","caller":"traceutil/trace.go:171","msg":"trace[542210377] transaction","detail":"{read_only:false; response_revision:1839; number_of_response:1; }","duration":"101.768714ms","start":"2026-04-24T15:15:26.590645Z","end":"2026-04-24T15:15:26.692414Z","steps":["trace[542210377] 'process raft request' (duration: 101.56157ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:15:28.296473Z","caller":"traceutil/trace.go:171","msg":"trace[2112307949] linearizableReadLoop","detail":"{readStateIndex:1930; appliedIndex:1929; }","duration":"489.02556ms","start":"2026-04-24T15:15:27.807417Z","end":"2026-04-24T15:15:28.296443Z","steps":["trace[2112307949] 'read index received' (duration: 463.083519ms)","trace[2112307949] 'applied index is now lower than readState.Index' (duration: 25.940661ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T15:15:28.296553Z","caller":"traceutil/trace.go:171","msg":"trace[1194363269] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"489.309886ms","start":"2026-04-24T15:15:27.807203Z","end":"2026-04-24T15:15:28.296513Z","steps":["trace[1194363269] 'process raft request' (duration: 463.252503ms)","trace[1194363269] 'compare' (duration: 25.817627ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T15:15:28.29671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.270315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T15:15:28.296766Z","caller":"traceutil/trace.go:171","msg":"trace[168131386] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1846; }","duration":"489.375197ms","start":"2026-04-24T15:15:27.807372Z","end":"2026-04-24T15:15:28.296748Z","steps":["trace[168131386] 'agreement among raft nodes before linearized reading' (duration: 489.234064ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:15:28.296794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:15:27.807166Z","time spent":"489.50611ms","remote":"127.0.0.1:58856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-24T15:15:28.29681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:15:27.80735Z","time spent":"489.450468ms","remote":"127.0.0.1:59052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-24T15:15:28.296947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.840344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-24T15:15:28.297051Z","caller":"traceutil/trace.go:171","msg":"trace[164344466] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1846; }","duration":"215.958426ms","start":"2026-04-24T15:15:28.081059Z","end":"2026-04-24T15:15:28.297017Z","steps":["trace[164344466] 'agreement among raft nodes before linearized reading' (duration: 215.739771ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:15:28.297807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.177623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:1948"} {"level":"info","ts":"2026-04-24T15:15:28.29797Z","caller":"traceutil/trace.go:171","msg":"trace[769928811] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1846; }","duration":"336.408358ms","start":"2026-04-24T15:15:27.96154Z","end":"2026-04-24T15:15:28.297949Z","steps":["trace[769928811] 'agreement among raft nodes before linearized reading' (duration: 335.240402ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:15:28.298106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:15:27.96152Z","time spent":"336.571372ms","remote":"127.0.0.1:58974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":1971,"request content":"key:\"/registry/pods/default/test-pvc-pod\" "} {"level":"warn","ts":"2026-04-24T15:15:28.776189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.304887ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-24T15:15:28.776455Z","caller":"traceutil/trace.go:171","msg":"trace[1407867330] linearizableReadLoop","detail":"{readStateIndex:1932; appliedIndex:1930; }","duration":"415.174298ms","start":"2026-04-24T15:15:28.361263Z","end":"2026-04-24T15:15:28.776437Z","steps":["trace[1407867330] 'read index received' (duration: 171.569514ms)","trace[1407867330] 'applied index is now lower than readState.Index' (duration: 243.603454ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T15:15:28.776473Z","caller":"traceutil/trace.go:171","msg":"trace[1605556194] transaction","detail":"{read_only:false; response_revision:1847; number_of_response:1; }","duration":"474.406892ms","start":"2026-04-24T15:15:28.301995Z","end":"2026-04-24T15:15:28.776402Z","steps":["trace[1605556194] 'process raft request' (duration: 230.822988ms)","trace[1605556194] 'compare' (duration: 242.94747ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T15:15:28.776575Z","caller":"traceutil/trace.go:171","msg":"trace[1030835846] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"330.350032ms","start":"2026-04-24T15:15:28.446215Z","end":"2026-04-24T15:15:28.776565Z","steps":["trace[1030835846] 'process raft request' (duration: 330.164068ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:15:28.776652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:15:28.446177Z","time spent":"330.424334ms","remote":"127.0.0.1:59052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T15:15:28.776683Z","caller":"traceutil/trace.go:171","msg":"trace[1275675094] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"473.916261ms","start":"2026-04-24T15:15:28.302741Z","end":"2026-04-24T15:15:28.776658Z","steps":["trace[1275675094] 'process raft request' (duration: 473.562193ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:15:28.776727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:15:28.301965Z","time spent":"474.609946ms","remote":"127.0.0.1:59052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-24T15:15:28.776803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:15:28.302683Z","time spent":"474.061114ms","remote":"127.0.0.1:58856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":837,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-24T15:15:28.776878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.624708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T15:15:28.776953Z","caller":"traceutil/trace.go:171","msg":"trace[1633511837] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1849; }","duration":"415.71121ms","start":"2026-04-24T15:15:28.361195Z","end":"2026-04-24T15:15:28.776906Z","steps":["trace[1633511837] 'agreement among raft nodes before linearized reading' (duration: 415.652908ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:15:28.776993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:15:28.361161Z","time spent":"415.823102ms","remote":"127.0.0.1:58800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-24T15:15:28.777248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.854446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-24T15:15:28.777285Z","caller":"traceutil/trace.go:171","msg":"trace[2064896870] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1849; }","duration":"246.938368ms","start":"2026-04-24T15:15:28.530338Z","end":"2026-04-24T15:15:28.777276Z","steps":["trace[2064896870] 'agreement among raft nodes before linearized reading' (duration: 246.809325ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:15:29.373006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.962633ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-24T15:15:29.373161Z","caller":"traceutil/trace.go:171","msg":"trace[1148033672] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"590.376744ms","start":"2026-04-24T15:15:28.782768Z","end":"2026-04-24T15:15:29.373144Z","steps":["trace[1148033672] 'process raft request' (duration: 290.217546ms)","trace[1148033672] 'compare' (duration: 299.846771ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T15:15:29.373228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:15:28.782746Z","time spent":"590.456656ms","remote":"127.0.0.1:59052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T15:15:29.452997Z","caller":"traceutil/trace.go:171","msg":"trace[1529537004] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"669.9054ms","start":"2026-04-24T15:15:28.783Z","end":"2026-04-24T15:15:29.452905Z","steps":["trace[1529537004] 'process raft request' (duration: 669.068192ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:15:29.453111Z","caller":"traceutil/trace.go:171","msg":"trace[44462555] linearizableReadLoop","detail":"{readStateIndex:1936; appliedIndex:1933; }","duration":"669.937632ms","start":"2026-04-24T15:15:28.783151Z","end":"2026-04-24T15:15:29.453089Z","steps":["trace[44462555] 'read index received' (duration: 289.823248ms)","trace[44462555] 'applied index is now lower than readState.Index' (duration: 380.113204ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T15:15:29.453129Z","caller":"traceutil/trace.go:171","msg":"trace[1809157796] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"670.029084ms","start":"2026-04-24T15:15:28.783069Z","end":"2026-04-24T15:15:29.453098Z","steps":["trace[1809157796] 'process raft request' (duration: 669.184935ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:15:29.453192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:15:28.782981Z","time spent":"670.112546ms","remote":"127.0.0.1:59052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T15:15:29.453258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:15:28.783051Z","time spent":"670.142266ms","remote":"127.0.0.1:59052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T15:15:29.453305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.128906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-04-24T15:15:29.45335Z","caller":"traceutil/trace.go:171","msg":"trace[1033891466] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1855; }","duration":"670.207518ms","start":"2026-04-24T15:15:28.783127Z","end":"2026-04-24T15:15:29.453334Z","steps":["trace[1033891466] 'agreement among raft nodes before linearized reading' (duration: 670.047193ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:15:29.453387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:15:28.783118Z","time spent":"670.26091ms","remote":"127.0.0.1:59052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":558,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-04-24T15:15:29.453413Z","caller":"traceutil/trace.go:171","msg":"trace[52507122] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"666.445374ms","start":"2026-04-24T15:15:28.786952Z","end":"2026-04-24T15:15:29.453398Z","steps":["trace[52507122] 'process raft request' (duration: 666.020794ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:15:29.453442Z","caller":"traceutil/trace.go:171","msg":"trace[1215229284] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"669.239705ms","start":"2026-04-24T15:15:28.784052Z","end":"2026-04-24T15:15:29.453292Z","steps":["trace[1215229284] 'process raft request' (duration: 668.286664ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:15:29.453641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:15:28.784012Z","time spent":"669.550843ms","remote":"127.0.0.1:59052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T15:15:29.453491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:15:28.78693Z","time spent":"666.527226ms","remote":"127.0.0.1:58856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":929,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-24T15:15:29.45351Z","caller":"traceutil/trace.go:171","msg":"trace[674259185] transaction","detail":"{read_only:false; response_revision:1853; number_of_response:1; }","duration":"670.308781ms","start":"2026-04-24T15:15:28.78319Z","end":"2026-04-24T15:15:29.453499Z","steps":["trace[674259185] 'process raft request' (duration: 669.111613ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:15:29.454932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:15:28.783179Z","time spent":"671.703121ms","remote":"127.0.0.1:59052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T15:15:29.777672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.663224ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-24T15:15:29.777857Z","caller":"traceutil/trace.go:171","msg":"trace[1819780216] linearizableReadLoop","detail":"{readStateIndex:1941; appliedIndex:1940; }","duration":"174.712974ms","start":"2026-04-24T15:15:29.603119Z","end":"2026-04-24T15:15:29.777832Z","steps":["trace[1819780216] 'read index received' (duration: 37.645691ms)","trace[1819780216] 'applied index is now lower than readState.Index' (duration: 137.065803ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T15:15:29.778005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.879728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T15:15:29.778039Z","caller":"traceutil/trace.go:171","msg":"trace[733452991] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1857; }","duration":"174.929868ms","start":"2026-04-24T15:15:29.603096Z","end":"2026-04-24T15:15:29.778025Z","steps":["trace[733452991] 'agreement among raft nodes before linearized reading' (duration: 174.867967ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:15:29.778007Z","caller":"traceutil/trace.go:171","msg":"trace[1398290640] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"185.25891ms","start":"2026-04-24T15:15:29.592694Z","end":"2026-04-24T15:15:29.777953Z","steps":["trace[1398290640] 'process raft request' (duration: 48.177036ms)","trace[1398290640] 'compare' (duration: 136.47183ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T15:15:30.832912Z","caller":"traceutil/trace.go:171","msg":"trace[1109370813] transaction","detail":"{read_only:false; response_revision:1858; number_of_response:1; }","duration":"141.577895ms","start":"2026-04-24T15:15:30.691313Z","end":"2026-04-24T15:15:30.832891Z","steps":["trace[1109370813] 'process raft request' (duration: 141.438572ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:15:31.517915Z","caller":"traceutil/trace.go:171","msg":"trace[114226995] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"132.345857ms","start":"2026-04-24T15:15:31.385525Z","end":"2026-04-24T15:15:31.517871Z","steps":["trace[114226995] 'process raft request' (duration: 132.155453ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:15:31.692667Z","caller":"traceutil/trace.go:171","msg":"trace[338289918] linearizableReadLoop","detail":"{readStateIndex:1946; appliedIndex:1945; }","duration":"174.802407ms","start":"2026-04-24T15:15:31.517826Z","end":"2026-04-24T15:15:31.692628Z","steps":["trace[338289918] 'read index received' (duration: 170.310736ms)","trace[338289918] 'applied index is now lower than readState.Index' (duration: 4.488821ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T15:15:31.693074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.57624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-24T15:15:31.69311Z","caller":"traceutil/trace.go:171","msg":"trace[484057269] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"178.579941ms","start":"2026-04-24T15:15:31.514488Z","end":"2026-04-24T15:15:31.693068Z","steps":["trace[484057269] 'process raft request' (duration: 173.463036ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:15:31.693178Z","caller":"traceutil/trace.go:171","msg":"trace[1848967699] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1862; }","duration":"233.708042ms","start":"2026-04-24T15:15:31.459425Z","end":"2026-04-24T15:15:31.693133Z","steps":["trace[1848967699] 'agreement among raft nodes before linearized reading' (duration: 233.387355ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:15:31.693516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.900535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-24T15:15:31.693563Z","caller":"traceutil/trace.go:171","msg":"trace[1220116885] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1862; }","duration":"233.009577ms","start":"2026-04-24T15:15:31.460542Z","end":"2026-04-24T15:15:31.693551Z","steps":["trace[1220116885] 'agreement among raft nodes before linearized reading' (duration: 232.806463ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:15:31.69355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.824252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-24T15:15:31.693729Z","caller":"traceutil/trace.go:171","msg":"trace[19250211] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1862; }","duration":"233.085549ms","start":"2026-04-24T15:15:31.460619Z","end":"2026-04-24T15:15:31.693705Z","steps":["trace[19250211] 'agreement among raft nodes before linearized reading' (duration: 232.513036ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:15:31.693865Z","caller":"traceutil/trace.go:171","msg":"trace[1536991952] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"172.34604ms","start":"2026-04-24T15:15:31.521489Z","end":"2026-04-24T15:15:31.693835Z","steps":["trace[1536991952] 'process raft request' (duration: 172.170457ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:15:44.95285Z","caller":"traceutil/trace.go:171","msg":"trace[397084599] linearizableReadLoop","detail":"{readStateIndex:2041; appliedIndex:2040; }","duration":"159.048793ms","start":"2026-04-24T15:15:44.793779Z","end":"2026-04-24T15:15:44.952827Z","steps":["trace[397084599] 'read index received' (duration: 158.893049ms)","trace[397084599] 'applied index is now lower than readState.Index' (duration: 154.834µs)"],"step_count":2} {"level":"info","ts":"2026-04-24T15:15:44.953119Z","caller":"traceutil/trace.go:171","msg":"trace[2114118638] transaction","detail":"{read_only:false; response_revision:1954; number_of_response:1; }","duration":"369.823624ms","start":"2026-04-24T15:15:44.583284Z","end":"2026-04-24T15:15:44.953108Z","steps":["trace[2114118638] 'process raft request' (duration: 369.411215ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:15:44.953261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:15:44.583268Z","time spent":"369.880265ms","remote":"127.0.0.1:59052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T15:15:44.953543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.761909ms","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-04-24T15:15:44.953575Z","caller":"traceutil/trace.go:171","msg":"trace[1683063790] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1954; }","duration":"159.79375ms","start":"2026-04-24T15:15:44.793771Z","end":"2026-04-24T15:15:44.953565Z","steps":["trace[1683063790] 'agreement among raft nodes before linearized reading' (duration: 159.681677ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:15:44.954383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.70843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-04-24T15:15:44.954433Z","caller":"traceutil/trace.go:171","msg":"trace[1356124318] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1955; }","duration":"145.796742ms","start":"2026-04-24T15:15:44.808625Z","end":"2026-04-24T15:15:44.954422Z","steps":["trace[1356124318] 'agreement among raft nodes before linearized reading' (duration: 145.668309ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:15:45.604118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.755786ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-24T15:15:45.604325Z","caller":"traceutil/trace.go:171","msg":"trace[1526406644] linearizableReadLoop","detail":"{readStateIndex:2044; appliedIndex:2042; }","duration":"603.748702ms","start":"2026-04-24T15:15:45.000553Z","end":"2026-04-24T15:15:45.604302Z","steps":["trace[1526406644] 'read index received' (duration: 434.72818ms)","trace[1526406644] 'applied index is now lower than readState.Index' (duration: 169.019612ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T15:15:45.604371Z","caller":"traceutil/trace.go:171","msg":"trace[1977891077] transaction","detail":"{read_only:false; response_revision:1956; number_of_response:1; }","duration":"646.005981ms","start":"2026-04-24T15:15:44.958306Z","end":"2026-04-24T15:15:45.604312Z","steps":["trace[1977891077] 'process raft request' (duration: 476.965428ms)","trace[1977891077] 'compare' (duration: 168.514441ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T15:15:45.604489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.457942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-24T15:15:45.604539Z","caller":"traceutil/trace.go:171","msg":"trace[1812579554] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1957; }","duration":"347.565114ms","start":"2026-04-24T15:15:45.256963Z","end":"2026-04-24T15:15:45.604528Z","steps":["trace[1812579554] 'agreement among raft nodes before linearized reading' (duration: 347.435051ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:15:45.604535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:15:44.958277Z","time spent":"646.179954ms","remote":"127.0.0.1:59052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T15:15:45.604582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:15:45.256939Z","time spent":"347.631896ms","remote":"127.0.0.1:59002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":45,"response size":30,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-04-24T15:15:45.604515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.938856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T15:15:45.604711Z","caller":"traceutil/trace.go:171","msg":"trace[1401567378] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1957; }","duration":"604.180122ms","start":"2026-04-24T15:15:45.000508Z","end":"2026-04-24T15:15:45.604688Z","steps":["trace[1401567378] 'agreement among raft nodes before linearized reading' (duration: 603.878775ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:15:45.605611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:15:45.000489Z","time spent":"605.102801ms","remote":"127.0.0.1:58934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"warn","ts":"2026-04-24T15:15:45.604797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.469058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T15:15:45.605942Z","caller":"traceutil/trace.go:171","msg":"trace[2129449250] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1957; }","duration":"244.596723ms","start":"2026-04-24T15:15:45.361298Z","end":"2026-04-24T15:15:45.605894Z","steps":["trace[2129449250] 'agreement among raft nodes before linearized reading' (duration: 243.473029ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:15:45.604953Z","caller":"traceutil/trace.go:171","msg":"trace[194435504] transaction","detail":"{read_only:false; response_revision:1957; number_of_response:1; }","duration":"646.094492ms","start":"2026-04-24T15:15:44.958846Z","end":"2026-04-24T15:15:45.604941Z","steps":["trace[194435504] 'process raft request' (duration: 645.374567ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:15:45.606089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:15:44.958827Z","time spent":"647.209437ms","remote":"127.0.0.1:59052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T15:15:46.205181Z","caller":"traceutil/trace.go:171","msg":"trace[948983259] transaction","detail":"{read_only:false; response_revision:1963; number_of_response:1; }","duration":"111.545119ms","start":"2026-04-24T15:15:46.093612Z","end":"2026-04-24T15:15:46.205157Z","steps":["trace[948983259] 'process raft request' (duration: 111.315544ms)"],"step_count":1}