{"level":"warn","ts":"2026-09-05T06:05:47.765293Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-05T06:05:47.765374Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.86: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.86:2380","--initial-cluster=instance=https://162.253.55.86:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.86:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.86: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-09-05T06:05:47.765444Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-05T06:05:47.765457Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.86:2380"]} {"level":"info","ts":"2026-09-05T06:05:47.765472Z","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-09-05T06:05:47.766057Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.86:2379"]} {"level":"info","ts":"2026-09-05T06:05:47.766128Z","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.86:2380"],"listen-peer-urls":["https://162.253.55.86:2380"],"advertise-client-urls":["https://162.253.55.86:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.86:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.86: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-09-05T06:05:47.788223Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"19.958171ms"} {"level":"info","ts":"2026-09-05T06:05:47.803744Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d01cde7b2aa18f95","cluster-id":"9e5d9cb67aed7785"} {"level":"info","ts":"2026-09-05T06:05:47.803819Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d01cde7b2aa18f95 switched to configuration voters=()"} {"level":"info","ts":"2026-09-05T06:05:47.803841Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d01cde7b2aa18f95 became follower at term 0"} {"level":"info","ts":"2026-09-05T06:05:47.803857Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d01cde7b2aa18f95 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-05T06:05:47.803872Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d01cde7b2aa18f95 became follower at term 1"} {"level":"info","ts":"2026-09-05T06:05:47.803911Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d01cde7b2aa18f95 switched to configuration voters=(14996105479814483861)"} {"level":"warn","ts":"2026-09-05T06:05:47.813124Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-05T06:05:47.815806Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-05T06:05:47.817798Z","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-09-05T06:05:47.82164Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d01cde7b2aa18f95","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-05T06:05:47.821813Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d01cde7b2aa18f95","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-05T06:05:47.821929Z","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-09-05T06:05:47.822078Z","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-09-05T06:05:47.822208Z","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-09-05T06:05:47.823733Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d01cde7b2aa18f95 switched to configuration voters=(14996105479814483861)"} {"level":"info","ts":"2026-09-05T06:05:47.824006Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9e5d9cb67aed7785","local-member-id":"d01cde7b2aa18f95","added-peer-id":"d01cde7b2aa18f95","added-peer-peer-urls":["https://162.253.55.86:2380"]} {"level":"info","ts":"2026-09-05T06:05:47.825147Z","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-09-05T06:05:47.825314Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.86:2380"} {"level":"info","ts":"2026-09-05T06:05:47.825347Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.86:2380"} {"level":"info","ts":"2026-09-05T06:05:47.825466Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d01cde7b2aa18f95","initial-advertise-peer-urls":["https://162.253.55.86:2380"],"listen-peer-urls":["https://162.253.55.86:2380"],"advertise-client-urls":["https://162.253.55.86:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.86:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-05T06:05:47.825532Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-05T06:05:48.004103Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d01cde7b2aa18f95 is starting a new election at term 1"} {"level":"info","ts":"2026-09-05T06:05:48.004147Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d01cde7b2aa18f95 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-05T06:05:48.004176Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d01cde7b2aa18f95 received MsgPreVoteResp from d01cde7b2aa18f95 at term 1"} {"level":"info","ts":"2026-09-05T06:05:48.004193Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d01cde7b2aa18f95 became candidate at term 2"} {"level":"info","ts":"2026-09-05T06:05:48.004208Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d01cde7b2aa18f95 received MsgVoteResp from d01cde7b2aa18f95 at term 2"} {"level":"info","ts":"2026-09-05T06:05:48.004224Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d01cde7b2aa18f95 became leader at term 2"} {"level":"info","ts":"2026-09-05T06:05:48.004241Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d01cde7b2aa18f95 elected leader d01cde7b2aa18f95 at term 2"} {"level":"info","ts":"2026-09-05T06:05:48.005249Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-05T06:05:48.00699Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d01cde7b2aa18f95","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.86:2379]}","request-path":"/0/members/d01cde7b2aa18f95/attributes","cluster-id":"9e5d9cb67aed7785","publish-timeout":"7s"} {"level":"info","ts":"2026-09-05T06:05:48.007261Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-05T06:05:48.007379Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-05T06:05:48.0075Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-05T06:05:48.007535Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-05T06:05:48.008647Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9e5d9cb67aed7785","local-member-id":"d01cde7b2aa18f95","cluster-version":"3.5"} {"level":"info","ts":"2026-09-05T06:05:48.008757Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-05T06:05:48.008793Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-05T06:05:48.010912Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-05T06:05:48.018372Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.86:2379"} {"level":"warn","ts":"2026-09-05T06:05:51.96586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.681512ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-05T06:05:51.966Z","caller":"traceutil/trace.go:171","msg":"trace[823148845] linearizableReadLoop","detail":"{readStateIndex:179; appliedIndex:178; }","duration":"138.259452ms","start":"2026-09-05T06:05:51.827722Z","end":"2026-09-05T06:05:51.965981Z","steps":["trace[823148845] 'read index received' (duration: 73.051µs)","trace[823148845] 'applied index is now lower than readState.Index' (duration: 138.184721ms)"],"step_count":2} {"level":"info","ts":"2026-09-05T06:05:51.966173Z","caller":"traceutil/trace.go:171","msg":"trace[662674224] transaction","detail":"{read_only:false; response_revision:175; number_of_response:1; }","duration":"548.87127ms","start":"2026-09-05T06:05:51.417211Z","end":"2026-09-05T06:05:51.966082Z","steps":["trace[662674224] 'process raft request' (duration: 249.234924ms)","trace[662674224] 'compare' (duration: 298.586651ms)"],"step_count":2} {"level":"warn","ts":"2026-09-05T06:05:51.966491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-05T06:05:51.417192Z","time spent":"549.165894ms","remote":"127.0.0.1:47262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":748,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-05T06:05:51.966785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.401835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-05T06:05:51.966902Z","caller":"traceutil/trace.go:171","msg":"trace[1437732383] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:175; }","duration":"139.245777ms","start":"2026-09-05T06:05:51.827632Z","end":"2026-09-05T06:05:51.966878Z","steps":["trace[1437732383] 'agreement among raft nodes before linearized reading' (duration: 138.404235ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:05:52.201643Z","caller":"traceutil/trace.go:171","msg":"trace[2065412736] transaction","detail":"{read_only:false; response_revision:176; number_of_response:1; }","duration":"230.608005ms","start":"2026-09-05T06:05:51.971007Z","end":"2026-09-05T06:05:52.201615Z","steps":["trace[2065412736] 'process raft request' (duration: 150.022694ms)","trace[2065412736] 'compare' (duration: 80.451639ms)"],"step_count":2} {"level":"info","ts":"2026-09-05T06:05:52.202748Z","caller":"traceutil/trace.go:171","msg":"trace[1511666614] transaction","detail":"{read_only:false; response_revision:177; number_of_response:1; }","duration":"231.12235ms","start":"2026-09-05T06:05:51.971613Z","end":"2026-09-05T06:05:52.202735Z","steps":["trace[1511666614] 'process raft request' (duration: 231.064209ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:06:50.564377Z","caller":"traceutil/trace.go:171","msg":"trace[37828292] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"116.464783ms","start":"2026-09-05T06:06:50.447892Z","end":"2026-09-05T06:06:50.564357Z","steps":["trace[37828292] 'process raft request' (duration: 112.442676ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:06:51.097242Z","caller":"traceutil/trace.go:171","msg":"trace[78765220] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"105.239388ms","start":"2026-09-05T06:06:50.991982Z","end":"2026-09-05T06:06:51.097222Z","steps":["trace[78765220] 'process raft request' (duration: 105.093907ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T06:06:56.509688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.377097ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-05T06:06:56.509817Z","caller":"traceutil/trace.go:171","msg":"trace[360898832] linearizableReadLoop","detail":"{readStateIndex:670; appliedIndex:669; }","duration":"231.472767ms","start":"2026-09-05T06:06:56.278326Z","end":"2026-09-05T06:06:56.509798Z","steps":["trace[360898832] 'read index received' (duration: 79.851248ms)","trace[360898832] 'applied index is now lower than readState.Index' (duration: 151.619989ms)"],"step_count":2} {"level":"info","ts":"2026-09-05T06:06:56.509894Z","caller":"traceutil/trace.go:171","msg":"trace[807450916] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"260.564708ms","start":"2026-09-05T06:06:56.249296Z","end":"2026-09-05T06:06:56.509861Z","steps":["trace[807450916] 'process raft request' (duration: 108.853438ms)","trace[807450916] 'compare' (duration: 151.185184ms)"],"step_count":2} {"level":"warn","ts":"2026-09-05T06:06:56.51003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.982705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-05T06:06:56.510104Z","caller":"traceutil/trace.go:171","msg":"trace[1374482308] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:640; }","duration":"181.093256ms","start":"2026-09-05T06:06:56.328996Z","end":"2026-09-05T06:06:56.510089Z","steps":["trace[1374482308] 'agreement among raft nodes before linearized reading' (duration: 180.953264ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T06:06:56.510122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.773651ms","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-09-05T06:06:56.510155Z","caller":"traceutil/trace.go:171","msg":"trace[1113700910] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:640; }","duration":"231.870592ms","start":"2026-09-05T06:06:56.278274Z","end":"2026-09-05T06:06:56.510144Z","steps":["trace[1113700910] 'agreement among raft nodes before linearized reading' (duration: 231.6329ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:06:57.173749Z","caller":"traceutil/trace.go:171","msg":"trace[1239948287] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"232.745008ms","start":"2026-09-05T06:06:56.940399Z","end":"2026-09-05T06:06:57.173144Z","steps":["trace[1239948287] 'process raft request' (duration: 231.914387ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T06:06:57.915847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.139755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-05T06:06:57.915974Z","caller":"traceutil/trace.go:171","msg":"trace[1044158327] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:649; }","duration":"474.296617ms","start":"2026-09-05T06:06:57.441626Z","end":"2026-09-05T06:06:57.915922Z","steps":["trace[1044158327] 'count revisions from in-memory index tree' (duration: 474.031484ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T06:06:57.916104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.122941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-2qdxz\" ","response":"range_response_count:1 size:16961"} {"level":"warn","ts":"2026-09-05T06:06:57.916159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-05T06:06:57.44159Z","time spent":"474.55057ms","remote":"127.0.0.1:47074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"info","ts":"2026-09-05T06:06:57.916168Z","caller":"traceutil/trace.go:171","msg":"trace[334321984] range","detail":"{range_begin:/registry/pods/kube-system/cilium-2qdxz; range_end:; response_count:1; response_revision:649; }","duration":"350.219423ms","start":"2026-09-05T06:06:57.565931Z","end":"2026-09-05T06:06:57.91615Z","steps":["trace[334321984] 'range keys from in-memory index tree' (duration: 349.883799ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T06:06:57.916204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-05T06:06:57.565912Z","time spent":"350.284083ms","remote":"127.0.0.1:47110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-2qdxz\" "} {"level":"info","ts":"2026-09-05T06:06:57.917742Z","caller":"traceutil/trace.go:171","msg":"trace[1421139471] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"179.682735ms","start":"2026-09-05T06:06:57.738029Z","end":"2026-09-05T06:06:57.917711Z","steps":["trace[1421139471] 'process raft request' (duration: 175.553767ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T06:06:58.916254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.914839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-05T06:06:58.916332Z","caller":"traceutil/trace.go:171","msg":"trace[201234675] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:665; }","duration":"130.025621ms","start":"2026-09-05T06:06:58.786286Z","end":"2026-09-05T06:06:58.916312Z","steps":["trace[201234675] 'range keys from in-memory index tree' (duration: 129.867239ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T06:06:58.91666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.034382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-05T06:06:58.916703Z","caller":"traceutil/trace.go:171","msg":"trace[190339803] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:665; }","duration":"131.113773ms","start":"2026-09-05T06:06:58.785579Z","end":"2026-09-05T06:06:58.916693Z","steps":["trace[190339803] 'range keys from in-memory index tree' (duration: 130.979002ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T06:06:58.916724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.905801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-05T06:06:58.916777Z","caller":"traceutil/trace.go:171","msg":"trace[1543384158] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:665; }","duration":"130.999411ms","start":"2026-09-05T06:06:58.785768Z","end":"2026-09-05T06:06:58.916767Z","steps":["trace[1543384158] 'count revisions from in-memory index tree' (duration: 130.815429ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T06:07:14.632388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.924867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-05T06:07:14.632503Z","caller":"traceutil/trace.go:171","msg":"trace[871607649] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:811; }","duration":"304.09453ms","start":"2026-09-05T06:07:14.328385Z","end":"2026-09-05T06:07:14.632479Z","steps":["trace[871607649] 'range keys from in-memory index tree' (duration: 303.838047ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T06:07:14.632574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-05T06:07:14.328368Z","time spent":"304.19083ms","remote":"127.0.0.1:46938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-05T06:07:29.009289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.914431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-09-05T06:07:29.009294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.980842ms","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-09-05T06:07:29.009406Z","caller":"traceutil/trace.go:171","msg":"trace[1249880824] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:976; }","duration":"148.064542ms","start":"2026-09-05T06:07:28.861313Z","end":"2026-09-05T06:07:29.009378Z","steps":["trace[1249880824] 'range keys from in-memory index tree' (duration: 147.77991ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T06:07:29.009323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.908272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-05T06:07:29.009507Z","caller":"traceutil/trace.go:171","msg":"trace[332227562] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:976; }","duration":"149.143755ms","start":"2026-09-05T06:07:28.860343Z","end":"2026-09-05T06:07:29.009487Z","steps":["trace[332227562] 'range keys from in-memory index tree' (duration: 148.761841ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:07:29.009474Z","caller":"traceutil/trace.go:171","msg":"trace[1081936102] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:976; }","duration":"148.147374ms","start":"2026-09-05T06:07:28.86125Z","end":"2026-09-05T06:07:29.009397Z","steps":["trace[1081936102] 'range keys from in-memory index tree' (duration: 147.77607ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:08:34.033442Z","caller":"traceutil/trace.go:171","msg":"trace[1439638304] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"128.604969ms","start":"2026-09-05T06:08:33.904807Z","end":"2026-09-05T06:08:34.033412Z","steps":["trace[1439638304] 'process raft request' (duration: 128.437235ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T06:08:34.336606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.474522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bb1a6d6a-6d5a-4f44-a6d8-7cbfc2634390\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-09-05T06:08:34.336691Z","caller":"traceutil/trace.go:171","msg":"trace[355675871] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-bb1a6d6a-6d5a-4f44-a6d8-7cbfc2634390; range_end:; response_count:1; response_revision:1889; }","duration":"267.616855ms","start":"2026-09-05T06:08:34.069055Z","end":"2026-09-05T06:08:34.336672Z","steps":["trace[355675871] 'range keys from in-memory index tree' (duration: 267.260427ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:08:34.337211Z","caller":"traceutil/trace.go:171","msg":"trace[354771462] linearizableReadLoop","detail":"{readStateIndex:1969; appliedIndex:1968; }","duration":"108.657335ms","start":"2026-09-05T06:08:34.228533Z","end":"2026-09-05T06:08:34.33719Z","steps":["trace[354771462] 'read index received' (duration: 27.712451ms)","trace[354771462] 'applied index is now lower than readState.Index' (duration: 80.943674ms)"],"step_count":2} {"level":"info","ts":"2026-09-05T06:08:34.33743Z","caller":"traceutil/trace.go:171","msg":"trace[1313203110] transaction","detail":"{read_only:false; response_revision:1890; number_of_response:1; }","duration":"115.104367ms","start":"2026-09-05T06:08:34.222312Z","end":"2026-09-05T06:08:34.337417Z","steps":["trace[1313203110] 'process raft request' (duration: 34.004109ms)","trace[1313203110] 'compare' (duration: 80.721108ms)"],"step_count":2} {"level":"warn","ts":"2026-09-05T06:08:34.337526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.985354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-09-05T06:08:34.337785Z","caller":"traceutil/trace.go:171","msg":"trace[1009204729] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1890; }","duration":"109.291602ms","start":"2026-09-05T06:08:34.22848Z","end":"2026-09-05T06:08:34.337771Z","steps":["trace[1009204729] 'agreement among raft nodes before linearized reading' (duration: 108.970594ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T06:08:34.755454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.376487ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-05T06:08:34.755752Z","caller":"traceutil/trace.go:171","msg":"trace[424073674] transaction","detail":"{read_only:false; response_revision:1893; number_of_response:1; }","duration":"410.134775ms","start":"2026-09-05T06:08:34.345565Z","end":"2026-09-05T06:08:34.7557Z","steps":["trace[424073674] 'process raft request' (duration: 409.983741ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:08:34.755793Z","caller":"traceutil/trace.go:171","msg":"trace[1563876535] transaction","detail":"{read_only:false; response_revision:1892; number_of_response:1; }","duration":"413.588967ms","start":"2026-09-05T06:08:34.342175Z","end":"2026-09-05T06:08:34.755764Z","steps":["trace[1563876535] 'process raft request' (duration: 154.591363ms)","trace[1563876535] 'compare' (duration: 258.153372ms)"],"step_count":2} {"level":"warn","ts":"2026-09-05T06:08:34.755928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-05T06:08:34.342161Z","time spent":"413.716982ms","remote":"127.0.0.1:47196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":538,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-05T06:08:34.756129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-05T06:08:34.345549Z","time spent":"410.239179ms","remote":"127.0.0.1:47110","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3731,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-05T06:08:47.261088Z","caller":"traceutil/trace.go:171","msg":"trace[946782133] transaction","detail":"{read_only:false; response_revision:2000; number_of_response:1; }","duration":"204.77553ms","start":"2026-09-05T06:08:47.056283Z","end":"2026-09-05T06:08:47.261059Z","steps":["trace[946782133] 'process raft request' (duration: 204.568314ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:09:13.48314Z","caller":"traceutil/trace.go:171","msg":"trace[968211206] transaction","detail":"{read_only:false; response_revision:2155; number_of_response:1; }","duration":"122.195021ms","start":"2026-09-05T06:09:13.360899Z","end":"2026-09-05T06:09:13.483094Z","steps":["trace[968211206] 'process raft request' (duration: 121.987036ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:09:13.631585Z","caller":"traceutil/trace.go:171","msg":"trace[362047094] linearizableReadLoop","detail":"{readStateIndex:2244; appliedIndex:2243; }","duration":"100.641144ms","start":"2026-09-05T06:09:13.530912Z","end":"2026-09-05T06:09:13.631553Z","steps":["trace[362047094] 'read index received' (duration: 85.548664ms)","trace[362047094] 'applied index is now lower than readState.Index' (duration: 15.09089ms)"],"step_count":2} {"level":"info","ts":"2026-09-05T06:09:13.631629Z","caller":"traceutil/trace.go:171","msg":"trace[1898385180] transaction","detail":"{read_only:false; response_revision:2156; number_of_response:1; }","duration":"100.851828ms","start":"2026-09-05T06:09:13.530747Z","end":"2026-09-05T06:09:13.631599Z","steps":["trace[1898385180] 'process raft request' (duration: 85.666246ms)","trace[1898385180] 'compare' (duration: 14.901585ms)"],"step_count":2} {"level":"warn","ts":"2026-09-05T06:09:13.631888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.9508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-05T06:09:13.631955Z","caller":"traceutil/trace.go:171","msg":"trace[1055477300] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2156; }","duration":"101.062122ms","start":"2026-09-05T06:09:13.530876Z","end":"2026-09-05T06:09:13.631938Z","steps":["trace[1055477300] 'agreement among raft nodes before linearized reading' (duration: 100.818957ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:09:13.808724Z","caller":"traceutil/trace.go:171","msg":"trace[1116274662] transaction","detail":"{read_only:false; response_revision:2159; number_of_response:1; }","duration":"144.010684ms","start":"2026-09-05T06:09:13.664667Z","end":"2026-09-05T06:09:13.808678Z","steps":["trace[1116274662] 'process raft request' (duration: 134.3022ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:09:13.817753Z","caller":"traceutil/trace.go:171","msg":"trace[1918403466] transaction","detail":"{read_only:false; response_revision:2160; number_of_response:1; }","duration":"149.687014ms","start":"2026-09-05T06:09:13.668044Z","end":"2026-09-05T06:09:13.817731Z","steps":["trace[1918403466] 'process raft request' (duration: 149.520731ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:09:14.457717Z","caller":"traceutil/trace.go:171","msg":"trace[497037918] transaction","detail":"{read_only:false; response_revision:2161; number_of_response:1; }","duration":"187.909422ms","start":"2026-09-05T06:09:14.269781Z","end":"2026-09-05T06:09:14.45769Z","steps":["trace[497037918] 'process raft request' (duration: 187.755778ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:09:14.75417Z","caller":"traceutil/trace.go:171","msg":"trace[577740223] transaction","detail":"{read_only:false; response_revision:2162; number_of_response:1; }","duration":"266.692855ms","start":"2026-09-05T06:09:14.487444Z","end":"2026-09-05T06:09:14.754137Z","steps":["trace[577740223] 'process raft request' (duration: 173.376267ms)","trace[577740223] 'compare' (duration: 93.01556ms)"],"step_count":2} {"level":"info","ts":"2026-09-05T06:09:14.754515Z","caller":"traceutil/trace.go:171","msg":"trace[158363416] linearizableReadLoop","detail":"{readStateIndex:2250; appliedIndex:2249; }","duration":"220.987153ms","start":"2026-09-05T06:09:14.533487Z","end":"2026-09-05T06:09:14.754474Z","steps":["trace[158363416] 'read index received' (duration: 127.280557ms)","trace[158363416] 'applied index is now lower than readState.Index' (duration: 93.704106ms)"],"step_count":2} {"level":"warn","ts":"2026-09-05T06:09:14.754794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.29072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-05T06:09:14.754886Z","caller":"traceutil/trace.go:171","msg":"trace[2016951949] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:2163; }","duration":"221.438143ms","start":"2026-09-05T06:09:14.53343Z","end":"2026-09-05T06:09:14.754868Z","steps":["trace[2016951949] 'agreement among raft nodes before linearized reading' (duration: 221.211178ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T06:09:14.75497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.716769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-05T06:09:14.755014Z","caller":"traceutil/trace.go:171","msg":"trace[70960445] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2163; }","duration":"171.77226ms","start":"2026-09-05T06:09:14.58323Z","end":"2026-09-05T06:09:14.755002Z","steps":["trace[70960445] 'agreement among raft nodes before linearized reading' (duration: 171.614737ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:09:15.137301Z","caller":"traceutil/trace.go:171","msg":"trace[1092634646] linearizableReadLoop","detail":"{readStateIndex:2252; appliedIndex:2251; }","duration":"171.173237ms","start":"2026-09-05T06:09:14.966108Z","end":"2026-09-05T06:09:15.137281Z","steps":["trace[1092634646] 'read index received' (duration: 170.953702ms)","trace[1092634646] 'applied index is now lower than readState.Index' (duration: 218.945µs)"],"step_count":2} {"level":"info","ts":"2026-09-05T06:09:15.137435Z","caller":"traceutil/trace.go:171","msg":"trace[703220659] transaction","detail":"{read_only:false; response_revision:2164; number_of_response:1; }","duration":"378.000235ms","start":"2026-09-05T06:09:14.759388Z","end":"2026-09-05T06:09:15.137388Z","steps":["trace[703220659] 'process raft request' (duration: 377.75954ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T06:09:15.137486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.387942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"warn","ts":"2026-09-05T06:09:15.137652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-05T06:09:14.75937Z","time spent":"378.160508ms","remote":"127.0.0.1:47196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-05T06:09:15.137772Z","caller":"traceutil/trace.go:171","msg":"trace[1490905443] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2164; }","duration":"171.692609ms","start":"2026-09-05T06:09:14.966027Z","end":"2026-09-05T06:09:15.13772Z","steps":["trace[1490905443] 'agreement among raft nodes before linearized reading' (duration: 171.33293ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:10:32.574763Z","caller":"traceutil/trace.go:171","msg":"trace[1156024264] transaction","detail":"{read_only:false; response_revision:2694; number_of_response:1; }","duration":"110.319416ms","start":"2026-09-05T06:10:32.464398Z","end":"2026-09-05T06:10:32.574718Z","steps":["trace[1156024264] 'process raft request' (duration: 102.629204ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T06:10:38.11341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.820582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-05T06:10:38.113527Z","caller":"traceutil/trace.go:171","msg":"trace[2147017914] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:2720; }","duration":"232.993045ms","start":"2026-09-05T06:10:37.880499Z","end":"2026-09-05T06:10:38.113492Z","steps":["trace[2147017914] 'count revisions from in-memory index tree' (duration: 232.692299ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:10:43.730286Z","caller":"traceutil/trace.go:171","msg":"trace[1708770270] transaction","detail":"{read_only:false; response_revision:2750; number_of_response:1; }","duration":"126.503886ms","start":"2026-09-05T06:10:43.60374Z","end":"2026-09-05T06:10:43.730244Z","steps":["trace[1708770270] 'process raft request' (duration: 126.255351ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:10:44.404848Z","caller":"traceutil/trace.go:171","msg":"trace[1334779371] transaction","detail":"{read_only:false; response_revision:2751; number_of_response:1; }","duration":"677.117461ms","start":"2026-09-05T06:10:43.727612Z","end":"2026-09-05T06:10:44.404729Z","steps":["trace[1334779371] 'process raft request' (duration: 630.62578ms)","trace[1334779371] 'compare' (duration: 46.012933ms)"],"step_count":2} {"level":"warn","ts":"2026-09-05T06:10:44.405163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-05T06:10:43.727594Z","time spent":"677.352045ms","remote":"127.0.0.1:47196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-05T06:10:44.420667Z","caller":"traceutil/trace.go:171","msg":"trace[1220032337] transaction","detail":"{read_only:false; response_revision:2752; number_of_response:1; }","duration":"686.068491ms","start":"2026-09-05T06:10:43.734561Z","end":"2026-09-05T06:10:44.420629Z","steps":["trace[1220032337] 'process raft request' (duration: 685.833747ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T06:10:44.42096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-05T06:10:43.734544Z","time spent":"686.271824ms","remote":"127.0.0.1:47196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-05T06:10:44.421079Z","caller":"traceutil/trace.go:171","msg":"trace[427805335] transaction","detail":"{read_only:false; response_revision:2753; number_of_response:1; }","duration":"574.843458ms","start":"2026-09-05T06:10:43.846206Z","end":"2026-09-05T06:10:44.421049Z","steps":["trace[427805335] 'process raft request' (duration: 574.36855ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:10:44.421192Z","caller":"traceutil/trace.go:171","msg":"trace[725801063] linearizableReadLoop","detail":"{readStateIndex:2861; appliedIndex:2858; }","duration":"258.958678ms","start":"2026-09-05T06:10:44.161888Z","end":"2026-09-05T06:10:44.420847Z","steps":["trace[725801063] 'read index received' (duration: 196.329509ms)","trace[725801063] 'applied index is now lower than readState.Index' (duration: 62.627239ms)"],"step_count":2} {"level":"warn","ts":"2026-09-05T06:10:44.421462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-05T06:10:43.846173Z","time spent":"575.228505ms","remote":"127.0.0.1:47196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-05T06:10:44.421271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.372316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-05T06:10:44.421647Z","caller":"traceutil/trace.go:171","msg":"trace[190246166] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2753; }","duration":"259.790913ms","start":"2026-09-05T06:10:44.161833Z","end":"2026-09-05T06:10:44.421624Z","steps":["trace[190246166] 'agreement among raft nodes before linearized reading' (duration: 259.298244ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T06:10:44.421744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.31233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-05T06:10:44.42179Z","caller":"traceutil/trace.go:171","msg":"trace[376841765] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2753; }","duration":"256.409942ms","start":"2026-09-05T06:10:44.165368Z","end":"2026-09-05T06:10:44.421778Z","steps":["trace[376841765] 'agreement among raft nodes before linearized reading' (duration: 256.227459ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T06:10:44.421882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.156402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-05T06:10:44.421962Z","caller":"traceutil/trace.go:171","msg":"trace[1274743279] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:2753; }","duration":"122.291165ms","start":"2026-09-05T06:10:44.299652Z","end":"2026-09-05T06:10:44.421943Z","steps":["trace[1274743279] 'agreement among raft nodes before linearized reading' (duration: 122.182023ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T06:10:44.421975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.589686ms","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-09-05T06:10:44.422002Z","caller":"traceutil/trace.go:171","msg":"trace[638336510] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2753; }","duration":"157.654177ms","start":"2026-09-05T06:10:44.264338Z","end":"2026-09-05T06:10:44.421992Z","steps":["trace[638336510] 'agreement among raft nodes before linearized reading' (duration: 157.554616ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T06:10:44.422122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.349086ms","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-09-05T06:10:44.42215Z","caller":"traceutil/trace.go:171","msg":"trace[1484941526] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2753; }","duration":"160.412616ms","start":"2026-09-05T06:10:44.26173Z","end":"2026-09-05T06:10:44.422142Z","steps":["trace[1484941526] 'agreement among raft nodes before linearized reading' (duration: 160.339515ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:15:01.37427Z","caller":"traceutil/trace.go:171","msg":"trace[1857466475] linearizableReadLoop","detail":"{readStateIndex:4949; appliedIndex:4948; }","duration":"260.324043ms","start":"2026-09-05T06:15:01.113925Z","end":"2026-09-05T06:15:01.374249Z","steps":["trace[1857466475] 'read index received' (duration: 260.09381ms)","trace[1857466475] 'applied index is now lower than readState.Index' (duration: 229.413µs)"],"step_count":2} {"level":"info","ts":"2026-09-05T06:15:01.374376Z","caller":"traceutil/trace.go:171","msg":"trace[1591615078] transaction","detail":"{read_only:false; response_revision:4762; number_of_response:1; }","duration":"277.140821ms","start":"2026-09-05T06:15:01.097204Z","end":"2026-09-05T06:15:01.374345Z","steps":["trace[1591615078] 'process raft request' (duration: 276.890588ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T06:15:01.374495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.551196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-05T06:15:01.374527Z","caller":"traceutil/trace.go:171","msg":"trace[1024201962] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4762; }","duration":"260.622747ms","start":"2026-09-05T06:15:01.113895Z","end":"2026-09-05T06:15:01.374518Z","steps":["trace[1024201962] 'agreement among raft nodes before linearized reading' (duration: 260.464145ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:15:07.340525Z","caller":"traceutil/trace.go:171","msg":"trace[442049271] transaction","detail":"{read_only:false; response_revision:4845; number_of_response:1; }","duration":"169.61769ms","start":"2026-09-05T06:15:07.170863Z","end":"2026-09-05T06:15:07.34048Z","steps":["trace[442049271] 'process raft request' (duration: 168.697857ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:15:08.019766Z","caller":"traceutil/trace.go:171","msg":"trace[57964349] transaction","detail":"{read_only:false; response_revision:4858; number_of_response:1; }","duration":"143.581348ms","start":"2026-09-05T06:15:07.87616Z","end":"2026-09-05T06:15:08.019741Z","steps":["trace[57964349] 'process raft request' (duration: 143.391056ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:15:08.02006Z","caller":"traceutil/trace.go:171","msg":"trace[271386652] transaction","detail":"{read_only:false; response_revision:4859; number_of_response:1; }","duration":"143.76225ms","start":"2026-09-05T06:15:07.876238Z","end":"2026-09-05T06:15:08.020001Z","steps":["trace[271386652] 'process raft request' (duration: 143.463097ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:15:08.020503Z","caller":"traceutil/trace.go:171","msg":"trace[454226086] linearizableReadLoop","detail":"{readStateIndex:5051; appliedIndex:5047; }","duration":"132.765063ms","start":"2026-09-05T06:15:07.887706Z","end":"2026-09-05T06:15:08.020471Z","steps":["trace[454226086] 'read index received' (duration: 7.42507ms)","trace[454226086] 'applied index is now lower than readState.Index' (duration: 125.338953ms)"],"step_count":2} {"level":"info","ts":"2026-09-05T06:15:08.020558Z","caller":"traceutil/trace.go:171","msg":"trace[1834119162] transaction","detail":"{read_only:false; response_revision:4860; number_of_response:1; }","duration":"143.276354ms","start":"2026-09-05T06:15:07.877258Z","end":"2026-09-05T06:15:08.020535Z","steps":["trace[1834119162] 'process raft request' (duration: 142.703717ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T06:15:08.020643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.913854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-05T06:15:08.020685Z","caller":"traceutil/trace.go:171","msg":"trace[1735150461] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:0; response_revision:4860; }","duration":"132.990015ms","start":"2026-09-05T06:15:07.887681Z","end":"2026-09-05T06:15:08.020671Z","steps":["trace[1735150461] 'agreement among raft nodes before linearized reading' (duration: 132.907664ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:15:10.423844Z","caller":"traceutil/trace.go:171","msg":"trace[675476860] transaction","detail":"{read_only:false; response_revision:4942; number_of_response:1; }","duration":"140.766159ms","start":"2026-09-05T06:15:10.283049Z","end":"2026-09-05T06:15:10.423815Z","steps":["trace[675476860] 'process raft request' (duration: 140.567976ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T06:15:12.629692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.23379ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-05T06:15:12.629949Z","caller":"traceutil/trace.go:171","msg":"trace[53342831] transaction","detail":"{read_only:false; response_revision:4976; number_of_response:1; }","duration":"285.078932ms","start":"2026-09-05T06:15:12.344853Z","end":"2026-09-05T06:15:12.629932Z","steps":["trace[53342831] 'process raft request' (duration: 285.001031ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:15:12.629981Z","caller":"traceutil/trace.go:171","msg":"trace[786328355] transaction","detail":"{read_only:false; response_revision:4975; number_of_response:1; }","duration":"295.194239ms","start":"2026-09-05T06:15:12.334746Z","end":"2026-09-05T06:15:12.62994Z","steps":["trace[786328355] 'process raft request' (duration: 194.632844ms)","trace[786328355] 'compare' (duration: 100.087828ms)"],"step_count":2} {"level":"info","ts":"2026-09-05T06:15:12.630145Z","caller":"traceutil/trace.go:171","msg":"trace[1981573052] linearizableReadLoop","detail":"{readStateIndex:5167; appliedIndex:5166; }","duration":"294.073254ms","start":"2026-09-05T06:15:12.336052Z","end":"2026-09-05T06:15:12.630125Z","steps":["trace[1981573052] 'read index received' (duration: 2.101818ms)","trace[1981573052] 'applied index is now lower than readState.Index' (duration: 291.969646ms)"],"step_count":2} {"level":"warn","ts":"2026-09-05T06:15:12.630244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.177345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-05T06:15:12.63056Z","caller":"traceutil/trace.go:171","msg":"trace[1824079811] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4976; }","duration":"294.504639ms","start":"2026-09-05T06:15:12.336023Z","end":"2026-09-05T06:15:12.630528Z","steps":["trace[1824079811] 'agreement among raft nodes before linearized reading' (duration: 294.156184ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T06:15:12.630732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.520569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-02e43e49-1a37-4fb3-a2bf-f74e496a5843\" ","response":"range_response_count:1 size:3948"} {"level":"info","ts":"2026-09-05T06:15:12.630759Z","caller":"traceutil/trace.go:171","msg":"trace[2549983] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-02e43e49-1a37-4fb3-a2bf-f74e496a5843; range_end:; response_count:1; response_revision:4976; }","duration":"124.57522ms","start":"2026-09-05T06:15:12.506175Z","end":"2026-09-05T06:15:12.63075Z","steps":["trace[2549983] 'agreement among raft nodes before linearized reading' (duration: 124.485589ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T06:15:12.632126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.070286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.86\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-09-05T06:15:12.632214Z","caller":"traceutil/trace.go:171","msg":"trace[637897306] range","detail":"{range_begin:/registry/masterleases/162.253.55.86; range_end:; response_count:1; response_revision:4976; }","duration":"168.187247ms","start":"2026-09-05T06:15:12.464011Z","end":"2026-09-05T06:15:12.632198Z","steps":["trace[637897306] 'agreement among raft nodes before linearized reading' (duration: 166.608086ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:15:20.542257Z","caller":"traceutil/trace.go:171","msg":"trace[629152746] linearizableReadLoop","detail":"{readStateIndex:5289; appliedIndex:5288; }","duration":"129.518941ms","start":"2026-09-05T06:15:20.412718Z","end":"2026-09-05T06:15:20.542237Z","steps":["trace[629152746] 'read index received' (duration: 129.274758ms)","trace[629152746] 'applied index is now lower than readState.Index' (duration: 243.243µs)"],"step_count":2} {"level":"info","ts":"2026-09-05T06:15:20.542374Z","caller":"traceutil/trace.go:171","msg":"trace[66678944] transaction","detail":"{read_only:false; response_revision:5094; number_of_response:1; }","duration":"129.922486ms","start":"2026-09-05T06:15:20.412409Z","end":"2026-09-05T06:15:20.542331Z","steps":["trace[66678944] 'process raft request' (duration: 129.639533ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T06:15:20.542459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.718414ms","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-09-05T06:15:20.542606Z","caller":"traceutil/trace.go:171","msg":"trace[1802294809] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5094; }","duration":"129.820345ms","start":"2026-09-05T06:15:20.412681Z","end":"2026-09-05T06:15:20.542501Z","steps":["trace[1802294809] 'agreement among raft nodes before linearized reading' (duration: 129.655842ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:15:48.220657Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2772} {"level":"info","ts":"2026-09-05T06:15:48.29785Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2772,"took":"75.924871ms","hash":1450712930,"current-db-size-bytes":51118080,"current-db-size":"51 MB","current-db-size-in-use-bytes":27242496,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-09-05T06:15:48.2979Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1450712930,"revision":2772,"compact-revision":-1} {"level":"info","ts":"2026-09-05T06:20:48.229025Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5266} {"level":"info","ts":"2026-09-05T06:20:48.352912Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5266,"took":"122.85075ms","hash":1833377646,"current-db-size-bytes":51118080,"current-db-size":"51 MB","current-db-size-in-use-bytes":18395136,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-09-05T06:20:48.352989Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1833377646,"revision":5266,"compact-revision":2772}