{"level":"warn","ts":"2026-08-30T01:22:34.079972Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-30T01:22:34.080127Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.186: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.186:2380","--initial-cluster=instance=https://162.253.55.186:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.186:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.186: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-08-30T01:22:34.080234Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-30T01:22:34.080253Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.186:2380"]} {"level":"info","ts":"2026-08-30T01:22:34.08029Z","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-08-30T01:22:34.081605Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.186:2379"]} {"level":"info","ts":"2026-08-30T01:22:34.081766Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.186:2380"],"listen-peer-urls":["https://162.253.55.186:2380"],"advertise-client-urls":["https://162.253.55.186:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.186:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.186: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-08-30T01:22:34.086848Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.692511ms"} {"level":"info","ts":"2026-08-30T01:22:34.096351Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"612d5169b08bb96b","cluster-id":"20ec8eafba602517"} {"level":"info","ts":"2026-08-30T01:22:34.09653Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b switched to configuration voters=()"} {"level":"info","ts":"2026-08-30T01:22:34.096566Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b became follower at term 0"} {"level":"info","ts":"2026-08-30T01:22:34.096587Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 612d5169b08bb96b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-30T01:22:34.096606Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b became follower at term 1"} {"level":"info","ts":"2026-08-30T01:22:34.096671Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b switched to configuration voters=(7002342510006352235)"} {"level":"warn","ts":"2026-08-30T01:22:34.10599Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-30T01:22:34.107909Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-30T01:22:34.109436Z","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-08-30T01:22:34.113184Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"612d5169b08bb96b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-30T01:22:34.113431Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"612d5169b08bb96b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-30T01:22:34.113542Z","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-08-30T01:22:34.113663Z","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-08-30T01:22:34.11368Z","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-08-30T01:22:34.116278Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b switched to configuration voters=(7002342510006352235)"} {"level":"info","ts":"2026-08-30T01:22:34.11718Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"20ec8eafba602517","local-member-id":"612d5169b08bb96b","added-peer-id":"612d5169b08bb96b","added-peer-peer-urls":["https://162.253.55.186:2380"]} {"level":"info","ts":"2026-08-30T01:22:34.120423Z","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-08-30T01:22:34.120635Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.186:2380"} {"level":"info","ts":"2026-08-30T01:22:34.120699Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.186:2380"} {"level":"info","ts":"2026-08-30T01:22:34.121082Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"612d5169b08bb96b","initial-advertise-peer-urls":["https://162.253.55.186:2380"],"listen-peer-urls":["https://162.253.55.186:2380"],"advertise-client-urls":["https://162.253.55.186:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.186:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-30T01:22:34.121164Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-30T01:22:34.497683Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b is starting a new election at term 1"} {"level":"info","ts":"2026-08-30T01:22:34.497759Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b became pre-candidate at term 1"} {"level":"info","ts":"2026-08-30T01:22:34.4978Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b received MsgPreVoteResp from 612d5169b08bb96b at term 1"} {"level":"info","ts":"2026-08-30T01:22:34.497827Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b became candidate at term 2"} {"level":"info","ts":"2026-08-30T01:22:34.497849Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b received MsgVoteResp from 612d5169b08bb96b at term 2"} {"level":"info","ts":"2026-08-30T01:22:34.497875Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b became leader at term 2"} {"level":"info","ts":"2026-08-30T01:22:34.497899Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 612d5169b08bb96b elected leader 612d5169b08bb96b at term 2"} {"level":"info","ts":"2026-08-30T01:22:34.499237Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"612d5169b08bb96b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.186:2379]}","request-path":"/0/members/612d5169b08bb96b/attributes","cluster-id":"20ec8eafba602517","publish-timeout":"7s"} {"level":"info","ts":"2026-08-30T01:22:34.499463Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-30T01:22:34.499512Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-30T01:22:34.499645Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-30T01:22:34.499692Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-30T01:22:34.499843Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-30T01:22:34.502106Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.186:2379"} {"level":"info","ts":"2026-08-30T01:22:34.503234Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-30T01:22:34.524961Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"20ec8eafba602517","local-member-id":"612d5169b08bb96b","cluster-version":"3.5"} {"level":"info","ts":"2026-08-30T01:22:34.52517Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-30T01:22:34.525244Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-30T01:23:20.532048Z","caller":"traceutil/trace.go:171","msg":"trace[1437635843] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"148.983595ms","start":"2026-08-30T01:23:20.383036Z","end":"2026-08-30T01:23:20.53202Z","steps":["trace[1437635843] 'process raft request' (duration: 147.803613ms)"],"step_count":1} {"level":"warn","ts":"2026-08-30T01:23:20.765071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.354584ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-30T01:23:20.765234Z","caller":"traceutil/trace.go:171","msg":"trace[9689916] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"199.707156ms","start":"2026-08-30T01:23:20.565507Z","end":"2026-08-30T01:23:20.765214Z","steps":["trace[9689916] 'process raft request' (duration: 83.574802ms)","trace[9689916] 'compare' (duration: 115.171259ms)"],"step_count":2} {"level":"info","ts":"2026-08-30T01:23:20.971482Z","caller":"traceutil/trace.go:171","msg":"trace[1696061087] linearizableReadLoop","detail":"{readStateIndex:613; appliedIndex:611; }","duration":"322.722125ms","start":"2026-08-30T01:23:20.648717Z","end":"2026-08-30T01:23:20.971439Z","steps":["trace[1696061087] 'read index received' (duration: 333.729µs)","trace[1696061087] 'applied index is now lower than readState.Index' (duration: 322.386476ms)"],"step_count":2} {"level":"info","ts":"2026-08-30T01:23:20.971545Z","caller":"traceutil/trace.go:171","msg":"trace[21801540] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"391.089875ms","start":"2026-08-30T01:23:20.580427Z","end":"2026-08-30T01:23:20.971517Z","steps":["trace[21801540] 'process raft request' (duration: 303.954598ms)","trace[21801540] 'compare' (duration: 86.778777ms)"],"step_count":2} {"level":"warn","ts":"2026-08-30T01:23:20.971701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.975861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-30T01:23:20.971684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-30T01:23:20.580392Z","time spent":"391.231539ms","remote":"127.0.0.1:53304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-30T01:23:20.971765Z","caller":"traceutil/trace.go:171","msg":"trace[1615158199] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:596; }","duration":"323.097784ms","start":"2026-08-30T01:23:20.648653Z","end":"2026-08-30T01:23:20.971751Z","steps":["trace[1615158199] 'agreement among raft nodes before linearized reading' (duration: 322.94968ms)"],"step_count":1} {"level":"warn","ts":"2026-08-30T01:23:20.971798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-30T01:23:20.648628Z","time spent":"323.163736ms","remote":"127.0.0.1:52942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-30T01:23:20.971856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.235998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-30T01:23:20.971907Z","caller":"traceutil/trace.go:171","msg":"trace[1680819633] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:596; }","duration":"187.35862ms","start":"2026-08-30T01:23:20.784535Z","end":"2026-08-30T01:23:20.971893Z","steps":["trace[1680819633] 'agreement among raft nodes before linearized reading' (duration: 187.095774ms)"],"step_count":1} {"level":"info","ts":"2026-08-30T01:23:21.569863Z","caller":"traceutil/trace.go:171","msg":"trace[883724115] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"529.794206ms","start":"2026-08-30T01:23:21.040021Z","end":"2026-08-30T01:23:21.569815Z","steps":["trace[883724115] 'process raft request' (duration: 482.438692ms)","trace[883724115] 'compare' (duration: 47.085756ms)"],"step_count":2} {"level":"warn","ts":"2026-08-30T01:23:21.570043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-30T01:23:21.039989Z","time spent":"529.95607ms","remote":"127.0.0.1:53212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-30T01:23:21.575901Z","caller":"traceutil/trace.go:171","msg":"trace[288497123] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"112.91079ms","start":"2026-08-30T01:23:21.46295Z","end":"2026-08-30T01:23:21.575861Z","steps":["trace[288497123] 'process raft request' (duration: 111.971185ms)"],"step_count":1} {"level":"info","ts":"2026-08-30T01:23:22.829932Z","caller":"traceutil/trace.go:171","msg":"trace[65280835] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"112.353475ms","start":"2026-08-30T01:23:22.717542Z","end":"2026-08-30T01:23:22.829895Z","steps":["trace[65280835] 'process raft request' (duration: 112.227001ms)"],"step_count":1} {"level":"warn","ts":"2026-08-30T01:23:37.902753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.465799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-30T01:23:37.902846Z","caller":"traceutil/trace.go:171","msg":"trace[626655746] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:767; }","duration":"245.628624ms","start":"2026-08-30T01:23:37.657195Z","end":"2026-08-30T01:23:37.902824Z","steps":["trace[626655746] 'count revisions from in-memory index tree' (duration: 245.341467ms)"],"step_count":1} {"level":"warn","ts":"2026-08-30T01:23:37.903119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.804957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-zl8gv\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-08-30T01:23:37.903181Z","caller":"traceutil/trace.go:171","msg":"trace[450118233] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-zl8gv; range_end:; response_count:1; response_revision:767; }","duration":"226.892739ms","start":"2026-08-30T01:23:37.67627Z","end":"2026-08-30T01:23:37.903163Z","steps":["trace[450118233] 'range keys from in-memory index tree' (duration: 226.410526ms)"],"step_count":1} {"level":"info","ts":"2026-08-30T01:24:59.274463Z","caller":"traceutil/trace.go:171","msg":"trace[1846732872] transaction","detail":"{read_only:false; response_revision:1864; number_of_response:1; }","duration":"124.734596ms","start":"2026-08-30T01:24:59.14969Z","end":"2026-08-30T01:24:59.274425Z","steps":["trace[1846732872] 'process raft request' (duration: 124.431418ms)"],"step_count":1} {"level":"info","ts":"2026-08-30T01:25:12.481595Z","caller":"traceutil/trace.go:171","msg":"trace[983317066] linearizableReadLoop","detail":"{readStateIndex:2040; appliedIndex:2039; }","duration":"144.208759ms","start":"2026-08-30T01:25:12.337351Z","end":"2026-08-30T01:25:12.48156Z","steps":["trace[983317066] 'read index received' (duration: 143.874971ms)","trace[983317066] 'applied index is now lower than readState.Index' (duration: 332.658µs)"],"step_count":2} {"level":"warn","ts":"2026-08-30T01:25:12.481943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.558449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11986"} {"level":"info","ts":"2026-08-30T01:25:12.482052Z","caller":"traceutil/trace.go:171","msg":"trace[1517156355] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1972; }","duration":"144.711012ms","start":"2026-08-30T01:25:12.337318Z","end":"2026-08-30T01:25:12.482029Z","steps":["trace[1517156355] 'agreement among raft nodes before linearized reading' (duration: 144.414244ms)"],"step_count":1} {"level":"info","ts":"2026-08-30T01:25:12.48241Z","caller":"traceutil/trace.go:171","msg":"trace[1149811745] transaction","detail":"{read_only:false; response_revision:1972; number_of_response:1; }","duration":"145.547123ms","start":"2026-08-30T01:25:12.336144Z","end":"2026-08-30T01:25:12.481691Z","steps":["trace[1149811745] 'process raft request' (duration: 145.194264ms)"],"step_count":1} {"level":"warn","ts":"2026-08-30T01:25:44.314845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.970721ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-30T01:25:44.314986Z","caller":"traceutil/trace.go:171","msg":"trace[1645641232] transaction","detail":"{read_only:false; response_revision:2163; number_of_response:1; }","duration":"160.362862ms","start":"2026-08-30T01:25:44.154598Z","end":"2026-08-30T01:25:44.314961Z","steps":["trace[1645641232] 'process raft request' (duration: 42.156485ms)","trace[1645641232] 'compare' (duration: 117.802197ms)"],"step_count":2} {"level":"info","ts":"2026-08-30T01:25:49.952108Z","caller":"traceutil/trace.go:171","msg":"trace[993632963] linearizableReadLoop","detail":"{readStateIndex:2265; appliedIndex:2264; }","duration":"124.896694ms","start":"2026-08-30T01:25:49.827178Z","end":"2026-08-30T01:25:49.952075Z","steps":["trace[993632963] 'read index received' (duration: 124.712829ms)","trace[993632963] 'applied index is now lower than readState.Index' (duration: 182.525µs)"],"step_count":2} {"level":"info","ts":"2026-08-30T01:25:49.952169Z","caller":"traceutil/trace.go:171","msg":"trace[558720927] transaction","detail":"{read_only:false; response_revision:2188; number_of_response:1; }","duration":"142.817334ms","start":"2026-08-30T01:25:49.809313Z","end":"2026-08-30T01:25:49.95213Z","steps":["trace[558720927] 'process raft request' (duration: 142.559747ms)"],"step_count":1} {"level":"warn","ts":"2026-08-30T01:25:49.952427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.201753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-30T01:25:49.952476Z","caller":"traceutil/trace.go:171","msg":"trace[1394944136] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2188; }","duration":"125.321785ms","start":"2026-08-30T01:25:49.82714Z","end":"2026-08-30T01:25:49.952462Z","steps":["trace[1394944136] 'agreement among raft nodes before linearized reading' (duration: 125.100359ms)"],"step_count":1} {"level":"info","ts":"2026-08-30T01:25:50.059524Z","caller":"traceutil/trace.go:171","msg":"trace[599487013] transaction","detail":"{read_only:false; response_revision:2189; number_of_response:1; }","duration":"124.900594ms","start":"2026-08-30T01:25:49.934598Z","end":"2026-08-30T01:25:50.059499Z","steps":["trace[599487013] 'process raft request' (duration: 93.06748ms)","trace[599487013] 'compare' (duration: 31.597219ms)"],"step_count":2} {"level":"info","ts":"2026-08-30T01:25:50.059667Z","caller":"traceutil/trace.go:171","msg":"trace[407084018] transaction","detail":"{read_only:false; response_revision:2190; number_of_response:1; }","duration":"103.00462ms","start":"2026-08-30T01:25:49.956641Z","end":"2026-08-30T01:25:50.059645Z","steps":["trace[407084018] 'process raft request' (duration: 102.796374ms)"],"step_count":1} {"level":"info","ts":"2026-08-30T01:25:50.059523Z","caller":"traceutil/trace.go:171","msg":"trace[1075728367] linearizableReadLoop","detail":"{readStateIndex:2266; appliedIndex:2265; }","duration":"107.284141ms","start":"2026-08-30T01:25:49.952206Z","end":"2026-08-30T01:25:50.05949Z","steps":["trace[1075728367] 'read index received' (duration: 75.473017ms)","trace[1075728367] 'applied index is now lower than readState.Index' (duration: 31.809344ms)"],"step_count":2} {"level":"warn","ts":"2026-08-30T01:25:50.059813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.931291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-08-30T01:25:50.059849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.838611ms","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-08-30T01:25:50.059891Z","caller":"traceutil/trace.go:171","msg":"trace[1277458439] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2190; }","duration":"218.084056ms","start":"2026-08-30T01:25:49.841789Z","end":"2026-08-30T01:25:50.059873Z","steps":["trace[1277458439] 'agreement among raft nodes before linearized reading' (duration: 217.796758ms)"],"step_count":1} {"level":"info","ts":"2026-08-30T01:25:50.059936Z","caller":"traceutil/trace.go:171","msg":"trace[1747125499] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2190; }","duration":"124.958495ms","start":"2026-08-30T01:25:49.934957Z","end":"2026-08-30T01:25:50.059915Z","steps":["trace[1747125499] 'agreement among raft nodes before linearized reading' (duration: 124.589135ms)"],"step_count":1} {"level":"warn","ts":"2026-08-30T01:25:50.05999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.096117ms","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-08-30T01:25:50.06007Z","caller":"traceutil/trace.go:171","msg":"trace[313403308] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2190; }","duration":"136.221979ms","start":"2026-08-30T01:25:49.923825Z","end":"2026-08-30T01:25:50.060047Z","steps":["trace[313403308] 'agreement among raft nodes before linearized reading' (duration: 135.690625ms)"],"step_count":1} {"level":"warn","ts":"2026-08-30T01:25:50.060316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.618672ms","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-08-30T01:25:50.060361Z","caller":"traceutil/trace.go:171","msg":"trace[1509895903] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2190; }","duration":"162.719824ms","start":"2026-08-30T01:25:49.897629Z","end":"2026-08-30T01:25:50.060349Z","steps":["trace[1509895903] 'agreement among raft nodes before linearized reading' (duration: 161.931573ms)"],"step_count":1} {"level":"info","ts":"2026-08-30T01:25:56.135155Z","caller":"traceutil/trace.go:171","msg":"trace[1492893987] transaction","detail":"{read_only:false; response_revision:2219; number_of_response:1; }","duration":"163.157845ms","start":"2026-08-30T01:25:55.97197Z","end":"2026-08-30T01:25:56.135128Z","steps":["trace[1492893987] 'process raft request' (duration: 162.97469ms)"],"step_count":1} {"level":"info","ts":"2026-08-30T01:27:16.661324Z","caller":"traceutil/trace.go:171","msg":"trace[572335568] transaction","detail":"{read_only:false; response_revision:2847; number_of_response:1; }","duration":"190.407207ms","start":"2026-08-30T01:27:16.470884Z","end":"2026-08-30T01:27:16.661292Z","steps":["trace[572335568] 'process raft request' (duration: 190.168031ms)"],"step_count":1} {"level":"info","ts":"2026-08-30T01:27:16.679514Z","caller":"traceutil/trace.go:171","msg":"trace[1967792150] linearizableReadLoop","detail":"{readStateIndex:2952; appliedIndex:2948; }","duration":"138.850585ms","start":"2026-08-30T01:27:16.540636Z","end":"2026-08-30T01:27:16.679487Z","steps":["trace[1967792150] 'read index received' (duration: 120.504813ms)","trace[1967792150] 'applied index is now lower than readState.Index' (duration: 18.344662ms)"],"step_count":2} {"level":"info","ts":"2026-08-30T01:27:16.679565Z","caller":"traceutil/trace.go:171","msg":"trace[792240304] transaction","detail":"{read_only:false; response_revision:2849; number_of_response:1; }","duration":"175.014903ms","start":"2026-08-30T01:27:16.504525Z","end":"2026-08-30T01:27:16.679539Z","steps":["trace[792240304] 'process raft request' (duration: 174.847519ms)"],"step_count":1} {"level":"info","ts":"2026-08-30T01:27:16.679644Z","caller":"traceutil/trace.go:171","msg":"trace[452796721] transaction","detail":"{read_only:false; response_revision:2850; number_of_response:1; }","duration":"168.864512ms","start":"2026-08-30T01:27:16.510744Z","end":"2026-08-30T01:27:16.679609Z","steps":["trace[452796721] 'process raft request' (duration: 168.699057ms)"],"step_count":1} {"level":"info","ts":"2026-08-30T01:27:16.679573Z","caller":"traceutil/trace.go:171","msg":"trace[649556267] transaction","detail":"{read_only:false; response_revision:2848; number_of_response:1; }","duration":"201.301133ms","start":"2026-08-30T01:27:16.478221Z","end":"2026-08-30T01:27:16.679522Z","steps":["trace[649556267] 'process raft request' (duration: 201.003295ms)"],"step_count":1} {"level":"warn","ts":"2026-08-30T01:27:16.679821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.169043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-30T01:27:16.679861Z","caller":"traceutil/trace.go:171","msg":"trace[270782928] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2850; }","duration":"139.290646ms","start":"2026-08-30T01:27:16.540559Z","end":"2026-08-30T01:27:16.67985Z","steps":["trace[270782928] 'agreement among raft nodes before linearized reading' (duration: 139.074921ms)"],"step_count":1} {"level":"warn","ts":"2026-08-30T01:27:16.679966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.10828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-30T01:27:16.680076Z","caller":"traceutil/trace.go:171","msg":"trace[2123628160] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2850; }","duration":"102.259694ms","start":"2026-08-30T01:27:16.577786Z","end":"2026-08-30T01:27:16.680046Z","steps":["trace[2123628160] 'agreement among raft nodes before linearized reading' (duration: 101.912874ms)"],"step_count":1} {"level":"info","ts":"2026-08-30T01:30:15.707212Z","caller":"traceutil/trace.go:171","msg":"trace[1951415392] transaction","detail":"{read_only:false; response_revision:3943; number_of_response:1; }","duration":"108.492306ms","start":"2026-08-30T01:30:15.598688Z","end":"2026-08-30T01:30:15.707181Z","steps":["trace[1951415392] 'process raft request' (duration: 108.350472ms)"],"step_count":1} {"level":"info","ts":"2026-08-30T01:30:15.707135Z","caller":"traceutil/trace.go:171","msg":"trace[2137644331] transaction","detail":"{read_only:false; response_revision:3942; number_of_response:1; }","duration":"109.35641ms","start":"2026-08-30T01:30:15.597739Z","end":"2026-08-30T01:30:15.707095Z","steps":["trace[2137644331] 'process raft request' (duration: 100.736853ms)"],"step_count":1} {"level":"warn","ts":"2026-08-30T01:30:15.707703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.463272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/secretgen-controller/\" range_end:\"/registry/limitranges/secretgen-controller0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-30T01:30:15.707845Z","caller":"traceutil/trace.go:171","msg":"trace[1574149787] range","detail":"{range_begin:/registry/limitranges/secretgen-controller/; range_end:/registry/limitranges/secretgen-controller0; response_count:0; response_revision:3944; }","duration":"109.599706ms","start":"2026-08-30T01:30:15.598189Z","end":"2026-08-30T01:30:15.707789Z","steps":["trace[1574149787] 'agreement among raft nodes before linearized reading' (duration: 109.442762ms)"],"step_count":1} {"level":"info","ts":"2026-08-30T01:30:15.707729Z","caller":"traceutil/trace.go:171","msg":"trace[1713251500] linearizableReadLoop","detail":"{readStateIndex:4087; appliedIndex:4085; }","duration":"109.338139ms","start":"2026-08-30T01:30:15.598208Z","end":"2026-08-30T01:30:15.707546Z","steps":["trace[1713251500] 'read index received' (duration: 1.610793ms)","trace[1713251500] 'applied index is now lower than readState.Index' (duration: 107.723236ms)"],"step_count":2} {"level":"warn","ts":"2026-08-30T01:30:15.708955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.869048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/secretgen-controller-cluster-role\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-30T01:30:15.709323Z","caller":"traceutil/trace.go:171","msg":"trace[166539466] range","detail":"{range_begin:/registry/clusterroles/secretgen-controller-cluster-role; range_end:; response_count:0; response_revision:3944; }","duration":"106.29725ms","start":"2026-08-30T01:30:15.603003Z","end":"2026-08-30T01:30:15.7093Z","steps":["trace[166539466] 'agreement among raft nodes before linearized reading' (duration: 105.589571ms)"],"step_count":1} {"level":"warn","ts":"2026-08-30T01:32:24.876889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.488529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:23 size:531308"} {"level":"info","ts":"2026-08-30T01:32:24.876988Z","caller":"traceutil/trace.go:171","msg":"trace[1324181013] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:23; response_revision:5242; }","duration":"125.662053ms","start":"2026-08-30T01:32:24.751305Z","end":"2026-08-30T01:32:24.876967Z","steps":["trace[1324181013] 'range keys from in-memory index tree' (duration: 124.944614ms)"],"step_count":1} {"level":"warn","ts":"2026-08-30T01:32:27.031536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.914544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-41df9b20-38bd-47d2-8085-1024bd603442\" ","response":"range_response_count:1 size:4077"} {"level":"info","ts":"2026-08-30T01:32:27.031672Z","caller":"traceutil/trace.go:171","msg":"trace[455417234] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-41df9b20-38bd-47d2-8085-1024bd603442; range_end:; response_count:1; response_revision:5264; }","duration":"145.13283ms","start":"2026-08-30T01:32:26.886514Z","end":"2026-08-30T01:32:27.031647Z","steps":["trace[455417234] 'range keys from in-memory index tree' (duration: 144.656367ms)"],"step_count":1} {"level":"warn","ts":"2026-08-30T01:32:27.031842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.976284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-30T01:32:27.032055Z","caller":"traceutil/trace.go:171","msg":"trace[971208531] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5264; }","duration":"223.323834ms","start":"2026-08-30T01:32:26.8087Z","end":"2026-08-30T01:32:27.032024Z","steps":["trace[971208531] 'range keys from in-memory index tree' (duration: 222.753728ms)"],"step_count":1} {"level":"info","ts":"2026-08-30T01:32:30.186044Z","caller":"traceutil/trace.go:171","msg":"trace[1665367338] transaction","detail":"{read_only:false; response_revision:5418; number_of_response:1; }","duration":"174.781526ms","start":"2026-08-30T01:32:30.01123Z","end":"2026-08-30T01:32:30.186012Z","steps":["trace[1665367338] 'process raft request' (duration: 174.670623ms)"],"step_count":1} {"level":"info","ts":"2026-08-30T01:32:30.186103Z","caller":"traceutil/trace.go:171","msg":"trace[547183131] transaction","detail":"{read_only:false; response_revision:5417; number_of_response:1; }","duration":"175.777684ms","start":"2026-08-30T01:32:30.010283Z","end":"2026-08-30T01:32:30.186061Z","steps":["trace[547183131] 'process raft request' (duration: 148.654034ms)","trace[547183131] 'compare' (duration: 26.793771ms)"],"step_count":2} {"level":"info","ts":"2026-08-30T01:32:30.186343Z","caller":"traceutil/trace.go:171","msg":"trace[807252570] transaction","detail":"{read_only:false; response_revision:5419; number_of_response:1; }","duration":"171.245011ms","start":"2026-08-30T01:32:30.015022Z","end":"2026-08-30T01:32:30.186267Z","steps":["trace[807252570] 'process raft request' (duration: 170.939424ms)"],"step_count":1} {"level":"info","ts":"2026-08-30T01:32:30.18641Z","caller":"traceutil/trace.go:171","msg":"trace[1602853871] linearizableReadLoop","detail":"{readStateIndex:5618; appliedIndex:5615; }","duration":"159.122821ms","start":"2026-08-30T01:32:30.02725Z","end":"2026-08-30T01:32:30.186373Z","steps":["trace[1602853871] 'read index received' (duration: 131.824237ms)","trace[1602853871] 'applied index is now lower than readState.Index' (duration: 27.296904ms)"],"step_count":2} {"level":"warn","ts":"2026-08-30T01:32:30.18662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.308496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-30T01:32:30.186666Z","caller":"traceutil/trace.go:171","msg":"trace[1894630780] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:5420; }","duration":"159.46486ms","start":"2026-08-30T01:32:30.027189Z","end":"2026-08-30T01:32:30.186654Z","steps":["trace[1894630780] 'agreement among raft nodes before linearized reading' (duration: 159.316776ms)"],"step_count":1} {"level":"info","ts":"2026-08-30T01:32:34.811447Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2952} {"level":"info","ts":"2026-08-30T01:32:34.90123Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2952,"took":"86.142675ms","hash":4079791186,"current-db-size-bytes":55730176,"current-db-size":"56 MB","current-db-size-in-use-bytes":30097408,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-30T01:32:34.9013Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4079791186,"revision":2952,"compact-revision":-1} {"level":"info","ts":"2026-08-30T01:32:45.554151Z","caller":"traceutil/trace.go:171","msg":"trace[818385886] transaction","detail":"{read_only:false; response_revision:5702; number_of_response:1; }","duration":"305.322999ms","start":"2026-08-30T01:32:45.248788Z","end":"2026-08-30T01:32:45.554111Z","steps":["trace[818385886] 'process raft request' (duration: 260.281734ms)","trace[818385886] 'compare' (duration: 44.773508ms)"],"step_count":2} {"level":"warn","ts":"2026-08-30T01:32:45.554378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-30T01:32:45.248768Z","time spent":"305.493194ms","remote":"127.0.0.1:53128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9564,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-30T01:32:45.599571Z","caller":"traceutil/trace.go:171","msg":"trace[1510694870] transaction","detail":"{read_only:false; response_revision:5703; number_of_response:1; }","duration":"258.724643ms","start":"2026-08-30T01:32:45.340807Z","end":"2026-08-30T01:32:45.599532Z","steps":["trace[1510694870] 'process raft request' (duration: 258.534898ms)"],"step_count":1} {"level":"info","ts":"2026-08-30T01:32:54.325544Z","caller":"traceutil/trace.go:171","msg":"trace[1630485834] linearizableReadLoop","detail":"{readStateIndex:6119; appliedIndex:6118; }","duration":"186.870756ms","start":"2026-08-30T01:32:54.138645Z","end":"2026-08-30T01:32:54.325516Z","steps":["trace[1630485834] 'read index received' (duration: 186.690452ms)","trace[1630485834] 'applied index is now lower than readState.Index' (duration: 179.264µs)"],"step_count":2} {"level":"info","ts":"2026-08-30T01:32:54.325603Z","caller":"traceutil/trace.go:171","msg":"trace[1590715091] transaction","detail":"{read_only:false; response_revision:5905; number_of_response:1; }","duration":"244.790104ms","start":"2026-08-30T01:32:54.080778Z","end":"2026-08-30T01:32:54.325568Z","steps":["trace[1590715091] 'process raft request' (duration: 244.566508ms)"],"step_count":1} {"level":"warn","ts":"2026-08-30T01:32:54.326008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.318779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-30T01:32:54.326128Z","caller":"traceutil/trace.go:171","msg":"trace[1626489774] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:5905; }","duration":"187.522114ms","start":"2026-08-30T01:32:54.138576Z","end":"2026-08-30T01:32:54.326098Z","steps":["trace[1626489774] 'agreement among raft nodes before linearized reading' (duration: 187.108013ms)"],"step_count":1} {"level":"info","ts":"2026-08-30T01:32:54.459787Z","caller":"traceutil/trace.go:171","msg":"trace[1711356518] transaction","detail":"{read_only:false; response_revision:5906; number_of_response:1; }","duration":"184.395511ms","start":"2026-08-30T01:32:54.275358Z","end":"2026-08-30T01:32:54.459754Z","steps":["trace[1711356518] 'process raft request' (duration: 108.550119ms)","trace[1711356518] 'compare' (duration: 75.620246ms)"],"step_count":2} {"level":"info","ts":"2026-08-30T01:32:54.460232Z","caller":"traceutil/trace.go:171","msg":"trace[2133775589] linearizableReadLoop","detail":"{readStateIndex:6122; appliedIndex:6119; }","duration":"130.686747ms","start":"2026-08-30T01:32:54.329513Z","end":"2026-08-30T01:32:54.4602Z","steps":["trace[2133775589] 'read index received' (duration: 54.410984ms)","trace[2133775589] 'applied index is now lower than readState.Index' (duration: 76.274333ms)"],"step_count":2} {"level":"info","ts":"2026-08-30T01:32:54.460425Z","caller":"traceutil/trace.go:171","msg":"trace[1664502210] transaction","detail":"{read_only:false; response_revision:5908; number_of_response:1; }","duration":"165.84771ms","start":"2026-08-30T01:32:54.294564Z","end":"2026-08-30T01:32:54.460412Z","steps":["trace[1664502210] 'process raft request' (duration: 165.46923ms)"],"step_count":1} {"level":"info","ts":"2026-08-30T01:32:54.460408Z","caller":"traceutil/trace.go:171","msg":"trace[360161128] transaction","detail":"{read_only:false; response_revision:5907; number_of_response:1; }","duration":"165.823289ms","start":"2026-08-30T01:32:54.29455Z","end":"2026-08-30T01:32:54.460373Z","steps":["trace[360161128] 'process raft request' (duration: 165.156691ms)"],"step_count":1} {"level":"warn","ts":"2026-08-30T01:32:54.460521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.975565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-pushgateway-7b8659c68b-d4mp2\" ","response":"range_response_count:1 size:4040"} {"level":"info","ts":"2026-08-30T01:32:54.460568Z","caller":"traceutil/trace.go:171","msg":"trace[1515273764] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-pushgateway-7b8659c68b-d4mp2; range_end:; response_count:1; response_revision:5908; }","duration":"131.084038ms","start":"2026-08-30T01:32:54.329468Z","end":"2026-08-30T01:32:54.460552Z","steps":["trace[1515273764] 'agreement among raft nodes before linearized reading' (duration: 130.853882ms)"],"step_count":1} {"level":"info","ts":"2026-08-30T01:32:54.581885Z","caller":"traceutil/trace.go:171","msg":"trace[1508630714] transaction","detail":"{read_only:false; response_revision:5909; number_of_response:1; }","duration":"214.585192ms","start":"2026-08-30T01:32:54.367188Z","end":"2026-08-30T01:32:54.581773Z","steps":["trace[1508630714] 'process raft request' (duration: 196.331228ms)","trace[1508630714] 'compare' (duration: 18.084769ms)"],"step_count":2} {"level":"info","ts":"2026-08-30T01:32:54.584463Z","caller":"traceutil/trace.go:171","msg":"trace[324988465] transaction","detail":"{read_only:false; response_revision:5910; number_of_response:1; }","duration":"111.9796ms","start":"2026-08-30T01:32:54.472455Z","end":"2026-08-30T01:32:54.584435Z","steps":["trace[324988465] 'process raft request' (duration: 111.787225ms)"],"step_count":1} {"level":"info","ts":"2026-08-30T01:37:34.821017Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5517} {"level":"info","ts":"2026-08-30T01:37:34.915514Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5517,"took":"92.279051ms","hash":2918626015,"current-db-size-bytes":55730176,"current-db-size":"56 MB","current-db-size-in-use-bytes":28086272,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-30T01:37:34.915861Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2918626015,"revision":5517,"compact-revision":2952} {"level":"info","ts":"2026-08-30T01:39:27.624955Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023a6.wal"} {"level":"info","ts":"2026-08-30T01:39:31.998971Z","caller":"traceutil/trace.go:171","msg":"trace[2064868701] linearizableReadLoop","detail":"{readStateIndex:9153; appliedIndex:9152; }","duration":"349.964124ms","start":"2026-08-30T01:39:31.648976Z","end":"2026-08-30T01:39:31.99894Z","steps":["trace[2064868701] 'read index received' (duration: 349.711938ms)","trace[2064868701] 'applied index is now lower than readState.Index' (duration: 250.496µs)"],"step_count":2} {"level":"info","ts":"2026-08-30T01:39:31.999071Z","caller":"traceutil/trace.go:171","msg":"trace[348201758] transaction","detail":"{read_only:false; response_revision:8845; number_of_response:1; }","duration":"381.833127ms","start":"2026-08-30T01:39:31.617188Z","end":"2026-08-30T01:39:31.999021Z","steps":["trace[348201758] 'process raft request' (duration: 381.494598ms)"],"step_count":1} {"level":"warn","ts":"2026-08-30T01:39:31.999208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.200211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-30T01:39:31.999345Z","caller":"traceutil/trace.go:171","msg":"trace[1170294152] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8845; }","duration":"350.412656ms","start":"2026-08-30T01:39:31.6489Z","end":"2026-08-30T01:39:31.999313Z","steps":["trace[1170294152] 'agreement among raft nodes before linearized reading' (duration: 350.16894ms)"],"step_count":1} {"level":"warn","ts":"2026-08-30T01:39:31.999351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-30T01:39:31.617157Z","time spent":"382.049522ms","remote":"127.0.0.1:53212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-30T01:39:31.999413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-30T01:39:31.648846Z","time spent":"350.555361ms","remote":"127.0.0.1:52936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-30T01:39:43.382045Z","caller":"traceutil/trace.go:171","msg":"trace[1278264717] transaction","detail":"{read_only:false; response_revision:8904; number_of_response:1; }","duration":"329.658471ms","start":"2026-08-30T01:39:43.052344Z","end":"2026-08-30T01:39:43.382003Z","steps":["trace[1278264717] 'process raft request' (duration: 295.126389ms)","trace[1278264717] 'compare' (duration: 33.922338ms)"],"step_count":2} {"level":"warn","ts":"2026-08-30T01:39:43.382272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-30T01:39:43.052299Z","time spent":"329.844455ms","remote":"127.0.0.1:53212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-30T01:39:43.382479Z","caller":"traceutil/trace.go:171","msg":"trace[698436475] linearizableReadLoop","detail":"{readStateIndex:9214; appliedIndex:9213; }","duration":"246.004389ms","start":"2026-08-30T01:39:43.136427Z","end":"2026-08-30T01:39:43.382431Z","steps":["trace[698436475] 'read index received' (duration: 211.056859ms)","trace[698436475] 'applied index is now lower than readState.Index' (duration: 34.94218ms)"],"step_count":2} {"level":"warn","ts":"2026-08-30T01:39:43.397563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.101968ms","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-08-30T01:39:43.39764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.504787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-08-30T01:39:43.397653Z","caller":"traceutil/trace.go:171","msg":"trace[1282195020] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8904; }","duration":"261.23927ms","start":"2026-08-30T01:39:43.13639Z","end":"2026-08-30T01:39:43.397629Z","steps":["trace[1282195020] 'agreement among raft nodes before linearized reading' (duration: 246.168523ms)","trace[1282195020] 'range keys from in-memory index tree' (duration: 14.830062ms)"],"step_count":2} {"level":"info","ts":"2026-08-30T01:39:43.397701Z","caller":"traceutil/trace.go:171","msg":"trace[1647144281] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8904; }","duration":"181.61669ms","start":"2026-08-30T01:39:43.216068Z","end":"2026-08-30T01:39:43.397685Z","steps":["trace[1647144281] 'agreement among raft nodes before linearized reading' (duration: 181.442106ms)"],"step_count":1} {"level":"warn","ts":"2026-08-30T01:39:43.397753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.377217ms","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-08-30T01:39:43.397882Z","caller":"traceutil/trace.go:171","msg":"trace[946938865] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8904; }","duration":"186.572222ms","start":"2026-08-30T01:39:43.211283Z","end":"2026-08-30T01:39:43.397855Z","steps":["trace[946938865] 'agreement among raft nodes before linearized reading' (duration: 186.216233ms)"],"step_count":1} {"level":"warn","ts":"2026-08-30T01:39:43.39805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.983489ms","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-08-30T01:39:43.398091Z","caller":"traceutil/trace.go:171","msg":"trace[1605209814] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8904; }","duration":"182.04491ms","start":"2026-08-30T01:39:43.216036Z","end":"2026-08-30T01:39:43.398081Z","steps":["trace[1605209814] 'agreement among raft nodes before linearized reading' (duration: 181.919707ms)"],"step_count":1} {"level":"warn","ts":"2026-08-30T01:40:25.884808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.383044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-30T01:40:25.884919Z","caller":"traceutil/trace.go:171","msg":"trace[632273029] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:9187; }","duration":"162.550258ms","start":"2026-08-30T01:40:25.722351Z","end":"2026-08-30T01:40:25.884901Z","steps":["trace[632273029] 'count revisions from in-memory index tree' (duration: 162.307732ms)"],"step_count":1} {"level":"warn","ts":"2026-08-30T01:40:25.884919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.567712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-m67xh\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-08-30T01:40:25.885055Z","caller":"traceutil/trace.go:171","msg":"trace[1056784414] range","detail":"{range_begin:/registry/pods/openstack/keepalived-m67xh; range_end:; response_count:1; response_revision:9187; }","duration":"161.790138ms","start":"2026-08-30T01:40:25.723233Z","end":"2026-08-30T01:40:25.885023Z","steps":["trace[1056784414] 'range keys from in-memory index tree' (duration: 161.339006ms)"],"step_count":1} {"level":"info","ts":"2026-08-30T01:40:26.663448Z","caller":"traceutil/trace.go:171","msg":"trace[1123847692] transaction","detail":"{read_only:false; response_revision:9188; number_of_response:1; }","duration":"121.121679ms","start":"2026-08-30T01:40:26.542293Z","end":"2026-08-30T01:40:26.663415Z","steps":["trace[1123847692] 'process raft request' (duration: 120.918923ms)"],"step_count":1} {"level":"info","ts":"2026-08-30T01:40:26.794428Z","caller":"traceutil/trace.go:171","msg":"trace[704412928] transaction","detail":"{read_only:false; response_revision:9189; number_of_response:1; }","duration":"250.534205ms","start":"2026-08-30T01:40:26.543865Z","end":"2026-08-30T01:40:26.794399Z","steps":["trace[704412928] 'process raft request' (duration: 250.287288ms)"],"step_count":1} {"level":"info","ts":"2026-08-30T01:40:26.794778Z","caller":"traceutil/trace.go:171","msg":"trace[2044523895] transaction","detail":"{read_only:false; response_revision:9190; number_of_response:1; }","duration":"250.036372ms","start":"2026-08-30T01:40:26.544706Z","end":"2026-08-30T01:40:26.794742Z","steps":["trace[2044523895] 'process raft request' (duration: 249.638562ms)"],"step_count":1} {"level":"info","ts":"2026-08-30T01:40:26.794989Z","caller":"traceutil/trace.go:171","msg":"trace[1452114579] transaction","detail":"{read_only:false; response_revision:9191; number_of_response:1; }","duration":"249.344514ms","start":"2026-08-30T01:40:26.545576Z","end":"2026-08-30T01:40:26.79492Z","steps":["trace[1452114579] 'process raft request' (duration: 249.061856ms)"],"step_count":1} {"level":"warn","ts":"2026-08-30T01:40:27.060079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.775637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-30T01:40:27.06017Z","caller":"traceutil/trace.go:171","msg":"trace[1357778176] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:9191; }","duration":"135.932731ms","start":"2026-08-30T01:40:26.924217Z","end":"2026-08-30T01:40:27.06015Z","steps":["trace[1357778176] 'count revisions from in-memory index tree' (duration: 135.650383ms)"],"step_count":1} {"level":"info","ts":"2026-08-30T01:40:40.793013Z","caller":"traceutil/trace.go:171","msg":"trace[1078412234] transaction","detail":"{read_only:false; response_revision:9339; number_of_response:1; }","duration":"108.520767ms","start":"2026-08-30T01:40:40.684454Z","end":"2026-08-30T01:40:40.792975Z","steps":["trace[1078412234] 'process raft request' (duration: 108.26078ms)"],"step_count":1} {"level":"info","ts":"2026-08-30T01:41:19.019877Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"612d5169b08bb96b","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-30T01:41:19.028747Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-30T01:41:19.028945Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-30T01:41:59.329239Z","caller":"traceutil/trace.go:171","msg":"trace[1768383106] transaction","detail":"{read_only:false; response_revision:10065; number_of_response:1; }","duration":"134.159518ms","start":"2026-08-30T01:41:59.19505Z","end":"2026-08-30T01:41:59.32921Z","steps":["trace[1768383106] 'process raft request' (duration: 133.977813ms)"],"step_count":1} {"level":"info","ts":"2026-08-30T01:41:59.357217Z","caller":"traceutil/trace.go:171","msg":"trace[413922413] transaction","detail":"{read_only:false; response_revision:10066; number_of_response:1; }","duration":"159.791056ms","start":"2026-08-30T01:41:59.197387Z","end":"2026-08-30T01:41:59.357179Z","steps":["trace[413922413] 'process raft request' (duration: 159.57078ms)"],"step_count":1} {"level":"info","ts":"2026-08-30T01:41:59.357484Z","caller":"traceutil/trace.go:171","msg":"trace[1696967724] transaction","detail":"{read_only:false; response_revision:10067; number_of_response:1; }","duration":"155.394568ms","start":"2026-08-30T01:41:59.202062Z","end":"2026-08-30T01:41:59.357457Z","steps":["trace[1696967724] 'process raft request' (duration: 155.0738ms)"],"step_count":1} {"level":"info","ts":"2026-08-30T01:41:59.357554Z","caller":"traceutil/trace.go:171","msg":"trace[853832805] transaction","detail":"{read_only:false; response_revision:10068; number_of_response:1; }","duration":"154.213376ms","start":"2026-08-30T01:41:59.203302Z","end":"2026-08-30T01:41:59.357515Z","steps":["trace[853832805] 'process raft request' (duration: 154.059142ms)"],"step_count":1} {"level":"info","ts":"2026-08-30T01:42:34.829803Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7948} {"level":"info","ts":"2026-08-30T01:42:34.928003Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7948,"took":"95.4271ms","hash":3077904550,"current-db-size-bytes":55730176,"current-db-size":"56 MB","current-db-size-in-use-bytes":24637440,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-08-30T01:42:34.928107Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3077904550,"revision":7948,"compact-revision":5517} {"level":"info","ts":"2026-08-30T01:47:18.830667Z","caller":"traceutil/trace.go:171","msg":"trace[956398377] transaction","detail":"{read_only:false; response_revision:12392; number_of_response:1; }","duration":"105.949661ms","start":"2026-08-30T01:47:18.724682Z","end":"2026-08-30T01:47:18.830632Z","steps":["trace[956398377] 'process raft request' (duration: 105.627562ms)"],"step_count":1} {"level":"info","ts":"2026-08-30T01:47:34.836913Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10311} {"level":"info","ts":"2026-08-30T01:47:34.928534Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10311,"took":"88.285794ms","hash":3647178065,"current-db-size-bytes":55730176,"current-db-size":"56 MB","current-db-size-in-use-bytes":25595904,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-30T01:47:34.928636Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3647178065,"revision":10311,"compact-revision":7948} {"level":"info","ts":"2026-08-30T01:52:34.84405Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12480} {"level":"info","ts":"2026-08-30T01:52:34.930604Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12480,"took":"83.375436ms","hash":1522358273,"current-db-size-bytes":55730176,"current-db-size":"56 MB","current-db-size-in-use-bytes":27316224,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-08-30T01:52:34.930684Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1522358273,"revision":12480,"compact-revision":10311} {"level":"info","ts":"2026-08-30T01:54:32.398526Z","caller":"traceutil/trace.go:171","msg":"trace[1329814737] transaction","detail":"{read_only:false; response_revision:15220; number_of_response:1; }","duration":"147.409261ms","start":"2026-08-30T01:54:32.251091Z","end":"2026-08-30T01:54:32.3985Z","steps":["trace[1329814737] 'process raft request' (duration: 147.203456ms)"],"step_count":1} {"level":"warn","ts":"2026-08-30T01:54:32.5141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.067015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-30T01:54:32.514217Z","caller":"traceutil/trace.go:171","msg":"trace[884887416] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15220; }","duration":"203.202659ms","start":"2026-08-30T01:54:32.310966Z","end":"2026-08-30T01:54:32.514169Z","steps":["trace[884887416] 'agreement among raft nodes before linearized reading' (duration: 87.510307ms)","trace[884887416] 'range keys from in-memory index tree' (duration: 115.322712ms)"],"step_count":2} {"level":"info","ts":"2026-08-30T01:54:50.939579Z","caller":"traceutil/trace.go:171","msg":"trace[984913754] transaction","detail":"{read_only:false; response_revision:15337; number_of_response:1; }","duration":"130.598438ms","start":"2026-08-30T01:54:50.808945Z","end":"2026-08-30T01:54:50.939543Z","steps":["trace[984913754] 'process raft request' (duration: 109.863994ms)","trace[984913754] 'compare' (duration: 20.23123ms)"],"step_count":2} {"level":"warn","ts":"2026-08-30T01:54:50.939771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.487574ms","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-08-30T01:54:50.939857Z","caller":"traceutil/trace.go:171","msg":"trace[304152519] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15337; }","duration":"125.662099ms","start":"2026-08-30T01:54:50.814175Z","end":"2026-08-30T01:54:50.939837Z","steps":["trace[304152519] 'agreement among raft nodes before linearized reading' (duration: 125.479214ms)"],"step_count":1} {"level":"info","ts":"2026-08-30T01:54:50.939565Z","caller":"traceutil/trace.go:171","msg":"trace[1370026587] linearizableReadLoop","detail":"{readStateIndex:15865; appliedIndex:15864; }","duration":"125.269178ms","start":"2026-08-30T01:54:50.814255Z","end":"2026-08-30T01:54:50.939523Z","steps":["trace[1370026587] 'read index received' (duration: 104.627267ms)","trace[1370026587] 'applied index is now lower than readState.Index' (duration: 20.640391ms)"],"step_count":2} {"level":"warn","ts":"2026-08-30T01:54:50.940103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.302184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-30T01:54:50.940151Z","caller":"traceutil/trace.go:171","msg":"trace[1069950339] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15337; }","duration":"124.375826ms","start":"2026-08-30T01:54:50.815758Z","end":"2026-08-30T01:54:50.940134Z","steps":["trace[1069950339] 'agreement among raft nodes before linearized reading' (duration: 124.263013ms)"],"step_count":1} {"level":"info","ts":"2026-08-30T01:55:03.000307Z","caller":"traceutil/trace.go:171","msg":"trace[1715693778] transaction","detail":"{read_only:false; response_revision:15518; number_of_response:1; }","duration":"176.647857ms","start":"2026-08-30T01:55:02.823628Z","end":"2026-08-30T01:55:03.000276Z","steps":["trace[1715693778] 'process raft request' (duration: 135.098196ms)","trace[1715693778] 'compare' (duration: 41.306334ms)"],"step_count":2} {"level":"info","ts":"2026-08-30T01:55:03.00069Z","caller":"traceutil/trace.go:171","msg":"trace[886957240] transaction","detail":"{read_only:false; response_revision:15519; number_of_response:1; }","duration":"176.067472ms","start":"2026-08-30T01:55:02.824554Z","end":"2026-08-30T01:55:03.000622Z","steps":["trace[886957240] 'process raft request' (duration: 175.670852ms)"],"step_count":1} {"level":"info","ts":"2026-08-30T01:55:03.000759Z","caller":"traceutil/trace.go:171","msg":"trace[626746228] transaction","detail":"{read_only:false; response_revision:15520; number_of_response:1; }","duration":"137.49333ms","start":"2026-08-30T01:55:02.863223Z","end":"2026-08-30T01:55:03.000716Z","steps":["trace[626746228] 'process raft request' (duration: 137.270894ms)"],"step_count":1} {"level":"info","ts":"2026-08-30T01:55:03.210487Z","caller":"traceutil/trace.go:171","msg":"trace[58397789] linearizableReadLoop","detail":"{readStateIndex:16052; appliedIndex:16051; }","duration":"193.261354ms","start":"2026-08-30T01:55:03.0172Z","end":"2026-08-30T01:55:03.210461Z","steps":["trace[58397789] 'read index received' (duration: 133.618668ms)","trace[58397789] 'applied index is now lower than readState.Index' (duration: 59.641095ms)"],"step_count":2} {"level":"info","ts":"2026-08-30T01:55:03.210529Z","caller":"traceutil/trace.go:171","msg":"trace[2026702063] transaction","detail":"{read_only:false; response_revision:15521; number_of_response:1; }","duration":"204.430736ms","start":"2026-08-30T01:55:03.006067Z","end":"2026-08-30T01:55:03.210498Z","steps":["trace[2026702063] 'process raft request' (duration: 144.745519ms)","trace[2026702063] 'compare' (duration: 59.186694ms)"],"step_count":2} {"level":"warn","ts":"2026-08-30T01:55:03.210754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.803669ms","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-08-30T01:55:03.210805Z","caller":"traceutil/trace.go:171","msg":"trace[177026074] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15521; }","duration":"158.908181ms","start":"2026-08-30T01:55:03.051884Z","end":"2026-08-30T01:55:03.210793Z","steps":["trace[177026074] 'agreement among raft nodes before linearized reading' (duration: 158.737717ms)"],"step_count":1} {"level":"warn","ts":"2026-08-30T01:55:03.21083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.588912ms","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-08-30T01:55:03.210925Z","caller":"traceutil/trace.go:171","msg":"trace[676714284] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15521; }","duration":"193.748036ms","start":"2026-08-30T01:55:03.017151Z","end":"2026-08-30T01:55:03.210899Z","steps":["trace[676714284] 'agreement among raft nodes before linearized reading' (duration: 193.425898ms)"],"step_count":1} {"level":"warn","ts":"2026-08-30T01:55:03.21109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.566552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-30T01:55:03.211134Z","caller":"traceutil/trace.go:171","msg":"trace[2100749789] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:15521; }","duration":"137.652703ms","start":"2026-08-30T01:55:03.073471Z","end":"2026-08-30T01:55:03.211123Z","steps":["trace[2100749789] 'agreement among raft nodes before linearized reading' (duration: 137.564831ms)"],"step_count":1} {"level":"info","ts":"2026-08-30T01:55:03.330907Z","caller":"traceutil/trace.go:171","msg":"trace[489004301] transaction","detail":"{read_only:false; response_revision:15522; number_of_response:1; }","duration":"117.105434ms","start":"2026-08-30T01:55:03.213762Z","end":"2026-08-30T01:55:03.330867Z","steps":["trace[489004301] 'process raft request' (duration: 100.477388ms)","trace[489004301] 'compare' (duration: 16.355279ms)"],"step_count":2} {"level":"info","ts":"2026-08-30T01:55:03.332371Z","caller":"traceutil/trace.go:171","msg":"trace[656635469] transaction","detail":"{read_only:false; response_revision:15524; number_of_response:1; }","duration":"115.499222ms","start":"2026-08-30T01:55:03.216846Z","end":"2026-08-30T01:55:03.332345Z","steps":["trace[656635469] 'process raft request' (duration: 115.216885ms)"],"step_count":1} {"level":"info","ts":"2026-08-30T01:55:03.332556Z","caller":"traceutil/trace.go:171","msg":"trace[55781108] transaction","detail":"{read_only:false; response_revision:15525; number_of_response:1; }","duration":"111.390724ms","start":"2026-08-30T01:55:03.221139Z","end":"2026-08-30T01:55:03.33253Z","steps":["trace[55781108] 'process raft request' (duration: 110.980264ms)"],"step_count":1} {"level":"info","ts":"2026-08-30T01:55:03.332633Z","caller":"traceutil/trace.go:171","msg":"trace[2050902409] transaction","detail":"{read_only:false; response_revision:15523; number_of_response:1; }","duration":"116.065597ms","start":"2026-08-30T01:55:03.216548Z","end":"2026-08-30T01:55:03.332614Z","steps":["trace[2050902409] 'process raft request' (duration: 115.334418ms)"],"step_count":1} {"level":"info","ts":"2026-08-30T01:56:41.482296Z","caller":"traceutil/trace.go:171","msg":"trace[1780293834] linearizableReadLoop","detail":"{readStateIndex:17162; appliedIndex:17161; }","duration":"122.043484ms","start":"2026-08-30T01:56:41.360228Z","end":"2026-08-30T01:56:41.482272Z","steps":["trace[1780293834] 'read index received' (duration: 121.782098ms)","trace[1780293834] 'applied index is now lower than readState.Index' (duration: 260.416µs)"],"step_count":2} {"level":"info","ts":"2026-08-30T01:56:41.482385Z","caller":"traceutil/trace.go:171","msg":"trace[872491161] transaction","detail":"{read_only:false; response_revision:16599; number_of_response:1; }","duration":"125.714491ms","start":"2026-08-30T01:56:41.356634Z","end":"2026-08-30T01:56:41.482349Z","steps":["trace[872491161] 'process raft request' (duration: 125.440305ms)"],"step_count":1} {"level":"warn","ts":"2026-08-30T01:56:41.482554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.308691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-08-30T01:56:41.482672Z","caller":"traceutil/trace.go:171","msg":"trace[1078342204] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16599; }","duration":"122.496777ms","start":"2026-08-30T01:56:41.360148Z","end":"2026-08-30T01:56:41.482645Z","steps":["trace[1078342204] 'agreement among raft nodes before linearized reading' (duration: 122.23433ms)"],"step_count":1} {"level":"warn","ts":"2026-08-30T01:56:41.7123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.589541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-08-30T01:56:41.712518Z","caller":"traceutil/trace.go:171","msg":"trace[1549779535] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:16599; }","duration":"127.879989ms","start":"2026-08-30T01:56:41.584605Z","end":"2026-08-30T01:56:41.712485Z","steps":["trace[1549779535] 'range keys from in-memory index tree' (duration: 127.295263ms)"],"step_count":1} {"level":"info","ts":"2026-08-30T01:57:34.855278Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14512} {"level":"info","ts":"2026-08-30T01:57:34.958393Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14512,"took":"98.008751ms","hash":4040257605,"current-db-size-bytes":55730176,"current-db-size":"56 MB","current-db-size-in-use-bytes":30081024,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-30T01:57:34.958497Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4040257605,"revision":14512,"compact-revision":12480} {"level":"info","ts":"2026-08-30T02:02:25.994116Z","caller":"traceutil/trace.go:171","msg":"trace[1903816696] transaction","detail":"{read_only:false; response_revision:19101; number_of_response:1; }","duration":"111.284765ms","start":"2026-08-30T02:02:25.882784Z","end":"2026-08-30T02:02:25.994069Z","steps":["trace[1903816696] 'process raft request' (duration: 111.10856ms)"],"step_count":1} {"level":"info","ts":"2026-08-30T02:02:26.281026Z","caller":"traceutil/trace.go:171","msg":"trace[2062681458] transaction","detail":"{read_only:false; response_revision:19102; number_of_response:1; }","duration":"161.052974ms","start":"2026-08-30T02:02:26.119921Z","end":"2026-08-30T02:02:26.280974Z","steps":["trace[2062681458] 'process raft request' (duration: 160.582461ms)"],"step_count":1} {"level":"info","ts":"2026-08-30T02:02:26.467716Z","caller":"traceutil/trace.go:171","msg":"trace[1774227951] linearizableReadLoop","detail":"{readStateIndex:19748; appliedIndex:19747; }","duration":"177.006381ms","start":"2026-08-30T02:02:26.290671Z","end":"2026-08-30T02:02:26.467677Z","steps":["trace[1774227951] 'read index received' (duration: 165.475849ms)","trace[1774227951] 'applied index is now lower than readState.Index' (duration: 11.529162ms)"],"step_count":2} {"level":"info","ts":"2026-08-30T02:02:26.467813Z","caller":"traceutil/trace.go:171","msg":"trace[687560945] transaction","detail":"{read_only:false; response_revision:19103; number_of_response:1; }","duration":"180.932504ms","start":"2026-08-30T02:02:26.286821Z","end":"2026-08-30T02:02:26.467754Z","steps":["trace[687560945] 'process raft request' (duration: 169.257649ms)","trace[687560945] 'compare' (duration: 11.227903ms)"],"step_count":2} {"level":"warn","ts":"2026-08-30T02:02:26.469134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.438537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:97 size:955731"} {"level":"info","ts":"2026-08-30T02:02:26.469198Z","caller":"traceutil/trace.go:171","msg":"trace[1731406007] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:97; response_revision:19103; }","duration":"178.539869ms","start":"2026-08-30T02:02:26.290645Z","end":"2026-08-30T02:02:26.469185Z","steps":["trace[1731406007] 'agreement among raft nodes before linearized reading' (duration: 177.162984ms)"],"step_count":1} {"level":"info","ts":"2026-08-30T02:02:26.492297Z","caller":"traceutil/trace.go:171","msg":"trace[537450325] transaction","detail":"{read_only:false; response_revision:19104; number_of_response:1; }","duration":"185.779449ms","start":"2026-08-30T02:02:26.306489Z","end":"2026-08-30T02:02:26.492269Z","steps":["trace[537450325] 'process raft request' (duration: 185.154852ms)"],"step_count":1} {"level":"warn","ts":"2026-08-30T02:02:27.018574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.626418ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-30T02:02:27.018724Z","caller":"traceutil/trace.go:171","msg":"trace[1897238644] transaction","detail":"{read_only:false; response_revision:19105; number_of_response:1; }","duration":"398.125181ms","start":"2026-08-30T02:02:26.620575Z","end":"2026-08-30T02:02:27.018701Z","steps":["trace[1897238644] 'process raft request' (duration: 200.245947ms)","trace[1897238644] 'compare' (duration: 196.98237ms)"],"step_count":2} {"level":"warn","ts":"2026-08-30T02:02:27.018814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-30T02:02:26.620542Z","time spent":"398.240564ms","remote":"127.0.0.1:53212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-30T02:02:27.099944Z","caller":"traceutil/trace.go:171","msg":"trace[2032131536] transaction","detail":"{read_only:false; response_revision:19106; number_of_response:1; }","duration":"473.154179ms","start":"2026-08-30T02:02:26.62675Z","end":"2026-08-30T02:02:27.099904Z","steps":["trace[2032131536] 'process raft request' (duration: 472.914093ms)"],"step_count":1} {"level":"warn","ts":"2026-08-30T02:02:27.100158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-30T02:02:26.626698Z","time spent":"473.346885ms","remote":"127.0.0.1:53212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-30T02:02:27.100177Z","caller":"traceutil/trace.go:171","msg":"trace[70090781] transaction","detail":"{read_only:false; response_revision:19107; number_of_response:1; }","duration":"467.570774ms","start":"2026-08-30T02:02:26.632581Z","end":"2026-08-30T02:02:27.100151Z","steps":["trace[70090781] 'process raft request' (duration: 467.273536ms)"],"step_count":1} {"level":"info","ts":"2026-08-30T02:02:27.100229Z","caller":"traceutil/trace.go:171","msg":"trace[1803071139] linearizableReadLoop","detail":"{readStateIndex:19753; appliedIndex:19749; }","duration":"353.381453ms","start":"2026-08-30T02:02:26.746819Z","end":"2026-08-30T02:02:27.1002Z","steps":["trace[1803071139] 'read index received' (duration: 74.017172ms)","trace[1803071139] 'applied index is now lower than readState.Index' (duration: 279.362691ms)"],"step_count":2} {"level":"warn","ts":"2026-08-30T02:02:27.100279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-30T02:02:26.632548Z","time spent":"467.679567ms","remote":"127.0.0.1:53212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-30T02:02:27.100369Z","caller":"traceutil/trace.go:171","msg":"trace[2101119669] transaction","detail":"{read_only:false; response_revision:19108; number_of_response:1; }","duration":"467.0554ms","start":"2026-08-30T02:02:26.633216Z","end":"2026-08-30T02:02:27.100271Z","steps":["trace[2101119669] 'process raft request' (duration: 466.864735ms)"],"step_count":1} {"level":"warn","ts":"2026-08-30T02:02:27.100444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.62524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"info","ts":"2026-08-30T02:02:27.100488Z","caller":"traceutil/trace.go:171","msg":"trace[565306105] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:19108; }","duration":"353.717133ms","start":"2026-08-30T02:02:26.746761Z","end":"2026-08-30T02:02:27.100478Z","steps":["trace[565306105] 'agreement among raft nodes before linearized reading' (duration: 353.572319ms)"],"step_count":1} {"level":"warn","ts":"2026-08-30T02:02:27.100515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.916809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-ks-user\" ","response":"range_response_count:1 size:7382"} {"level":"warn","ts":"2026-08-30T02:02:27.100519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-30T02:02:26.746708Z","time spent":"353.804835ms","remote":"127.0.0.1:53116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":725,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"warn","ts":"2026-08-30T02:02:27.100537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.41008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-5khg5\" ","response":"range_response_count:1 size:9792"} {"level":"warn","ts":"2026-08-30T02:02:27.100538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.623193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-ks-user\" ","response":"range_response_count:1 size:7382"} {"level":"info","ts":"2026-08-30T02:02:27.100587Z","caller":"traceutil/trace.go:171","msg":"trace[1336820695] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-5khg5; range_end:; response_count:1; response_revision:19108; }","duration":"331.499982ms","start":"2026-08-30T02:02:26.769075Z","end":"2026-08-30T02:02:27.100575Z","steps":["trace[1336820695] 'agreement among raft nodes before linearized reading' (duration: 331.299577ms)"],"step_count":1} {"level":"info","ts":"2026-08-30T02:02:27.1006Z","caller":"traceutil/trace.go:171","msg":"trace[36301832] range","detail":"{range_begin:/registry/jobs/openstack/neutron-ks-user; range_end:; response_count:1; response_revision:19108; }","duration":"165.755646ms","start":"2026-08-30T02:02:26.934829Z","end":"2026-08-30T02:02:27.100585Z","steps":["trace[36301832] 'agreement among raft nodes before linearized reading' (duration: 165.600362ms)"],"step_count":1} {"level":"warn","ts":"2026-08-30T02:02:27.10063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-30T02:02:26.769064Z","time spent":"331.558873ms","remote":"127.0.0.1:53128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9814,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-5khg5\" "} {"level":"info","ts":"2026-08-30T02:02:27.100554Z","caller":"traceutil/trace.go:171","msg":"trace[722406121] range","detail":"{range_begin:/registry/jobs/openstack/neutron-ks-user; range_end:; response_count:1; response_revision:19108; }","duration":"163.97517ms","start":"2026-08-30T02:02:26.936569Z","end":"2026-08-30T02:02:27.100545Z","steps":["trace[722406121] 'agreement among raft nodes before linearized reading' (duration: 163.810516ms)"],"step_count":1} {"level":"warn","ts":"2026-08-30T02:02:27.100694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-30T02:02:26.633184Z","time spent":"467.332428ms","remote":"127.0.0.1:53212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-30T02:02:28.583208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.126664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-08-30T02:02:28.583342Z","caller":"traceutil/trace.go:171","msg":"trace[717661698] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:19117; }","duration":"110.289858ms","start":"2026-08-30T02:02:28.473015Z","end":"2026-08-30T02:02:28.583305Z","steps":["trace[717661698] 'range keys from in-memory index tree' (duration: 109.96378ms)"],"step_count":1} {"level":"info","ts":"2026-08-30T02:02:34.865707Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16918} {"level":"info","ts":"2026-08-30T02:02:34.978662Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16918,"took":"108.209234ms","hash":4207336940,"current-db-size-bytes":55730176,"current-db-size":"56 MB","current-db-size-in-use-bytes":31711232,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-30T02:02:34.978759Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4207336940,"revision":16918,"compact-revision":14512} {"level":"warn","ts":"2026-08-30T02:02:37.638752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.47075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1344"} {"level":"info","ts":"2026-08-30T02:02:37.638857Z","caller":"traceutil/trace.go:171","msg":"trace[533580708] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:19266; }","duration":"124.636964ms","start":"2026-08-30T02:02:37.514194Z","end":"2026-08-30T02:02:37.638831Z","steps":["trace[533580708] 'range keys from in-memory index tree' (duration: 124.261374ms)"],"step_count":1} {"level":"info","ts":"2026-08-30T02:02:37.638931Z","caller":"traceutil/trace.go:171","msg":"trace[1882620426] transaction","detail":"{read_only:false; response_revision:19267; number_of_response:1; }","duration":"105.230186ms","start":"2026-08-30T02:02:37.53365Z","end":"2026-08-30T02:02:37.63888Z","steps":["trace[1882620426] 'process raft request' (duration: 88.09745ms)","trace[1882620426] 'compare' (duration: 16.466799ms)"],"step_count":2} {"level":"info","ts":"2026-08-30T02:02:45.886706Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"612d5169b08bb96b","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-30T02:02:45.894694Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-30T02:02:45.894925Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-30T02:07:34.875683Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19171} {"level":"info","ts":"2026-08-30T02:07:34.995033Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19171,"took":"114.930852ms","hash":3502303263,"current-db-size-bytes":55730176,"current-db-size":"56 MB","current-db-size-in-use-bytes":34451456,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-30T02:07:34.995119Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3502303263,"revision":19171,"compact-revision":16918} {"level":"warn","ts":"2026-08-30T02:09:33.141079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.332383ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-30T02:09:33.141304Z","caller":"traceutil/trace.go:171","msg":"trace[2096540048] transaction","detail":"{read_only:false; response_revision:22357; number_of_response:1; }","duration":"289.259743ms","start":"2026-08-30T02:09:32.851962Z","end":"2026-08-30T02:09:33.141222Z","steps":["trace[2096540048] 'process raft request' (duration: 132.664473ms)","trace[2096540048] 'compare' (duration: 156.046555ms)"],"step_count":2} {"level":"info","ts":"2026-08-30T02:09:33.198505Z","caller":"traceutil/trace.go:171","msg":"trace[1869595986] linearizableReadLoop","detail":"{readStateIndex:23113; appliedIndex:23112; }","duration":"152.416181ms","start":"2026-08-30T02:09:33.046054Z","end":"2026-08-30T02:09:33.19847Z","steps":["trace[1869595986] 'read index received' (duration: 152.314719ms)","trace[1869595986] 'applied index is now lower than readState.Index' (duration: 99.982µs)"],"step_count":2} {"level":"info","ts":"2026-08-30T02:09:33.198657Z","caller":"traceutil/trace.go:171","msg":"trace[772852574] transaction","detail":"{read_only:false; response_revision:22358; number_of_response:1; }","duration":"311.156927ms","start":"2026-08-30T02:09:32.887453Z","end":"2026-08-30T02:09:33.19861Z","steps":["trace[772852574] 'process raft request' (duration: 310.846519ms)"],"step_count":1} {"level":"warn","ts":"2026-08-30T02:09:33.198735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.652927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-30T02:09:33.198786Z","caller":"traceutil/trace.go:171","msg":"trace[951932628] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:22358; }","duration":"152.73337ms","start":"2026-08-30T02:09:33.046037Z","end":"2026-08-30T02:09:33.19877Z","steps":["trace[951932628] 'agreement among raft nodes before linearized reading' (duration: 152.611647ms)"],"step_count":1} {"level":"warn","ts":"2026-08-30T02:09:33.198877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-30T02:09:32.887418Z","time spent":"311.328312ms","remote":"127.0.0.1:53212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-30T02:09:33.345085Z","caller":"traceutil/trace.go:171","msg":"trace[1669645425] transaction","detail":"{read_only:false; response_revision:22359; number_of_response:1; }","duration":"260.928981ms","start":"2026-08-30T02:09:33.084117Z","end":"2026-08-30T02:09:33.345046Z","steps":["trace[1669645425] 'process raft request' (duration: 222.365692ms)","trace[1669645425] 'compare' (duration: 38.279843ms)"],"step_count":2} {"level":"info","ts":"2026-08-30T02:09:33.345653Z","caller":"traceutil/trace.go:171","msg":"trace[919178478] linearizableReadLoop","detail":"{readStateIndex:23116; appliedIndex:23113; }","duration":"146.997248ms","start":"2026-08-30T02:09:33.198612Z","end":"2026-08-30T02:09:33.34561Z","steps":["trace[919178478] 'read index received' (duration: 107.889464ms)","trace[919178478] 'applied index is now lower than readState.Index' (duration: 39.106474ms)"],"step_count":2} {"level":"info","ts":"2026-08-30T02:09:33.345762Z","caller":"traceutil/trace.go:171","msg":"trace[52688803] transaction","detail":"{read_only:false; response_revision:22361; number_of_response:1; }","duration":"192.753676ms","start":"2026-08-30T02:09:33.152967Z","end":"2026-08-30T02:09:33.345721Z","steps":["trace[52688803] 'process raft request' (duration: 192.451388ms)"],"step_count":1} {"level":"info","ts":"2026-08-30T02:09:33.345882Z","caller":"traceutil/trace.go:171","msg":"trace[1134533670] transaction","detail":"{read_only:false; response_revision:22360; number_of_response:1; }","duration":"254.257367ms","start":"2026-08-30T02:09:33.091609Z","end":"2026-08-30T02:09:33.345866Z","steps":["trace[1134533670] 'process raft request' (duration: 253.385715ms)"],"step_count":1} {"level":"warn","ts":"2026-08-30T02:09:33.346209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.041169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-08-30T02:09:33.346282Z","caller":"traceutil/trace.go:171","msg":"trace[1935316075] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:22361; }","duration":"239.185913ms","start":"2026-08-30T02:09:33.107078Z","end":"2026-08-30T02:09:33.346264Z","steps":["trace[1935316075] 'agreement among raft nodes before linearized reading' (duration: 238.672039ms)"],"step_count":1} {"level":"warn","ts":"2026-08-30T02:09:33.647802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.457283ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-30T02:09:33.647981Z","caller":"traceutil/trace.go:171","msg":"trace[1345748579] transaction","detail":"{read_only:false; response_revision:22362; number_of_response:1; }","duration":"296.195245ms","start":"2026-08-30T02:09:33.351731Z","end":"2026-08-30T02:09:33.647926Z","steps":["trace[1345748579] 'process raft request' (duration: 125.489295ms)","trace[1345748579] 'compare' (duration: 170.252198ms)"],"step_count":2} {"level":"info","ts":"2026-08-30T02:09:33.648041Z","caller":"traceutil/trace.go:171","msg":"trace[2050410295] linearizableReadLoop","detail":"{readStateIndex:23117; appliedIndex:23116; }","duration":"103.721326ms","start":"2026-08-30T02:09:33.544284Z","end":"2026-08-30T02:09:33.648005Z","steps":["trace[2050410295] 'read index received' (duration: 64.412µs)","trace[2050410295] 'applied index is now lower than readState.Index' (duration: 103.653314ms)"],"step_count":2} {"level":"warn","ts":"2026-08-30T02:09:33.796441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.496308ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-30T02:09:33.796559Z","caller":"traceutil/trace.go:171","msg":"trace[1949121709] linearizableReadLoop","detail":"{readStateIndex:23118; appliedIndex:23117; }","duration":"146.156977ms","start":"2026-08-30T02:09:33.65038Z","end":"2026-08-30T02:09:33.796537Z","steps":["trace[1949121709] 'read index received' (duration: 51.491µs)","trace[1949121709] 'applied index is now lower than readState.Index' (duration: 146.103796ms)"],"step_count":2} {"level":"warn","ts":"2026-08-30T02:09:33.796646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.26535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-30T02:09:33.79668Z","caller":"traceutil/trace.go:171","msg":"trace[910403743] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22362; }","duration":"146.350903ms","start":"2026-08-30T02:09:33.650319Z","end":"2026-08-30T02:09:33.79667Z","steps":["trace[910403743] 'agreement among raft nodes before linearized reading' (duration: 146.274831ms)"],"step_count":1} {"level":"warn","ts":"2026-08-30T02:09:33.79684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.499061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-30T02:09:33.796961Z","caller":"traceutil/trace.go:171","msg":"trace[361168804] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:22362; }","duration":"252.708008ms","start":"2026-08-30T02:09:33.544222Z","end":"2026-08-30T02:09:33.79693Z","steps":["trace[361168804] 'agreement among raft nodes before linearized reading' (duration: 103.903911ms)","trace[361168804] 'count revisions from in-memory index tree' (duration: 148.610412ms)"],"step_count":2} {"level":"info","ts":"2026-08-30T02:09:34.148616Z","caller":"traceutil/trace.go:171","msg":"trace[772170037] transaction","detail":"{read_only:false; response_revision:22363; number_of_response:1; }","duration":"118.919913ms","start":"2026-08-30T02:09:34.029664Z","end":"2026-08-30T02:09:34.148584Z","steps":["trace[772170037] 'process raft request' (duration: 118.680758ms)"],"step_count":1} {"level":"info","ts":"2026-08-30T02:09:34.346223Z","caller":"traceutil/trace.go:171","msg":"trace[185843817] linearizableReadLoop","detail":"{readStateIndex:23120; appliedIndex:23119; }","duration":"158.216734ms","start":"2026-08-30T02:09:34.187971Z","end":"2026-08-30T02:09:34.346188Z","steps":["trace[185843817] 'read index received' (duration: 144.32086ms)","trace[185843817] 'applied index is now lower than readState.Index' (duration: 13.893934ms)"],"step_count":2} {"level":"info","ts":"2026-08-30T02:09:34.346631Z","caller":"traceutil/trace.go:171","msg":"trace[1077789415] transaction","detail":"{read_only:false; response_revision:22364; number_of_response:1; }","duration":"158.683806ms","start":"2026-08-30T02:09:34.187932Z","end":"2026-08-30T02:09:34.346616Z","steps":["trace[1077789415] 'process raft request' (duration: 144.34999ms)","trace[1077789415] 'compare' (duration: 13.577396ms)"],"step_count":2} {"level":"warn","ts":"2026-08-30T02:09:34.346748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.561805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-30T02:09:34.346787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.774658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-30T02:09:34.346841Z","caller":"traceutil/trace.go:171","msg":"trace[625459877] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:22364; }","duration":"118.707698ms","start":"2026-08-30T02:09:34.22811Z","end":"2026-08-30T02:09:34.346818Z","steps":["trace[625459877] 'agreement among raft nodes before linearized reading' (duration: 118.538344ms)"],"step_count":1} {"level":"info","ts":"2026-08-30T02:09:34.346882Z","caller":"traceutil/trace.go:171","msg":"trace[294472196] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:22364; }","duration":"158.918461ms","start":"2026-08-30T02:09:34.187936Z","end":"2026-08-30T02:09:34.346855Z","steps":["trace[294472196] 'agreement among raft nodes before linearized reading' (duration: 158.732286ms)"],"step_count":1} {"level":"warn","ts":"2026-08-30T02:09:34.347093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.083695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-30T02:09:34.347191Z","caller":"traceutil/trace.go:171","msg":"trace[1023831980] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:22364; }","duration":"112.217359ms","start":"2026-08-30T02:09:34.234953Z","end":"2026-08-30T02:09:34.347171Z","steps":["trace[1023831980] 'agreement among raft nodes before linearized reading' (duration: 111.729806ms)"],"step_count":1} {"level":"info","ts":"2026-08-30T02:09:34.6831Z","caller":"traceutil/trace.go:171","msg":"trace[1694201783] transaction","detail":"{read_only:false; response_revision:22366; number_of_response:1; }","duration":"112.744312ms","start":"2026-08-30T02:09:34.570321Z","end":"2026-08-30T02:09:34.683065Z","steps":["trace[1694201783] 'process raft request' (duration: 112.137666ms)"],"step_count":1} {"level":"warn","ts":"2026-08-30T02:09:34.93127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.129756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-08-30T02:09:34.931462Z","caller":"traceutil/trace.go:171","msg":"trace[1553801699] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:22366; }","duration":"112.66652ms","start":"2026-08-30T02:09:34.818772Z","end":"2026-08-30T02:09:34.931439Z","steps":["trace[1553801699] 'range keys from in-memory index tree' (duration: 111.853329ms)"],"step_count":1} {"level":"info","ts":"2026-08-30T02:12:34.882447Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21612} {"level":"info","ts":"2026-08-30T02:12:35.010454Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21612,"took":"123.562279ms","hash":2666464941,"current-db-size-bytes":55730176,"current-db-size":"56 MB","current-db-size-in-use-bytes":30474240,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-30T02:12:35.010553Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2666464941,"revision":21612,"compact-revision":19171} {"level":"info","ts":"2026-08-30T02:12:54.489565Z","caller":"traceutil/trace.go:171","msg":"trace[1708240216] transaction","detail":"{read_only:false; response_revision:23904; number_of_response:1; }","duration":"105.960068ms","start":"2026-08-30T02:12:54.383571Z","end":"2026-08-30T02:12:54.489531Z","steps":["trace[1708240216] 'process raft request' (duration: 100.434283ms)"],"step_count":1} {"level":"info","ts":"2026-08-30T02:12:54.494019Z","caller":"traceutil/trace.go:171","msg":"trace[351014095] transaction","detail":"{read_only:false; number_of_response:1; response_revision:23904; }","duration":"107.517839ms","start":"2026-08-30T02:12:54.386486Z","end":"2026-08-30T02:12:54.494004Z","steps":["trace[351014095] 'process raft request' (duration: 107.379175ms)"],"step_count":1} {"level":"info","ts":"2026-08-30T02:15:18.201532Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000686c.wal"} {"level":"info","ts":"2026-08-30T02:17:34.890245Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23743} {"level":"info","ts":"2026-08-30T02:17:35.024033Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23743,"took":"128.14434ms","hash":2207333943,"current-db-size-bytes":56995840,"current-db-size":"57 MB","current-db-size-in-use-bytes":42971136,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-08-30T02:17:35.024132Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2207333943,"revision":23743,"compact-revision":21612} {"level":"info","ts":"2026-08-30T02:22:26.516942Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"612d5169b08bb96b","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-30T02:22:26.522782Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-08-30T02:22:26.522876Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-08-30T02:22:34.898641Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26889} {"level":"info","ts":"2026-08-30T02:22:35.063422Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26889,"took":"159.132883ms","hash":1897331628,"current-db-size-bytes":56995840,"current-db-size":"57 MB","current-db-size-in-use-bytes":30302208,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-30T02:22:35.063515Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1897331628,"revision":26889,"compact-revision":23743}