{"level":"warn","ts":"2026-10-02T15:08:36.289032Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-02T15:08:36.289128Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.27:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.27:2380","--initial-cluster=instance=https://162.253.55.27:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.27:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.27:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-10-02T15:08:36.289241Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-02T15:08:36.289295Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.27:2380"]} {"level":"info","ts":"2026-10-02T15:08:36.28933Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-02T15:08:36.289967Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.27:2379"]} {"level":"info","ts":"2026-10-02T15:08:36.290136Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.27:2380"],"listen-peer-urls":["https://162.253.55.27:2380"],"advertise-client-urls":["https://162.253.55.27:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.27:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.27:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-10-02T15:08:36.294895Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.495533ms"} {"level":"info","ts":"2026-10-02T15:08:36.30254Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d9b565cb482924cf","cluster-id":"5bc3f8d4cf836b6c"} {"level":"info","ts":"2026-10-02T15:08:36.302603Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf switched to configuration voters=()"} {"level":"info","ts":"2026-10-02T15:08:36.302618Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf became follower at term 0"} {"level":"info","ts":"2026-10-02T15:08:36.302626Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d9b565cb482924cf [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-02T15:08:36.302714Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf became follower at term 1"} {"level":"info","ts":"2026-10-02T15:08:36.302789Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf switched to configuration voters=(15687556800778413263)"} {"level":"warn","ts":"2026-10-02T15:08:36.308847Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-02T15:08:36.310467Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-02T15:08:36.312833Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-10-02T15:08:36.314959Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d9b565cb482924cf","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-02T15:08:36.315169Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d9b565cb482924cf","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-02T15:08:36.315299Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-02T15:08:36.315363Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-02T15:08:36.315372Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-02T15:08:36.317106Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf switched to configuration voters=(15687556800778413263)"} {"level":"info","ts":"2026-10-02T15:08:36.317269Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5bc3f8d4cf836b6c","local-member-id":"d9b565cb482924cf","added-peer-id":"d9b565cb482924cf","added-peer-peer-urls":["https://162.253.55.27:2380"]} {"level":"info","ts":"2026-10-02T15:08:36.318545Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-02T15:08:36.318724Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.27:2380"} {"level":"info","ts":"2026-10-02T15:08:36.318769Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.27:2380"} {"level":"info","ts":"2026-10-02T15:08:36.318722Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d9b565cb482924cf","initial-advertise-peer-urls":["https://162.253.55.27:2380"],"listen-peer-urls":["https://162.253.55.27:2380"],"advertise-client-urls":["https://162.253.55.27:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.27:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-02T15:08:36.318752Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-02T15:08:36.703261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf is starting a new election at term 1"} {"level":"info","ts":"2026-10-02T15:08:36.703424Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf became pre-candidate at term 1"} {"level":"info","ts":"2026-10-02T15:08:36.70347Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf received MsgPreVoteResp from d9b565cb482924cf at term 1"} {"level":"info","ts":"2026-10-02T15:08:36.703544Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf became candidate at term 2"} {"level":"info","ts":"2026-10-02T15:08:36.703601Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf received MsgVoteResp from d9b565cb482924cf at term 2"} {"level":"info","ts":"2026-10-02T15:08:36.703645Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf became leader at term 2"} {"level":"info","ts":"2026-10-02T15:08:36.703708Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d9b565cb482924cf elected leader d9b565cb482924cf at term 2"} {"level":"info","ts":"2026-10-02T15:08:36.704767Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d9b565cb482924cf","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.27:2379]}","request-path":"/0/members/d9b565cb482924cf/attributes","cluster-id":"5bc3f8d4cf836b6c","publish-timeout":"7s"} {"level":"info","ts":"2026-10-02T15:08:36.705114Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-02T15:08:36.705326Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-02T15:08:36.705359Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-02T15:08:36.705282Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-02T15:08:36.705003Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-02T15:08:36.706444Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5bc3f8d4cf836b6c","local-member-id":"d9b565cb482924cf","cluster-version":"3.5"} {"level":"info","ts":"2026-10-02T15:08:36.706689Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-02T15:08:36.706777Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-02T15:08:36.707984Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-02T15:08:36.714956Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.27:2379"} {"level":"info","ts":"2026-10-02T15:08:38.395903Z","caller":"traceutil/trace.go:171","msg":"trace[1483870428] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"176.961687ms","start":"2026-10-02T15:08:38.218919Z","end":"2026-10-02T15:08:38.39588Z","steps":["trace[1483870428] 'process raft request' (duration: 176.737723ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:08:38.692841Z","caller":"traceutil/trace.go:171","msg":"trace[1232978381] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"473.356428ms","start":"2026-10-02T15:08:38.218964Z","end":"2026-10-02T15:08:38.69232Z","steps":["trace[1232978381] 'process raft request' (duration: 473.019341ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:08:38.692986Z","caller":"traceutil/trace.go:171","msg":"trace[705192316] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"396.037836ms","start":"2026-10-02T15:08:38.296656Z","end":"2026-10-02T15:08:38.692694Z","steps":["trace[705192316] 'process raft request' (duration: 395.486185ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:08:38.693026Z","caller":"traceutil/trace.go:171","msg":"trace[795463313] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"395.633498ms","start":"2026-10-02T15:08:38.296757Z","end":"2026-10-02T15:08:38.692391Z","steps":["trace[795463313] 'process raft request' (duration: 395.422954ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:08:38.693532Z","caller":"traceutil/trace.go:171","msg":"trace[103234579] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"395.478025ms","start":"2026-10-02T15:08:38.296906Z","end":"2026-10-02T15:08:38.692384Z","steps":["trace[103234579] 'process raft request' (duration: 395.355753ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:08:38.693785Z","caller":"traceutil/trace.go:171","msg":"trace[710160234] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"385.187535ms","start":"2026-10-02T15:08:38.308534Z","end":"2026-10-02T15:08:38.693721Z","steps":["trace[710160234] 'process raft request' (duration: 383.781169ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:08:38.693836Z","caller":"traceutil/trace.go:171","msg":"trace[2100634810] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"396.442404ms","start":"2026-10-02T15:08:38.297308Z","end":"2026-10-02T15:08:38.69375Z","steps":["trace[2100634810] 'process raft request' (duration: 394.979326ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:08:38.694301Z","caller":"traceutil/trace.go:171","msg":"trace[1777334489] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"474.5753ms","start":"2026-10-02T15:08:38.219643Z","end":"2026-10-02T15:08:38.694219Z","steps":["trace[1777334489] 'read index received' (duration: 176.213634ms)","trace[1777334489] 'applied index is now lower than readState.Index' (duration: 298.298755ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T15:08:38.694579Z","caller":"traceutil/trace.go:171","msg":"trace[134007899] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"397.277338ms","start":"2026-10-02T15:08:38.296799Z","end":"2026-10-02T15:08:38.694076Z","steps":["trace[134007899] 'process raft request' (duration: 395.413744ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:08:38.694708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:08:38.296779Z","time spent":"397.835219ms","remote":"127.0.0.1:42628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-02T15:08:38.694879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:08:38.218949Z","time spent":"473.972089ms","remote":"127.0.0.1:42122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":100,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T15:08:38.695016Z","caller":"traceutil/trace.go:171","msg":"trace[1156960572] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"382.690268ms","start":"2026-10-02T15:08:38.312281Z","end":"2026-10-02T15:08:38.694971Z","steps":["trace[1156960572] 'process raft request' (duration: 380.060519ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:08:38.695003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:08:38.296637Z","time spent":"396.492324ms","remote":"127.0.0.1:42628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-02T15:08:38.695126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:08:38.312268Z","time spent":"382.80584ms","remote":"127.0.0.1:42344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-02T15:08:38.695512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:08:38.296735Z","time spent":"396.530605ms","remote":"127.0.0.1:42628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-02T15:08:38.695901Z","caller":"traceutil/trace.go:171","msg":"trace[1676813185] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"398.878688ms","start":"2026-10-02T15:08:38.296903Z","end":"2026-10-02T15:08:38.695781Z","steps":["trace[1676813185] 'process raft request' (duration: 395.335032ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:08:38.695958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:08:38.296891Z","time spent":"396.747419ms","remote":"127.0.0.1:42628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-02T15:08:38.695984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:08:38.297295Z","time spent":"396.588266ms","remote":"127.0.0.1:42526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-02T15:08:38.696162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:08:38.30852Z","time spent":"385.335128ms","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3158,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-02T15:08:38.696177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:08:38.296893Z","time spent":"399.060031ms","remote":"127.0.0.1:42628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-02T15:08:39.069294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"773.661412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.27\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-02T15:08:39.0694Z","caller":"traceutil/trace.go:171","msg":"trace[229083451] range","detail":"{range_begin:/registry/masterleases/162.253.55.27; range_end:; response_count:0; response_revision:11; }","duration":"773.819055ms","start":"2026-10-02T15:08:38.295558Z","end":"2026-10-02T15:08:39.069377Z","steps":["trace[229083451] 'agreement among raft nodes before linearized reading' (duration: 400.620251ms)","trace[229083451] 'range keys from in-memory index tree' (duration: 372.993601ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T15:08:39.069445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:08:38.295552Z","time spent":"773.883926ms","remote":"127.0.0.1:42098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":27,"request content":"key:\"/registry/masterleases/162.253.55.27\" "} {"level":"warn","ts":"2026-10-02T15:08:39.069364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.030163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-f6n24\" ","response":"range_response_count:1 size:861"} {"level":"info","ts":"2026-10-02T15:08:39.069596Z","caller":"traceutil/trace.go:171","msg":"trace[583524686] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-f6n24; range_end:; response_count:1; response_revision:11; }","duration":"366.272757ms","start":"2026-10-02T15:08:38.703234Z","end":"2026-10-02T15:08:39.069507Z","steps":["trace[583524686] 'range keys from in-memory index tree' (duration: 365.834878ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:08:39.069677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"849.985266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-02T15:08:39.069712Z","caller":"traceutil/trace.go:171","msg":"trace[13404811] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"850.093877ms","start":"2026-10-02T15:08:38.219607Z","end":"2026-10-02T15:08:39.0697Z","steps":["trace[13404811] 'agreement among raft nodes before linearized reading' (duration: 475.470796ms)","trace[13404811] 'range keys from in-memory index tree' (duration: 374.454909ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T15:08:39.069757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:08:38.219599Z","time spent":"850.151999ms","remote":"127.0.0.1:42472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-10-02T15:08:39.069836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"774.355585ms","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-10-02T15:08:39.069941Z","caller":"traceutil/trace.go:171","msg":"trace[422715506] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"774.513868ms","start":"2026-10-02T15:08:38.295397Z","end":"2026-10-02T15:08:39.069911Z","steps":["trace[422715506] 'agreement among raft nodes before linearized reading' (duration: 400.720703ms)","trace[422715506] 'range keys from in-memory index tree' (duration: 373.629552ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T15:08:39.070009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:08:38.295378Z","time spent":"774.62052ms","remote":"127.0.0.1:42158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-10-02T15:08:39.070106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"850.458175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3170"} {"level":"info","ts":"2026-10-02T15:08:39.070145Z","caller":"traceutil/trace.go:171","msg":"trace[978641638] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:11; }","duration":"850.516216ms","start":"2026-10-02T15:08:38.219619Z","end":"2026-10-02T15:08:39.070135Z","steps":["trace[978641638] 'agreement among raft nodes before linearized reading' (duration: 475.603369ms)","trace[978641638] 'range keys from in-memory index tree' (duration: 374.499079ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T15:08:39.070202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:08:38.219604Z","time spent":"850.589197ms","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3193,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-10-02T15:08:39.069876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"774.162412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-02T15:08:39.070339Z","caller":"traceutil/trace.go:171","msg":"trace[1734374690] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"774.63422ms","start":"2026-10-02T15:08:38.295692Z","end":"2026-10-02T15:08:39.070326Z","steps":["trace[1734374690] 'agreement among raft nodes before linearized reading' (duration: 400.466377ms)","trace[1734374690] 'range keys from in-memory index tree' (duration: 373.691463ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T15:08:39.070379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:08:38.295686Z","time spent":"774.682781ms","remote":"127.0.0.1:42190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-10-02T15:08:39.070484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"798.174597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-02T15:08:39.07052Z","caller":"traceutil/trace.go:171","msg":"trace[871551164] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"798.247298ms","start":"2026-10-02T15:08:38.272263Z","end":"2026-10-02T15:08:39.07051Z","steps":["trace[871551164] 'agreement among raft nodes before linearized reading' (duration: 423.989663ms)","trace[871551164] 'range keys from in-memory index tree' (duration: 373.570193ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T15:08:39.070551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:08:38.272232Z","time spent":"798.313069ms","remote":"127.0.0.1:42190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-10-02T15:08:39.070654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.447251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-02T15:08:39.070687Z","caller":"traceutil/trace.go:171","msg":"trace[1736575657] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"724.524713ms","start":"2026-10-02T15:08:38.346152Z","end":"2026-10-02T15:08:39.070676Z","steps":["trace[1736575657] 'agreement among raft nodes before linearized reading' (duration: 349.435172ms)","trace[1736575657] 'range keys from in-memory index tree' (duration: 374.397737ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T15:08:39.070711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:08:38.346122Z","time spent":"724.582553ms","remote":"127.0.0.1:42190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-10-02T15:08:39.070781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"798.3552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-02T15:08:39.070818Z","caller":"traceutil/trace.go:171","msg":"trace[1917722904] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"798.448142ms","start":"2026-10-02T15:08:38.27236Z","end":"2026-10-02T15:08:39.070808Z","steps":["trace[1917722904] 'agreement among raft nodes before linearized reading' (duration: 424.202948ms)","trace[1917722904] 'range keys from in-memory index tree' (duration: 373.838677ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T15:08:39.070846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:08:38.272335Z","time spent":"798.505123ms","remote":"127.0.0.1:42190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-10-02T15:08:39.070319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"797.936072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-02T15:08:39.071187Z","caller":"traceutil/trace.go:171","msg":"trace[880809540] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"798.822349ms","start":"2026-10-02T15:08:38.272322Z","end":"2026-10-02T15:08:39.071144Z","steps":["trace[880809540] 'agreement among raft nodes before linearized reading' (duration: 423.905072ms)","trace[880809540] 'range keys from in-memory index tree' (duration: 373.343967ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T15:08:39.071326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:08:38.272315Z","time spent":"798.992602ms","remote":"127.0.0.1:42190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-10-02T15:08:39.071817Z","caller":"traceutil/trace.go:171","msg":"trace[1630813026] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"367.676533ms","start":"2026-10-02T15:08:38.704119Z","end":"2026-10-02T15:08:39.071795Z","steps":["trace[1630813026] 'process raft request' (duration: 367.54502ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:08:39.071902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:08:38.704097Z","time spent":"367.760195ms","remote":"127.0.0.1:42526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-02T15:08:39.07359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:08:38.703215Z","time spent":"370.356333ms","remote":"127.0.0.1:42344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":884,"request content":"key:\"/registry/certificatesigningrequests/csr-f6n24\" "} {"level":"info","ts":"2026-10-02T15:08:39.079341Z","caller":"traceutil/trace.go:171","msg":"trace[1758743271] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"374.826574ms","start":"2026-10-02T15:08:38.704493Z","end":"2026-10-02T15:08:39.07932Z","steps":["trace[1758743271] 'process raft request' (duration: 374.681521ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:08:39.079433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:08:38.704442Z","time spent":"374.943446ms","remote":"127.0.0.1:42628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-02T15:08:39.079468Z","caller":"traceutil/trace.go:171","msg":"trace[840392924] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"374.926617ms","start":"2026-10-02T15:08:38.704534Z","end":"2026-10-02T15:08:39.079461Z","steps":["trace[840392924] 'process raft request' (duration: 374.726423ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:08:39.079424Z","caller":"traceutil/trace.go:171","msg":"trace[733342052] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"366.622493ms","start":"2026-10-02T15:08:38.712784Z","end":"2026-10-02T15:08:39.079406Z","steps":["trace[733342052] 'process raft request' (duration: 366.576582ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:08:39.079548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:08:38.70452Z","time spent":"374.980287ms","remote":"127.0.0.1:42628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-02T15:08:39.079558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:08:38.712771Z","time spent":"366.756145ms","remote":"127.0.0.1:42276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3478,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T15:08:39.079648Z","caller":"traceutil/trace.go:171","msg":"trace[1516972693] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"374.815104ms","start":"2026-10-02T15:08:38.704825Z","end":"2026-10-02T15:08:39.07964Z","steps":["trace[1516972693] 'process raft request' (duration: 374.457207ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:08:39.07969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:08:38.704796Z","time spent":"374.875776ms","remote":"127.0.0.1:42628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-02T15:08:39.079752Z","caller":"traceutil/trace.go:171","msg":"trace[194984326] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"375.054179ms","start":"2026-10-02T15:08:38.704687Z","end":"2026-10-02T15:08:39.079741Z","steps":["trace[194984326] 'process raft request' (duration: 374.640341ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:08:39.079877Z","caller":"traceutil/trace.go:171","msg":"trace[1025249042] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"375.059179ms","start":"2026-10-02T15:08:38.704795Z","end":"2026-10-02T15:08:39.079854Z","steps":["trace[1025249042] 'process raft request' (duration: 374.513089ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:08:39.079931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:08:38.704772Z","time spent":"375.13553ms","remote":"127.0.0.1:42628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-02T15:08:39.080018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:08:38.704675Z","time spent":"375.10686ms","remote":"127.0.0.1:42628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":923,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-02T15:09:14.042707Z","caller":"traceutil/trace.go:171","msg":"trace[615253047] transaction","detail":"{read_only:false; number_of_response:0; response_revision:503; }","duration":"129.290703ms","start":"2026-10-02T15:09:13.913396Z","end":"2026-10-02T15:09:14.042687Z","steps":["trace[615253047] 'process raft request' (duration: 121.650134ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:09:14.051832Z","caller":"traceutil/trace.go:171","msg":"trace[1258945919] transaction","detail":"{read_only:false; number_of_response:0; response_revision:504; }","duration":"135.417939ms","start":"2026-10-02T15:09:13.916398Z","end":"2026-10-02T15:09:14.051816Z","steps":["trace[1258945919] 'process raft request' (duration: 135.321718ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:09:14.051944Z","caller":"traceutil/trace.go:171","msg":"trace[2096984938] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"126.370656ms","start":"2026-10-02T15:09:13.925565Z","end":"2026-10-02T15:09:14.051936Z","steps":["trace[2096984938] 'process raft request' (duration: 126.254294ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:09:14.052028Z","caller":"traceutil/trace.go:171","msg":"trace[1521345302] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"133.718123ms","start":"2026-10-02T15:09:13.918304Z","end":"2026-10-02T15:09:14.052022Z","steps":["trace[1521345302] 'process raft request' (duration: 133.440708ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:09:14.052197Z","caller":"traceutil/trace.go:171","msg":"trace[407493856] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"122.928432ms","start":"2026-10-02T15:09:13.929262Z","end":"2026-10-02T15:09:14.052191Z","steps":["trace[407493856] 'process raft request' (duration: 122.606978ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:09:14.052325Z","caller":"traceutil/trace.go:171","msg":"trace[1944483278] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"110.512076ms","start":"2026-10-02T15:09:13.941784Z","end":"2026-10-02T15:09:14.052296Z","steps":["trace[1944483278] 'process raft request' (duration: 110.182482ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:09:14.052452Z","caller":"traceutil/trace.go:171","msg":"trace[1047463215] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"106.96665ms","start":"2026-10-02T15:09:13.945468Z","end":"2026-10-02T15:09:14.052435Z","steps":["trace[1047463215] 'process raft request' (duration: 106.622806ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:09:14.055941Z","caller":"traceutil/trace.go:171","msg":"trace[1796383935] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"110.313253ms","start":"2026-10-02T15:09:13.945593Z","end":"2026-10-02T15:09:14.055906Z","steps":["trace[1796383935] 'process raft request' (duration: 106.576754ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:09:14.056225Z","caller":"traceutil/trace.go:171","msg":"trace[140195369] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"136.987914ms","start":"2026-10-02T15:09:13.915603Z","end":"2026-10-02T15:09:14.052591Z","steps":["trace[140195369] 'process raft request' (duration: 135.963298ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:09:14.056642Z","caller":"traceutil/trace.go:171","msg":"trace[1963642540] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"107.817254ms","start":"2026-10-02T15:09:13.948809Z","end":"2026-10-02T15:09:14.056626Z","steps":["trace[1963642540] 'process raft request' (duration: 103.446025ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:09:15.396636Z","caller":"traceutil/trace.go:171","msg":"trace[19410849] linearizableReadLoop","detail":"{readStateIndex:586; appliedIndex:585; }","duration":"139.126614ms","start":"2026-10-02T15:09:15.257483Z","end":"2026-10-02T15:09:15.39661Z","steps":["trace[19410849] 'read index received' (duration: 110.510147ms)","trace[19410849] 'applied index is now lower than readState.Index' (duration: 28.615487ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T15:09:15.396706Z","caller":"traceutil/trace.go:171","msg":"trace[867922143] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"143.234269ms","start":"2026-10-02T15:09:15.253449Z","end":"2026-10-02T15:09:15.396683Z","steps":["trace[867922143] 'process raft request' (duration: 114.49394ms)","trace[867922143] 'compare' (duration: 28.565657ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T15:09:15.396917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.412959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/xlistenersets.gateway.networking.x-k8s.io\" ","response":"range_response_count:1 size:29476"} {"level":"info","ts":"2026-10-02T15:09:15.396954Z","caller":"traceutil/trace.go:171","msg":"trace[937680541] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/xlistenersets.gateway.networking.x-k8s.io; range_end:; response_count:1; response_revision:563; }","duration":"139.491319ms","start":"2026-10-02T15:09:15.257449Z","end":"2026-10-02T15:09:15.396941Z","steps":["trace[937680541] 'agreement among raft nodes before linearized reading' (duration: 139.265706ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:09:15.417078Z","caller":"traceutil/trace.go:171","msg":"trace[10776164] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"157.39561ms","start":"2026-10-02T15:09:15.259661Z","end":"2026-10-02T15:09:15.417057Z","steps":["trace[10776164] 'process raft request' (duration: 157.218057ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:09:15.975026Z","caller":"traceutil/trace.go:171","msg":"trace[1096343535] linearizableReadLoop","detail":"{readStateIndex:589; appliedIndex:588; }","duration":"442.588386ms","start":"2026-10-02T15:09:15.532409Z","end":"2026-10-02T15:09:15.974997Z","steps":["trace[1096343535] 'read index received' (duration: 442.19242ms)","trace[1096343535] 'applied index is now lower than readState.Index' (duration: 394.676µs)"],"step_count":2} {"level":"warn","ts":"2026-10-02T15:09:15.975216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.782179ms","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-10-02T15:09:15.975275Z","caller":"traceutil/trace.go:171","msg":"trace[1663707640] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:565; }","duration":"442.869001ms","start":"2026-10-02T15:09:15.532393Z","end":"2026-10-02T15:09:15.975262Z","steps":["trace[1663707640] 'agreement among raft nodes before linearized reading' (duration: 442.741539ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:09:15.975299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.672068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoyextensionpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:74769"} {"level":"warn","ts":"2026-10-02T15:09:15.975321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:09:15.532384Z","time spent":"442.925722ms","remote":"127.0.0.1:42354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-02T15:09:15.975352Z","caller":"traceutil/trace.go:171","msg":"trace[588842232] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/envoyextensionpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:565; }","duration":"442.728819ms","start":"2026-10-02T15:09:15.532608Z","end":"2026-10-02T15:09:15.975337Z","steps":["trace[588842232] 'agreement among raft nodes before linearized reading' (duration: 442.530556ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:09:15.975396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:09:15.532577Z","time spent":"442.80759ms","remote":"127.0.0.1:42108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":103,"response count":1,"response size":74792,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoyextensionpolicies.gateway.envoyproxy.io\" "} {"level":"info","ts":"2026-10-02T15:09:15.975096Z","caller":"traceutil/trace.go:171","msg":"trace[221850951] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"451.853662ms","start":"2026-10-02T15:09:15.523218Z","end":"2026-10-02T15:09:15.975071Z","steps":["trace[221850951] 'process raft request' (duration: 451.485076ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:09:15.97588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:09:15.52319Z","time spent":"452.613022ms","remote":"127.0.0.1:42108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":208470,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T15:09:20.27245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.87361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-10-02T15:09:20.272578Z","caller":"traceutil/trace.go:171","msg":"trace[1085704529] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:590; }","duration":"159.058423ms","start":"2026-10-02T15:09:20.1135Z","end":"2026-10-02T15:09:20.272559Z","steps":["trace[1085704529] 'range keys from in-memory index tree' (duration: 158.786329ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:09:20.572959Z","caller":"traceutil/trace.go:171","msg":"trace[711672645] linearizableReadLoop","detail":"{readStateIndex:617; appliedIndex:616; }","duration":"118.362036ms","start":"2026-10-02T15:09:20.454476Z","end":"2026-10-02T15:09:20.572838Z","steps":["trace[711672645] 'read index received' (duration: 109.49444ms)","trace[711672645] 'applied index is now lower than readState.Index' (duration: 8.866226ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T15:09:20.573097Z","caller":"traceutil/trace.go:171","msg":"trace[1446241530] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"229.611855ms","start":"2026-10-02T15:09:20.343459Z","end":"2026-10-02T15:09:20.573071Z","steps":["trace[1446241530] 'process raft request' (duration: 220.682978ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:09:20.573121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.56872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-02T15:09:20.573355Z","caller":"traceutil/trace.go:171","msg":"trace[2130084945] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:592; }","duration":"118.738633ms","start":"2026-10-02T15:09:20.454424Z","end":"2026-10-02T15:09:20.573163Z","steps":["trace[2130084945] 'agreement among raft nodes before linearized reading' (duration: 118.452528ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:09:21.062419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.324823ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-02T15:09:21.062812Z","caller":"traceutil/trace.go:171","msg":"trace[889773179] linearizableReadLoop","detail":"{readStateIndex:618; appliedIndex:617; }","duration":"489.750664ms","start":"2026-10-02T15:09:20.573036Z","end":"2026-10-02T15:09:21.062787Z","steps":["trace[889773179] 'read index received' (duration: 239.812071ms)","trace[889773179] 'applied index is now lower than readState.Index' (duration: 249.935973ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T15:09:21.062808Z","caller":"traceutil/trace.go:171","msg":"trace[846221432] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"532.042034ms","start":"2026-10-02T15:09:20.530724Z","end":"2026-10-02T15:09:21.062766Z","steps":["trace[846221432] 'process raft request' (duration: 282.153012ms)","trace[846221432] 'compare' (duration: 249.148641ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T15:09:21.063048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:09:20.530681Z","time spent":"532.307499ms","remote":"127.0.0.1:42354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T15:09:21.063112Z","caller":"traceutil/trace.go:171","msg":"trace[1086776035] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"413.573985ms","start":"2026-10-02T15:09:20.649461Z","end":"2026-10-02T15:09:21.063035Z","steps":["trace[1086776035] 'process raft request' (duration: 413.127508ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:09:21.063155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.608178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-02T15:09:21.063205Z","caller":"traceutil/trace.go:171","msg":"trace[916734204] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:595; }","duration":"419.69718ms","start":"2026-10-02T15:09:20.643495Z","end":"2026-10-02T15:09:21.063192Z","steps":["trace[916734204] 'agreement among raft nodes before linearized reading' (duration: 419.500607ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:09:21.063237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:09:20.64348Z","time spent":"419.74883ms","remote":"127.0.0.1:42354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-02T15:09:21.062951Z","caller":"traceutil/trace.go:171","msg":"trace[620663428] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"481.502448ms","start":"2026-10-02T15:09:20.581333Z","end":"2026-10-02T15:09:21.062835Z","steps":["trace[620663428] 'process raft request' (duration: 481.191843ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:09:21.06336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:09:20.649451Z","time spent":"413.701257ms","remote":"127.0.0.1:42436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-02T15:09:21.063427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.051589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-02T15:09:21.063786Z","caller":"traceutil/trace.go:171","msg":"trace[1626196040] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:595; }","duration":"602.442825ms","start":"2026-10-02T15:09:20.461324Z","end":"2026-10-02T15:09:21.063766Z","steps":["trace[1626196040] 'agreement among raft nodes before linearized reading' (duration: 601.603762ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:09:21.06385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:09:20.461311Z","time spent":"602.520847ms","remote":"127.0.0.1:42354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-02T15:09:21.064051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:09:20.58131Z","time spent":"482.24507ms","remote":"127.0.0.1:42354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T15:09:22.602184Z","caller":"traceutil/trace.go:171","msg":"trace[745409890] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"125.441002ms","start":"2026-10-02T15:09:22.476716Z","end":"2026-10-02T15:09:22.602157Z","steps":["trace[745409890] 'process raft request' (duration: 125.29399ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:09:22.64625Z","caller":"traceutil/trace.go:171","msg":"trace[1882234808] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"166.56804ms","start":"2026-10-02T15:09:22.47965Z","end":"2026-10-02T15:09:22.646218Z","steps":["trace[1882234808] 'process raft request' (duration: 166.391897ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:09:27.38568Z","caller":"traceutil/trace.go:171","msg":"trace[909885766] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"114.078382ms","start":"2026-10-02T15:09:27.271582Z","end":"2026-10-02T15:09:27.385661Z","steps":["trace[909885766] 'process raft request' (duration: 113.94534ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:09:33.359047Z","caller":"traceutil/trace.go:171","msg":"trace[29819517] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"117.043213ms","start":"2026-10-02T15:09:33.241975Z","end":"2026-10-02T15:09:33.359018Z","steps":["trace[29819517] 'process raft request' (duration: 116.895711ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:09:35.951643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.164825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T15:09:35.951704Z","caller":"traceutil/trace.go:171","msg":"trace[1046044285] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:757; }","duration":"173.275286ms","start":"2026-10-02T15:09:35.778415Z","end":"2026-10-02T15:09:35.95169Z","steps":["trace[1046044285] 'range keys from in-memory index tree' (duration: 173.092344ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:10:05.663119Z","caller":"traceutil/trace.go:171","msg":"trace[1730608323] transaction","detail":"{read_only:false; response_revision:1157; number_of_response:1; }","duration":"101.860899ms","start":"2026-10-02T15:10:05.561224Z","end":"2026-10-02T15:10:05.663085Z","steps":["trace[1730608323] 'process raft request' (duration: 101.662296ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:10:05.831377Z","caller":"traceutil/trace.go:171","msg":"trace[1420337953] transaction","detail":"{read_only:false; response_revision:1158; number_of_response:1; }","duration":"168.399226ms","start":"2026-10-02T15:10:05.662935Z","end":"2026-10-02T15:10:05.831334Z","steps":["trace[1420337953] 'process raft request' (duration: 98.741466ms)","trace[1420337953] 'compare' (duration: 69.370006ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T15:10:54.046143Z","caller":"traceutil/trace.go:171","msg":"trace[831104410] linearizableReadLoop","detail":"{readStateIndex:1894; appliedIndex:1893; }","duration":"353.858561ms","start":"2026-10-02T15:10:53.692256Z","end":"2026-10-02T15:10:54.046115Z","steps":["trace[831104410] 'read index received' (duration: 353.700069ms)","trace[831104410] 'applied index is now lower than readState.Index' (duration: 157.172µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T15:10:54.046235Z","caller":"traceutil/trace.go:171","msg":"trace[485626579] transaction","detail":"{read_only:false; response_revision:1821; number_of_response:1; }","duration":"449.897576ms","start":"2026-10-02T15:10:53.596302Z","end":"2026-10-02T15:10:54.046199Z","steps":["trace[485626579] 'process raft request' (duration: 449.651492ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:10:54.04639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:10:53.596284Z","time spent":"450.028087ms","remote":"127.0.0.1:42354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T15:10:54.046387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.113284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-02T15:10:54.046523Z","caller":"traceutil/trace.go:171","msg":"trace[883860361] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1821; }","duration":"354.265126ms","start":"2026-10-02T15:10:53.692242Z","end":"2026-10-02T15:10:54.046507Z","steps":["trace[883860361] 'agreement among raft nodes before linearized reading' (duration: 353.976372ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:10:54.046568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:10:53.692229Z","time spent":"354.325706ms","remote":"127.0.0.1:42354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-02T15:10:54.075104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.036339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-10-02T15:10:54.075207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.022402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T15:10:54.075218Z","caller":"traceutil/trace.go:171","msg":"trace[1506736995] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1822; }","duration":"349.198281ms","start":"2026-10-02T15:10:53.725994Z","end":"2026-10-02T15:10:54.075193Z","steps":["trace[1506736995] 'agreement among raft nodes before linearized reading' (duration: 348.914778ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:10:54.075251Z","caller":"traceutil/trace.go:171","msg":"trace[669556069] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1822; }","duration":"201.094843ms","start":"2026-10-02T15:10:53.874144Z","end":"2026-10-02T15:10:54.075239Z","steps":["trace[669556069] 'agreement among raft nodes before linearized reading' (duration: 201.024352ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:10:54.075301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:10:53.725974Z","time spent":"349.298652ms","remote":"127.0.0.1:42354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-02T15:10:54.075298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.842535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-31e2db8f-38b7-495f-822b-1e48bc9c862a\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-10-02T15:10:54.075711Z","caller":"traceutil/trace.go:171","msg":"trace[2027734437] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-31e2db8f-38b7-495f-822b-1e48bc9c862a; range_end:; response_count:1; response_revision:1822; }","duration":"228.342351ms","start":"2026-10-02T15:10:53.847343Z","end":"2026-10-02T15:10:54.075686Z","steps":["trace[2027734437] 'agreement among raft nodes before linearized reading' (duration: 227.796184ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:10:54.07546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.054677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T15:10:54.075897Z","caller":"traceutil/trace.go:171","msg":"trace[1364396015] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1822; }","duration":"297.564453ms","start":"2026-10-02T15:10:53.77832Z","end":"2026-10-02T15:10:54.075885Z","steps":["trace[1364396015] 'agreement among raft nodes before linearized reading' (duration: 297.009536ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:10:54.17421Z","caller":"traceutil/trace.go:171","msg":"trace[1208960858] transaction","detail":"{read_only:false; response_revision:1823; number_of_response:1; }","duration":"122.223258ms","start":"2026-10-02T15:10:54.051959Z","end":"2026-10-02T15:10:54.174182Z","steps":["trace[1208960858] 'process raft request' (duration: 64.961428ms)","trace[1208960858] 'compare' (duration: 57.144428ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T15:10:54.382402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.101225ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-02T15:10:54.382801Z","caller":"traceutil/trace.go:171","msg":"trace[827239364] transaction","detail":"{read_only:false; response_revision:1824; number_of_response:1; }","duration":"302.701086ms","start":"2026-10-02T15:10:54.079909Z","end":"2026-10-02T15:10:54.38261Z","steps":["trace[827239364] 'process raft request' (duration: 202.235736ms)","trace[827239364] 'compare' (duration: 99.771641ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T15:10:54.382892Z","caller":"traceutil/trace.go:171","msg":"trace[203268975] transaction","detail":"{read_only:false; response_revision:1825; number_of_response:1; }","duration":"301.812554ms","start":"2026-10-02T15:10:54.081051Z","end":"2026-10-02T15:10:54.382864Z","steps":["trace[203268975] 'process raft request' (duration: 301.52015ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:10:54.383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:10:54.081036Z","time spent":"301.920895ms","remote":"127.0.0.1:42354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T15:10:54.383098Z","caller":"traceutil/trace.go:171","msg":"trace[2053708935] linearizableReadLoop","detail":"{readStateIndex:1899; appliedIndex:1896; }","duration":"229.342231ms","start":"2026-10-02T15:10:54.153723Z","end":"2026-10-02T15:10:54.383066Z","steps":["trace[2053708935] 'read index received' (duration: 128.322074ms)","trace[2053708935] 'applied index is now lower than readState.Index' (duration: 101.018537ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T15:10:54.383111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:10:54.079881Z","time spent":"302.995709ms","remote":"127.0.0.1:42354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T15:10:54.383238Z","caller":"traceutil/trace.go:171","msg":"trace[285984380] transaction","detail":"{read_only:false; response_revision:1826; number_of_response:1; }","duration":"298.521943ms","start":"2026-10-02T15:10:54.084613Z","end":"2026-10-02T15:10:54.383135Z","steps":["trace[285984380] 'process raft request' (duration: 298.163978ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:10:54.383433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.694735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-10-02T15:10:54.383486Z","caller":"traceutil/trace.go:171","msg":"trace[567110423] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1826; }","duration":"229.795197ms","start":"2026-10-02T15:10:54.153677Z","end":"2026-10-02T15:10:54.383472Z","steps":["trace[567110423] 'agreement among raft nodes before linearized reading' (duration: 229.502903ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:10:54.686463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.741786ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-02T15:10:54.686877Z","caller":"traceutil/trace.go:171","msg":"trace[264563221] transaction","detail":"{read_only:false; response_revision:1827; number_of_response:1; }","duration":"204.496094ms","start":"2026-10-02T15:10:54.482079Z","end":"2026-10-02T15:10:54.686575Z","steps":["trace[264563221] 'process raft request' (duration: 69.262052ms)","trace[264563221] 'compare' (duration: 133.714673ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T15:10:55.284488Z","caller":"traceutil/trace.go:171","msg":"trace[634557922] transaction","detail":"{read_only:false; response_revision:1832; number_of_response:1; }","duration":"122.527069ms","start":"2026-10-02T15:10:55.161932Z","end":"2026-10-02T15:10:55.28446Z","steps":["trace[634557922] 'process raft request' (duration: 112.997087ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:10:55.285228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.365408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-10-02T15:10:55.285296Z","caller":"traceutil/trace.go:171","msg":"trace[1972634790] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1831; }","duration":"126.519541ms","start":"2026-10-02T15:10:55.15876Z","end":"2026-10-02T15:10:55.28528Z","steps":["trace[1972634790] 'range keys from in-memory index tree' (duration: 125.625838ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:11:12.271793Z","caller":"traceutil/trace.go:171","msg":"trace[412494614] linearizableReadLoop","detail":"{readStateIndex:2045; appliedIndex:2044; }","duration":"131.615522ms","start":"2026-10-02T15:11:12.14015Z","end":"2026-10-02T15:11:12.271766Z","steps":["trace[412494614] 'read index received' (duration: 131.424039ms)","trace[412494614] 'applied index is now lower than readState.Index' (duration: 190.083µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T15:11:12.271835Z","caller":"traceutil/trace.go:171","msg":"trace[2038894604] transaction","detail":"{read_only:false; response_revision:1968; number_of_response:1; }","duration":"137.583046ms","start":"2026-10-02T15:11:12.134226Z","end":"2026-10-02T15:11:12.271809Z","steps":["trace[2038894604] 'process raft request' (duration: 137.336313ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:11:12.272005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.837115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-02T15:11:12.272062Z","caller":"traceutil/trace.go:171","msg":"trace[1765659265] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1968; }","duration":"131.946895ms","start":"2026-10-02T15:11:12.1401Z","end":"2026-10-02T15:11:12.272047Z","steps":["trace[1765659265] 'agreement among raft nodes before linearized reading' (duration: 131.762803ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:11:27.233066Z","caller":"traceutil/trace.go:171","msg":"trace[1866083330] transaction","detail":"{read_only:false; response_revision:2063; number_of_response:1; }","duration":"121.766614ms","start":"2026-10-02T15:11:27.11127Z","end":"2026-10-02T15:11:27.233037Z","steps":["trace[1866083330] 'process raft request' (duration: 121.589771ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:11:28.670105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.665821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-02T15:11:28.670193Z","caller":"traceutil/trace.go:171","msg":"trace[1771917260] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2067; }","duration":"117.778742ms","start":"2026-10-02T15:11:28.552397Z","end":"2026-10-02T15:11:28.670176Z","steps":["trace[1771917260] 'range keys from in-memory index tree' (duration: 117.53143ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:11:28.670478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.639161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-02T15:11:28.670515Z","caller":"traceutil/trace.go:171","msg":"trace[1042300576] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2067; }","duration":"113.703372ms","start":"2026-10-02T15:11:28.556803Z","end":"2026-10-02T15:11:28.670506Z","steps":["trace[1042300576] 'range keys from in-memory index tree' (duration: 112.993143ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:11:28.775377Z","caller":"traceutil/trace.go:171","msg":"trace[315957328] transaction","detail":"{read_only:false; response_revision:2068; number_of_response:1; }","duration":"100.208105ms","start":"2026-10-02T15:11:28.675115Z","end":"2026-10-02T15:11:28.775323Z","steps":["trace[315957328] 'process raft request' (duration: 100.019532ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:11:28.776009Z","caller":"traceutil/trace.go:171","msg":"trace[2027172365] transaction","detail":"{read_only:false; response_revision:2069; number_of_response:1; }","duration":"100.067063ms","start":"2026-10-02T15:11:28.67592Z","end":"2026-10-02T15:11:28.775987Z","steps":["trace[2027172365] 'process raft request' (duration: 99.945941ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:12:25.564029Z","caller":"traceutil/trace.go:171","msg":"trace[1439349402] transaction","detail":"{read_only:false; response_revision:2424; number_of_response:1; }","duration":"115.323031ms","start":"2026-10-02T15:12:25.448681Z","end":"2026-10-02T15:12:25.564004Z","steps":["trace[1439349402] 'process raft request' (duration: 115.155206ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:12:34.710564Z","caller":"traceutil/trace.go:171","msg":"trace[647952057] linearizableReadLoop","detail":"{readStateIndex:2616; appliedIndex:2615; }","duration":"138.603287ms","start":"2026-10-02T15:12:34.571941Z","end":"2026-10-02T15:12:34.710544Z","steps":["trace[647952057] 'read index received' (duration: 138.542885ms)","trace[647952057] 'applied index is now lower than readState.Index' (duration: 59.562µs)"],"step_count":2} {"level":"warn","ts":"2026-10-02T15:12:34.710811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.852895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-02T15:12:34.710842Z","caller":"traceutil/trace.go:171","msg":"trace[518579075] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:2521; }","duration":"138.988219ms","start":"2026-10-02T15:12:34.571843Z","end":"2026-10-02T15:12:34.710831Z","steps":["trace[518579075] 'agreement among raft nodes before linearized reading' (duration: 138.804793ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:12:34.711177Z","caller":"traceutil/trace.go:171","msg":"trace[1376636934] transaction","detail":"{read_only:false; response_revision:2521; number_of_response:1; }","duration":"279.807572ms","start":"2026-10-02T15:12:34.431359Z","end":"2026-10-02T15:12:34.711166Z","steps":["trace[1376636934] 'process raft request' (duration: 279.050696ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:13:01.434247Z","caller":"traceutil/trace.go:171","msg":"trace[1185787050] transaction","detail":"{read_only:false; response_revision:2673; number_of_response:1; }","duration":"114.397723ms","start":"2026-10-02T15:13:01.319822Z","end":"2026-10-02T15:13:01.43422Z","steps":["trace[1185787050] 'process raft request' (duration: 103.392374ms)","trace[1185787050] 'compare' (duration: 10.820963ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T15:13:07.166187Z","caller":"traceutil/trace.go:171","msg":"trace[1628084250] linearizableReadLoop","detail":"{readStateIndex:2802; appliedIndex:2801; }","duration":"203.138459ms","start":"2026-10-02T15:13:06.963021Z","end":"2026-10-02T15:13:07.16616Z","steps":["trace[1628084250] 'read index received' (duration: 142.296929ms)","trace[1628084250] 'applied index is now lower than readState.Index' (duration: 60.84022ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T15:13:07.166216Z","caller":"traceutil/trace.go:171","msg":"trace[1543386791] transaction","detail":"{read_only:false; response_revision:2699; number_of_response:1; }","duration":"263.675337ms","start":"2026-10-02T15:13:06.902516Z","end":"2026-10-02T15:13:07.166191Z","steps":["trace[1543386791] 'process raft request' (duration: 202.873089ms)","trace[1543386791] 'compare' (duration: 60.458468ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T15:13:07.166897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.852499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-02T15:13:07.167008Z","caller":"traceutil/trace.go:171","msg":"trace[1020276822] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2699; }","duration":"204.054436ms","start":"2026-10-02T15:13:06.962924Z","end":"2026-10-02T15:13:07.166979Z","steps":["trace[1020276822] 'agreement among raft nodes before linearized reading' (duration: 203.337205ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:16:55.891095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.930847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T15:16:55.891234Z","caller":"traceutil/trace.go:171","msg":"trace[1364743708] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4007; }","duration":"112.09549ms","start":"2026-10-02T15:16:55.779096Z","end":"2026-10-02T15:16:55.891192Z","steps":["trace[1364743708] 'agreement among raft nodes before linearized reading' (duration: 111.875226ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:16:56.0047Z","caller":"traceutil/trace.go:171","msg":"trace[344378589] transaction","detail":"{read_only:false; response_revision:4009; number_of_response:1; }","duration":"102.396588ms","start":"2026-10-02T15:16:55.902226Z","end":"2026-10-02T15:16:56.004622Z","steps":["trace[344378589] 'process raft request' (duration: 87.844118ms)","trace[344378589] 'compare' (duration: 14.280515ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T15:16:56.004799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.151185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T15:16:56.004898Z","caller":"traceutil/trace.go:171","msg":"trace[1360087846] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4011; }","duration":"106.284157ms","start":"2026-10-02T15:16:55.898591Z","end":"2026-10-02T15:16:56.004876Z","steps":["trace[1360087846] 'agreement among raft nodes before linearized reading' (duration: 106.098183ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:16:56.004995Z","caller":"traceutil/trace.go:171","msg":"trace[2057599135] transaction","detail":"{read_only:false; response_revision:4010; number_of_response:1; }","duration":"101.525702ms","start":"2026-10-02T15:16:55.903434Z","end":"2026-10-02T15:16:56.00496Z","steps":["trace[2057599135] 'process raft request' (duration: 101.067494ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:16:56.005261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.331411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T15:16:56.005314Z","caller":"traceutil/trace.go:171","msg":"trace[717204742] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4011; }","duration":"104.426402ms","start":"2026-10-02T15:16:55.900878Z","end":"2026-10-02T15:16:56.005304Z","steps":["trace[717204742] 'agreement among raft nodes before linearized reading' (duration: 104.354671ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:17:06.933428Z","caller":"traceutil/trace.go:171","msg":"trace[1133088114] transaction","detail":"{read_only:false; response_revision:4112; number_of_response:1; }","duration":"110.619023ms","start":"2026-10-02T15:17:06.822777Z","end":"2026-10-02T15:17:06.933396Z","steps":["trace[1133088114] 'process raft request' (duration: 61.025228ms)","trace[1133088114] 'compare' (duration: 49.467123ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T15:17:46.072748Z","caller":"traceutil/trace.go:171","msg":"trace[1472615894] transaction","detail":"{read_only:false; response_revision:4749; number_of_response:1; }","duration":"101.786439ms","start":"2026-10-02T15:17:45.970937Z","end":"2026-10-02T15:17:46.072724Z","steps":["trace[1472615894] 'process raft request' (duration: 98.692926ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:17:52.726192Z","caller":"traceutil/trace.go:171","msg":"trace[1939224373] transaction","detail":"{read_only:false; response_revision:4885; number_of_response:1; }","duration":"103.708655ms","start":"2026-10-02T15:17:52.622442Z","end":"2026-10-02T15:17:52.72615Z","steps":["trace[1939224373] 'process raft request' (duration: 103.221066ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:17:58.037705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.53547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-88a61938-e8df-4122-b9bb-a1aad864a7d0\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-10-02T15:17:58.037762Z","caller":"traceutil/trace.go:171","msg":"trace[940029609] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-88a61938-e8df-4122-b9bb-a1aad864a7d0; range_end:; response_count:1; response_revision:4977; }","duration":"164.638272ms","start":"2026-10-02T15:17:57.873111Z","end":"2026-10-02T15:17:58.037749Z","steps":["trace[940029609] 'range keys from in-memory index tree' (duration: 164.359228ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:18:03.443147Z","caller":"traceutil/trace.go:171","msg":"trace[349818552] linearizableReadLoop","detail":"{readStateIndex:5263; appliedIndex:5262; }","duration":"113.146231ms","start":"2026-10-02T15:18:03.329964Z","end":"2026-10-02T15:18:03.44311Z","steps":["trace[349818552] 'read index received' (duration: 112.946488ms)","trace[349818552] 'applied index is now lower than readState.Index' (duration: 198.853µs)"],"step_count":2} {"level":"warn","ts":"2026-10-02T15:18:03.443294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.302493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-02T15:18:03.44333Z","caller":"traceutil/trace.go:171","msg":"trace[293150541] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:5070; }","duration":"113.360214ms","start":"2026-10-02T15:18:03.32996Z","end":"2026-10-02T15:18:03.44332Z","steps":["trace[293150541] 'agreement among raft nodes before linearized reading' (duration: 113.260763ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:18:03.443768Z","caller":"traceutil/trace.go:171","msg":"trace[1843059449] transaction","detail":"{read_only:false; response_revision:5070; number_of_response:1; }","duration":"122.807245ms","start":"2026-10-02T15:18:03.320932Z","end":"2026-10-02T15:18:03.443739Z","steps":["trace[1843059449] 'process raft request' (duration: 122.037432ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:18:14.492279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.228315ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-02T15:18:14.493125Z","caller":"traceutil/trace.go:171","msg":"trace[1244340621] transaction","detail":"{read_only:false; response_revision:5184; number_of_response:1; }","duration":"227.055281ms","start":"2026-10-02T15:18:14.266047Z","end":"2026-10-02T15:18:14.493102Z","steps":["trace[1244340621] 'process raft request' (duration: 226.99086ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:18:14.493074Z","caller":"traceutil/trace.go:171","msg":"trace[1395194201] linearizableReadLoop","detail":"{readStateIndex:5380; appliedIndex:5379; }","duration":"523.848752ms","start":"2026-10-02T15:18:13.969202Z","end":"2026-10-02T15:18:14.493051Z","steps":["trace[1395194201] 'read index received' (duration: 373.765903ms)","trace[1395194201] 'applied index is now lower than readState.Index' (duration: 150.081309ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T15:18:14.493384Z","caller":"traceutil/trace.go:171","msg":"trace[1396891803] transaction","detail":"{read_only:false; response_revision:5182; number_of_response:1; }","duration":"542.18869ms","start":"2026-10-02T15:18:13.951183Z","end":"2026-10-02T15:18:14.493372Z","steps":["trace[1396891803] 'process raft request' (duration: 391.771576ms)","trace[1396891803] 'compare' (duration: 149.107113ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T15:18:14.493443Z","caller":"traceutil/trace.go:171","msg":"trace[1738641145] transaction","detail":"{read_only:false; response_revision:5183; number_of_response:1; }","duration":"296.482436ms","start":"2026-10-02T15:18:14.196944Z","end":"2026-10-02T15:18:14.493427Z","steps":["trace[1738641145] 'process raft request' (duration: 295.986208ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:18:14.493557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:18:13.951162Z","time spent":"542.269152ms","remote":"127.0.0.1:42142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":725,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T15:18:14.49363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.402413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10391"} {"level":"info","ts":"2026-10-02T15:18:14.493712Z","caller":"traceutil/trace.go:171","msg":"trace[504098571] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5184; }","duration":"278.516474ms","start":"2026-10-02T15:18:14.215182Z","end":"2026-10-02T15:18:14.493698Z","steps":["trace[504098571] 'agreement among raft nodes before linearized reading' (duration: 278.342771ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:18:14.493978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.800349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9635"} {"level":"info","ts":"2026-10-02T15:18:14.494015Z","caller":"traceutil/trace.go:171","msg":"trace[474729896] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:5184; }","duration":"524.89767ms","start":"2026-10-02T15:18:13.969108Z","end":"2026-10-02T15:18:14.494006Z","steps":["trace[474729896] 'agreement among raft nodes before linearized reading' (duration: 524.754108ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:18:14.494041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:18:13.969076Z","time spent":"524.957211ms","remote":"127.0.0.1:42554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":9658,"request content":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" "} {"level":"warn","ts":"2026-10-02T15:18:14.494214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.653589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-10-02T15:18:14.494342Z","caller":"traceutil/trace.go:171","msg":"trace[2054973060] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5184; }","duration":"234.817411ms","start":"2026-10-02T15:18:14.259511Z","end":"2026-10-02T15:18:14.494329Z","steps":["trace[2054973060] 'agreement among raft nodes before linearized reading' (duration: 234.470845ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:18:14.612148Z","caller":"traceutil/trace.go:171","msg":"trace[415617133] transaction","detail":"{read_only:false; response_revision:5187; number_of_response:1; }","duration":"103.843513ms","start":"2026-10-02T15:18:14.508282Z","end":"2026-10-02T15:18:14.612125Z","steps":["trace[415617133] 'process raft request' (duration: 103.763942ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:18:14.612295Z","caller":"traceutil/trace.go:171","msg":"trace[786949166] transaction","detail":"{read_only:false; response_revision:5186; number_of_response:1; }","duration":"110.518505ms","start":"2026-10-02T15:18:14.501733Z","end":"2026-10-02T15:18:14.612252Z","steps":["trace[786949166] 'process raft request' (duration: 106.155572ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:18:14.735491Z","caller":"traceutil/trace.go:171","msg":"trace[77157525] transaction","detail":"{read_only:false; response_revision:5188; number_of_response:1; }","duration":"115.292565ms","start":"2026-10-02T15:18:14.62016Z","end":"2026-10-02T15:18:14.735453Z","steps":["trace[77157525] 'process raft request' (duration: 101.495884ms)","trace[77157525] 'compare' (duration: 13.673029ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T15:18:14.777581Z","caller":"traceutil/trace.go:171","msg":"trace[54590967] transaction","detail":"{read_only:false; response_revision:5189; number_of_response:1; }","duration":"156.631709ms","start":"2026-10-02T15:18:14.620928Z","end":"2026-10-02T15:18:14.77756Z","steps":["trace[54590967] 'process raft request' (duration: 156.359354ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:18:14.777904Z","caller":"traceutil/trace.go:171","msg":"trace[395012534] transaction","detail":"{read_only:false; response_revision:5190; number_of_response:1; }","duration":"156.881423ms","start":"2026-10-02T15:18:14.621012Z","end":"2026-10-02T15:18:14.777893Z","steps":["trace[395012534] 'process raft request' (duration: 156.478366ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:18:14.778325Z","caller":"traceutil/trace.go:171","msg":"trace[2123491560] transaction","detail":"{read_only:false; response_revision:5191; number_of_response:1; }","duration":"157.229879ms","start":"2026-10-02T15:18:14.621069Z","end":"2026-10-02T15:18:14.778299Z","steps":["trace[2123491560] 'process raft request' (duration: 156.768081ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:18:14.778402Z","caller":"traceutil/trace.go:171","msg":"trace[624323706] transaction","detail":"{read_only:false; response_revision:5192; number_of_response:1; }","duration":"103.864792ms","start":"2026-10-02T15:18:14.674521Z","end":"2026-10-02T15:18:14.778386Z","steps":["trace[624323706] 'process raft request' (duration: 103.634749ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:18:15.127546Z","caller":"traceutil/trace.go:171","msg":"trace[1593857305] transaction","detail":"{read_only:false; response_revision:5194; number_of_response:1; }","duration":"158.008792ms","start":"2026-10-02T15:18:14.969504Z","end":"2026-10-02T15:18:15.127513Z","steps":["trace[1593857305] 'process raft request' (duration: 69.183372ms)","trace[1593857305] 'compare' (duration: 88.678827ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T15:18:15.159796Z","caller":"traceutil/trace.go:171","msg":"trace[1583625082] linearizableReadLoop","detail":"{readStateIndex:5393; appliedIndex:5391; }","duration":"182.353619ms","start":"2026-10-02T15:18:14.977413Z","end":"2026-10-02T15:18:15.159766Z","steps":["trace[1583625082] 'read index received' (duration: 61.256659ms)","trace[1583625082] 'applied index is now lower than readState.Index' (duration: 121.09555ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T15:18:15.159878Z","caller":"traceutil/trace.go:171","msg":"trace[450216525] transaction","detail":"{read_only:false; response_revision:5195; number_of_response:1; }","duration":"190.160691ms","start":"2026-10-02T15:18:14.969691Z","end":"2026-10-02T15:18:15.159852Z","steps":["trace[450216525] 'process raft request' (duration: 189.913756ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:18:15.160012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.586643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-10-02T15:18:15.160041Z","caller":"traceutil/trace.go:171","msg":"trace[1255053065] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5195; }","duration":"182.665095ms","start":"2026-10-02T15:18:14.977367Z","end":"2026-10-02T15:18:15.160032Z","steps":["trace[1255053065] 'agreement among raft nodes before linearized reading' (duration: 182.525782ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:18:36.818618Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2856} {"level":"info","ts":"2026-10-02T15:18:36.90586Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2856,"took":"85.920715ms","hash":2072657412,"current-db-size-bytes":52891648,"current-db-size":"53 MB","current-db-size-in-use-bytes":28037120,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-02T15:18:36.905937Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2072657412,"revision":2856,"compact-revision":-1} {"level":"info","ts":"2026-10-02T15:21:54.270553Z","caller":"traceutil/trace.go:171","msg":"trace[442588578] transaction","detail":"{read_only:false; response_revision:6780; number_of_response:1; }","duration":"106.891673ms","start":"2026-10-02T15:21:54.163558Z","end":"2026-10-02T15:21:54.27045Z","steps":["trace[442588578] 'process raft request' (duration: 106.514051ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:23:36.824495Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5338} {"level":"info","ts":"2026-10-02T15:23:36.908957Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5338,"took":"83.046456ms","hash":591980625,"current-db-size-bytes":52891648,"current-db-size":"53 MB","current-db-size-in-use-bytes":18653184,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-10-02T15:23:36.909018Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":591980625,"revision":5338,"compact-revision":2856}