{"level":"warn","ts":"2026-06-15T11:49:07.627251Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-15T11:49:07.627372Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.205: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.205:2380","--initial-cluster=instance=https://199.204.45.205:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.205:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.205: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-06-15T11:49:07.627461Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-15T11:49:07.62747Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.205:2380"]} {"level":"info","ts":"2026-06-15T11:49:07.627493Z","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-06-15T11:49:07.628251Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"]} {"level":"info","ts":"2026-06-15T11:49:07.62836Z","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":16,"max-cpu-available":16,"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.205:2380"],"listen-peer-urls":["https://199.204.45.205:2380"],"advertise-client-urls":["https://199.204.45.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.205: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-06-15T11:49:07.632915Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.268313ms"} {"level":"info","ts":"2026-06-15T11:49:07.640671Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"71d28e9ac1bc4e5d","cluster-id":"4a4ae660c7a9a6a7"} {"level":"info","ts":"2026-06-15T11:49:07.640881Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=()"} {"level":"info","ts":"2026-06-15T11:49:07.640934Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became follower at term 0"} {"level":"info","ts":"2026-06-15T11:49:07.640946Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 71d28e9ac1bc4e5d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-15T11:49:07.640957Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became follower at term 1"} {"level":"info","ts":"2026-06-15T11:49:07.641028Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=(8201774666721545821)"} {"level":"warn","ts":"2026-06-15T11:49:07.647923Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-15T11:49:07.649593Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-15T11:49:07.650727Z","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-06-15T11:49:07.653455Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"71d28e9ac1bc4e5d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-15T11:49:07.653635Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"71d28e9ac1bc4e5d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-15T11:49:07.653717Z","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-06-15T11:49:07.653833Z","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-06-15T11:49:07.653852Z","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-06-15T11:49:07.657243Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=(8201774666721545821)"} {"level":"info","ts":"2026-06-15T11:49:07.657462Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4a4ae660c7a9a6a7","local-member-id":"71d28e9ac1bc4e5d","added-peer-id":"71d28e9ac1bc4e5d","added-peer-peer-urls":["https://199.204.45.205:2380"]} {"level":"info","ts":"2026-06-15T11:49:07.65954Z","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-06-15T11:49:07.659725Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.205:2380"} {"level":"info","ts":"2026-06-15T11:49:07.659752Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.205:2380"} {"level":"info","ts":"2026-06-15T11:49:07.660108Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"71d28e9ac1bc4e5d","initial-advertise-peer-urls":["https://199.204.45.205:2380"],"listen-peer-urls":["https://199.204.45.205:2380"],"advertise-client-urls":["https://199.204.45.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-15T11:49:07.660173Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-15T11:49:07.942007Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d is starting a new election at term 1"} {"level":"info","ts":"2026-06-15T11:49:07.942084Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became pre-candidate at term 1"} {"level":"info","ts":"2026-06-15T11:49:07.942128Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d received MsgPreVoteResp from 71d28e9ac1bc4e5d at term 1"} {"level":"info","ts":"2026-06-15T11:49:07.942155Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became candidate at term 2"} {"level":"info","ts":"2026-06-15T11:49:07.942173Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d received MsgVoteResp from 71d28e9ac1bc4e5d at term 2"} {"level":"info","ts":"2026-06-15T11:49:07.942196Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became leader at term 2"} {"level":"info","ts":"2026-06-15T11:49:07.942216Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 71d28e9ac1bc4e5d elected leader 71d28e9ac1bc4e5d at term 2"} {"level":"info","ts":"2026-06-15T11:49:07.943196Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"71d28e9ac1bc4e5d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.205:2379]}","request-path":"/0/members/71d28e9ac1bc4e5d/attributes","cluster-id":"4a4ae660c7a9a6a7","publish-timeout":"7s"} {"level":"info","ts":"2026-06-15T11:49:07.943225Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-15T11:49:07.94322Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-15T11:49:07.943454Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-15T11:49:07.944245Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4a4ae660c7a9a6a7","local-member-id":"71d28e9ac1bc4e5d","cluster-version":"3.5"} {"level":"info","ts":"2026-06-15T11:49:07.94433Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-15T11:49:07.944304Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-15T11:49:07.944363Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-15T11:49:07.944388Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-15T11:49:07.947266Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-15T11:49:07.947578Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.205:2379"} {"level":"info","ts":"2026-06-15T11:49:09.830697Z","caller":"traceutil/trace.go:171","msg":"trace[1691135161] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"255.672706ms","start":"2026-06-15T11:49:09.574991Z","end":"2026-06-15T11:49:09.830664Z","steps":["trace[1691135161] 'process raft request' (duration: 168.709336ms)","trace[1691135161] 'compare' (duration: 86.830646ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T11:49:09.847831Z","caller":"traceutil/trace.go:171","msg":"trace[1866026385] linearizableReadLoop","detail":"{readStateIndex:11; appliedIndex:6; }","duration":"272.142195ms","start":"2026-06-15T11:49:09.575653Z","end":"2026-06-15T11:49:09.847795Z","steps":["trace[1866026385] 'read index received' (duration: 168.038889ms)","trace[1866026385] 'applied index is now lower than readState.Index' (duration: 104.101736ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T11:49:09.848028Z","caller":"traceutil/trace.go:171","msg":"trace[632925221] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"272.506864ms","start":"2026-06-15T11:49:09.575479Z","end":"2026-06-15T11:49:09.847986Z","steps":["trace[632925221] 'process raft request' (duration: 272.026592ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:49:09.84805Z","caller":"traceutil/trace.go:171","msg":"trace[1146911554] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"272.298159ms","start":"2026-06-15T11:49:09.575648Z","end":"2026-06-15T11:49:09.847947Z","steps":["trace[1146911554] 'process raft request' (duration: 272.014722ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:49:09.848201Z","caller":"traceutil/trace.go:171","msg":"trace[317078753] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"272.642857ms","start":"2026-06-15T11:49:09.575488Z","end":"2026-06-15T11:49:09.848131Z","steps":["trace[317078753] 'process raft request' (duration: 272.096524ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:49:09.848065Z","caller":"traceutil/trace.go:171","msg":"trace[900089229] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"263.30589ms","start":"2026-06-15T11:49:09.584716Z","end":"2026-06-15T11:49:09.848022Z","steps":["trace[900089229] 'process raft request' (duration: 263.046983ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:49:09.84796Z","caller":"traceutil/trace.go:171","msg":"trace[1001044152] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"263.892924ms","start":"2026-06-15T11:49:09.584035Z","end":"2026-06-15T11:49:09.847928Z","steps":["trace[1001044152] 'process raft request' (duration: 263.683719ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:49:09.848329Z","caller":"traceutil/trace.go:171","msg":"trace[1669285229] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"272.72795ms","start":"2026-06-15T11:49:09.575533Z","end":"2026-06-15T11:49:09.848261Z","steps":["trace[1669285229] 'process raft request' (duration: 272.094064ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:49:09.849134Z","caller":"traceutil/trace.go:171","msg":"trace[2071565187] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"272.664177ms","start":"2026-06-15T11:49:09.575338Z","end":"2026-06-15T11:49:09.848002Z","steps":["trace[2071565187] 'process raft request' (duration: 272.032812ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:49:09.849679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.650537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-15T11:49:09.849812Z","caller":"traceutil/trace.go:171","msg":"trace[1612595824] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"274.163884ms","start":"2026-06-15T11:49:09.575619Z","end":"2026-06-15T11:49:09.849783Z","steps":["trace[1612595824] 'agreement among raft nodes before linearized reading' (duration: 272.525614ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:49:09.857773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.121644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-15T11:49:09.857842Z","caller":"traceutil/trace.go:171","msg":"trace[869270761] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:12; }","duration":"232.239977ms","start":"2026-06-15T11:49:09.625582Z","end":"2026-06-15T11:49:09.857822Z","steps":["trace[869270761] 'agreement among raft nodes before linearized reading' (duration: 230.827212ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:49:09.857989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.999063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-15T11:49:09.85809Z","caller":"traceutil/trace.go:171","msg":"trace[212814533] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:12; }","duration":"111.154697ms","start":"2026-06-15T11:49:09.746908Z","end":"2026-06-15T11:49:09.858063Z","steps":["trace[212814533] 'agreement among raft nodes before linearized reading' (duration: 107.454688ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:49:09.858426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.694881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-15T11:49:09.858471Z","caller":"traceutil/trace.go:171","msg":"trace[1223995621] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:13; }","duration":"282.747402ms","start":"2026-06-15T11:49:09.57571Z","end":"2026-06-15T11:49:09.858457Z","steps":["trace[1223995621] 'agreement among raft nodes before linearized reading' (duration: 280.718543ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:49:26.440313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.437904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T11:49:26.440411Z","caller":"traceutil/trace.go:171","msg":"trace[1794953454] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:374; }","duration":"202.598098ms","start":"2026-06-15T11:49:26.23779Z","end":"2026-06-15T11:49:26.440388Z","steps":["trace[1794953454] 'range keys from in-memory index tree' (duration: 202.251659ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:49:26.696073Z","caller":"traceutil/trace.go:171","msg":"trace[522588931] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"114.309914ms","start":"2026-06-15T11:49:26.581732Z","end":"2026-06-15T11:49:26.696042Z","steps":["trace[522588931] 'process raft request' (duration: 114.067779ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:49:26.985791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.648419ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"warn","ts":"2026-06-15T11:49:51.703226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.284109ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-15T11:49:51.703436Z","caller":"traceutil/trace.go:171","msg":"trace[195981412] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"147.357955ms","start":"2026-06-15T11:49:51.556058Z","end":"2026-06-15T11:49:51.703416Z","steps":["trace[195981412] 'process raft request' (duration: 34.457261ms)","trace[195981412] 'compare' (duration: 112.091174ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T11:49:53.788405Z","caller":"traceutil/trace.go:171","msg":"trace[2089761347] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"195.064505ms","start":"2026-06-15T11:49:53.593299Z","end":"2026-06-15T11:49:53.788364Z","steps":["trace[2089761347] 'process raft request' (duration: 194.261616ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:49:53.957451Z","caller":"traceutil/trace.go:171","msg":"trace[81910467] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"101.716724ms","start":"2026-06-15T11:49:53.855701Z","end":"2026-06-15T11:49:53.957418Z","steps":["trace[81910467] 'process raft request' (duration: 67.885598ms)","trace[81910467] 'compare' (duration: 33.655082ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T11:49:54.612244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.948696ms","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-06-15T11:49:54.612432Z","caller":"traceutil/trace.go:171","msg":"trace[2042849735] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:596; }","duration":"283.152751ms","start":"2026-06-15T11:49:54.329253Z","end":"2026-06-15T11:49:54.612406Z","steps":["trace[2042849735] 'range keys from in-memory index tree' (duration: 282.927306ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:49:54.612473Z","caller":"traceutil/trace.go:171","msg":"trace[1476753892] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"596.146452ms","start":"2026-06-15T11:49:54.016289Z","end":"2026-06-15T11:49:54.612435Z","steps":["trace[1476753892] 'process raft request' (duration: 522.470375ms)","trace[1476753892] 'compare' (duration: 73.429261ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T11:49:54.612858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T11:49:54.016275Z","time spent":"596.282185ms","remote":"127.0.0.1:50864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T11:49:54.61384Z","caller":"traceutil/trace.go:171","msg":"trace[905802500] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"544.548997ms","start":"2026-06-15T11:49:54.069272Z","end":"2026-06-15T11:49:54.613821Z","steps":["trace[905802500] 'process raft request' (duration: 544.292571ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:49:54.61393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T11:49:54.069246Z","time spent":"544.640709ms","remote":"127.0.0.1:50864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T11:49:54.617969Z","caller":"traceutil/trace.go:171","msg":"trace[1044954611] linearizableReadLoop","detail":"{readStateIndex:626; appliedIndex:624; }","duration":"378.233055ms","start":"2026-06-15T11:49:54.239638Z","end":"2026-06-15T11:49:54.617871Z","steps":["trace[1044954611] 'read index received' (duration: 299.208419ms)","trace[1044954611] 'applied index is now lower than readState.Index' (duration: 79.022516ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T11:49:54.618278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.620414ms","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-06-15T11:49:54.618335Z","caller":"traceutil/trace.go:171","msg":"trace[884951327] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:599; }","duration":"378.741687ms","start":"2026-06-15T11:49:54.239566Z","end":"2026-06-15T11:49:54.618307Z","steps":["trace[884951327] 'agreement among raft nodes before linearized reading' (duration: 378.511991ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:49:54.618377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T11:49:54.239538Z","time spent":"378.827319ms","remote":"127.0.0.1:50864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-15T11:49:54.61901Z","caller":"traceutil/trace.go:171","msg":"trace[685173419] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"250.605025ms","start":"2026-06-15T11:49:54.368352Z","end":"2026-06-15T11:49:54.618957Z","steps":["trace[685173419] 'process raft request' (duration: 246.432335ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:51:05.169687Z","caller":"traceutil/trace.go:171","msg":"trace[691750184] linearizableReadLoop","detail":"{readStateIndex:1481; appliedIndex:1480; }","duration":"103.161744ms","start":"2026-06-15T11:51:05.066487Z","end":"2026-06-15T11:51:05.169648Z","steps":["trace[691750184] 'read index received' (duration: 102.839597ms)","trace[691750184] 'applied index is now lower than readState.Index' (duration: 320.817µs)"],"step_count":2} {"level":"info","ts":"2026-06-15T11:51:05.169756Z","caller":"traceutil/trace.go:171","msg":"trace[207787462] transaction","detail":"{read_only:false; response_revision:1430; number_of_response:1; }","duration":"123.142548ms","start":"2026-06-15T11:51:05.046556Z","end":"2026-06-15T11:51:05.169699Z","steps":["trace[207787462] 'process raft request' (duration: 122.84516ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:51:05.170037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.520572ms","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-06-15T11:51:05.17011Z","caller":"traceutil/trace.go:171","msg":"trace[353470675] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1430; }","duration":"103.687607ms","start":"2026-06-15T11:51:05.066404Z","end":"2026-06-15T11:51:05.170092Z","steps":["trace[353470675] 'agreement among raft nodes before linearized reading' (duration: 103.40749ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:51:05.306751Z","caller":"traceutil/trace.go:171","msg":"trace[1082765783] transaction","detail":"{read_only:false; response_revision:1431; number_of_response:1; }","duration":"131.284944ms","start":"2026-06-15T11:51:05.175431Z","end":"2026-06-15T11:51:05.306716Z","steps":["trace[1082765783] 'process raft request' (duration: 91.965584ms)","trace[1082765783] 'compare' (duration: 39.067934ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T11:51:06.953091Z","caller":"traceutil/trace.go:171","msg":"trace[726758847] transaction","detail":"{read_only:false; response_revision:1446; number_of_response:1; }","duration":"125.020033ms","start":"2026-06-15T11:51:06.828045Z","end":"2026-06-15T11:51:06.953065Z","steps":["trace[726758847] 'process raft request' (duration: 124.867779ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:51:33.525138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.016002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T11:51:33.525248Z","caller":"traceutil/trace.go:171","msg":"trace[1481979716] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1868; }","duration":"285.167186ms","start":"2026-06-15T11:51:33.240059Z","end":"2026-06-15T11:51:33.525226Z","steps":["trace[1481979716] 'range keys from in-memory index tree' (duration: 284.834408ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:51:33.525484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.041427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-15T11:51:33.525576Z","caller":"traceutil/trace.go:171","msg":"trace[1137959301] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1868; }","duration":"432.1826ms","start":"2026-06-15T11:51:33.093369Z","end":"2026-06-15T11:51:33.525552Z","steps":["trace[1137959301] 'range keys from in-memory index tree' (duration: 431.819462ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:51:33.525642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T11:51:33.093343Z","time spent":"432.278582ms","remote":"127.0.0.1:57718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-06-15T11:51:33.52584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.45552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-06-15T11:51:33.525821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.350386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3698bf13-507b-41a5-baf0-e28aa56f37e6\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-06-15T11:51:33.525886Z","caller":"traceutil/trace.go:171","msg":"trace[1454153702] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1868; }","duration":"153.557352ms","start":"2026-06-15T11:51:33.372318Z","end":"2026-06-15T11:51:33.525875Z","steps":["trace[1454153702] 'range keys from in-memory index tree' (duration: 153.251996ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:51:33.52592Z","caller":"traceutil/trace.go:171","msg":"trace[2135995586] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3698bf13-507b-41a5-baf0-e28aa56f37e6; range_end:; response_count:1; response_revision:1868; }","duration":"229.500699ms","start":"2026-06-15T11:51:33.296389Z","end":"2026-06-15T11:51:33.52589Z","steps":["trace[2135995586] 'range keys from in-memory index tree' (duration: 229.056768ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:51:34.003097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.542161ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T11:51:34.003398Z","caller":"traceutil/trace.go:171","msg":"trace[956243036] transaction","detail":"{read_only:false; response_revision:1871; number_of_response:1; }","duration":"472.854443ms","start":"2026-06-15T11:51:33.530521Z","end":"2026-06-15T11:51:34.003375Z","steps":["trace[956243036] 'process raft request' (duration: 472.698499ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:51:34.003423Z","caller":"traceutil/trace.go:171","msg":"trace[526705133] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"472.871993ms","start":"2026-06-15T11:51:33.530473Z","end":"2026-06-15T11:51:34.003345Z","steps":["trace[526705133] 'process raft request' (duration: 151.953673ms)","trace[526705133] 'compare' (duration: 320.334886ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T11:51:34.003476Z","caller":"traceutil/trace.go:171","msg":"trace[1260023380] linearizableReadLoop","detail":"{readStateIndex:1951; appliedIndex:1948; }","duration":"461.13336ms","start":"2026-06-15T11:51:33.542316Z","end":"2026-06-15T11:51:34.003449Z","steps":["trace[1260023380] 'read index received' (duration: 36.40751ms)","trace[1260023380] 'applied index is now lower than readState.Index' (duration: 424.72366ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T11:51:34.003498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T11:51:33.530493Z","time spent":"472.964426ms","remote":"127.0.0.1:50864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T11:51:34.003573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.253282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T11:51:34.003619Z","caller":"traceutil/trace.go:171","msg":"trace[1623139544] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1872; }","duration":"461.362326ms","start":"2026-06-15T11:51:33.54224Z","end":"2026-06-15T11:51:34.003603Z","steps":["trace[1623139544] 'agreement among raft nodes before linearized reading' (duration: 461.284064ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:51:34.003621Z","caller":"traceutil/trace.go:171","msg":"trace[753063012] transaction","detail":"{read_only:false; response_revision:1872; number_of_response:1; }","duration":"453.013593ms","start":"2026-06-15T11:51:33.550582Z","end":"2026-06-15T11:51:34.003596Z","steps":["trace[753063012] 'process raft request' (duration: 452.730307ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:51:34.00364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T11:51:33.53045Z","time spent":"473.061108ms","remote":"127.0.0.1:50864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T11:51:34.003661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T11:51:33.542222Z","time spent":"461.429377ms","remote":"127.0.0.1:36420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"warn","ts":"2026-06-15T11:51:34.003736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T11:51:33.550564Z","time spent":"453.109906ms","remote":"127.0.0.1:50864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T11:51:34.451317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.279152ms","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-06-15T11:51:34.451452Z","caller":"traceutil/trace.go:171","msg":"trace[1572636113] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1872; }","duration":"121.439946ms","start":"2026-06-15T11:51:34.32999Z","end":"2026-06-15T11:51:34.45143Z","steps":["trace[1572636113] 'range keys from in-memory index tree' (duration: 121.252062ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:51:34.451483Z","caller":"traceutil/trace.go:171","msg":"trace[1469106417] linearizableReadLoop","detail":"{readStateIndex:1953; appliedIndex:1952; }","duration":"397.138623ms","start":"2026-06-15T11:51:34.05431Z","end":"2026-06-15T11:51:34.451448Z","steps":["trace[1469106417] 'read index received' (duration: 333.808941ms)","trace[1469106417] 'applied index is now lower than readState.Index' (duration: 63.328182ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T11:51:34.451513Z","caller":"traceutil/trace.go:171","msg":"trace[803987100] transaction","detail":"{read_only:false; response_revision:1873; number_of_response:1; }","duration":"413.847377ms","start":"2026-06-15T11:51:34.037639Z","end":"2026-06-15T11:51:34.451487Z","steps":["trace[803987100] 'process raft request' (duration: 350.464094ms)","trace[803987100] 'compare' (duration: 63.035354ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T11:51:34.451655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T11:51:34.037619Z","time spent":"413.94745ms","remote":"127.0.0.1:50864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T11:51:34.451759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.43357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-15T11:51:34.451824Z","caller":"traceutil/trace.go:171","msg":"trace[520663970] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1873; }","duration":"397.517932ms","start":"2026-06-15T11:51:34.054285Z","end":"2026-06-15T11:51:34.451803Z","steps":["trace[520663970] 'agreement among raft nodes before linearized reading' (duration: 397.331418ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:51:34.451876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T11:51:34.054274Z","time spent":"397.590604ms","remote":"127.0.0.1:50864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-15T11:51:34.451958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.817916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-15T11:51:34.452111Z","caller":"traceutil/trace.go:171","msg":"trace[287130404] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1873; }","duration":"261.076593ms","start":"2026-06-15T11:51:34.190989Z","end":"2026-06-15T11:51:34.452066Z","steps":["trace[287130404] 'agreement among raft nodes before linearized reading' (duration: 260.686383ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:51:43.038394Z","caller":"traceutil/trace.go:171","msg":"trace[48093767] transaction","detail":"{read_only:false; response_revision:1949; number_of_response:1; }","duration":"216.452874ms","start":"2026-06-15T11:51:42.821912Z","end":"2026-06-15T11:51:43.038365Z","steps":["trace[48093767] 'process raft request' (duration: 216.269169ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:51:43.079168Z","caller":"traceutil/trace.go:171","msg":"trace[628574136] transaction","detail":"{read_only:false; response_revision:1950; number_of_response:1; }","duration":"141.558073ms","start":"2026-06-15T11:51:42.937575Z","end":"2026-06-15T11:51:43.079133Z","steps":["trace[628574136] 'process raft request' (duration: 141.377328ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:51:47.134831Z","caller":"traceutil/trace.go:171","msg":"trace[1174017655] transaction","detail":"{read_only:false; response_revision:1984; number_of_response:1; }","duration":"137.152097ms","start":"2026-06-15T11:51:46.997651Z","end":"2026-06-15T11:51:47.134803Z","steps":["trace[1174017655] 'process raft request' (duration: 136.972622ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:51:47.174827Z","caller":"traceutil/trace.go:171","msg":"trace[2076011682] transaction","detail":"{read_only:false; response_revision:1985; number_of_response:1; }","duration":"153.609764ms","start":"2026-06-15T11:51:47.021195Z","end":"2026-06-15T11:51:47.174805Z","steps":["trace[2076011682] 'process raft request' (duration: 153.363958ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:51:47.175178Z","caller":"traceutil/trace.go:171","msg":"trace[591084080] transaction","detail":"{read_only:false; response_revision:1986; number_of_response:1; }","duration":"124.07964ms","start":"2026-06-15T11:51:47.051067Z","end":"2026-06-15T11:51:47.175147Z","steps":["trace[591084080] 'process raft request' (duration: 123.66027ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:51:47.797444Z","caller":"traceutil/trace.go:171","msg":"trace[2077839488] transaction","detail":"{read_only:false; response_revision:1989; number_of_response:1; }","duration":"145.463398ms","start":"2026-06-15T11:51:47.651949Z","end":"2026-06-15T11:51:47.797412Z","steps":["trace[2077839488] 'process raft request' (duration: 145.250182ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:52:24.995183Z","caller":"traceutil/trace.go:171","msg":"trace[1516246627] linearizableReadLoop","detail":"{readStateIndex:2289; appliedIndex:2288; }","duration":"161.095617ms","start":"2026-06-15T11:52:24.83406Z","end":"2026-06-15T11:52:24.995156Z","steps":["trace[1516246627] 'read index received' (duration: 160.864911ms)","trace[1516246627] 'applied index is now lower than readState.Index' (duration: 229.016µs)"],"step_count":2} {"level":"info","ts":"2026-06-15T11:52:24.99524Z","caller":"traceutil/trace.go:171","msg":"trace[578760613] transaction","detail":"{read_only:false; response_revision:2197; number_of_response:1; }","duration":"196.232759ms","start":"2026-06-15T11:52:24.798973Z","end":"2026-06-15T11:52:24.995206Z","steps":["trace[578760613] 'process raft request' (duration: 195.982323ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:52:24.995475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.367293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T11:52:24.995607Z","caller":"traceutil/trace.go:171","msg":"trace[1169535258] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:2197; }","duration":"161.529817ms","start":"2026-06-15T11:52:24.834047Z","end":"2026-06-15T11:52:24.995577Z","steps":["trace[1169535258] 'agreement among raft nodes before linearized reading' (duration: 161.269771ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:52:25.526128Z","caller":"traceutil/trace.go:171","msg":"trace[1761694706] transaction","detail":"{read_only:false; response_revision:2199; number_of_response:1; }","duration":"131.451458ms","start":"2026-06-15T11:52:25.394645Z","end":"2026-06-15T11:52:25.526097Z","steps":["trace[1761694706] 'process raft request' (duration: 131.248393ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:52:25.725145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.202327ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T11:52:25.725367Z","caller":"traceutil/trace.go:171","msg":"trace[1680206483] linearizableReadLoop","detail":"{readStateIndex:2292; appliedIndex:2290; }","duration":"259.856682ms","start":"2026-06-15T11:52:25.465484Z","end":"2026-06-15T11:52:25.725341Z","steps":["trace[1680206483] 'read index received' (duration: 60.545938ms)","trace[1680206483] 'applied index is now lower than readState.Index' (duration: 199.308924ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T11:52:25.725568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.087508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18b93eedecffad41\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-06-15T11:52:25.725649Z","caller":"traceutil/trace.go:171","msg":"trace[13887312] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18b93eedecffad41; range_end:; response_count:1; response_revision:2200; }","duration":"260.226411ms","start":"2026-06-15T11:52:25.465409Z","end":"2026-06-15T11:52:25.725635Z","steps":["trace[13887312] 'agreement among raft nodes before linearized reading' (duration: 260.034556ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:52:25.725505Z","caller":"traceutil/trace.go:171","msg":"trace[843638434] transaction","detail":"{read_only:false; response_revision:2200; number_of_response:1; }","duration":"319.57099ms","start":"2026-06-15T11:52:25.405816Z","end":"2026-06-15T11:52:25.725387Z","steps":["trace[843638434] 'process raft request' (duration: 201.005174ms)","trace[843638434] 'compare' (duration: 118.061334ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T11:52:25.725934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T11:52:25.405785Z","time spent":"320.035331ms","remote":"127.0.0.1:50864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T11:52:25.974537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.146659ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T11:52:25.974653Z","caller":"traceutil/trace.go:171","msg":"trace[580015304] linearizableReadLoop","detail":"{readStateIndex:2293; appliedIndex:2292; }","duration":"174.455231ms","start":"2026-06-15T11:52:25.80018Z","end":"2026-06-15T11:52:25.974636Z","steps":["trace[580015304] 'read index received' (duration: 62.111767ms)","trace[580015304] 'applied index is now lower than readState.Index' (duration: 112.342174ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T11:52:25.974925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.737567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12386"} {"level":"info","ts":"2026-06-15T11:52:25.974991Z","caller":"traceutil/trace.go:171","msg":"trace[1521319277] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2201; }","duration":"174.904841ms","start":"2026-06-15T11:52:25.800073Z","end":"2026-06-15T11:52:25.974978Z","steps":["trace[1521319277] 'agreement among raft nodes before linearized reading' (duration: 174.686516ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:52:25.974818Z","caller":"traceutil/trace.go:171","msg":"trace[752887625] transaction","detail":"{read_only:false; response_revision:2201; number_of_response:1; }","duration":"246.098728ms","start":"2026-06-15T11:52:25.72865Z","end":"2026-06-15T11:52:25.974749Z","steps":["trace[752887625] 'process raft request' (duration: 133.660491ms)","trace[752887625] 'compare' (duration: 111.975636ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T11:53:21.615322Z","caller":"traceutil/trace.go:171","msg":"trace[791709354] transaction","detail":"{read_only:false; response_revision:2670; number_of_response:1; }","duration":"255.833261ms","start":"2026-06-15T11:53:21.35945Z","end":"2026-06-15T11:53:21.615284Z","steps":["trace[791709354] 'process raft request' (duration: 255.660167ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:53:21.616135Z","caller":"traceutil/trace.go:171","msg":"trace[1328914890] transaction","detail":"{read_only:false; response_revision:2671; number_of_response:1; }","duration":"255.860902ms","start":"2026-06-15T11:53:21.360245Z","end":"2026-06-15T11:53:21.616106Z","steps":["trace[1328914890] 'process raft request' (duration: 255.683568ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:53:53.294715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.758652ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T11:53:53.294879Z","caller":"traceutil/trace.go:171","msg":"trace[628127156] transaction","detail":"{read_only:false; response_revision:2864; number_of_response:1; }","duration":"379.114025ms","start":"2026-06-15T11:53:52.915737Z","end":"2026-06-15T11:53:53.294851Z","steps":["trace[628127156] 'process raft request' (duration: 48.082976ms)","trace[628127156] 'compare' (duration: 330.25451ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T11:53:53.295114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T11:53:52.915703Z","time spent":"379.358961ms","remote":"127.0.0.1:50864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T11:53:53.331928Z","caller":"traceutil/trace.go:171","msg":"trace[58410222] transaction","detail":"{read_only:false; response_revision:2865; number_of_response:1; }","duration":"253.864693ms","start":"2026-06-15T11:53:53.078035Z","end":"2026-06-15T11:53:53.3319Z","steps":["trace[58410222] 'process raft request' (duration: 253.74526ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:53:58.900729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.702977ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T11:53:58.901172Z","caller":"traceutil/trace.go:171","msg":"trace[2024603843] transaction","detail":"{read_only:false; response_revision:2893; number_of_response:1; }","duration":"312.380006ms","start":"2026-06-15T11:53:58.588743Z","end":"2026-06-15T11:53:58.901123Z","steps":["trace[2024603843] 'process raft request' (duration: 152.182037ms)","trace[2024603843] 'compare' (duration: 159.547143ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T11:53:58.901384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T11:53:58.588711Z","time spent":"312.559711ms","remote":"127.0.0.1:50864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T11:53:59.07672Z","caller":"traceutil/trace.go:171","msg":"trace[1090501561] transaction","detail":"{read_only:false; response_revision:2894; number_of_response:1; }","duration":"170.403305ms","start":"2026-06-15T11:53:58.906284Z","end":"2026-06-15T11:53:59.076687Z","steps":["trace[1090501561] 'process raft request' (duration: 147.879233ms)","trace[1090501561] 'compare' (duration: 22.374628ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T11:53:59.105509Z","caller":"traceutil/trace.go:171","msg":"trace[1943510107] transaction","detail":"{read_only:false; response_revision:2895; number_of_response:1; }","duration":"198.071409ms","start":"2026-06-15T11:53:58.907388Z","end":"2026-06-15T11:53:59.105459Z","steps":["trace[1943510107] 'process raft request' (duration: 197.927686ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:55:51.844693Z","caller":"traceutil/trace.go:171","msg":"trace[390514392] transaction","detail":"{read_only:false; response_revision:3488; number_of_response:1; }","duration":"113.644855ms","start":"2026-06-15T11:55:51.731005Z","end":"2026-06-15T11:55:51.84465Z","steps":["trace[390514392] 'process raft request' (duration: 100.036137ms)","trace[390514392] 'compare' (duration: 13.144147ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T11:56:10.536561Z","caller":"traceutil/trace.go:171","msg":"trace[30604517] transaction","detail":"{read_only:false; response_revision:3755; number_of_response:1; }","duration":"105.647157ms","start":"2026-06-15T11:56:10.430853Z","end":"2026-06-15T11:56:10.5365Z","steps":["trace[30604517] 'process raft request' (duration: 87.069997ms)","trace[30604517] 'compare' (duration: 18.119639ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T11:56:10.536595Z","caller":"traceutil/trace.go:171","msg":"trace[1444853921] transaction","detail":"{read_only:false; response_revision:3756; number_of_response:1; }","duration":"104.416627ms","start":"2026-06-15T11:56:10.43214Z","end":"2026-06-15T11:56:10.536556Z","steps":["trace[1444853921] 'process raft request' (duration: 104.244943ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:56:39.628664Z","caller":"traceutil/trace.go:171","msg":"trace[18876123] transaction","detail":"{read_only:false; response_revision:3938; number_of_response:1; }","duration":"195.656205ms","start":"2026-06-15T11:56:39.432978Z","end":"2026-06-15T11:56:39.628634Z","steps":["trace[18876123] 'process raft request' (duration: 195.408869ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:58:03.993669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.080211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T11:58:03.993788Z","caller":"traceutil/trace.go:171","msg":"trace[2032439009] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:4824; }","duration":"101.289087ms","start":"2026-06-15T11:58:03.892474Z","end":"2026-06-15T11:58:03.993763Z","steps":["trace[2032439009] 'count revisions from in-memory index tree' (duration: 100.976129ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:58:12.665202Z","caller":"traceutil/trace.go:171","msg":"trace[1449603980] transaction","detail":"{read_only:false; response_revision:5077; number_of_response:1; }","duration":"137.892874ms","start":"2026-06-15T11:58:12.527266Z","end":"2026-06-15T11:58:12.665159Z","steps":["trace[1449603980] 'process raft request' (duration: 117.012917ms)","trace[1449603980] 'compare' (duration: 20.702692ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T11:58:12.669845Z","caller":"traceutil/trace.go:171","msg":"trace[1494009003] transaction","detail":"{read_only:false; response_revision:5078; number_of_response:1; }","duration":"140.958188ms","start":"2026-06-15T11:58:12.528848Z","end":"2026-06-15T11:58:12.669806Z","steps":["trace[1494009003] 'process raft request' (duration: 140.729703ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:58:12.66989Z","caller":"traceutil/trace.go:171","msg":"trace[821263161] linearizableReadLoop","detail":"{readStateIndex:5281; appliedIndex:5278; }","duration":"108.896031ms","start":"2026-06-15T11:58:12.560964Z","end":"2026-06-15T11:58:12.66986Z","steps":["trace[821263161] 'read index received' (duration: 83.331591ms)","trace[821263161] 'applied index is now lower than readState.Index' (duration: 25.56262ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T11:58:12.670143Z","caller":"traceutil/trace.go:171","msg":"trace[1630051316] transaction","detail":"{read_only:false; response_revision:5079; number_of_response:1; }","duration":"141.282476ms","start":"2026-06-15T11:58:12.528805Z","end":"2026-06-15T11:58:12.670088Z","steps":["trace[1630051316] 'process raft request' (duration: 140.910397ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:58:12.670779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.791083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-06-15T11:58:12.6709Z","caller":"traceutil/trace.go:171","msg":"trace[1007064324] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5080; }","duration":"110.013688ms","start":"2026-06-15T11:58:12.560863Z","end":"2026-06-15T11:58:12.670877Z","steps":["trace[1007064324] 'agreement among raft nodes before linearized reading' (duration: 109.428604ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:58:12.845433Z","caller":"traceutil/trace.go:171","msg":"trace[2088915482] transaction","detail":"{read_only:false; response_revision:5090; number_of_response:1; }","duration":"100.442075ms","start":"2026-06-15T11:58:12.744944Z","end":"2026-06-15T11:58:12.845386Z","steps":["trace[2088915482] 'process raft request' (duration: 100.399084ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:58:12.845677Z","caller":"traceutil/trace.go:171","msg":"trace[1553373412] transaction","detail":"{read_only:false; response_revision:5088; number_of_response:1; }","duration":"101.642685ms","start":"2026-06-15T11:58:12.743992Z","end":"2026-06-15T11:58:12.845635Z","steps":["trace[1553373412] 'process raft request' (duration: 101.240805ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:58:12.845829Z","caller":"traceutil/trace.go:171","msg":"trace[987851882] transaction","detail":"{read_only:false; response_revision:5089; number_of_response:1; }","duration":"101.219055ms","start":"2026-06-15T11:58:12.744597Z","end":"2026-06-15T11:58:12.845816Z","steps":["trace[987851882] 'process raft request' (duration: 100.680882ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:58:12.84593Z","caller":"traceutil/trace.go:171","msg":"trace[528037142] transaction","detail":"{read_only:false; response_revision:5087; number_of_response:1; }","duration":"102.202188ms","start":"2026-06-15T11:58:12.743712Z","end":"2026-06-15T11:58:12.845914Z","steps":["trace[528037142] 'process raft request' (duration: 101.342937ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:58:13.148135Z","caller":"traceutil/trace.go:171","msg":"trace[1006746987] linearizableReadLoop","detail":"{readStateIndex:5307; appliedIndex:5305; }","duration":"148.606113ms","start":"2026-06-15T11:58:12.999494Z","end":"2026-06-15T11:58:13.1481Z","steps":["trace[1006746987] 'read index received' (duration: 148.48154ms)","trace[1006746987] 'applied index is now lower than readState.Index' (duration: 123.283µs)"],"step_count":2} {"level":"warn","ts":"2026-06-15T11:58:13.148375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.671696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T11:58:13.148427Z","caller":"traceutil/trace.go:171","msg":"trace[1973862671] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:5105; }","duration":"207.801829ms","start":"2026-06-15T11:58:12.940611Z","end":"2026-06-15T11:58:13.148413Z","steps":["trace[1973862671] 'agreement among raft nodes before linearized reading' (duration: 207.651975ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:58:13.148511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.202949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/loki-gateway-cf54cb88c-ln9mh\" ","response":"range_response_count:1 size:3534"} {"level":"info","ts":"2026-06-15T11:58:13.148602Z","caller":"traceutil/trace.go:171","msg":"trace[1620436204] range","detail":"{range_begin:/registry/pods/monitoring/loki-gateway-cf54cb88c-ln9mh; range_end:; response_count:1; response_revision:5105; }","duration":"161.365013ms","start":"2026-06-15T11:58:12.987215Z","end":"2026-06-15T11:58:13.14858Z","steps":["trace[1620436204] 'agreement among raft nodes before linearized reading' (duration: 161.042415ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:58:13.148884Z","caller":"traceutil/trace.go:171","msg":"trace[2008869813] transaction","detail":"{read_only:false; response_revision:5105; number_of_response:1; }","duration":"164.049738ms","start":"2026-06-15T11:58:12.984825Z","end":"2026-06-15T11:58:13.148874Z","steps":["trace[2008869813] 'process raft request' (duration: 163.193187ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:58:13.148932Z","caller":"traceutil/trace.go:171","msg":"trace[891240929] transaction","detail":"{read_only:false; response_revision:5104; number_of_response:1; }","duration":"164.730365ms","start":"2026-06-15T11:58:12.984187Z","end":"2026-06-15T11:58:13.148917Z","steps":["trace[891240929] 'process raft request' (duration: 163.68821ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:58:13.387029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.910912ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T11:58:13.387318Z","caller":"traceutil/trace.go:171","msg":"trace[801747871] transaction","detail":"{read_only:false; response_revision:5106; number_of_response:1; }","duration":"382.372913ms","start":"2026-06-15T11:58:13.004859Z","end":"2026-06-15T11:58:13.387232Z","steps":["trace[801747871] 'process raft request' (duration: 242.132472ms)","trace[801747871] 'compare' (duration: 138.96572ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T11:58:13.387547Z","caller":"traceutil/trace.go:171","msg":"trace[1670449262] transaction","detail":"{read_only:false; response_revision:5107; number_of_response:1; }","duration":"381.227155ms","start":"2026-06-15T11:58:13.00629Z","end":"2026-06-15T11:58:13.387517Z","steps":["trace[1670449262] 'process raft request' (duration: 380.861877ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:58:13.387567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T11:58:13.004785Z","time spent":"382.65351ms","remote":"127.0.0.1:50768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3416,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T11:58:13.387652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T11:58:13.006259Z","time spent":"381.349428ms","remote":"127.0.0.1:50768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3406,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T11:58:13.387665Z","caller":"traceutil/trace.go:171","msg":"trace[460071719] transaction","detail":"{read_only:false; response_revision:5108; number_of_response:1; }","duration":"381.133403ms","start":"2026-06-15T11:58:13.006484Z","end":"2026-06-15T11:58:13.387617Z","steps":["trace[460071719] 'process raft request' (duration: 380.907267ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:58:13.387834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T11:58:13.006434Z","time spent":"381.317277ms","remote":"127.0.0.1:50768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4641,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-15T11:58:13.388006Z","caller":"traceutil/trace.go:171","msg":"trace[2027767692] transaction","detail":"{read_only:false; response_revision:5109; number_of_response:1; }","duration":"381.517362ms","start":"2026-06-15T11:58:13.006474Z","end":"2026-06-15T11:58:13.387992Z","steps":["trace[2027767692] 'process raft request' (duration: 380.961929ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:58:13.38808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T11:58:13.006445Z","time spent":"381.599704ms","remote":"127.0.0.1:50906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1364,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T11:58:13.388273Z","caller":"traceutil/trace.go:171","msg":"trace[1295589236] transaction","detail":"{read_only:false; response_revision:5110; number_of_response:1; }","duration":"381.585373ms","start":"2026-06-15T11:58:13.006625Z","end":"2026-06-15T11:58:13.38821Z","steps":["trace[1295589236] 'process raft request' (duration: 381.272666ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:58:13.388447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T11:58:13.006598Z","time spent":"381.763468ms","remote":"127.0.0.1:50656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":692,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-15T11:58:13.388763Z","caller":"traceutil/trace.go:171","msg":"trace[1024953051] linearizableReadLoop","detail":"{readStateIndex:5317; appliedIndex:5307; }","duration":"240.519982ms","start":"2026-06-15T11:58:13.148228Z","end":"2026-06-15T11:58:13.388748Z","steps":["trace[1024953051] 'read index received' (duration: 98.779695ms)","trace[1024953051] 'applied index is now lower than readState.Index' (duration: 141.738967ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T11:58:13.388885Z","caller":"traceutil/trace.go:171","msg":"trace[247600974] transaction","detail":"{read_only:false; response_revision:5113; number_of_response:1; }","duration":"380.106637ms","start":"2026-06-15T11:58:13.008763Z","end":"2026-06-15T11:58:13.388869Z","steps":["trace[247600974] 'process raft request' (duration: 379.80209ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:58:13.388947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T11:58:13.008754Z","time spent":"380.155649ms","remote":"127.0.0.1:38938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4601,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T11:58:13.389045Z","caller":"traceutil/trace.go:171","msg":"trace[2077082980] transaction","detail":"{read_only:false; response_revision:5114; number_of_response:1; }","duration":"379.431212ms","start":"2026-06-15T11:58:13.009574Z","end":"2026-06-15T11:58:13.389005Z","steps":["trace[2077082980] 'process raft request' (duration: 379.057852ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:58:13.389121Z","caller":"traceutil/trace.go:171","msg":"trace[1462992655] transaction","detail":"{read_only:false; response_revision:5112; number_of_response:1; }","duration":"381.240456ms","start":"2026-06-15T11:58:13.007851Z","end":"2026-06-15T11:58:13.389092Z","steps":["trace[1462992655] 'process raft request' (duration: 380.666752ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:58:13.389219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T11:58:13.009564Z","time spent":"379.574225ms","remote":"127.0.0.1:50656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":730,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-15T11:58:13.389268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T11:58:13.007836Z","time spent":"381.341848ms","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3774,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T11:58:13.389315Z","caller":"traceutil/trace.go:171","msg":"trace[75254294] transaction","detail":"{read_only:false; response_revision:5115; number_of_response:1; }","duration":"324.822737ms","start":"2026-06-15T11:58:13.064477Z","end":"2026-06-15T11:58:13.3893Z","steps":["trace[75254294] 'process raft request' (duration: 324.198872ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:58:13.389363Z","caller":"traceutil/trace.go:171","msg":"trace[270293612] transaction","detail":"{read_only:false; response_revision:5111; number_of_response:1; }","duration":"382.285031ms","start":"2026-06-15T11:58:13.007065Z","end":"2026-06-15T11:58:13.38935Z","steps":["trace[270293612] 'process raft request' (duration: 380.885737ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:58:13.389382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T11:58:13.064447Z","time spent":"324.899599ms","remote":"127.0.0.1:50864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T11:58:13.389475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T11:58:13.007057Z","time spent":"382.330772ms","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3763,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T11:58:13.389633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.053984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:728651"} {"level":"info","ts":"2026-06-15T11:58:13.389691Z","caller":"traceutil/trace.go:171","msg":"trace[1905294364] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:5115; }","duration":"309.155037ms","start":"2026-06-15T11:58:13.080521Z","end":"2026-06-15T11:58:13.389676Z","steps":["trace[1905294364] 'agreement among raft nodes before linearized reading' (duration: 308.547172ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:58:13.389728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T11:58:13.080504Z","time spent":"309.210219ms","remote":"127.0.0.1:50664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":24,"response size":728673,"request content":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" "} {"level":"info","ts":"2026-06-15T11:58:13.482801Z","caller":"traceutil/trace.go:171","msg":"trace[304610444] transaction","detail":"{read_only:false; response_revision:5116; number_of_response:1; }","duration":"325.201347ms","start":"2026-06-15T11:58:13.157569Z","end":"2026-06-15T11:58:13.48277Z","steps":["trace[304610444] 'process raft request' (duration: 325.056283ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:58:13.48298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T11:58:13.157543Z","time spent":"325.34218ms","remote":"127.0.0.1:50768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4429,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T11:58:13.511328Z","caller":"traceutil/trace.go:171","msg":"trace[602344899] transaction","detail":"{read_only:false; response_revision:5118; number_of_response:1; }","duration":"118.274978ms","start":"2026-06-15T11:58:13.393017Z","end":"2026-06-15T11:58:13.511292Z","steps":["trace[602344899] 'process raft request' (duration: 118.100564ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:58:13.511366Z","caller":"traceutil/trace.go:171","msg":"trace[1638754926] transaction","detail":"{read_only:false; response_revision:5117; number_of_response:1; }","duration":"225.592631ms","start":"2026-06-15T11:58:13.285726Z","end":"2026-06-15T11:58:13.511319Z","steps":["trace[1638754926] 'process raft request' (duration: 225.204901ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:58:13.511611Z","caller":"traceutil/trace.go:171","msg":"trace[1149814100] transaction","detail":"{read_only:false; response_revision:5119; number_of_response:1; }","duration":"117.227543ms","start":"2026-06-15T11:58:13.394341Z","end":"2026-06-15T11:58:13.511568Z","steps":["trace[1149814100] 'process raft request' (duration: 116.857734ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:58:13.511786Z","caller":"traceutil/trace.go:171","msg":"trace[920819338] transaction","detail":"{read_only:false; number_of_response:1; response_revision:5119; }","duration":"116.355982ms","start":"2026-06-15T11:58:13.395412Z","end":"2026-06-15T11:58:13.511768Z","steps":["trace[920819338] 'process raft request' (duration: 116.097995ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:58:13.511821Z","caller":"traceutil/trace.go:171","msg":"trace[1012360965] transaction","detail":"{read_only:false; response_revision:5120; number_of_response:1; }","duration":"115.664545ms","start":"2026-06-15T11:58:13.396143Z","end":"2026-06-15T11:58:13.511807Z","steps":["trace[1012360965] 'process raft request' (duration: 115.46524ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:58:13.51191Z","caller":"traceutil/trace.go:171","msg":"trace[1003237499] transaction","detail":"{read_only:false; response_revision:5121; number_of_response:1; }","duration":"114.416755ms","start":"2026-06-15T11:58:13.39748Z","end":"2026-06-15T11:58:13.511897Z","steps":["trace[1003237499] 'process raft request' (duration: 114.250061ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:58:13.511975Z","caller":"traceutil/trace.go:171","msg":"trace[783603327] linearizableReadLoop","detail":"{readStateIndex:5319; appliedIndex:5318; }","duration":"123.153926ms","start":"2026-06-15T11:58:13.388803Z","end":"2026-06-15T11:58:13.511957Z","steps":["trace[783603327] 'read index received' (duration: 93.967319ms)","trace[783603327] 'applied index is now lower than readState.Index' (duration: 29.185817ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T11:58:13.512082Z","caller":"traceutil/trace.go:171","msg":"trace[2049335480] transaction","detail":"{read_only:false; response_revision:5123; number_of_response:1; }","duration":"112.776525ms","start":"2026-06-15T11:58:13.399295Z","end":"2026-06-15T11:58:13.512072Z","steps":["trace[2049335480] 'process raft request' (duration: 112.56352ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:58:13.512185Z","caller":"traceutil/trace.go:171","msg":"trace[1034533590] transaction","detail":"{read_only:false; response_revision:5122; number_of_response:1; }","duration":"112.98496ms","start":"2026-06-15T11:58:13.399187Z","end":"2026-06-15T11:58:13.512171Z","steps":["trace[1034533590] 'process raft request' (duration: 112.623901ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:58:13.512327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.412722ms","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":"warn","ts":"2026-06-15T11:58:13.512369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.833949ms","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-06-15T11:58:13.512375Z","caller":"traceutil/trace.go:171","msg":"trace[613021031] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5124; }","duration":"111.516654ms","start":"2026-06-15T11:58:13.400847Z","end":"2026-06-15T11:58:13.512364Z","steps":["trace[613021031] 'agreement among raft nodes before linearized reading' (duration: 111.33484ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:58:13.512404Z","caller":"traceutil/trace.go:171","msg":"trace[1496904133] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5124; }","duration":"108.938182ms","start":"2026-06-15T11:58:13.403452Z","end":"2026-06-15T11:58:13.51239Z","steps":["trace[1496904133] 'agreement among raft nodes before linearized reading' (duration: 108.84686ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:58:13.512393Z","caller":"traceutil/trace.go:171","msg":"trace[1319354443] transaction","detail":"{read_only:false; response_revision:5124; number_of_response:1; }","duration":"113.01715ms","start":"2026-06-15T11:58:13.399351Z","end":"2026-06-15T11:58:13.512368Z","steps":["trace[1319354443] 'process raft request' (duration: 112.562949ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:58:13.51249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.007901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/loki\" ","response":"range_response_count:1 size:800"} {"level":"info","ts":"2026-06-15T11:58:13.512547Z","caller":"traceutil/trace.go:171","msg":"trace[1080710522] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/loki; range_end:; response_count:1; response_revision:5124; }","duration":"332.119274ms","start":"2026-06-15T11:58:13.180417Z","end":"2026-06-15T11:58:13.512537Z","steps":["trace[1080710522] 'agreement among raft nodes before linearized reading' (duration: 332.005132ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:58:13.512592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T11:58:13.180388Z","time spent":"332.192176ms","remote":"127.0.0.1:50782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":822,"request content":"key:\"/registry/serviceaccounts/monitoring/loki\" "} {"level":"warn","ts":"2026-06-15T11:58:13.512303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.898022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-vpjmq.18b93f44c17fa8fc\" ","response":"range_response_count:1 size:962"} {"level":"info","ts":"2026-06-15T11:58:13.51268Z","caller":"traceutil/trace.go:171","msg":"trace[2114325503] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-vpjmq.18b93f44c17fa8fc; range_end:; response_count:1; response_revision:5124; }","duration":"361.344723ms","start":"2026-06-15T11:58:13.151319Z","end":"2026-06-15T11:58:13.512664Z","steps":["trace[2114325503] 'agreement among raft nodes before linearized reading' (duration: 360.794429ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:58:13.512746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T11:58:13.151301Z","time spent":"361.409475ms","remote":"127.0.0.1:50656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":1,"response size":984,"request content":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-vpjmq.18b93f44c17fa8fc\" "} {"level":"warn","ts":"2026-06-15T11:58:16.517347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.146844ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-06-15T11:58:19.693316Z","caller":"traceutil/trace.go:171","msg":"trace[2042860540] linearizableReadLoop","detail":"{readStateIndex:5467; appliedIndex:5466; }","duration":"145.322498ms","start":"2026-06-15T11:58:19.547965Z","end":"2026-06-15T11:58:19.693287Z","steps":["trace[2042860540] 'read index received' (duration: 145.220895ms)","trace[2042860540] 'applied index is now lower than readState.Index' (duration: 100.643µs)"],"step_count":2} {"level":"info","ts":"2026-06-15T11:58:19.693423Z","caller":"traceutil/trace.go:171","msg":"trace[1431976080] transaction","detail":"{read_only:false; response_revision:5259; number_of_response:1; }","duration":"160.422235ms","start":"2026-06-15T11:58:19.532978Z","end":"2026-06-15T11:58:19.693401Z","steps":["trace[1431976080] 'process raft request' (duration: 160.127247ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:58:19.693565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.591885ms","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-06-15T11:58:19.693591Z","caller":"traceutil/trace.go:171","msg":"trace[750771932] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5259; }","duration":"145.698907ms","start":"2026-06-15T11:58:19.547885Z","end":"2026-06-15T11:58:19.693584Z","steps":["trace[750771932] 'agreement among raft nodes before linearized reading' (duration: 145.535513ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:58:19.693976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.967382ms","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-06-15T11:58:19.694083Z","caller":"traceutil/trace.go:171","msg":"trace[1545588917] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5259; }","duration":"136.154196ms","start":"2026-06-15T11:58:19.557906Z","end":"2026-06-15T11:58:19.69406Z","steps":["trace[1545588917] 'agreement among raft nodes before linearized reading' (duration: 135.830898ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:58:28.95036Z","caller":"traceutil/trace.go:171","msg":"trace[606779309] transaction","detail":"{read_only:false; response_revision:5414; number_of_response:1; }","duration":"118.764014ms","start":"2026-06-15T11:58:28.831559Z","end":"2026-06-15T11:58:28.950323Z","steps":["trace[606779309] 'process raft request' (duration: 118.423996ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:58:28.95036Z","caller":"traceutil/trace.go:171","msg":"trace[65023840] linearizableReadLoop","detail":"{readStateIndex:5627; appliedIndex:5626; }","duration":"113.464325ms","start":"2026-06-15T11:58:28.83686Z","end":"2026-06-15T11:58:28.950324Z","steps":["trace[65023840] 'read index received' (duration: 113.051115ms)","trace[65023840] 'applied index is now lower than readState.Index' (duration: 411.9µs)"],"step_count":2} {"level":"warn","ts":"2026-06-15T11:58:28.950549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.658709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-06-15T11:58:28.950647Z","caller":"traceutil/trace.go:171","msg":"trace[453602429] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5414; }","duration":"113.777042ms","start":"2026-06-15T11:58:28.836853Z","end":"2026-06-15T11:58:28.95063Z","steps":["trace[453602429] 'agreement among raft nodes before linearized reading' (duration: 113.515536ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:58:29.111308Z","caller":"traceutil/trace.go:171","msg":"trace[1587777911] linearizableReadLoop","detail":"{readStateIndex:5632; appliedIndex:5628; }","duration":"138.635196ms","start":"2026-06-15T11:58:28.972644Z","end":"2026-06-15T11:58:29.111279Z","steps":["trace[1587777911] 'read index received' (duration: 9.270545ms)","trace[1587777911] 'applied index is now lower than readState.Index' (duration: 129.363871ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T11:58:29.111438Z","caller":"traceutil/trace.go:171","msg":"trace[1767944389] transaction","detail":"{read_only:false; response_revision:5418; number_of_response:1; }","duration":"147.471031ms","start":"2026-06-15T11:58:28.963899Z","end":"2026-06-15T11:58:29.11137Z","steps":["trace[1767944389] 'process raft request' (duration: 85.131627ms)","trace[1767944389] 'compare' (duration: 62.003626ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T11:58:29.111458Z","caller":"traceutil/trace.go:171","msg":"trace[1196332309] transaction","detail":"{read_only:false; response_revision:5419; number_of_response:1; }","duration":"147.207624ms","start":"2026-06-15T11:58:28.964205Z","end":"2026-06-15T11:58:29.111413Z","steps":["trace[1196332309] 'process raft request' (duration: 147.006669ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:58:29.111511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.840511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T11:58:29.111727Z","caller":"traceutil/trace.go:171","msg":"trace[1611587632] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:5419; }","duration":"139.145658ms","start":"2026-06-15T11:58:28.972563Z","end":"2026-06-15T11:58:29.111709Z","steps":["trace[1611587632] 'agreement among raft nodes before linearized reading' (duration: 138.830531ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:58:29.453941Z","caller":"traceutil/trace.go:171","msg":"trace[797782117] linearizableReadLoop","detail":"{readStateIndex:5633; appliedIndex:5632; }","duration":"102.593301ms","start":"2026-06-15T11:58:29.351322Z","end":"2026-06-15T11:58:29.453915Z","steps":["trace[797782117] 'read index received' (duration: 102.303714ms)","trace[797782117] 'applied index is now lower than readState.Index' (duration: 288.497µs)"],"step_count":2} {"level":"info","ts":"2026-06-15T11:58:29.454082Z","caller":"traceutil/trace.go:171","msg":"trace[596892748] transaction","detail":"{read_only:false; response_revision:5420; number_of_response:1; }","duration":"123.682132ms","start":"2026-06-15T11:58:29.330345Z","end":"2026-06-15T11:58:29.454027Z","steps":["trace[596892748] 'process raft request' (duration: 123.393945ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:58:29.454265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.914838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-15T11:58:29.454847Z","caller":"traceutil/trace.go:171","msg":"trace[1287405185] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:5420; }","duration":"103.525553ms","start":"2026-06-15T11:58:29.351271Z","end":"2026-06-15T11:58:29.454797Z","steps":["trace[1287405185] 'agreement among raft nodes before linearized reading' (duration: 102.890618ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:58:29.574607Z","caller":"traceutil/trace.go:171","msg":"trace[2009665462] transaction","detail":"{read_only:false; response_revision:5422; number_of_response:1; }","duration":"115.197817ms","start":"2026-06-15T11:58:29.459369Z","end":"2026-06-15T11:58:29.574567Z","steps":["trace[2009665462] 'process raft request' (duration: 114.987172ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:58:29.575075Z","caller":"traceutil/trace.go:171","msg":"trace[466574925] transaction","detail":"{read_only:false; response_revision:5424; number_of_response:1; }","duration":"115.293679ms","start":"2026-06-15T11:58:29.459738Z","end":"2026-06-15T11:58:29.575032Z","steps":["trace[466574925] 'process raft request' (duration: 114.787917ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:58:29.575078Z","caller":"traceutil/trace.go:171","msg":"trace[1455701417] transaction","detail":"{read_only:false; response_revision:5425; number_of_response:1; }","duration":"115.066893ms","start":"2026-06-15T11:58:29.459956Z","end":"2026-06-15T11:58:29.575023Z","steps":["trace[1455701417] 'process raft request' (duration: 114.599422ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:58:29.57536Z","caller":"traceutil/trace.go:171","msg":"trace[659021793] transaction","detail":"{read_only:false; response_revision:5423; number_of_response:1; }","duration":"115.627897ms","start":"2026-06-15T11:58:29.459679Z","end":"2026-06-15T11:58:29.575307Z","steps":["trace[659021793] 'process raft request' (duration: 114.809257ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:58:35.156672Z","caller":"traceutil/trace.go:171","msg":"trace[1104447760] transaction","detail":"{read_only:false; response_revision:5495; number_of_response:1; }","duration":"144.091548ms","start":"2026-06-15T11:58:35.012549Z","end":"2026-06-15T11:58:35.15664Z","steps":["trace[1104447760] 'process raft request' (duration: 137.475908ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:58:37.52578Z","caller":"traceutil/trace.go:171","msg":"trace[1416591965] transaction","detail":"{read_only:false; response_revision:5566; number_of_response:1; }","duration":"106.618098ms","start":"2026-06-15T11:58:37.419137Z","end":"2026-06-15T11:58:37.525755Z","steps":["trace[1416591965] 'process raft request' (duration: 106.450524ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:58:37.817855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.90897ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-15T11:58:37.817981Z","caller":"traceutil/trace.go:171","msg":"trace[1234998081] transaction","detail":"{read_only:false; response_revision:5569; number_of_response:1; }","duration":"201.680907ms","start":"2026-06-15T11:58:37.616278Z","end":"2026-06-15T11:58:37.817959Z","steps":["trace[1234998081] 'process raft request' (duration: 58.574612ms)","trace[1234998081] 'compare' (duration: 142.165582ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T11:58:38.176231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.993349ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T11:58:38.176367Z","caller":"traceutil/trace.go:171","msg":"trace[1432069029] linearizableReadLoop","detail":"{readStateIndex:5785; appliedIndex:5784; }","duration":"294.822548ms","start":"2026-06-15T11:58:37.881523Z","end":"2026-06-15T11:58:38.176345Z","steps":["trace[1432069029] 'read index received' (duration: 56.582214ms)","trace[1432069029] 'applied index is now lower than readState.Index' (duration: 238.238714ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T11:58:38.176452Z","caller":"traceutil/trace.go:171","msg":"trace[996614084] transaction","detail":"{read_only:false; response_revision:5570; number_of_response:1; }","duration":"409.83158ms","start":"2026-06-15T11:58:37.766608Z","end":"2026-06-15T11:58:38.17644Z","steps":["trace[996614084] 'process raft request' (duration: 171.485143ms)","trace[996614084] 'compare' (duration: 237.673761ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T11:58:38.176917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.365511ms","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-06-15T11:58:38.17701Z","caller":"traceutil/trace.go:171","msg":"trace[1644803689] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5570; }","duration":"295.523085ms","start":"2026-06-15T11:58:37.881466Z","end":"2026-06-15T11:58:38.176989Z","steps":["trace[1644803689] 'agreement among raft nodes before linearized reading' (duration: 295.208867ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:58:38.177305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T11:58:37.766576Z","time spent":"409.951964ms","remote":"127.0.0.1:50864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T11:58:38.392545Z","caller":"traceutil/trace.go:171","msg":"trace[1823347141] linearizableReadLoop","detail":"{readStateIndex:5786; appliedIndex:5785; }","duration":"216.116767ms","start":"2026-06-15T11:58:38.176394Z","end":"2026-06-15T11:58:38.392511Z","steps":["trace[1823347141] 'read index received' (duration: 187.185365ms)","trace[1823347141] 'applied index is now lower than readState.Index' (duration: 28.929572ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T11:58:38.392561Z","caller":"traceutil/trace.go:171","msg":"trace[425865740] transaction","detail":"{read_only:false; response_revision:5571; number_of_response:1; }","duration":"249.211351ms","start":"2026-06-15T11:58:38.143309Z","end":"2026-06-15T11:58:38.392521Z","steps":["trace[425865740] 'process raft request' (duration: 220.252468ms)","trace[425865740] 'compare' (duration: 28.651935ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T11:58:38.392791Z","caller":"traceutil/trace.go:171","msg":"trace[1202486438] transaction","detail":"{read_only:false; response_revision:5572; number_of_response:1; }","duration":"211.268859ms","start":"2026-06-15T11:58:38.181491Z","end":"2026-06-15T11:58:38.39276Z","steps":["trace[1202486438] 'process raft request' (duration: 210.971912ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:58:38.392967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.947307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T11:58:38.393036Z","caller":"traceutil/trace.go:171","msg":"trace[644639819] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5572; }","duration":"154.04622ms","start":"2026-06-15T11:58:38.238972Z","end":"2026-06-15T11:58:38.393018Z","steps":["trace[644639819] 'agreement among raft nodes before linearized reading' (duration: 153.920317ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:58:38.393019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.706515ms","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-06-15T11:58:38.393129Z","caller":"traceutil/trace.go:171","msg":"trace[2099298626] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5572; }","duration":"509.88415ms","start":"2026-06-15T11:58:37.883217Z","end":"2026-06-15T11:58:38.393101Z","steps":["trace[2099298626] 'agreement among raft nodes before linearized reading' (duration: 509.406858ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:58:38.393621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T11:58:37.88319Z","time spent":"510.416792ms","remote":"127.0.0.1:50864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-15T11:58:38.393523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.291286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-t7789\" ","response":"range_response_count:1 size:6445"} {"level":"info","ts":"2026-06-15T11:58:38.393756Z","caller":"traceutil/trace.go:171","msg":"trace[1048590366] range","detail":"{range_begin:/registry/pods/openstack/keepalived-t7789; range_end:; response_count:1; response_revision:5572; }","duration":"390.596654ms","start":"2026-06-15T11:58:38.003126Z","end":"2026-06-15T11:58:38.393723Z","steps":["trace[1048590366] 'agreement among raft nodes before linearized reading' (duration: 389.446206ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T11:58:38.393883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T11:58:38.003099Z","time spent":"390.757877ms","remote":"127.0.0.1:50768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6467,"request content":"key:\"/registry/pods/openstack/keepalived-t7789\" "} {"level":"warn","ts":"2026-06-15T11:58:38.393964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.14849ms","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-06-15T11:58:38.394224Z","caller":"traceutil/trace.go:171","msg":"trace[1537947916] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5572; }","duration":"102.441287ms","start":"2026-06-15T11:58:38.291752Z","end":"2026-06-15T11:58:38.394193Z","steps":["trace[1537947916] 'agreement among raft nodes before linearized reading' (duration: 101.269869ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T11:59:08.17444Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2947} {"level":"info","ts":"2026-06-15T11:59:08.258067Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2947,"took":"81.777276ms","hash":1248663561,"current-db-size-bytes":57274368,"current-db-size":"57 MB","current-db-size-in-use-bytes":32378880,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-15T11:59:08.25817Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1248663561,"revision":2947,"compact-revision":-1} {"level":"info","ts":"2026-06-15T11:59:43.617906Z","caller":"traceutil/trace.go:171","msg":"trace[599636906] transaction","detail":"{read_only:false; response_revision:6199; number_of_response:1; }","duration":"117.909442ms","start":"2026-06-15T11:59:43.499969Z","end":"2026-06-15T11:59:43.617878Z","steps":["trace[599636906] 'process raft request' (duration: 117.41342ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:02:25.706498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.450458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-97bb5021-c49b-40ea-94c4-847bd881f281\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-06-15T12:02:25.706609Z","caller":"traceutil/trace.go:171","msg":"trace[315656648] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-97bb5021-c49b-40ea-94c4-847bd881f281; range_end:; response_count:1; response_revision:7301; }","duration":"246.641502ms","start":"2026-06-15T12:02:25.459943Z","end":"2026-06-15T12:02:25.706585Z","steps":["trace[315656648] 'range keys from in-memory index tree' (duration: 246.188681ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:02:25.706648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.927185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-97bb5021-c49b-40ea-94c4-847bd881f281\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-06-15T12:02:25.706755Z","caller":"traceutil/trace.go:171","msg":"trace[2018559591] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-97bb5021-c49b-40ea-94c4-847bd881f281; range_end:; response_count:1; response_revision:7301; }","duration":"236.069308ms","start":"2026-06-15T12:02:25.470644Z","end":"2026-06-15T12:02:25.706714Z","steps":["trace[2018559591] 'range keys from in-memory index tree' (duration: 235.708469ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:04:08.183747Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5874} {"level":"info","ts":"2026-06-15T12:04:08.278972Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5874,"took":"92.796151ms","hash":2191075767,"current-db-size-bytes":57274368,"current-db-size":"57 MB","current-db-size-in-use-bytes":25772032,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-06-15T12:04:08.279032Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2191075767,"revision":5874,"compact-revision":2947} {"level":"info","ts":"2026-06-15T12:04:39.202433Z","caller":"traceutil/trace.go:171","msg":"trace[391644458] linearizableReadLoop","detail":"{readStateIndex:8592; appliedIndex:8591; }","duration":"121.90766ms","start":"2026-06-15T12:04:39.080497Z","end":"2026-06-15T12:04:39.202405Z","steps":["trace[391644458] 'read index received' (duration: 121.792687ms)","trace[391644458] 'applied index is now lower than readState.Index' (duration: 113.973µs)"],"step_count":2} {"level":"info","ts":"2026-06-15T12:04:39.202568Z","caller":"traceutil/trace.go:171","msg":"trace[982873731] transaction","detail":"{read_only:false; response_revision:8288; number_of_response:1; }","duration":"127.587517ms","start":"2026-06-15T12:04:39.074966Z","end":"2026-06-15T12:04:39.202553Z","steps":["trace[982873731] 'process raft request' (duration: 127.271939ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:04:39.202822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.293699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T12:04:39.202868Z","caller":"traceutil/trace.go:171","msg":"trace[923088115] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:8288; }","duration":"122.413983ms","start":"2026-06-15T12:04:39.080443Z","end":"2026-06-15T12:04:39.202857Z","steps":["trace[923088115] 'agreement among raft nodes before linearized reading' (duration: 122.261529ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:04:39.216321Z","caller":"traceutil/trace.go:171","msg":"trace[1498340283] transaction","detail":"{read_only:false; response_revision:8289; number_of_response:1; }","duration":"128.283644ms","start":"2026-06-15T12:04:39.088014Z","end":"2026-06-15T12:04:39.216298Z","steps":["trace[1498340283] 'process raft request' (duration: 128.035998ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:04:49.515813Z","caller":"traceutil/trace.go:171","msg":"trace[1089923225] linearizableReadLoop","detail":"{readStateIndex:8659; appliedIndex:8658; }","duration":"166.313175ms","start":"2026-06-15T12:04:49.349472Z","end":"2026-06-15T12:04:49.515785Z","steps":["trace[1089923225] 'read index received' (duration: 166.0983ms)","trace[1089923225] 'applied index is now lower than readState.Index' (duration: 213.735µs)"],"step_count":2} {"level":"warn","ts":"2026-06-15T12:04:49.51616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.663775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-06-15T12:04:49.51624Z","caller":"traceutil/trace.go:171","msg":"trace[1721118764] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8353; }","duration":"166.812768ms","start":"2026-06-15T12:04:49.349413Z","end":"2026-06-15T12:04:49.516226Z","steps":["trace[1721118764] 'agreement among raft nodes before linearized reading' (duration: 166.48922ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:04:49.515868Z","caller":"traceutil/trace.go:171","msg":"trace[176015765] transaction","detail":"{read_only:false; response_revision:8353; number_of_response:1; }","duration":"257.318633ms","start":"2026-06-15T12:04:49.258527Z","end":"2026-06-15T12:04:49.515846Z","steps":["trace[176015765] 'process raft request' (duration: 257.070177ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:04:49.901077Z","caller":"traceutil/trace.go:171","msg":"trace[338983852] linearizableReadLoop","detail":"{readStateIndex:8661; appliedIndex:8659; }","duration":"378.143455ms","start":"2026-06-15T12:04:49.522905Z","end":"2026-06-15T12:04:49.901048Z","steps":["trace[338983852] 'read index received' (duration: 51.886976ms)","trace[338983852] 'applied index is now lower than readState.Index' (duration: 326.255529ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T12:04:49.901206Z","caller":"traceutil/trace.go:171","msg":"trace[785401973] transaction","detail":"{read_only:false; response_revision:8355; number_of_response:1; }","duration":"380.269856ms","start":"2026-06-15T12:04:49.520924Z","end":"2026-06-15T12:04:49.901194Z","steps":["trace[785401973] 'process raft request' (duration: 379.946989ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:04:49.90131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:04:49.52089Z","time spent":"380.342208ms","remote":"127.0.0.1:50864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T12:04:49.901774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.833752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19574"} {"level":"info","ts":"2026-06-15T12:04:49.901938Z","caller":"traceutil/trace.go:171","msg":"trace[2077088890] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:8355; }","duration":"379.049967ms","start":"2026-06-15T12:04:49.522865Z","end":"2026-06-15T12:04:49.901915Z","steps":["trace[2077088890] 'agreement among raft nodes before linearized reading' (duration: 378.433252ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:04:49.902077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:04:49.522849Z","time spent":"379.202781ms","remote":"127.0.0.1:50760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":19596,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-06-15T12:04:50.060621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.337426ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T12:04:50.060968Z","caller":"traceutil/trace.go:171","msg":"trace[1679968555] linearizableReadLoop","detail":"{readStateIndex:8662; appliedIndex:8661; }","duration":"159.79868ms","start":"2026-06-15T12:04:49.901149Z","end":"2026-06-15T12:04:50.060948Z","steps":["trace[1679968555] 'read index received' (duration: 38.054855ms)","trace[1679968555] 'applied index is now lower than readState.Index' (duration: 121.741875ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T12:04:50.061069Z","caller":"traceutil/trace.go:171","msg":"trace[80031090] transaction","detail":"{read_only:false; response_revision:8356; number_of_response:1; }","duration":"222.958537ms","start":"2026-06-15T12:04:49.838012Z","end":"2026-06-15T12:04:50.060971Z","steps":["trace[80031090] 'process raft request' (duration: 101.147831ms)","trace[80031090] 'compare' (duration: 121.211362ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T12:04:50.061182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.211643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-15T12:04:50.061278Z","caller":"traceutil/trace.go:171","msg":"trace[51063225] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:8356; }","duration":"402.374107ms","start":"2026-06-15T12:04:49.658884Z","end":"2026-06-15T12:04:50.061258Z","steps":["trace[51063225] 'agreement among raft nodes before linearized reading' (duration: 402.179482ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:04:50.061337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:04:49.658857Z","time spent":"402.46196ms","remote":"127.0.0.1:38988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":3,"response size":29,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-06-15T12:04:59.81656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.202736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T12:04:59.816674Z","caller":"traceutil/trace.go:171","msg":"trace[439296735] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8534; }","duration":"579.384441ms","start":"2026-06-15T12:04:59.237267Z","end":"2026-06-15T12:04:59.816652Z","steps":["trace[439296735] 'range keys from in-memory index tree' (duration: 579.107134ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:04:59.816719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:04:59.23724Z","time spent":"579.470002ms","remote":"127.0.0.1:50600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-15T12:04:59.816648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.40109ms","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-06-15T12:04:59.816791Z","caller":"traceutil/trace.go:171","msg":"trace[581164367] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:8534; }","duration":"241.607054ms","start":"2026-06-15T12:04:59.575163Z","end":"2026-06-15T12:04:59.81677Z","steps":["trace[581164367] 'count revisions from in-memory index tree' (duration: 241.318358ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:04:59.816889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.67407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-15T12:04:59.816983Z","caller":"traceutil/trace.go:171","msg":"trace[2107007002] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:8534; }","duration":"381.983007ms","start":"2026-06-15T12:04:59.434979Z","end":"2026-06-15T12:04:59.816962Z","steps":["trace[2107007002] 'count revisions from in-memory index tree' (duration: 381.560837ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:04:59.817022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:04:59.434948Z","time spent":"382.064709ms","remote":"127.0.0.1:43370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":56,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-06-15T12:05:00.162253Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646844102997575774,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-15T12:05:00.255966Z","caller":"traceutil/trace.go:171","msg":"trace[597984669] transaction","detail":"{read_only:false; response_revision:8535; number_of_response:1; }","duration":"605.104318ms","start":"2026-06-15T12:04:59.650838Z","end":"2026-06-15T12:05:00.255942Z","steps":["trace[597984669] 'process raft request' (duration: 604.936734ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:05:00.256311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:04:59.650787Z","time spent":"605.279883ms","remote":"127.0.0.1:50864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T12:05:00.267955Z","caller":"traceutil/trace.go:171","msg":"trace[1525236242] transaction","detail":"{read_only:false; response_revision:8536; number_of_response:1; }","duration":"152.254198ms","start":"2026-06-15T12:05:00.115686Z","end":"2026-06-15T12:05:00.26794Z","steps":["trace[1525236242] 'process raft request' (duration: 152.118685ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:05:00.268074Z","caller":"traceutil/trace.go:171","msg":"trace[987964875] linearizableReadLoop","detail":"{readStateIndex:8844; appliedIndex:8842; }","duration":"606.590064ms","start":"2026-06-15T12:04:59.66147Z","end":"2026-06-15T12:05:00.26806Z","steps":["trace[987964875] 'read index received' (duration: 594.301069ms)","trace[987964875] 'applied index is now lower than readState.Index' (duration: 12.287905ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T12:05:00.268274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.419158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-15T12:05:00.268287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.803639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-06-15T12:05:00.268318Z","caller":"traceutil/trace.go:171","msg":"trace[1750161514] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8537; }","duration":"449.472929ms","start":"2026-06-15T12:04:59.818835Z","end":"2026-06-15T12:05:00.268308Z","steps":["trace[1750161514] 'agreement among raft nodes before linearized reading' (duration: 449.392077ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:05:00.268293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.52841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-15T12:05:00.268352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:04:59.818763Z","time spent":"449.583212ms","remote":"127.0.0.1:50586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-15T12:05:00.268354Z","caller":"traceutil/trace.go:171","msg":"trace[599046020] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8537; }","duration":"606.918272ms","start":"2026-06-15T12:04:59.661419Z","end":"2026-06-15T12:05:00.268337Z","steps":["trace[599046020] 'agreement among raft nodes before linearized reading' (duration: 606.708847ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:05:00.26835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.356401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-06-15T12:05:00.268388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:04:59.6614Z","time spent":"606.981934ms","remote":"127.0.0.1:50864","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-06-15T12:05:00.268382Z","caller":"traceutil/trace.go:171","msg":"trace[1481669230] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:8537; }","duration":"511.693863ms","start":"2026-06-15T12:04:59.756668Z","end":"2026-06-15T12:05:00.268362Z","steps":["trace[1481669230] 'agreement among raft nodes before linearized reading' (duration: 511.491989ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:05:00.268434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:04:59.756643Z","time spent":"511.780076ms","remote":"127.0.0.1:38964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":24,"response size":29,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"info","ts":"2026-06-15T12:05:00.268436Z","caller":"traceutil/trace.go:171","msg":"trace[470343738] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8537; }","duration":"306.483374ms","start":"2026-06-15T12:04:59.961926Z","end":"2026-06-15T12:05:00.26841Z","steps":["trace[470343738] 'agreement among raft nodes before linearized reading' (duration: 306.255658ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:05:00.268496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:04:59.961891Z","time spent":"306.591096ms","remote":"127.0.0.1:50864","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":"info","ts":"2026-06-15T12:05:10.471612Z","caller":"traceutil/trace.go:171","msg":"trace[1267679347] transaction","detail":"{read_only:false; response_revision:8590; number_of_response:1; }","duration":"170.891766ms","start":"2026-06-15T12:05:10.300698Z","end":"2026-06-15T12:05:10.47159Z","steps":["trace[1267679347] 'process raft request' (duration: 170.755632ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:05:10.631427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.145706ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T12:05:10.631695Z","caller":"traceutil/trace.go:171","msg":"trace[1720418973] linearizableReadLoop","detail":"{readStateIndex:8902; appliedIndex:8899; }","duration":"252.789554ms","start":"2026-06-15T12:05:10.378885Z","end":"2026-06-15T12:05:10.631674Z","steps":["trace[1720418973] 'read index received' (duration: 92.654766ms)","trace[1720418973] 'applied index is now lower than readState.Index' (duration: 160.133718ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T12:05:10.631671Z","caller":"traceutil/trace.go:171","msg":"trace[156302131] transaction","detail":"{read_only:false; response_revision:8591; number_of_response:1; }","duration":"287.59583ms","start":"2026-06-15T12:05:10.344038Z","end":"2026-06-15T12:05:10.631633Z","steps":["trace[156302131] 'process raft request' (duration: 187.131216ms)","trace[156302131] 'compare' (duration: 99.960722ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T12:05:10.631805Z","caller":"traceutil/trace.go:171","msg":"trace[1326476014] transaction","detail":"{read_only:false; response_revision:8592; number_of_response:1; }","duration":"282.483657ms","start":"2026-06-15T12:05:10.34931Z","end":"2026-06-15T12:05:10.631794Z","steps":["trace[1326476014] 'process raft request' (duration: 282.287972ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:05:10.632026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.530244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-06-15T12:05:10.632084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.165673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-06-15T12:05:10.632163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.973785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-06-15T12:05:10.632181Z","caller":"traceutil/trace.go:171","msg":"trace[283842180] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8592; }","duration":"253.334386ms","start":"2026-06-15T12:05:10.378827Z","end":"2026-06-15T12:05:10.632161Z","steps":["trace[283842180] 'agreement among raft nodes before linearized reading' (duration: 253.000068ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:05:10.632201Z","caller":"traceutil/trace.go:171","msg":"trace[308404265] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8592; }","duration":"138.061517ms","start":"2026-06-15T12:05:10.49413Z","end":"2026-06-15T12:05:10.632191Z","steps":["trace[308404265] 'agreement among raft nodes before linearized reading' (duration: 137.685018ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:05:10.632123Z","caller":"traceutil/trace.go:171","msg":"trace[975607405] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8592; }","duration":"251.656976ms","start":"2026-06-15T12:05:10.380449Z","end":"2026-06-15T12:05:10.632106Z","steps":["trace[975607405] 'agreement among raft nodes before linearized reading' (duration: 251.367559ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:05:10.879604Z","caller":"traceutil/trace.go:171","msg":"trace[972306498] linearizableReadLoop","detail":"{readStateIndex:8905; appliedIndex:8904; }","duration":"136.359276ms","start":"2026-06-15T12:05:10.743218Z","end":"2026-06-15T12:05:10.879578Z","steps":["trace[972306498] 'read index received' (duration: 135.884605ms)","trace[972306498] 'applied index is now lower than readState.Index' (duration: 473.391µs)"],"step_count":2} {"level":"info","ts":"2026-06-15T12:05:10.879672Z","caller":"traceutil/trace.go:171","msg":"trace[1998218691] transaction","detail":"{read_only:false; response_revision:8595; number_of_response:1; }","duration":"181.011779ms","start":"2026-06-15T12:05:10.698626Z","end":"2026-06-15T12:05:10.879637Z","steps":["trace[1998218691] 'process raft request' (duration: 180.584029ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:05:10.879788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.54154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T12:05:10.879888Z","caller":"traceutil/trace.go:171","msg":"trace[99950803] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8595; }","duration":"136.704815ms","start":"2026-06-15T12:05:10.743159Z","end":"2026-06-15T12:05:10.879864Z","steps":["trace[99950803] 'agreement among raft nodes before linearized reading' (duration: 136.52096ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:05:11.455744Z","caller":"traceutil/trace.go:171","msg":"trace[41546213] linearizableReadLoop","detail":"{readStateIndex:8907; appliedIndex:8906; }","duration":"338.649736ms","start":"2026-06-15T12:05:11.117072Z","end":"2026-06-15T12:05:11.455722Z","steps":["trace[41546213] 'read index received' (duration: 338.431691ms)","trace[41546213] 'applied index is now lower than readState.Index' (duration: 217.425µs)"],"step_count":2} {"level":"info","ts":"2026-06-15T12:05:11.4558Z","caller":"traceutil/trace.go:171","msg":"trace[1772121095] transaction","detail":"{read_only:false; response_revision:8597; number_of_response:1; }","duration":"346.011623ms","start":"2026-06-15T12:05:11.109759Z","end":"2026-06-15T12:05:11.455771Z","steps":["trace[1772121095] 'process raft request' (duration: 345.816159ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:05:11.455939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:05:11.10973Z","time spent":"346.132247ms","remote":"127.0.0.1:50864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T12:05:11.455952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.118005ms","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-06-15T12:05:11.455984Z","caller":"traceutil/trace.go:171","msg":"trace[1747070866] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8597; }","duration":"316.210757ms","start":"2026-06-15T12:05:11.139764Z","end":"2026-06-15T12:05:11.455975Z","steps":["trace[1747070866] 'agreement among raft nodes before linearized reading' (duration: 316.078174ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:05:11.45601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:05:11.139739Z","time spent":"316.264568ms","remote":"127.0.0.1:50864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-15T12:05:11.456029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.436171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-15T12:05:11.456128Z","caller":"traceutil/trace.go:171","msg":"trace[134717500] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:8597; }","duration":"102.614466ms","start":"2026-06-15T12:05:11.353493Z","end":"2026-06-15T12:05:11.456107Z","steps":["trace[134717500] 'agreement among raft nodes before linearized reading' (duration: 102.39215ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:05:11.456164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.356252ms","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-06-15T12:05:11.456257Z","caller":"traceutil/trace.go:171","msg":"trace[516411649] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8597; }","duration":"289.473805ms","start":"2026-06-15T12:05:11.166761Z","end":"2026-06-15T12:05:11.456234Z","steps":["trace[516411649] 'agreement among raft nodes before linearized reading' (duration: 289.237179ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:05:11.456278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.314265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.205\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-15T12:05:11.456378Z","caller":"traceutil/trace.go:171","msg":"trace[384403044] range","detail":"{range_begin:/registry/masterleases/199.204.45.205; range_end:; response_count:1; response_revision:8597; }","duration":"111.469288ms","start":"2026-06-15T12:05:11.344887Z","end":"2026-06-15T12:05:11.456356Z","steps":["trace[384403044] 'agreement among raft nodes before linearized reading' (duration: 111.15194ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:05:11.456663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.584959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-t7789\" ","response":"range_response_count:1 size:6445"} {"level":"info","ts":"2026-06-15T12:05:11.456824Z","caller":"traceutil/trace.go:171","msg":"trace[1564039066] range","detail":"{range_begin:/registry/pods/openstack/keepalived-t7789; range_end:; response_count:1; response_revision:8597; }","duration":"339.720482ms","start":"2026-06-15T12:05:11.117028Z","end":"2026-06-15T12:05:11.456748Z","steps":["trace[1564039066] 'agreement among raft nodes before linearized reading' (duration: 338.78764ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:05:11.456862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:05:11.117005Z","time spent":"339.846124ms","remote":"127.0.0.1:50768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6467,"request content":"key:\"/registry/pods/openstack/keepalived-t7789\" "} {"level":"warn","ts":"2026-06-15T12:05:11.947556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.638353ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"warn","ts":"2026-06-15T12:05:11.947776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:05:11.457418Z","time spent":"490.348281ms","remote":"127.0.0.1:50616","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-06-15T12:05:11.947827Z","caller":"traceutil/trace.go:171","msg":"trace[290738179] linearizableReadLoop","detail":"{readStateIndex:8909; appliedIndex:8907; }","duration":"486.790455ms","start":"2026-06-15T12:05:11.46102Z","end":"2026-06-15T12:05:11.947811Z","steps":["trace[290738179] 'read index received' (duration: 205.814325ms)","trace[290738179] 'applied index is now lower than readState.Index' (duration: 280.97533ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T12:05:11.947923Z","caller":"traceutil/trace.go:171","msg":"trace[963320947] transaction","detail":"{read_only:false; response_revision:8598; number_of_response:1; }","duration":"487.999205ms","start":"2026-06-15T12:05:11.459913Z","end":"2026-06-15T12:05:11.947912Z","steps":["trace[963320947] 'process raft request' (duration: 487.736669ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:05:11.948016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:05:11.459866Z","time spent":"488.081677ms","remote":"127.0.0.1:50864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T12:05:11.948306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.355669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-15T12:05:11.948359Z","caller":"traceutil/trace.go:171","msg":"trace[1059462705] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:8599; }","duration":"318.460101ms","start":"2026-06-15T12:05:11.629887Z","end":"2026-06-15T12:05:11.948348Z","steps":["trace[1059462705] 'agreement among raft nodes before linearized reading' (duration: 318.370419ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:05:11.948418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:05:11.62986Z","time spent":"318.527643ms","remote":"127.0.0.1:48466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":5,"response size":29,"request content":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true "} {"level":"info","ts":"2026-06-15T12:05:11.948215Z","caller":"traceutil/trace.go:171","msg":"trace[102245758] transaction","detail":"{read_only:false; response_revision:8599; number_of_response:1; }","duration":"486.703604ms","start":"2026-06-15T12:05:11.461501Z","end":"2026-06-15T12:05:11.948204Z","steps":["trace[102245758] 'process raft request' (duration: 486.262043ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:05:11.948671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.657957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:230062"} {"level":"info","ts":"2026-06-15T12:05:11.948724Z","caller":"traceutil/trace.go:171","msg":"trace[66758132] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8599; }","duration":"487.739558ms","start":"2026-06-15T12:05:11.460972Z","end":"2026-06-15T12:05:11.948712Z","steps":["trace[66758132] 'agreement among raft nodes before linearized reading' (duration: 487.320918ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:05:11.948755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:05:11.460952Z","time spent":"487.7954ms","remote":"127.0.0.1:50768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":26,"response size":230084,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-06-15T12:05:11.948943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:05:11.461477Z","time spent":"487.178985ms","remote":"127.0.0.1:50864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T12:05:12.350892Z","caller":"traceutil/trace.go:171","msg":"trace[615938751] linearizableReadLoop","detail":"{readStateIndex:8911; appliedIndex:8910; }","duration":"300.242153ms","start":"2026-06-15T12:05:12.050625Z","end":"2026-06-15T12:05:12.350868Z","steps":["trace[615938751] 'read index received' (duration: 300.072839ms)","trace[615938751] 'applied index is now lower than readState.Index' (duration: 168.324µs)"],"step_count":2} {"level":"info","ts":"2026-06-15T12:05:12.350945Z","caller":"traceutil/trace.go:171","msg":"trace[2107817844] transaction","detail":"{read_only:false; response_revision:8600; number_of_response:1; }","duration":"401.723022ms","start":"2026-06-15T12:05:11.949177Z","end":"2026-06-15T12:05:12.3509Z","steps":["trace[2107817844] 'process raft request' (duration: 401.489967ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:05:12.351121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:05:11.949149Z","time spent":"401.875495ms","remote":"127.0.0.1:50616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T12:05:12.351083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.707098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T12:05:12.351182Z","caller":"traceutil/trace.go:171","msg":"trace[927728207] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8600; }","duration":"112.866332ms","start":"2026-06-15T12:05:12.238301Z","end":"2026-06-15T12:05:12.351167Z","steps":["trace[927728207] 'agreement among raft nodes before linearized reading' (duration: 112.722578ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:05:12.35111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.456289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-15T12:05:12.351339Z","caller":"traceutil/trace.go:171","msg":"trace[354046780] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:8600; }","duration":"300.746156ms","start":"2026-06-15T12:05:12.050566Z","end":"2026-06-15T12:05:12.351312Z","steps":["trace[354046780] 'agreement among raft nodes before linearized reading' (duration: 300.403488ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:05:12.351408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:05:12.050541Z","time spent":"300.848388ms","remote":"127.0.0.1:36370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-06-15T12:05:12.638754Z","caller":"traceutil/trace.go:171","msg":"trace[1954099545] transaction","detail":"{read_only:false; response_revision:8601; number_of_response:1; }","duration":"161.309015ms","start":"2026-06-15T12:05:12.477417Z","end":"2026-06-15T12:05:12.638726Z","steps":["trace[1954099545] 'process raft request' (duration: 161.140211ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:05:12.977233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.14295ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T12:05:12.977432Z","caller":"traceutil/trace.go:171","msg":"trace[1755813449] transaction","detail":"{read_only:false; response_revision:8602; number_of_response:1; }","duration":"338.824291ms","start":"2026-06-15T12:05:12.638581Z","end":"2026-06-15T12:05:12.977405Z","steps":["trace[1755813449] 'process raft request' (duration: 108.393015ms)","trace[1755813449] 'compare' (duration: 229.808451ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T12:05:12.977579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:05:12.638553Z","time spent":"338.947093ms","remote":"127.0.0.1:50864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T12:05:12.97783Z","caller":"traceutil/trace.go:171","msg":"trace[1378587103] linearizableReadLoop","detail":"{readStateIndex:8914; appliedIndex:8912; }","duration":"292.228691ms","start":"2026-06-15T12:05:12.685581Z","end":"2026-06-15T12:05:12.97781Z","steps":["trace[1378587103] 'read index received' (duration: 61.410215ms)","trace[1378587103] 'applied index is now lower than readState.Index' (duration: 230.817426ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T12:05:12.978002Z","caller":"traceutil/trace.go:171","msg":"trace[463236659] transaction","detail":"{read_only:false; response_revision:8603; number_of_response:1; }","duration":"338.518783ms","start":"2026-06-15T12:05:12.639451Z","end":"2026-06-15T12:05:12.97797Z","steps":["trace[463236659] 'process raft request' (duration: 337.892428ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:05:12.978181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:05:12.639437Z","time spent":"338.637166ms","remote":"127.0.0.1:50864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T12:05:12.978254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.646771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-06-15T12:05:12.978368Z","caller":"traceutil/trace.go:171","msg":"trace[51299671] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8603; }","duration":"292.829415ms","start":"2026-06-15T12:05:12.685513Z","end":"2026-06-15T12:05:12.978342Z","steps":["trace[51299671] 'agreement among raft nodes before linearized reading' (duration: 292.461307ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:05:13.021829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.439735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-15T12:05:13.021852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.484377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-06-15T12:05:13.021891Z","caller":"traceutil/trace.go:171","msg":"trace[649744981] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:8604; }","duration":"262.568608ms","start":"2026-06-15T12:05:12.759308Z","end":"2026-06-15T12:05:13.021877Z","steps":["trace[649744981] 'agreement among raft nodes before linearized reading' (duration: 262.361503ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:05:13.021899Z","caller":"traceutil/trace.go:171","msg":"trace[80391276] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8604; }","duration":"297.57049ms","start":"2026-06-15T12:05:12.724316Z","end":"2026-06-15T12:05:13.021886Z","steps":["trace[80391276] 'agreement among raft nodes before linearized reading' (duration: 297.371585ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:05:13.24567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.352452ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T12:05:13.245798Z","caller":"traceutil/trace.go:171","msg":"trace[1240079349] linearizableReadLoop","detail":"{readStateIndex:8916; appliedIndex:8915; }","duration":"224.117645ms","start":"2026-06-15T12:05:13.02166Z","end":"2026-06-15T12:05:13.245777Z","steps":["trace[1240079349] 'read index received' (duration: 88.569338ms)","trace[1240079349] 'applied index is now lower than readState.Index' (duration: 135.546727ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T12:05:13.245847Z","caller":"traceutil/trace.go:171","msg":"trace[1790147884] transaction","detail":"{read_only:false; response_revision:8605; number_of_response:1; }","duration":"263.617504ms","start":"2026-06-15T12:05:12.982199Z","end":"2026-06-15T12:05:13.245817Z","steps":["trace[1790147884] 'process raft request' (duration: 128.017026ms)","trace[1790147884] 'compare' (duration: 135.207668ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T12:05:13.245885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.920465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T12:05:13.246126Z","caller":"traceutil/trace.go:171","msg":"trace[395667166] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:8605; }","duration":"265.187672ms","start":"2026-06-15T12:05:12.980913Z","end":"2026-06-15T12:05:13.246101Z","steps":["trace[395667166] 'agreement among raft nodes before linearized reading' (duration: 264.923665ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:05:13.330345Z","caller":"traceutil/trace.go:171","msg":"trace[185981200] transaction","detail":"{read_only:false; response_revision:8606; number_of_response:1; }","duration":"304.869205ms","start":"2026-06-15T12:05:13.025441Z","end":"2026-06-15T12:05:13.33031Z","steps":["trace[185981200] 'process raft request' (duration: 304.69583ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:05:13.330533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:05:13.025419Z","time spent":"305.013508ms","remote":"127.0.0.1:50864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T12:05:14.343773Z","caller":"traceutil/trace.go:171","msg":"trace[970808692] transaction","detail":"{read_only:false; response_revision:8608; number_of_response:1; }","duration":"386.126517ms","start":"2026-06-15T12:05:13.957625Z","end":"2026-06-15T12:05:14.343751Z","steps":["trace[970808692] 'process raft request' (duration: 385.966963ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:05:14.343912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:05:13.957612Z","time spent":"386.22714ms","remote":"127.0.0.1:50864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T12:05:14.392404Z","caller":"traceutil/trace.go:171","msg":"trace[185177456] linearizableReadLoop","detail":"{readStateIndex:8920; appliedIndex:8918; }","duration":"431.700292ms","start":"2026-06-15T12:05:13.960686Z","end":"2026-06-15T12:05:14.392386Z","steps":["trace[185177456] 'read index received' (duration: 382.969281ms)","trace[185177456] 'applied index is now lower than readState.Index' (duration: 48.730381ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T12:05:14.392465Z","caller":"traceutil/trace.go:171","msg":"trace[1598918627] transaction","detail":"{read_only:false; response_revision:8609; number_of_response:1; }","duration":"434.683203ms","start":"2026-06-15T12:05:13.957759Z","end":"2026-06-15T12:05:14.392443Z","steps":["trace[1598918627] 'process raft request' (duration: 434.493719ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:05:14.392598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:05:13.957736Z","time spent":"434.781976ms","remote":"127.0.0.1:50864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T12:05:14.392589Z","caller":"traceutil/trace.go:171","msg":"trace[1319779033] transaction","detail":"{read_only:false; response_revision:8610; number_of_response:1; }","duration":"246.262466ms","start":"2026-06-15T12:05:14.146269Z","end":"2026-06-15T12:05:14.392532Z","steps":["trace[1319779033] 'process raft request' (duration: 246.088692ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:05:14.392684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.659765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-15T12:05:14.39274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.0473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:230062"} {"level":"info","ts":"2026-06-15T12:05:14.39279Z","caller":"traceutil/trace.go:171","msg":"trace[664739289] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8610; }","duration":"154.82976ms","start":"2026-06-15T12:05:14.237939Z","end":"2026-06-15T12:05:14.392769Z","steps":["trace[664739289] 'agreement among raft nodes before linearized reading' (duration: 154.637666ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:05:14.39287Z","caller":"traceutil/trace.go:171","msg":"trace[498362888] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8610; }","duration":"432.188393ms","start":"2026-06-15T12:05:13.960655Z","end":"2026-06-15T12:05:14.392844Z","steps":["trace[498362888] 'agreement among raft nodes before linearized reading' (duration: 431.820144ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:05:14.393034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:05:13.960639Z","time spent":"432.351848ms","remote":"127.0.0.1:50768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":26,"response size":230084,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-06-15T12:05:14.72591Z","caller":"traceutil/trace.go:171","msg":"trace[2112450722] transaction","detail":"{read_only:false; response_revision:8611; number_of_response:1; }","duration":"254.498815ms","start":"2026-06-15T12:05:14.471392Z","end":"2026-06-15T12:05:14.725891Z","steps":["trace[2112450722] 'process raft request' (duration: 254.361272ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:05:14.799377Z","caller":"traceutil/trace.go:171","msg":"trace[17164524] transaction","detail":"{read_only:false; response_revision:8612; number_of_response:1; }","duration":"154.376739ms","start":"2026-06-15T12:05:14.644969Z","end":"2026-06-15T12:05:14.799345Z","steps":["trace[17164524] 'process raft request' (duration: 154.174994ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:05:15.237009Z","caller":"traceutil/trace.go:171","msg":"trace[1602494395] transaction","detail":"{read_only:false; response_revision:8614; number_of_response:1; }","duration":"252.543547ms","start":"2026-06-15T12:05:14.984432Z","end":"2026-06-15T12:05:15.236976Z","steps":["trace[1602494395] 'process raft request' (duration: 252.099266ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:05:15.237247Z","caller":"traceutil/trace.go:171","msg":"trace[975193541] transaction","detail":"{read_only:false; response_revision:8615; number_of_response:1; }","duration":"208.281034ms","start":"2026-06-15T12:05:15.028941Z","end":"2026-06-15T12:05:15.237222Z","steps":["trace[975193541] 'process raft request' (duration: 207.965916ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:05:15.519131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.096254ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T12:05:15.519231Z","caller":"traceutil/trace.go:171","msg":"trace[467389037] linearizableReadLoop","detail":"{readStateIndex:8927; appliedIndex:8926; }","duration":"204.075854ms","start":"2026-06-15T12:05:15.315139Z","end":"2026-06-15T12:05:15.519215Z","steps":["trace[467389037] 'read index received' (duration: 37.121µs)","trace[467389037] 'applied index is now lower than readState.Index' (duration: 204.037753ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T12:05:15.519488Z","caller":"traceutil/trace.go:171","msg":"trace[792115322] transaction","detail":"{read_only:false; response_revision:8616; number_of_response:1; }","duration":"265.072519ms","start":"2026-06-15T12:05:15.254396Z","end":"2026-06-15T12:05:15.519468Z","steps":["trace[792115322] 'process raft request' (duration: 25.549124ms)","trace[792115322] 'compare' (duration: 238.967301ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T12:05:15.519773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.018192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-06-15T12:05:15.519864Z","caller":"traceutil/trace.go:171","msg":"trace[955750536] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8616; }","duration":"184.167565ms","start":"2026-06-15T12:05:15.335677Z","end":"2026-06-15T12:05:15.519844Z","steps":["trace[955750536] 'agreement among raft nodes before linearized reading' (duration: 183.879488ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:05:15.52003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.731199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1633"} {"level":"info","ts":"2026-06-15T12:05:15.520281Z","caller":"traceutil/trace.go:171","msg":"trace[1685408556] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:8616; }","duration":"205.050956ms","start":"2026-06-15T12:05:15.315108Z","end":"2026-06-15T12:05:15.520159Z","steps":["trace[1685408556] 'agreement among raft nodes before linearized reading' (duration: 204.432721ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:05:15.786851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.981495ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T12:05:15.787037Z","caller":"traceutil/trace.go:171","msg":"trace[1158555322] transaction","detail":"{read_only:false; response_revision:8618; number_of_response:1; }","duration":"262.857455ms","start":"2026-06-15T12:05:15.524142Z","end":"2026-06-15T12:05:15.787Z","steps":["trace[1158555322] 'process raft request' (duration: 119.626564ms)","trace[1158555322] 'compare' (duration: 142.843662ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T12:05:16.067403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.650835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-06-15T12:05:16.067601Z","caller":"traceutil/trace.go:171","msg":"trace[38217629] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8618; }","duration":"119.924441ms","start":"2026-06-15T12:05:15.947648Z","end":"2026-06-15T12:05:16.067573Z","steps":["trace[38217629] 'range keys from in-memory index tree' (duration: 119.336458ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:05:16.743162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.157949ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T12:05:16.743356Z","caller":"traceutil/trace.go:171","msg":"trace[1997301175] transaction","detail":"{read_only:false; response_revision:8620; number_of_response:1; }","duration":"301.268529ms","start":"2026-06-15T12:05:16.442067Z","end":"2026-06-15T12:05:16.743336Z","steps":["trace[1997301175] 'process raft request' (duration: 110.842903ms)","trace[1997301175] 'compare' (duration: 190.034206ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T12:05:16.74345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:05:16.442043Z","time spent":"301.374412ms","remote":"127.0.0.1:50864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T12:05:16.743374Z","caller":"traceutil/trace.go:171","msg":"trace[257075579] linearizableReadLoop","detail":"{readStateIndex:8931; appliedIndex:8930; }","duration":"300.90357ms","start":"2026-06-15T12:05:16.442451Z","end":"2026-06-15T12:05:16.743355Z","steps":["trace[257075579] 'read index received' (duration: 110.517496ms)","trace[257075579] 'applied index is now lower than readState.Index' (duration: 190.383994ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T12:05:16.776789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.311952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:230062"} {"level":"info","ts":"2026-06-15T12:05:16.776916Z","caller":"traceutil/trace.go:171","msg":"trace[280203616] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8620; }","duration":"334.499427ms","start":"2026-06-15T12:05:16.442396Z","end":"2026-06-15T12:05:16.776896Z","steps":["trace[280203616] 'agreement among raft nodes before linearized reading' (duration: 301.004942ms)","trace[280203616] 'range keys from in-memory index tree' (duration: 32.942112ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T12:05:16.776972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:05:16.442373Z","time spent":"334.58691ms","remote":"127.0.0.1:50768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":26,"response size":230084,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-06-15T12:05:17.127529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.233153ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T12:05:17.127658Z","caller":"traceutil/trace.go:171","msg":"trace[1981090492] transaction","detail":"{read_only:false; response_revision:8623; number_of_response:1; }","duration":"183.262303ms","start":"2026-06-15T12:05:16.944365Z","end":"2026-06-15T12:05:17.127627Z","steps":["trace[1981090492] 'process raft request' (duration: 56.826675ms)","trace[1981090492] 'compare' (duration: 125.487485ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T12:05:17.237748Z","caller":"traceutil/trace.go:171","msg":"trace[165557282] transaction","detail":"{read_only:false; response_revision:8624; number_of_response:1; }","duration":"186.223404ms","start":"2026-06-15T12:05:17.050516Z","end":"2026-06-15T12:05:17.236739Z","steps":["trace[165557282] 'process raft request' (duration: 178.53542ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:05:17.808719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.451476ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-15T12:05:17.808955Z","caller":"traceutil/trace.go:171","msg":"trace[416595949] transaction","detail":"{read_only:false; response_revision:8629; number_of_response:1; }","duration":"151.315515ms","start":"2026-06-15T12:05:17.657591Z","end":"2026-06-15T12:05:17.808907Z","steps":["trace[416595949] 'process raft request' (duration: 22.583542ms)","trace[416595949] 'compare' (duration: 128.282232ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T12:05:41.186505Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000238d.wal"} {"level":"warn","ts":"2026-06-15T12:05:46.346741Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646844102997576706,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-15T12:05:46.530733Z","caller":"traceutil/trace.go:171","msg":"trace[2069750650] transaction","detail":"{read_only:false; response_revision:8830; number_of_response:1; }","duration":"768.369061ms","start":"2026-06-15T12:05:45.762332Z","end":"2026-06-15T12:05:46.530701Z","steps":["trace[2069750650] 'process raft request' (duration: 768.133725ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:05:46.531259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:05:45.762295Z","time spent":"768.524144ms","remote":"127.0.0.1:50864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T12:05:46.534165Z","caller":"traceutil/trace.go:171","msg":"trace[34389189] transaction","detail":"{read_only:false; response_revision:8831; number_of_response:1; }","duration":"768.403492ms","start":"2026-06-15T12:05:45.765736Z","end":"2026-06-15T12:05:46.534139Z","steps":["trace[34389189] 'process raft request' (duration: 768.148956ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:05:46.534299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:05:45.765718Z","time spent":"768.524625ms","remote":"127.0.0.1:50864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T12:05:46.542325Z","caller":"traceutil/trace.go:171","msg":"trace[1197750523] linearizableReadLoop","detail":"{readStateIndex:9151; appliedIndex:9149; }","duration":"700.040629ms","start":"2026-06-15T12:05:45.842261Z","end":"2026-06-15T12:05:46.542302Z","steps":["trace[1197750523] 'read index received' (duration: 688.197235ms)","trace[1197750523] 'applied index is now lower than readState.Index' (duration: 11.842274ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T12:05:46.542582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"700.308505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T12:05:46.542626Z","caller":"traceutil/trace.go:171","msg":"trace[1586955904] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:8831; }","duration":"700.404578ms","start":"2026-06-15T12:05:45.842213Z","end":"2026-06-15T12:05:46.542618Z","steps":["trace[1586955904] 'agreement among raft nodes before linearized reading' (duration: 700.325676ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:05:46.542657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:05:45.842196Z","time spent":"700.45069ms","remote":"127.0.0.1:50972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":27,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-06-15T12:05:46.542647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.330803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-15T12:05:46.54264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.478405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T12:05:46.542725Z","caller":"traceutil/trace.go:171","msg":"trace[611225929] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8831; }","duration":"304.607378ms","start":"2026-06-15T12:05:46.238104Z","end":"2026-06-15T12:05:46.542712Z","steps":["trace[611225929] 'agreement among raft nodes before linearized reading' (duration: 304.488595ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:05:46.542783Z","caller":"traceutil/trace.go:171","msg":"trace[1201996840] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:8831; }","duration":"662.448416ms","start":"2026-06-15T12:05:45.880247Z","end":"2026-06-15T12:05:46.542695Z","steps":["trace[1201996840] 'agreement among raft nodes before linearized reading' (duration: 662.321283ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:05:46.542801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:05:46.238079Z","time spent":"304.711881ms","remote":"127.0.0.1:50600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-15T12:05:46.542891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.730477ms","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-06-15T12:05:46.542999Z","caller":"traceutil/trace.go:171","msg":"trace[967746118] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8831; }","duration":"519.905862ms","start":"2026-06-15T12:05:46.023071Z","end":"2026-06-15T12:05:46.542977Z","steps":["trace[967746118] 'agreement among raft nodes before linearized reading' (duration: 519.588484ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:05:46.54306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:05:46.023046Z","time spent":"520.001833ms","remote":"127.0.0.1:50864","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-06-15T12:05:46.542991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:05:45.880227Z","time spent":"662.627931ms","remote":"127.0.0.1:46148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":31,"response size":29,"request content":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true "} {"level":"info","ts":"2026-06-15T12:05:50.409654Z","caller":"traceutil/trace.go:171","msg":"trace[1161075101] transaction","detail":"{read_only:false; response_revision:8852; number_of_response:1; }","duration":"116.775006ms","start":"2026-06-15T12:05:50.292851Z","end":"2026-06-15T12:05:50.409626Z","steps":["trace[1161075101] 'process raft request' (duration: 116.610142ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:05:50.715403Z","caller":"traceutil/trace.go:171","msg":"trace[1432040474] transaction","detail":"{read_only:false; response_revision:8853; number_of_response:1; }","duration":"163.307344ms","start":"2026-06-15T12:05:50.55207Z","end":"2026-06-15T12:05:50.715378Z","steps":["trace[1432040474] 'process raft request' (duration: 163.1478ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:05:50.746932Z","caller":"traceutil/trace.go:171","msg":"trace[248354210] linearizableReadLoop","detail":"{readStateIndex:9175; appliedIndex:9173; }","duration":"172.284749ms","start":"2026-06-15T12:05:50.574617Z","end":"2026-06-15T12:05:50.746902Z","steps":["trace[248354210] 'read index received' (duration: 140.67945ms)","trace[248354210] 'applied index is now lower than readState.Index' (duration: 31.603699ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T12:05:50.747051Z","caller":"traceutil/trace.go:171","msg":"trace[1070479582] transaction","detail":"{read_only:false; response_revision:8854; number_of_response:1; }","duration":"194.055313ms","start":"2026-06-15T12:05:50.552968Z","end":"2026-06-15T12:05:50.747023Z","steps":["trace[1070479582] 'process raft request' (duration: 188.006267ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:05:50.747198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.565476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-06-15T12:05:50.747243Z","caller":"traceutil/trace.go:171","msg":"trace[1386217250] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8854; }","duration":"172.648648ms","start":"2026-06-15T12:05:50.574581Z","end":"2026-06-15T12:05:50.74723Z","steps":["trace[1386217250] 'agreement among raft nodes before linearized reading' (duration: 172.473044ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:05:50.747264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.726903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T12:05:50.747384Z","caller":"traceutil/trace.go:171","msg":"trace[2062115114] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusagents/; range_end:/registry/monitoring.coreos.com/prometheusagents0; response_count:0; response_revision:8854; }","duration":"128.878467ms","start":"2026-06-15T12:05:50.618476Z","end":"2026-06-15T12:05:50.747355Z","steps":["trace[2062115114] 'agreement among raft nodes before linearized reading' (duration: 128.656101ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:05:50.881556Z","caller":"traceutil/trace.go:171","msg":"trace[1065006593] transaction","detail":"{read_only:false; response_revision:8855; number_of_response:1; }","duration":"129.349388ms","start":"2026-06-15T12:05:50.752187Z","end":"2026-06-15T12:05:50.881537Z","steps":["trace[1065006593] 'process raft request' (duration: 103.833255ms)","trace[1065006593] 'compare' (duration: 25.359099ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T12:05:50.881792Z","caller":"traceutil/trace.go:171","msg":"trace[795076011] transaction","detail":"{read_only:false; response_revision:8856; number_of_response:1; }","duration":"129.247766ms","start":"2026-06-15T12:05:50.752499Z","end":"2026-06-15T12:05:50.881747Z","steps":["trace[795076011] 'process raft request' (duration: 129.00708ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:05:59.625556Z","caller":"traceutil/trace.go:171","msg":"trace[1258601370] transaction","detail":"{read_only:false; response_revision:8945; number_of_response:1; }","duration":"172.216088ms","start":"2026-06-15T12:05:59.453303Z","end":"2026-06-15T12:05:59.625519Z","steps":["trace[1258601370] 'process raft request' (duration: 171.996372ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:06:00.035904Z","caller":"traceutil/trace.go:171","msg":"trace[588195585] transaction","detail":"{read_only:false; response_revision:8946; number_of_response:1; }","duration":"575.758033ms","start":"2026-06-15T12:05:59.460118Z","end":"2026-06-15T12:06:00.035876Z","steps":["trace[588195585] 'process raft request' (duration: 497.761639ms)","trace[588195585] 'compare' (duration: 77.792379ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T12:06:00.036074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:05:59.460087Z","time spent":"575.896487ms","remote":"127.0.0.1:50822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3037,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T12:06:00.036272Z","caller":"traceutil/trace.go:171","msg":"trace[1129133121] transaction","detail":"{read_only:false; response_revision:8947; number_of_response:1; }","duration":"533.814436ms","start":"2026-06-15T12:05:59.502428Z","end":"2026-06-15T12:06:00.036242Z","steps":["trace[1129133121] 'process raft request' (duration: 533.380485ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:06:00.03647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:05:59.502394Z","time spent":"533.98215ms","remote":"127.0.0.1:50864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T12:06:00.036607Z","caller":"traceutil/trace.go:171","msg":"trace[571596856] transaction","detail":"{read_only:false; response_revision:8948; number_of_response:1; }","duration":"508.448596ms","start":"2026-06-15T12:05:59.528146Z","end":"2026-06-15T12:06:00.036595Z","steps":["trace[571596856] 'process raft request' (duration: 507.964795ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:06:00.03712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:05:59.528117Z","time spent":"508.58115ms","remote":"127.0.0.1:50864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T12:06:00.212173Z","caller":"traceutil/trace.go:171","msg":"trace[1535805651] transaction","detail":"{read_only:false; response_revision:8949; number_of_response:1; }","duration":"118.649001ms","start":"2026-06-15T12:06:00.093493Z","end":"2026-06-15T12:06:00.212142Z","steps":["trace[1535805651] 'process raft request' (duration: 118.446286ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:06:01.334757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.826505ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T12:06:01.334849Z","caller":"traceutil/trace.go:171","msg":"trace[1910793457] linearizableReadLoop","detail":"{readStateIndex:9283; appliedIndex:9282; }","duration":"228.92041ms","start":"2026-06-15T12:06:01.105915Z","end":"2026-06-15T12:06:01.334836Z","steps":["trace[1910793457] 'read index received' (duration: 89.969792ms)","trace[1910793457] 'applied index is now lower than readState.Index' (duration: 138.949518ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T12:06:01.334974Z","caller":"traceutil/trace.go:171","msg":"trace[1429548964] transaction","detail":"{read_only:false; response_revision:8957; number_of_response:1; }","duration":"233.191153ms","start":"2026-06-15T12:06:01.101774Z","end":"2026-06-15T12:06:01.334966Z","steps":["trace[1429548964] 'process raft request' (duration: 94.078811ms)","trace[1429548964] 'compare' (duration: 138.531988ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T12:06:01.33537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.441372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:27 size:234708"} {"level":"info","ts":"2026-06-15T12:06:01.335447Z","caller":"traceutil/trace.go:171","msg":"trace[455158480] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:27; response_revision:8957; }","duration":"229.521535ms","start":"2026-06-15T12:06:01.105895Z","end":"2026-06-15T12:06:01.335416Z","steps":["trace[455158480] 'agreement among raft nodes before linearized reading' (duration: 229.071914ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:06:01.398426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.214979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T12:06:01.398508Z","caller":"traceutil/trace.go:171","msg":"trace[154621343] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8958; }","duration":"160.351712ms","start":"2026-06-15T12:06:01.238135Z","end":"2026-06-15T12:06:01.398487Z","steps":["trace[154621343] 'agreement among raft nodes before linearized reading' (duration: 160.208919ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:06:01.398577Z","caller":"traceutil/trace.go:171","msg":"trace[1363764880] transaction","detail":"{read_only:false; response_revision:8958; number_of_response:1; }","duration":"269.561487ms","start":"2026-06-15T12:06:01.128938Z","end":"2026-06-15T12:06:01.398499Z","steps":["trace[1363764880] 'process raft request' (duration: 269.166287ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:06:01.398766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.865946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/openstack/ceph\" ","response":"range_response_count:1 size:3672"} {"level":"info","ts":"2026-06-15T12:06:01.398872Z","caller":"traceutil/trace.go:171","msg":"trace[1092273025] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/openstack/ceph; range_end:; response_count:1; response_revision:8958; }","duration":"139.0078ms","start":"2026-06-15T12:06:01.259843Z","end":"2026-06-15T12:06:01.39885Z","steps":["trace[1092273025] 'agreement among raft nodes before linearized reading' (duration: 138.759974ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:06:01.398725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.085626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-15T12:06:01.399068Z","caller":"traceutil/trace.go:171","msg":"trace[849671705] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:8958; }","duration":"222.501526ms","start":"2026-06-15T12:06:01.176542Z","end":"2026-06-15T12:06:01.399044Z","steps":["trace[849671705] 'agreement among raft nodes before linearized reading' (duration: 221.956483ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:06:02.032188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.088644ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T12:06:02.032433Z","caller":"traceutil/trace.go:171","msg":"trace[718860496] linearizableReadLoop","detail":"{readStateIndex:9286; appliedIndex:9285; }","duration":"614.393052ms","start":"2026-06-15T12:06:01.417926Z","end":"2026-06-15T12:06:02.032319Z","steps":["trace[718860496] 'read index received' (duration: 462.051772ms)","trace[718860496] 'applied index is now lower than readState.Index' (duration: 152.3388ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T12:06:02.032435Z","caller":"traceutil/trace.go:171","msg":"trace[1437819831] transaction","detail":"{read_only:false; response_revision:8959; number_of_response:1; }","duration":"615.501448ms","start":"2026-06-15T12:06:01.416901Z","end":"2026-06-15T12:06:02.032402Z","steps":["trace[1437819831] 'process raft request' (duration: 463.064915ms)","trace[1437819831] 'compare' (duration: 151.848789ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T12:06:02.032604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:06:01.416872Z","time spent":"615.674802ms","remote":"127.0.0.1:50616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T12:06:02.032748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.822822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/ceph.18b93fad51b88108\" ","response":"range_response_count:1 size:699"} {"level":"info","ts":"2026-06-15T12:06:02.032848Z","caller":"traceutil/trace.go:171","msg":"trace[1734505022] range","detail":"{range_begin:/registry/events/openstack/ceph.18b93fad51b88108; range_end:; response_count:1; response_revision:8959; }","duration":"614.920274ms","start":"2026-06-15T12:06:01.41787Z","end":"2026-06-15T12:06:02.032791Z","steps":["trace[1734505022] 'agreement among raft nodes before linearized reading' (duration: 614.638908ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:06:02.032909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:06:01.417853Z","time spent":"615.042267ms","remote":"127.0.0.1:50656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":721,"request content":"key:\"/registry/events/openstack/ceph.18b93fad51b88108\" "} {"level":"info","ts":"2026-06-15T12:06:02.065801Z","caller":"traceutil/trace.go:171","msg":"trace[2132438837] transaction","detail":"{read_only:false; response_revision:8960; number_of_response:1; }","duration":"433.331562ms","start":"2026-06-15T12:06:01.632439Z","end":"2026-06-15T12:06:02.065771Z","steps":["trace[2132438837] 'process raft request' (duration: 433.135677ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:06:02.065991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:06:01.632407Z","time spent":"433.483405ms","remote":"127.0.0.1:50864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T12:06:02.273605Z","caller":"traceutil/trace.go:171","msg":"trace[1313660978] transaction","detail":"{read_only:false; response_revision:8961; number_of_response:1; }","duration":"363.565176ms","start":"2026-06-15T12:06:01.910011Z","end":"2026-06-15T12:06:02.273576Z","steps":["trace[1313660978] 'process raft request' (duration: 363.257718ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:06:02.273797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:06:01.909993Z","time spent":"363.703048ms","remote":"127.0.0.1:50864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T12:06:02.273909Z","caller":"traceutil/trace.go:171","msg":"trace[37747796] transaction","detail":"{read_only:false; response_revision:8962; number_of_response:1; }","duration":"238.05838ms","start":"2026-06-15T12:06:02.035828Z","end":"2026-06-15T12:06:02.273886Z","steps":["trace[37747796] 'process raft request' (duration: 237.64968ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:06:02.274617Z","caller":"traceutil/trace.go:171","msg":"trace[1310478734] transaction","detail":"{read_only:false; response_revision:8963; number_of_response:1; }","duration":"231.661726ms","start":"2026-06-15T12:06:02.042931Z","end":"2026-06-15T12:06:02.274592Z","steps":["trace[1310478734] 'process raft request' (duration: 230.846046ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:06:02.275007Z","caller":"traceutil/trace.go:171","msg":"trace[270859782] transaction","detail":"{read_only:false; response_revision:8964; number_of_response:1; }","duration":"231.605054ms","start":"2026-06-15T12:06:02.043388Z","end":"2026-06-15T12:06:02.274993Z","steps":["trace[270859782] 'process raft request' (duration: 231.087842ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:06:02.275329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.211644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T12:06:02.275478Z","caller":"traceutil/trace.go:171","msg":"trace[123428931] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:8964; }","duration":"427.4432ms","start":"2026-06-15T12:06:01.848Z","end":"2026-06-15T12:06:02.275443Z","steps":["trace[123428931] 'agreement among raft nodes before linearized reading' (duration: 427.202804ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:06:02.275556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:06:01.847974Z","time spent":"427.563513ms","remote":"127.0.0.1:36318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"info","ts":"2026-06-15T12:06:02.275758Z","caller":"traceutil/trace.go:171","msg":"trace[599258900] linearizableReadLoop","detail":"{readStateIndex:9288; appliedIndex:9286; }","duration":"242.371653ms","start":"2026-06-15T12:06:02.032489Z","end":"2026-06-15T12:06:02.27486Z","steps":["trace[599258900] 'read index received' (duration: 33.170277ms)","trace[599258900] 'applied index is now lower than readState.Index' (duration: 209.200316ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T12:06:02.27599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.682953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-06-15T12:06:02.276036Z","caller":"traceutil/trace.go:171","msg":"trace[1981452153] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:8964; }","duration":"240.758304ms","start":"2026-06-15T12:06:02.035267Z","end":"2026-06-15T12:06:02.276025Z","steps":["trace[1981452153] 'agreement among raft nodes before linearized reading' (duration: 240.5872ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:06:02.276166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.047062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-06-15T12:06:02.276227Z","caller":"traceutil/trace.go:171","msg":"trace[852518772] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8964; }","duration":"199.167585ms","start":"2026-06-15T12:06:02.077046Z","end":"2026-06-15T12:06:02.276214Z","steps":["trace[852518772] 'agreement among raft nodes before linearized reading' (duration: 198.95474ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:06:53.6091Z","caller":"traceutil/trace.go:171","msg":"trace[1066856267] transaction","detail":"{read_only:false; response_revision:9464; number_of_response:1; }","duration":"132.408171ms","start":"2026-06-15T12:06:53.476657Z","end":"2026-06-15T12:06:53.609066Z","steps":["trace[1066856267] 'process raft request' (duration: 111.391316ms)","trace[1066856267] 'compare' (duration: 20.743028ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T12:06:53.609446Z","caller":"traceutil/trace.go:171","msg":"trace[398574567] transaction","detail":"{read_only:false; response_revision:9465; number_of_response:1; }","duration":"132.730059ms","start":"2026-06-15T12:06:53.476683Z","end":"2026-06-15T12:06:53.609413Z","steps":["trace[398574567] 'process raft request' (duration: 132.320549ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:06:53.609517Z","caller":"traceutil/trace.go:171","msg":"trace[455304818] transaction","detail":"{read_only:false; response_revision:9466; number_of_response:1; }","duration":"132.256537ms","start":"2026-06-15T12:06:53.477232Z","end":"2026-06-15T12:06:53.609488Z","steps":["trace[455304818] 'process raft request' (duration: 132.050652ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:06:53.609718Z","caller":"traceutil/trace.go:171","msg":"trace[1196519630] transaction","detail":"{read_only:false; response_revision:9468; number_of_response:1; }","duration":"129.548813ms","start":"2026-06-15T12:06:53.480136Z","end":"2026-06-15T12:06:53.609685Z","steps":["trace[1196519630] 'process raft request' (duration: 129.42719ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:06:53.609782Z","caller":"traceutil/trace.go:171","msg":"trace[467380724] transaction","detail":"{read_only:false; response_revision:9467; number_of_response:1; }","duration":"132.066053ms","start":"2026-06-15T12:06:53.477653Z","end":"2026-06-15T12:06:53.609719Z","steps":["trace[467380724] 'process raft request' (duration: 131.727255ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:07:01.294938Z","caller":"traceutil/trace.go:171","msg":"trace[1943061] transaction","detail":"{read_only:false; response_revision:9567; number_of_response:1; }","duration":"101.730943ms","start":"2026-06-15T12:07:01.19318Z","end":"2026-06-15T12:07:01.294911Z","steps":["trace[1943061] 'process raft request' (duration: 99.52781ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:07:09.772056Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"71d28e9ac1bc4e5d","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-15T12:07:09.802735Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-06-15T12:07:09.802848Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-06-15T12:07:19.226992Z","caller":"traceutil/trace.go:171","msg":"trace[543726295] linearizableReadLoop","detail":"{readStateIndex:10058; appliedIndex:10057; }","duration":"122.307177ms","start":"2026-06-15T12:07:19.104655Z","end":"2026-06-15T12:07:19.226962Z","steps":["trace[543726295] 'read index received' (duration: 122.00862ms)","trace[543726295] 'applied index is now lower than readState.Index' (duration: 297.337µs)"],"step_count":2} {"level":"info","ts":"2026-06-15T12:07:19.227069Z","caller":"traceutil/trace.go:171","msg":"trace[1598835760] transaction","detail":"{read_only:false; response_revision:9711; number_of_response:1; }","duration":"133.195459ms","start":"2026-06-15T12:07:19.093837Z","end":"2026-06-15T12:07:19.227032Z","steps":["trace[1598835760] 'process raft request' (duration: 132.931682ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:07:19.22714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.466742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T12:07:19.227176Z","caller":"traceutil/trace.go:171","msg":"trace[991655977] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:9711; }","duration":"122.525923ms","start":"2026-06-15T12:07:19.10464Z","end":"2026-06-15T12:07:19.227166Z","steps":["trace[991655977] 'agreement among raft nodes before linearized reading' (duration: 122.438141ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:07:29.434729Z","caller":"traceutil/trace.go:171","msg":"trace[798772596] transaction","detail":"{read_only:false; response_revision:9762; number_of_response:1; }","duration":"575.279886ms","start":"2026-06-15T12:07:28.859421Z","end":"2026-06-15T12:07:29.434701Z","steps":["trace[798772596] 'process raft request' (duration: 574.901737ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:07:29.435587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:07:28.859394Z","time spent":"575.669035ms","remote":"127.0.0.1:50864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T12:07:29.441096Z","caller":"traceutil/trace.go:171","msg":"trace[939618141] linearizableReadLoop","detail":"{readStateIndex:10112; appliedIndex:10110; }","duration":"202.503974ms","start":"2026-06-15T12:07:29.238566Z","end":"2026-06-15T12:07:29.44107Z","steps":["trace[939618141] 'read index received' (duration: 195.730551ms)","trace[939618141] 'applied index is now lower than readState.Index' (duration: 6.772423ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T12:07:29.441273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.698458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T12:07:29.441262Z","caller":"traceutil/trace.go:171","msg":"trace[189133746] transaction","detail":"{read_only:false; response_revision:9763; number_of_response:1; }","duration":"549.741883ms","start":"2026-06-15T12:07:28.891479Z","end":"2026-06-15T12:07:29.441221Z","steps":["trace[189133746] 'process raft request' (duration: 549.453196ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:07:29.441318Z","caller":"traceutil/trace.go:171","msg":"trace[1748987742] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9763; }","duration":"202.820482ms","start":"2026-06-15T12:07:29.238486Z","end":"2026-06-15T12:07:29.441306Z","steps":["trace[1748987742] 'agreement among raft nodes before linearized reading' (duration: 202.704629ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:07:29.441444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:07:28.89145Z","time spent":"549.896657ms","remote":"127.0.0.1:50864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T12:07:29.999407Z","caller":"traceutil/trace.go:171","msg":"trace[384998360] linearizableReadLoop","detail":"{readStateIndex:10114; appliedIndex:10113; }","duration":"263.847207ms","start":"2026-06-15T12:07:29.735525Z","end":"2026-06-15T12:07:29.999372Z","steps":["trace[384998360] 'read index received' (duration: 263.647342ms)","trace[384998360] 'applied index is now lower than readState.Index' (duration: 198.205µs)"],"step_count":2} {"level":"info","ts":"2026-06-15T12:07:29.999553Z","caller":"traceutil/trace.go:171","msg":"trace[188762363] transaction","detail":"{read_only:false; response_revision:9765; number_of_response:1; }","duration":"391.369389ms","start":"2026-06-15T12:07:29.60813Z","end":"2026-06-15T12:07:29.999499Z","steps":["trace[188762363] 'process raft request' (duration: 391.058132ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:07:29.999724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:07:29.608106Z","time spent":"391.530073ms","remote":"127.0.0.1:50864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T12:07:30.000246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.710857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-06-15T12:07:30.000404Z","caller":"traceutil/trace.go:171","msg":"trace[453180972] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9765; }","duration":"264.911712ms","start":"2026-06-15T12:07:29.73547Z","end":"2026-06-15T12:07:30.000382Z","steps":["trace[453180972] 'agreement among raft nodes before linearized reading' (duration: 264.065962ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:07:30.322804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.133617ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T12:07:30.322919Z","caller":"traceutil/trace.go:171","msg":"trace[1070974690] transaction","detail":"{read_only:false; response_revision:9766; number_of_response:1; }","duration":"319.941434ms","start":"2026-06-15T12:07:30.002962Z","end":"2026-06-15T12:07:30.322903Z","steps":["trace[1070974690] 'process raft request' (duration: 84.611832ms)","trace[1070974690] 'compare' (duration: 234.999544ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T12:07:30.322969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:07:30.002945Z","time spent":"320.002575ms","remote":"127.0.0.1:50864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T12:07:30.367143Z","caller":"traceutil/trace.go:171","msg":"trace[1005466440] transaction","detail":"{read_only:false; response_revision:9768; number_of_response:1; }","duration":"362.385613ms","start":"2026-06-15T12:07:30.004729Z","end":"2026-06-15T12:07:30.367114Z","steps":["trace[1005466440] 'process raft request' (duration: 362.295091ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:07:30.367225Z","caller":"traceutil/trace.go:171","msg":"trace[1750395191] transaction","detail":"{read_only:false; response_revision:9767; number_of_response:1; }","duration":"362.934936ms","start":"2026-06-15T12:07:30.004232Z","end":"2026-06-15T12:07:30.367167Z","steps":["trace[1750395191] 'process raft request' (duration: 362.618369ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:07:30.367337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:07:30.004706Z","time spent":"362.530117ms","remote":"127.0.0.1:50864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T12:07:30.36747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:07:30.004223Z","time spent":"363.12351ms","remote":"127.0.0.1:50864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T12:07:30.367489Z","caller":"traceutil/trace.go:171","msg":"trace[725565897] transaction","detail":"{read_only:false; response_revision:9769; number_of_response:1; }","duration":"362.530237ms","start":"2026-06-15T12:07:30.004917Z","end":"2026-06-15T12:07:30.367447Z","steps":["trace[725565897] 'process raft request' (duration: 362.150778ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:07:30.367632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:07:30.004907Z","time spent":"362.65719ms","remote":"127.0.0.1:50864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T12:07:30.367776Z","caller":"traceutil/trace.go:171","msg":"trace[1246700344] linearizableReadLoop","detail":"{readStateIndex:10119; appliedIndex:10114; }","duration":"356.871831ms","start":"2026-06-15T12:07:30.010877Z","end":"2026-06-15T12:07:30.367749Z","steps":["trace[1246700344] 'read index received' (duration: 76.709963ms)","trace[1246700344] 'applied index is now lower than readState.Index' (duration: 280.127477ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T12:07:30.36783Z","caller":"traceutil/trace.go:171","msg":"trace[943622773] transaction","detail":"{read_only:false; response_revision:9770; number_of_response:1; }","duration":"362.099507ms","start":"2026-06-15T12:07:30.005711Z","end":"2026-06-15T12:07:30.36781Z","steps":["trace[943622773] 'process raft request' (duration: 361.643506ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:07:30.367932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:07:30.005703Z","time spent":"362.175698ms","remote":"127.0.0.1:50864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T12:07:30.367987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.102846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-t7789\" ","response":"range_response_count:1 size:6445"} {"level":"info","ts":"2026-06-15T12:07:30.368086Z","caller":"traceutil/trace.go:171","msg":"trace[346568338] range","detail":"{range_begin:/registry/pods/openstack/keepalived-t7789; range_end:; response_count:1; response_revision:9770; }","duration":"357.23265ms","start":"2026-06-15T12:07:30.010832Z","end":"2026-06-15T12:07:30.368065Z","steps":["trace[346568338] 'agreement among raft nodes before linearized reading' (duration: 356.994234ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:07:30.368144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:07:30.010809Z","time spent":"357.319861ms","remote":"127.0.0.1:50768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6467,"request content":"key:\"/registry/pods/openstack/keepalived-t7789\" "} {"level":"info","ts":"2026-06-15T12:09:08.191382Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8064} {"level":"info","ts":"2026-06-15T12:09:08.269431Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8064,"took":"75.33631ms","hash":895841767,"current-db-size-bytes":57274368,"current-db-size":"57 MB","current-db-size-in-use-bytes":27869184,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-06-15T12:09:08.269575Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":895841767,"revision":8064,"compact-revision":5874} {"level":"info","ts":"2026-06-15T12:14:08.197153Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10447} {"level":"info","ts":"2026-06-15T12:14:08.282517Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10447,"took":"83.589738ms","hash":1504559090,"current-db-size-bytes":57274368,"current-db-size":"57 MB","current-db-size-in-use-bytes":23465984,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-06-15T12:14:08.28257Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1504559090,"revision":10447,"compact-revision":8064}