{"level":"warn","ts":"2026-10-02T09:05:08.828003Z","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-02T09:05:08.82818Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.33:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.33:2380","--initial-cluster=instance=https://199.204.45.33:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.33:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.33: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-02T09:05:08.828296Z","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-02T09:05:08.828341Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.33:2380"]} {"level":"info","ts":"2026-10-02T09:05:08.82838Z","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-02T09:05:08.82913Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.33:2379"]} {"level":"info","ts":"2026-10-02T09:05:08.829278Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.33:2380"],"listen-peer-urls":["https://199.204.45.33:2380"],"advertise-client-urls":["https://199.204.45.33:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.33:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.33: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-02T09:05:08.833617Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.111286ms"} {"level":"info","ts":"2026-10-02T09:05:08.844534Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f1e5729930cf7e1f","cluster-id":"14aeb8c3ec66b749"} {"level":"info","ts":"2026-10-02T09:05:08.84462Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f switched to configuration voters=()"} {"level":"info","ts":"2026-10-02T09:05:08.844652Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became follower at term 0"} {"level":"info","ts":"2026-10-02T09:05:08.84467Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f1e5729930cf7e1f [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-02T09:05:08.844693Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became follower at term 1"} {"level":"info","ts":"2026-10-02T09:05:08.844734Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f switched to configuration voters=(17430463935081840159)"} {"level":"warn","ts":"2026-10-02T09:05:08.850507Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-02T09:05:08.852464Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-02T09:05:08.853708Z","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-02T09:05:08.855658Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f1e5729930cf7e1f","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-02T09:05:08.856357Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f1e5729930cf7e1f","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-02T09:05:08.856369Z","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-02T09:05:08.856574Z","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-02T09:05:08.85666Z","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-02T09:05:08.857147Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f switched to configuration voters=(17430463935081840159)"} {"level":"info","ts":"2026-10-02T09:05:08.857406Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"14aeb8c3ec66b749","local-member-id":"f1e5729930cf7e1f","added-peer-id":"f1e5729930cf7e1f","added-peer-peer-urls":["https://199.204.45.33:2380"]} {"level":"info","ts":"2026-10-02T09:05:08.862489Z","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-02T09:05:08.862723Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f1e5729930cf7e1f","initial-advertise-peer-urls":["https://199.204.45.33:2380"],"listen-peer-urls":["https://199.204.45.33:2380"],"advertise-client-urls":["https://199.204.45.33:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.33:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-02T09:05:08.862749Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-02T09:05:08.862834Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.33:2380"} {"level":"info","ts":"2026-10-02T09:05:08.862847Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.33:2380"} {"level":"info","ts":"2026-10-02T09:05:09.845315Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f is starting a new election at term 1"} {"level":"info","ts":"2026-10-02T09:05:09.845381Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became pre-candidate at term 1"} {"level":"info","ts":"2026-10-02T09:05:09.84543Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f received MsgPreVoteResp from f1e5729930cf7e1f at term 1"} {"level":"info","ts":"2026-10-02T09:05:09.845628Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became candidate at term 2"} {"level":"info","ts":"2026-10-02T09:05:09.845662Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f received MsgVoteResp from f1e5729930cf7e1f at term 2"} {"level":"info","ts":"2026-10-02T09:05:09.845677Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became leader at term 2"} {"level":"info","ts":"2026-10-02T09:05:09.845696Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f1e5729930cf7e1f elected leader f1e5729930cf7e1f at term 2"} {"level":"info","ts":"2026-10-02T09:05:09.847141Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f1e5729930cf7e1f","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.33:2379]}","request-path":"/0/members/f1e5729930cf7e1f/attributes","cluster-id":"14aeb8c3ec66b749","publish-timeout":"7s"} {"level":"info","ts":"2026-10-02T09:05:09.847153Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-02T09:05:09.84723Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-02T09:05:09.847161Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-02T09:05:09.8478Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-02T09:05:09.847836Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-02T09:05:09.84897Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.33:2379"} {"level":"info","ts":"2026-10-02T09:05:09.849571Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"14aeb8c3ec66b749","local-member-id":"f1e5729930cf7e1f","cluster-version":"3.5"} {"level":"info","ts":"2026-10-02T09:05:09.849773Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-02T09:05:09.849832Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-02T09:05:09.851256Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-02T09:05:11.435496Z","caller":"traceutil/trace.go:171","msg":"trace[780270168] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"139.552572ms","start":"2026-10-02T09:05:11.29593Z","end":"2026-10-02T09:05:11.435483Z","steps":["trace[780270168] 'process raft request' (duration: 139.411229ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:05:11.602229Z","caller":"traceutil/trace.go:171","msg":"trace[225094317] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"235.150351ms","start":"2026-10-02T09:05:11.367056Z","end":"2026-10-02T09:05:11.602206Z","steps":["trace[225094317] 'process raft request' (duration: 235.006538ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:05:11.602275Z","caller":"traceutil/trace.go:171","msg":"trace[1548172451] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"302.896079ms","start":"2026-10-02T09:05:11.29936Z","end":"2026-10-02T09:05:11.602256Z","steps":["trace[1548172451] 'read index received' (duration: 136.059609ms)","trace[1548172451] 'applied index is now lower than readState.Index' (duration: 166.8355ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:05:11.602285Z","caller":"traceutil/trace.go:171","msg":"trace[1195606168] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"235.289264ms","start":"2026-10-02T09:05:11.366965Z","end":"2026-10-02T09:05:11.602254Z","steps":["trace[1195606168] 'process raft request' (duration: 235.058759ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:05:11.602314Z","caller":"traceutil/trace.go:171","msg":"trace[96095252] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"304.737237ms","start":"2026-10-02T09:05:11.297553Z","end":"2026-10-02T09:05:11.60229Z","steps":["trace[96095252] 'process raft request' (duration: 304.35996ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:05:11.602354Z","caller":"traceutil/trace.go:171","msg":"trace[667101613] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"235.296483ms","start":"2026-10-02T09:05:11.367047Z","end":"2026-10-02T09:05:11.602344Z","steps":["trace[667101613] 'process raft request' (duration: 235.041118ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:05:11.602582Z","caller":"traceutil/trace.go:171","msg":"trace[106219523] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"235.440606ms","start":"2026-10-02T09:05:11.367129Z","end":"2026-10-02T09:05:11.602569Z","steps":["trace[106219523] 'process raft request' (duration: 234.989057ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:05:11.602585Z","caller":"traceutil/trace.go:171","msg":"trace[1650076051] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"205.841031ms","start":"2026-10-02T09:05:11.396731Z","end":"2026-10-02T09:05:11.602572Z","steps":["trace[1650076051] 'process raft request' (duration: 205.493564ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:05:11.602714Z","caller":"traceutil/trace.go:171","msg":"trace[853349142] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"235.577829ms","start":"2026-10-02T09:05:11.367127Z","end":"2026-10-02T09:05:11.602705Z","steps":["trace[853349142] 'process raft request' (duration: 235.014577ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:05:11.602806Z","caller":"traceutil/trace.go:171","msg":"trace[1331452773] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"235.60473ms","start":"2026-10-02T09:05:11.367193Z","end":"2026-10-02T09:05:11.602798Z","steps":["trace[1331452773] 'process raft request' (duration: 234.969297ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:05:11.602773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.385338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-02T09:05:11.602845Z","caller":"traceutil/trace.go:171","msg":"trace[969204038] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:11; }","duration":"303.49124ms","start":"2026-10-02T09:05:11.299341Z","end":"2026-10-02T09:05:11.602833Z","steps":["trace[969204038] 'agreement among raft nodes before linearized reading' (duration: 303.324137ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:05:11.602876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:05:11.299333Z","time spent":"303.534812ms","remote":"127.0.0.1:56008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-02T09:05:11.602873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:05:11.29754Z","time spent":"304.835029ms","remote":"127.0.0.1:55798","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-02T09:05:11.602966Z","caller":"traceutil/trace.go:171","msg":"trace[45871497] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"218.377412ms","start":"2026-10-02T09:05:11.384579Z","end":"2026-10-02T09:05:11.602956Z","steps":["trace[45871497] 'process raft request' (duration: 217.609916ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:05:11.603053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.642304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-02T09:05:11.603081Z","caller":"traceutil/trace.go:171","msg":"trace[1798584871] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:11; }","duration":"303.680424ms","start":"2026-10-02T09:05:11.299392Z","end":"2026-10-02T09:05:11.603072Z","steps":["trace[1798584871] 'agreement among raft nodes before linearized reading' (duration: 303.641023ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:05:11.603104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:05:11.299387Z","time spent":"303.711076ms","remote":"127.0.0.1:56008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-02T09:05:11.603247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.789375ms","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-02T09:05:11.603285Z","caller":"traceutil/trace.go:171","msg":"trace[1737320436] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"237.845006ms","start":"2026-10-02T09:05:11.365427Z","end":"2026-10-02T09:05:11.603272Z","steps":["trace[1737320436] 'agreement among raft nodes before linearized reading' (duration: 237.786355ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:05:11.603466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.817843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-02T09:05:11.607071Z","caller":"traceutil/trace.go:171","msg":"trace[1941843951] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"300.419237ms","start":"2026-10-02T09:05:11.306619Z","end":"2026-10-02T09:05:11.607038Z","steps":["trace[1941843951] 'agreement among raft nodes before linearized reading' (duration: 296.804752ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:05:11.607152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:05:11.30661Z","time spent":"300.51513ms","remote":"127.0.0.1:56122","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-02T09:05:11.603492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.753322ms","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-02T09:05:11.60736Z","caller":"traceutil/trace.go:171","msg":"trace[546233648] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"188.648514ms","start":"2026-10-02T09:05:11.418699Z","end":"2026-10-02T09:05:11.607348Z","steps":["trace[546233648] 'agreement among raft nodes before linearized reading' (duration: 184.759652ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:05:11.60349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.115431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.33\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-02T09:05:11.607479Z","caller":"traceutil/trace.go:171","msg":"trace[202386167] range","detail":"{range_begin:/registry/masterleases/199.204.45.33; range_end:; response_count:0; response_revision:11; }","duration":"242.141466ms","start":"2026-10-02T09:05:11.365329Z","end":"2026-10-02T09:05:11.607471Z","steps":["trace[202386167] 'agreement among raft nodes before linearized reading' (duration: 238.110182ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:05:11.603548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.14254ms","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-02T09:05:11.607761Z","caller":"traceutil/trace.go:171","msg":"trace[1561068676] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"240.370079ms","start":"2026-10-02T09:05:11.367355Z","end":"2026-10-02T09:05:11.607725Z","steps":["trace[1561068676] 'agreement among raft nodes before linearized reading' (duration: 236.147901ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:05:11.603561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.864841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-02T09:05:11.608127Z","caller":"traceutil/trace.go:171","msg":"trace[837327497] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:11; }","duration":"172.442306ms","start":"2026-10-02T09:05:11.43567Z","end":"2026-10-02T09:05:11.608113Z","steps":["trace[837327497] 'agreement among raft nodes before linearized reading' (duration: 167.84583ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:05:11.603222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.96055ms","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-02T09:05:11.608285Z","caller":"traceutil/trace.go:171","msg":"trace[1726109635] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"170.038007ms","start":"2026-10-02T09:05:11.438236Z","end":"2026-10-02T09:05:11.608274Z","steps":["trace[1726109635] 'agreement among raft nodes before linearized reading' (duration: 164.967301ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:05:11.78502Z","caller":"traceutil/trace.go:171","msg":"trace[2046697481] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"178.645546ms","start":"2026-10-02T09:05:11.606355Z","end":"2026-10-02T09:05:11.785Z","steps":["trace[2046697481] 'process raft request' (duration: 174.365086ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:05:11.785641Z","caller":"traceutil/trace.go:171","msg":"trace[1722677535] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"179.225858ms","start":"2026-10-02T09:05:11.606406Z","end":"2026-10-02T09:05:11.785632Z","steps":["trace[1722677535] 'process raft request' (duration: 179.048754ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:05:11.785713Z","caller":"traceutil/trace.go:171","msg":"trace[2137707913] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"178.715457ms","start":"2026-10-02T09:05:11.606965Z","end":"2026-10-02T09:05:11.785681Z","steps":["trace[2137707913] 'process raft request' (duration: 178.602194ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:05:11.785724Z","caller":"traceutil/trace.go:171","msg":"trace[654892948] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"176.179374ms","start":"2026-10-02T09:05:11.60951Z","end":"2026-10-02T09:05:11.785689Z","steps":["trace[654892948] 'process raft request' (duration: 176.089962ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:05:11.785826Z","caller":"traceutil/trace.go:171","msg":"trace[1764114562] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"175.814405ms","start":"2026-10-02T09:05:11.609989Z","end":"2026-10-02T09:05:11.785803Z","steps":["trace[1764114562] 'process raft request' (duration: 175.682252ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:05:11.785841Z","caller":"traceutil/trace.go:171","msg":"trace[1918342297] linearizableReadLoop","detail":"{readStateIndex:16; appliedIndex:14; }","duration":"178.940251ms","start":"2026-10-02T09:05:11.606879Z","end":"2026-10-02T09:05:11.785819Z","steps":["trace[1918342297] 'read index received' (duration: 173.875746ms)","trace[1918342297] 'applied index is now lower than readState.Index' (duration: 5.063345ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:05:11.785902Z","caller":"traceutil/trace.go:171","msg":"trace[120117277] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"175.097351ms","start":"2026-10-02T09:05:11.610801Z","end":"2026-10-02T09:05:11.785898Z","steps":["trace[120117277] 'process raft request' (duration: 174.891627ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:05:11.785918Z","caller":"traceutil/trace.go:171","msg":"trace[1454952194] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"171.876034ms","start":"2026-10-02T09:05:11.614031Z","end":"2026-10-02T09:05:11.785907Z","steps":["trace[1454952194] 'process raft request' (duration: 171.713121ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:05:11.785859Z","caller":"traceutil/trace.go:171","msg":"trace[242087712] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"175.860726ms","start":"2026-10-02T09:05:11.60999Z","end":"2026-10-02T09:05:11.785851Z","steps":["trace[242087712] 'process raft request' (duration: 175.649652ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:05:11.786073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.844457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-02T09:05:11.786103Z","caller":"traceutil/trace.go:171","msg":"trace[1439355777] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19; }","duration":"176.909679ms","start":"2026-10-02T09:05:11.609184Z","end":"2026-10-02T09:05:11.786093Z","steps":["trace[1439355777] 'agreement among raft nodes before linearized reading' (duration: 176.832897ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:05:11.786112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.207347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-2h6n8\" ","response":"range_response_count:1 size:857"} {"level":"info","ts":"2026-10-02T09:05:11.786148Z","caller":"traceutil/trace.go:171","msg":"trace[1321290170] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-2h6n8; range_end:; response_count:1; response_revision:19; }","duration":"179.284988ms","start":"2026-10-02T09:05:11.606854Z","end":"2026-10-02T09:05:11.786139Z","steps":["trace[1321290170] 'agreement among raft nodes before linearized reading' (duration: 179.083264ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:05:11.785886Z","caller":"traceutil/trace.go:171","msg":"trace[838273753] transaction","detail":"{read_only:false; number_of_response:0; response_revision:18; }","duration":"172.910165ms","start":"2026-10-02T09:05:11.612969Z","end":"2026-10-02T09:05:11.785879Z","steps":["trace[838273753] 'process raft request' (duration: 172.749241ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:05:11.786199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.890165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-02T09:05:11.786242Z","caller":"traceutil/trace.go:171","msg":"trace[1277077563] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:19; }","duration":"121.958686ms","start":"2026-10-02T09:05:11.664272Z","end":"2026-10-02T09:05:11.78623Z","steps":["trace[1277077563] 'agreement among raft nodes before linearized reading' (duration: 121.880254ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:05:11.786316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.467548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-10-02T09:05:11.786338Z","caller":"traceutil/trace.go:171","msg":"trace[47899576] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:19; }","duration":"125.53578ms","start":"2026-10-02T09:05:11.660797Z","end":"2026-10-02T09:05:11.786333Z","steps":["trace[47899576] 'agreement among raft nodes before linearized reading' (duration: 125.462728ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:05:11.78639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.509292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"warn","ts":"2026-10-02T09:05:11.786389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.375757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3490"} {"level":"warn","ts":"2026-10-02T09:05:11.7864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.610891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-10-02T09:05:11.786422Z","caller":"traceutil/trace.go:171","msg":"trace[482798540] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:19; }","duration":"116.557995ms","start":"2026-10-02T09:05:11.669856Z","end":"2026-10-02T09:05:11.786414Z","steps":["trace[482798540] 'agreement among raft nodes before linearized reading' (duration: 116.501462ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:05:11.786433Z","caller":"traceutil/trace.go:171","msg":"trace[559887194] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:19; }","duration":"176.46222ms","start":"2026-10-02T09:05:11.60996Z","end":"2026-10-02T09:05:11.786422Z","steps":["trace[559887194] 'agreement among raft nodes before linearized reading' (duration: 176.373057ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:05:11.786454Z","caller":"traceutil/trace.go:171","msg":"trace[1024916485] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:19; }","duration":"125.701434ms","start":"2026-10-02T09:05:11.660732Z","end":"2026-10-02T09:05:11.786434Z","steps":["trace[1024916485] 'agreement among raft nodes before linearized reading' (duration: 125.602861ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:05:44.856891Z","caller":"traceutil/trace.go:171","msg":"trace[221973986] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"104.728281ms","start":"2026-10-02T09:05:44.75214Z","end":"2026-10-02T09:05:44.856869Z","steps":["trace[221973986] 'process raft request' (duration: 104.405586ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:05:45.109481Z","caller":"traceutil/trace.go:171","msg":"trace[905954824] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"110.98584ms","start":"2026-10-02T09:05:44.998464Z","end":"2026-10-02T09:05:45.109449Z","steps":["trace[905954824] 'process raft request' (duration: 77.896492ms)","trace[905954824] 'compare' (duration: 32.918655ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:05:45.117366Z","caller":"traceutil/trace.go:171","msg":"trace[2043279493] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"115.417382ms","start":"2026-10-02T09:05:45.001918Z","end":"2026-10-02T09:05:45.117335Z","steps":["trace[2043279493] 'process raft request' (duration: 115.31256ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:05:48.636021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.178719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/udproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:31126"} {"level":"info","ts":"2026-10-02T09:05:48.636079Z","caller":"traceutil/trace.go:171","msg":"trace[966137470] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/udproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:600; }","duration":"104.279521ms","start":"2026-10-02T09:05:48.531786Z","end":"2026-10-02T09:05:48.636066Z","steps":["trace[966137470] 'range keys from in-memory index tree' (duration: 104.049917ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:05:48.856684Z","caller":"traceutil/trace.go:171","msg":"trace[2028845821] linearizableReadLoop","detail":"{readStateIndex:627; appliedIndex:626; }","duration":"147.587548ms","start":"2026-10-02T09:05:48.70905Z","end":"2026-10-02T09:05:48.856637Z","steps":["trace[2028845821] 'read index received' (duration: 146.924327ms)","trace[2028845821] 'applied index is now lower than readState.Index' (duration: 662.051µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:05:48.85674Z","caller":"traceutil/trace.go:171","msg":"trace[782988082] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"149.034056ms","start":"2026-10-02T09:05:48.707672Z","end":"2026-10-02T09:05:48.856706Z","steps":["trace[782988082] 'process raft request' (duration: 148.383864ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:05:48.856942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.865724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/clienttrafficpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:65436"} {"level":"info","ts":"2026-10-02T09:05:48.857003Z","caller":"traceutil/trace.go:171","msg":"trace[334167885] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/clienttrafficpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:602; }","duration":"147.960787ms","start":"2026-10-02T09:05:48.709026Z","end":"2026-10-02T09:05:48.856987Z","steps":["trace[334167885] 'agreement among raft nodes before linearized reading' (duration: 147.743062ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:05:53.577856Z","caller":"traceutil/trace.go:171","msg":"trace[432604157] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:655; }","duration":"183.344947ms","start":"2026-10-02T09:05:53.394485Z","end":"2026-10-02T09:05:53.57783Z","steps":["trace[432604157] 'read index received' (duration: 182.626663ms)","trace[432604157] 'applied index is now lower than readState.Index' (duration: 717.434µs)"],"step_count":2} {"level":"warn","ts":"2026-10-02T09:05:53.578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.481299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T09:05:53.578043Z","caller":"traceutil/trace.go:171","msg":"trace[208170349] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:630; }","duration":"183.59897ms","start":"2026-10-02T09:05:53.394433Z","end":"2026-10-02T09:05:53.578032Z","steps":["trace[208170349] 'agreement among raft nodes before linearized reading' (duration: 183.494559ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:05:53.578164Z","caller":"traceutil/trace.go:171","msg":"trace[968659455] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"308.858232ms","start":"2026-10-02T09:05:53.269257Z","end":"2026-10-02T09:05:53.578115Z","steps":["trace[968659455] 'process raft request' (duration: 308.065538ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:05:53.578312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:05:53.269227Z","time spent":"309.004804ms","remote":"127.0.0.1:55830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036257,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T09:06:08.644034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.112577ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-02T09:06:40.572354Z","caller":"traceutil/trace.go:171","msg":"trace[1714271957] transaction","detail":"{read_only:false; response_revision:1202; number_of_response:1; }","duration":"143.181348ms","start":"2026-10-02T09:06:40.429127Z","end":"2026-10-02T09:06:40.572309Z","steps":["trace[1714271957] 'process raft request' (duration: 134.297083ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:07:28.496673Z","caller":"traceutil/trace.go:171","msg":"trace[1613323159] linearizableReadLoop","detail":"{readStateIndex:1948; appliedIndex:1947; }","duration":"100.694856ms","start":"2026-10-02T09:07:28.395955Z","end":"2026-10-02T09:07:28.49665Z","steps":["trace[1613323159] 'read index received' (duration: 68.722966ms)","trace[1613323159] 'applied index is now lower than readState.Index' (duration: 31.97096ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:07:28.496926Z","caller":"traceutil/trace.go:171","msg":"trace[2086000892] transaction","detail":"{read_only:false; response_revision:1874; number_of_response:1; }","duration":"262.508847ms","start":"2026-10-02T09:07:28.234339Z","end":"2026-10-02T09:07:28.496848Z","steps":["trace[2086000892] 'process raft request' (duration: 230.434835ms)","trace[2086000892] 'compare' (duration: 31.569184ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T09:07:28.497148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.180103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T09:07:28.497254Z","caller":"traceutil/trace.go:171","msg":"trace[152074922] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1874; }","duration":"101.340085ms","start":"2026-10-02T09:07:28.395895Z","end":"2026-10-02T09:07:28.497235Z","steps":["trace[152074922] 'agreement among raft nodes before linearized reading' (duration: 101.174192ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:07:41.68859Z","caller":"traceutil/trace.go:171","msg":"trace[1566400626] transaction","detail":"{read_only:false; response_revision:1981; number_of_response:1; }","duration":"100.185595ms","start":"2026-10-02T09:07:41.588367Z","end":"2026-10-02T09:07:41.688553Z","steps":["trace[1566400626] 'process raft request' (duration: 47.870423ms)","trace[1566400626] 'compare' (duration: 52.077029ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T09:07:41.689496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.844875ms","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-02T09:07:41.689585Z","caller":"traceutil/trace.go:171","msg":"trace[449495142] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1981; }","duration":"100.962847ms","start":"2026-10-02T09:07:41.588604Z","end":"2026-10-02T09:07:41.689567Z","steps":["trace[449495142] 'agreement among raft nodes before linearized reading' (duration: 100.048473ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:08:07.698446Z","caller":"traceutil/trace.go:171","msg":"trace[1826807289] transaction","detail":"{read_only:false; response_revision:2135; number_of_response:1; }","duration":"103.297532ms","start":"2026-10-02T09:08:07.595122Z","end":"2026-10-02T09:08:07.69842Z","steps":["trace[1826807289] 'process raft request' (duration: 103.153128ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:08:07.893988Z","caller":"traceutil/trace.go:171","msg":"trace[41749045] transaction","detail":"{read_only:false; response_revision:2136; number_of_response:1; }","duration":"182.11675ms","start":"2026-10-02T09:08:07.711844Z","end":"2026-10-02T09:08:07.893961Z","steps":["trace[41749045] 'process raft request' (duration: 181.909094ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:08:07.966684Z","caller":"traceutil/trace.go:171","msg":"trace[1938631420] transaction","detail":"{read_only:false; response_revision:2137; number_of_response:1; }","duration":"144.228573ms","start":"2026-10-02T09:08:07.822392Z","end":"2026-10-02T09:08:07.966621Z","steps":["trace[1938631420] 'process raft request' (duration: 144.003506ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:08:08.19402Z","caller":"traceutil/trace.go:171","msg":"trace[247138024] transaction","detail":"{read_only:false; response_revision:2140; number_of_response:1; }","duration":"137.02766ms","start":"2026-10-02T09:08:08.056946Z","end":"2026-10-02T09:08:08.193973Z","steps":["trace[247138024] 'process raft request' (duration: 96.175304ms)","trace[247138024] 'compare' (duration: 40.63404ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T09:12:43.495232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.953421ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-02T09:12:43.495716Z","caller":"traceutil/trace.go:171","msg":"trace[1696260820] transaction","detail":"{read_only:false; response_revision:3788; number_of_response:1; }","duration":"119.523158ms","start":"2026-10-02T09:12:43.376156Z","end":"2026-10-02T09:12:43.495679Z","steps":["trace[1696260820] 'process raft request' (duration: 16.026576ms)","trace[1696260820] 'compare' (duration: 102.86087ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T09:12:43.495882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.264215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T09:12:43.496033Z","caller":"traceutil/trace.go:171","msg":"trace[1366533613] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3788; }","duration":"100.452089ms","start":"2026-10-02T09:12:43.39556Z","end":"2026-10-02T09:12:43.496012Z","steps":["trace[1366533613] 'agreement among raft nodes before linearized reading' (duration: 100.191584ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:14:07.939496Z","caller":"traceutil/trace.go:171","msg":"trace[676716973] transaction","detail":"{read_only:false; response_revision:4841; number_of_response:1; }","duration":"150.521113ms","start":"2026-10-02T09:14:07.788946Z","end":"2026-10-02T09:14:07.939467Z","steps":["trace[676716973] 'process raft request' (duration: 150.3782ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:14:07.940099Z","caller":"traceutil/trace.go:171","msg":"trace[1032471071] linearizableReadLoop","detail":"{readStateIndex:5026; appliedIndex:5026; }","duration":"128.77038ms","start":"2026-10-02T09:14:07.811279Z","end":"2026-10-02T09:14:07.940049Z","steps":["trace[1032471071] 'read index received' (duration: 128.75728ms)","trace[1032471071] 'applied index is now lower than readState.Index' (duration: 11.22µs)"],"step_count":2} {"level":"warn","ts":"2026-10-02T09:14:07.941069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.782948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2207411"} {"level":"info","ts":"2026-10-02T09:14:07.94113Z","caller":"traceutil/trace.go:171","msg":"trace[955100017] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4841; }","duration":"132.8886ms","start":"2026-10-02T09:14:07.808227Z","end":"2026-10-02T09:14:07.941115Z","steps":["trace[955100017] 'agreement among raft nodes before linearized reading' (duration: 131.954184ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:14:07.994201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.449278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-02T09:14:07.99425Z","caller":"traceutil/trace.go:171","msg":"trace[980403562] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4842; }","duration":"169.538219ms","start":"2026-10-02T09:14:07.8247Z","end":"2026-10-02T09:14:07.994238Z","steps":["trace[980403562] 'agreement among raft nodes before linearized reading' (duration: 169.444398ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:14:07.994336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.256297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-10-02T09:14:07.99436Z","caller":"traceutil/trace.go:171","msg":"trace[7429560] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4842; }","duration":"179.304687ms","start":"2026-10-02T09:14:07.81505Z","end":"2026-10-02T09:14:07.994354Z","steps":["trace[7429560] 'agreement among raft nodes before linearized reading' (duration: 179.248366ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:14:07.994665Z","caller":"traceutil/trace.go:171","msg":"trace[1599644915] transaction","detail":"{read_only:false; response_revision:4842; number_of_response:1; }","duration":"158.215965ms","start":"2026-10-02T09:14:07.836416Z","end":"2026-10-02T09:14:07.994632Z","steps":["trace[1599644915] 'process raft request' (duration: 157.36184ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:14:09.064186Z","caller":"traceutil/trace.go:171","msg":"trace[1452051679] linearizableReadLoop","detail":"{readStateIndex:5037; appliedIndex:5036; }","duration":"181.964902ms","start":"2026-10-02T09:14:08.8822Z","end":"2026-10-02T09:14:09.064164Z","steps":["trace[1452051679] 'read index received' (duration: 181.817479ms)","trace[1452051679] 'applied index is now lower than readState.Index' (duration: 146.552µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:14:09.064223Z","caller":"traceutil/trace.go:171","msg":"trace[1334051263] transaction","detail":"{read_only:false; response_revision:4851; number_of_response:1; }","duration":"197.923556ms","start":"2026-10-02T09:14:08.86628Z","end":"2026-10-02T09:14:09.064204Z","steps":["trace[1334051263] 'process raft request' (duration: 197.737663ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:14:09.064379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.161705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-10-02T09:14:09.06442Z","caller":"traceutil/trace.go:171","msg":"trace[1843936897] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4851; }","duration":"182.244007ms","start":"2026-10-02T09:14:08.882167Z","end":"2026-10-02T09:14:09.064411Z","steps":["trace[1843936897] 'agreement among raft nodes before linearized reading' (duration: 182.101154ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:14:09.064425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.7908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T09:14:09.064459Z","caller":"traceutil/trace.go:171","msg":"trace[1921664914] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:4851; }","duration":"100.87054ms","start":"2026-10-02T09:14:08.963578Z","end":"2026-10-02T09:14:09.064448Z","steps":["trace[1921664914] 'agreement among raft nodes before linearized reading' (duration: 100.805879ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:14:15.005025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.374242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bad63b42-545a-49b1-bb75-6260aaa6b9cb\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-10-02T09:14:15.00511Z","caller":"traceutil/trace.go:171","msg":"trace[1467954554] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-bad63b42-545a-49b1-bb75-6260aaa6b9cb; range_end:; response_count:1; response_revision:4952; }","duration":"120.505484ms","start":"2026-10-02T09:14:14.884586Z","end":"2026-10-02T09:14:15.005091Z","steps":["trace[1467954554] 'range keys from in-memory index tree' (duration: 120.098587ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:14:20.90365Z","caller":"traceutil/trace.go:171","msg":"trace[258408357] linearizableReadLoop","detail":"{readStateIndex:5230; appliedIndex:5229; }","duration":"100.787413ms","start":"2026-10-02T09:14:20.802844Z","end":"2026-10-02T09:14:20.903631Z","steps":["trace[258408357] 'read index received' (duration: 100.512989ms)","trace[258408357] 'applied index is now lower than readState.Index' (duration: 273.224µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:14:20.903721Z","caller":"traceutil/trace.go:171","msg":"trace[1655521214] transaction","detail":"{read_only:false; response_revision:5039; number_of_response:1; }","duration":"104.69707ms","start":"2026-10-02T09:14:20.799005Z","end":"2026-10-02T09:14:20.903703Z","steps":["trace[1655521214] 'process raft request' (duration: 104.438866ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:14:20.903952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.066028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-10-02T09:14:20.904078Z","caller":"traceutil/trace.go:171","msg":"trace[248728804] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:5039; }","duration":"101.214811ms","start":"2026-10-02T09:14:20.80282Z","end":"2026-10-02T09:14:20.904035Z","steps":["trace[248728804] 'agreement among raft nodes before linearized reading' (duration: 100.965567ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:14:29.071989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.37603ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-02T09:14:29.072194Z","caller":"traceutil/trace.go:171","msg":"trace[411292889] transaction","detail":"{read_only:false; response_revision:5120; number_of_response:1; }","duration":"341.409545ms","start":"2026-10-02T09:14:28.730758Z","end":"2026-10-02T09:14:29.072167Z","steps":["trace[411292889] 'process raft request' (duration: 147.734341ms)","trace[411292889] 'compare' (duration: 193.237416ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T09:14:29.072303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:14:28.730742Z","time spent":"341.517547ms","remote":"127.0.0.1:56008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T09:14:29.127996Z","caller":"traceutil/trace.go:171","msg":"trace[708166051] linearizableReadLoop","detail":"{readStateIndex:5316; appliedIndex:5314; }","duration":"312.678584ms","start":"2026-10-02T09:14:28.815295Z","end":"2026-10-02T09:14:29.127974Z","steps":["trace[708166051] 'read index received' (duration: 63.194528ms)","trace[708166051] 'applied index is now lower than readState.Index' (duration: 249.482946ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T09:14:29.128235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.917517ms","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-02T09:14:29.128246Z","caller":"traceutil/trace.go:171","msg":"trace[582339106] transaction","detail":"{read_only:false; response_revision:5121; number_of_response:1; }","duration":"394.47819ms","start":"2026-10-02T09:14:28.73364Z","end":"2026-10-02T09:14:29.128119Z","steps":["trace[582339106] 'process raft request' (duration: 394.182775ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:14:29.128296Z","caller":"traceutil/trace.go:171","msg":"trace[1259141229] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5121; }","duration":"313.02242ms","start":"2026-10-02T09:14:28.815258Z","end":"2026-10-02T09:14:29.12828Z","steps":["trace[1259141229] 'agreement among raft nodes before linearized reading' (duration: 312.856217ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:14:29.12834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:14:28.815241Z","time spent":"313.08989ms","remote":"127.0.0.1:55890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-10-02T09:14:29.128348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.253449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3244"} {"level":"info","ts":"2026-10-02T09:14:29.128416Z","caller":"traceutil/trace.go:171","msg":"trace[1164734427] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5121; }","duration":"301.353411ms","start":"2026-10-02T09:14:28.827046Z","end":"2026-10-02T09:14:29.128399Z","steps":["trace[1164734427] 'agreement among raft nodes before linearized reading' (duration: 301.138797ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:14:29.128451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:14:28.827038Z","time spent":"301.407391ms","remote":"127.0.0.1:34656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3267,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"warn","ts":"2026-10-02T09:14:29.12844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:14:28.733627Z","time spent":"394.707474ms","remote":"127.0.0.1:55914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T09:14:29.413089Z","caller":"traceutil/trace.go:171","msg":"trace[1625514575] transaction","detail":"{read_only:false; response_revision:5129; number_of_response:1; }","duration":"182.688126ms","start":"2026-10-02T09:14:29.230372Z","end":"2026-10-02T09:14:29.41306Z","steps":["trace[1625514575] 'process raft request' (duration: 154.865942ms)","trace[1625514575] 'compare' (duration: 27.674702ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:14:29.422765Z","caller":"traceutil/trace.go:171","msg":"trace[324084619] transaction","detail":"{read_only:false; response_revision:5130; number_of_response:1; }","duration":"191.947814ms","start":"2026-10-02T09:14:29.230792Z","end":"2026-10-02T09:14:29.42274Z","steps":["trace[324084619] 'process raft request' (duration: 191.754841ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:14:29.423002Z","caller":"traceutil/trace.go:171","msg":"trace[1700321624] transaction","detail":"{read_only:false; response_revision:5131; number_of_response:1; }","duration":"191.14784ms","start":"2026-10-02T09:14:29.23181Z","end":"2026-10-02T09:14:29.422958Z","steps":["trace[1700321624] 'process raft request' (duration: 190.873256ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:14:29.423473Z","caller":"traceutil/trace.go:171","msg":"trace[556403118] linearizableReadLoop","detail":"{readStateIndex:5326; appliedIndex:5323; }","duration":"191.057309ms","start":"2026-10-02T09:14:29.232377Z","end":"2026-10-02T09:14:29.423434Z","steps":["trace[556403118] 'read index received' (duration: 152.874238ms)","trace[556403118] 'applied index is now lower than readState.Index' (duration: 38.181931ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:14:29.423631Z","caller":"traceutil/trace.go:171","msg":"trace[1388625622] transaction","detail":"{read_only:false; response_revision:5133; number_of_response:1; }","duration":"182.519633ms","start":"2026-10-02T09:14:29.241098Z","end":"2026-10-02T09:14:29.423618Z","steps":["trace[1388625622] 'process raft request' (duration: 182.258949ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:14:29.42366Z","caller":"traceutil/trace.go:171","msg":"trace[1713018071] transaction","detail":"{read_only:false; response_revision:5132; number_of_response:1; }","duration":"185.572366ms","start":"2026-10-02T09:14:29.238067Z","end":"2026-10-02T09:14:29.42364Z","steps":["trace[1713018071] 'process raft request' (duration: 185.121358ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:14:29.423868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.284524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-10-02T09:14:29.423958Z","caller":"traceutil/trace.go:171","msg":"trace[850432882] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5133; }","duration":"187.422367ms","start":"2026-10-02T09:14:29.236517Z","end":"2026-10-02T09:14:29.423939Z","steps":["trace[850432882] 'agreement among raft nodes before linearized reading' (duration: 187.220713ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:14:29.424074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.6776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone-nodes\" ","response":"range_response_count:1 size:1265"} {"level":"info","ts":"2026-10-02T09:14:29.424148Z","caller":"traceutil/trace.go:171","msg":"trace[234276611] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone-nodes; range_end:; response_count:1; response_revision:5133; }","duration":"191.764221ms","start":"2026-10-02T09:14:29.232373Z","end":"2026-10-02T09:14:29.424137Z","steps":["trace[234276611] 'agreement among raft nodes before linearized reading' (duration: 191.562778ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:15:09.888808Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2900} {"level":"info","ts":"2026-10-02T09:15:09.962561Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2900,"took":"72.482776ms","hash":1247838859,"current-db-size-bytes":52215808,"current-db-size":"52 MB","current-db-size-in-use-bytes":28348416,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-02T09:15:09.962633Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1247838859,"revision":2900,"compact-revision":-1} {"level":"info","ts":"2026-10-02T09:18:24.281247Z","caller":"traceutil/trace.go:171","msg":"trace[1549431645] transaction","detail":"{read_only:false; response_revision:6868; number_of_response:1; }","duration":"128.584734ms","start":"2026-10-02T09:18:24.152639Z","end":"2026-10-02T09:18:24.281224Z","steps":["trace[1549431645] 'process raft request' (duration: 128.437051ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:20:09.926586Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5492} {"level":"info","ts":"2026-10-02T09:20:10.102302Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5492,"took":"174.082222ms","hash":3195158490,"current-db-size-bytes":52215808,"current-db-size":"52 MB","current-db-size-in-use-bytes":18599936,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-10-02T09:20:10.102367Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3195158490,"revision":5492,"compact-revision":2900}