{"level":"warn","ts":"2026-03-06T04:54:18.821442Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-06T04:54:18.821591Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.207: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.207:2380","--initial-cluster=instance=https://162.253.55.207:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.207:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.207: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-03-06T04:54:18.821691Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-06T04:54:18.821708Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.207:2380"]} {"level":"info","ts":"2026-03-06T04:54:18.821944Z","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-03-06T04:54:18.823811Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.207:2379"]} {"level":"info","ts":"2026-03-06T04:54:18.823895Z","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.207:2380"],"listen-peer-urls":["https://162.253.55.207:2380"],"advertise-client-urls":["https://162.253.55.207:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.207:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.207: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-03-06T04:54:18.830075Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.999504ms"} {"level":"info","ts":"2026-03-06T04:54:18.839131Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6f1fcbe50562a63a","cluster-id":"a9b9f04f4e8a9fba"} {"level":"info","ts":"2026-03-06T04:54:18.839223Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a switched to configuration voters=()"} {"level":"info","ts":"2026-03-06T04:54:18.83925Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became follower at term 0"} {"level":"info","ts":"2026-03-06T04:54:18.839805Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6f1fcbe50562a63a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-06T04:54:18.839831Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became follower at term 1"} {"level":"info","ts":"2026-03-06T04:54:18.840072Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a switched to configuration voters=(8007342846986331706)"} {"level":"warn","ts":"2026-03-06T04:54:18.854222Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-06T04:54:18.856383Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-06T04:54:18.85784Z","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-03-06T04:54:18.861477Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6f1fcbe50562a63a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-06T04:54:18.86167Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6f1fcbe50562a63a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-06T04:54:18.861796Z","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-03-06T04:54:18.861907Z","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-03-06T04:54:18.861936Z","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-03-06T04:54:18.862584Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a switched to configuration voters=(8007342846986331706)"} {"level":"info","ts":"2026-03-06T04:54:18.863271Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"a9b9f04f4e8a9fba","local-member-id":"6f1fcbe50562a63a","added-peer-id":"6f1fcbe50562a63a","added-peer-peer-urls":["https://162.253.55.207:2380"]} {"level":"info","ts":"2026-03-06T04:54:18.865587Z","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-03-06T04:54:18.865687Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.207:2380"} {"level":"info","ts":"2026-03-06T04:54:18.865795Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.207:2380"} {"level":"info","ts":"2026-03-06T04:54:18.865995Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6f1fcbe50562a63a","initial-advertise-peer-urls":["https://162.253.55.207:2380"],"listen-peer-urls":["https://162.253.55.207:2380"],"advertise-client-urls":["https://162.253.55.207:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.207:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-06T04:54:18.866032Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-06T04:54:19.041303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a is starting a new election at term 1"} {"level":"info","ts":"2026-03-06T04:54:19.041384Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became pre-candidate at term 1"} {"level":"info","ts":"2026-03-06T04:54:19.041415Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a received MsgPreVoteResp from 6f1fcbe50562a63a at term 1"} {"level":"info","ts":"2026-03-06T04:54:19.041438Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became candidate at term 2"} {"level":"info","ts":"2026-03-06T04:54:19.041451Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a received MsgVoteResp from 6f1fcbe50562a63a at term 2"} {"level":"info","ts":"2026-03-06T04:54:19.041468Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became leader at term 2"} {"level":"info","ts":"2026-03-06T04:54:19.041483Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6f1fcbe50562a63a elected leader 6f1fcbe50562a63a at term 2"} {"level":"info","ts":"2026-03-06T04:54:19.043962Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6f1fcbe50562a63a","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.207:2379]}","request-path":"/0/members/6f1fcbe50562a63a/attributes","cluster-id":"a9b9f04f4e8a9fba","publish-timeout":"7s"} {"level":"info","ts":"2026-03-06T04:54:19.043956Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-06T04:54:19.04396Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-06T04:54:19.044009Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-06T04:54:19.044391Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-06T04:54:19.044542Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-06T04:54:19.045289Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"a9b9f04f4e8a9fba","local-member-id":"6f1fcbe50562a63a","cluster-version":"3.5"} {"level":"info","ts":"2026-03-06T04:54:19.04552Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-06T04:54:19.045569Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-06T04:54:19.046316Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.207:2379"} {"level":"info","ts":"2026-03-06T04:54:19.048502Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-06T04:54:36.224018Z","caller":"traceutil/trace.go:171","msg":"trace[2109970220] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"278.507027ms","start":"2026-03-06T04:54:35.945466Z","end":"2026-03-06T04:54:36.223973Z","steps":["trace[2109970220] 'process raft request' (duration: 278.310623ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T04:54:54.286945Z","caller":"traceutil/trace.go:171","msg":"trace[475655246] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"181.441162ms","start":"2026-03-06T04:54:54.105481Z","end":"2026-03-06T04:54:54.286922Z","steps":["trace[475655246] 'process raft request' (duration: 181.290118ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T04:54:54.660265Z","caller":"traceutil/trace.go:171","msg":"trace[1692249769] linearizableReadLoop","detail":"{readStateIndex:531; appliedIndex:530; }","duration":"109.463034ms","start":"2026-03-06T04:54:54.550784Z","end":"2026-03-06T04:54:54.660247Z","steps":["trace[1692249769] 'read index received' (duration: 84.422205ms)","trace[1692249769] 'applied index is now lower than readState.Index' (duration: 25.040029ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T04:54:54.660383Z","caller":"traceutil/trace.go:171","msg":"trace[1611295123] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"336.029412ms","start":"2026-03-06T04:54:54.324305Z","end":"2026-03-06T04:54:54.660335Z","steps":["trace[1611295123] 'process raft request' (duration: 310.914811ms)","trace[1611295123] 'compare' (duration: 24.846695ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T04:54:54.660717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.564266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T04:54:54.660808Z","caller":"traceutil/trace.go:171","msg":"trace[1210848105] range","detail":"{range_begin:/registry/secrets/cert-manager/; range_end:/registry/secrets/cert-manager0; response_count:0; response_revision:512; }","duration":"110.100988ms","start":"2026-03-06T04:54:54.550695Z","end":"2026-03-06T04:54:54.660796Z","steps":["trace[1210848105] 'agreement among raft nodes before linearized reading' (duration: 109.616497ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T04:54:54.662035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T04:54:54.324288Z","time spent":"336.184976ms","remote":"127.0.0.1:37186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T04:54:55.052017Z","caller":"traceutil/trace.go:171","msg":"trace[2092263490] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"303.953477ms","start":"2026-03-06T04:54:54.748032Z","end":"2026-03-06T04:54:55.051985Z","steps":["trace[2092263490] 'process raft request' (duration: 283.827198ms)","trace[2092263490] 'compare' (duration: 19.875783ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T04:54:55.052189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T04:54:54.747992Z","time spent":"304.09824ms","remote":"127.0.0.1:37186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T04:54:56.407434Z","caller":"traceutil/trace.go:171","msg":"trace[193307541] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"168.612435ms","start":"2026-03-06T04:54:56.238788Z","end":"2026-03-06T04:54:56.4074Z","steps":["trace[193307541] 'process raft request' (duration: 162.131369ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T04:54:56.407884Z","caller":"traceutil/trace.go:171","msg":"trace[2146899379] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"168.594584ms","start":"2026-03-06T04:54:56.239276Z","end":"2026-03-06T04:54:56.407871Z","steps":["trace[2146899379] 'process raft request' (duration: 168.140474ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T04:54:56.408504Z","caller":"traceutil/trace.go:171","msg":"trace[1399017072] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"158.694832ms","start":"2026-03-06T04:54:56.249781Z","end":"2026-03-06T04:54:56.408476Z","steps":["trace[1399017072] 'process raft request' (duration: 158.189481ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T04:55:13.808345Z","caller":"traceutil/trace.go:171","msg":"trace[1386444376] linearizableReadLoop","detail":"{readStateIndex:911; appliedIndex:910; }","duration":"145.989347ms","start":"2026-03-06T04:55:13.662332Z","end":"2026-03-06T04:55:13.808321Z","steps":["trace[1386444376] 'read index received' (duration: 145.812593ms)","trace[1386444376] 'applied index is now lower than readState.Index' (duration: 175.694µs)"],"step_count":2} {"level":"info","ts":"2026-03-06T04:55:13.808462Z","caller":"traceutil/trace.go:171","msg":"trace[75396661] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"209.225982ms","start":"2026-03-06T04:55:13.599203Z","end":"2026-03-06T04:55:13.808429Z","steps":["trace[75396661] 'process raft request' (duration: 208.983436ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T04:55:13.808508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.162241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T04:55:13.808564Z","caller":"traceutil/trace.go:171","msg":"trace[1896845950] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:886; }","duration":"146.258163ms","start":"2026-03-06T04:55:13.662277Z","end":"2026-03-06T04:55:13.808535Z","steps":["trace[1896845950] 'agreement among raft nodes before linearized reading' (duration: 146.14921ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T04:55:39.0368Z","caller":"traceutil/trace.go:171","msg":"trace[1171386065] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"142.744815ms","start":"2026-03-06T04:55:38.894021Z","end":"2026-03-06T04:55:39.036766Z","steps":["trace[1171386065] 'process raft request' (duration: 142.562121ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T04:56:08.354279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.056388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-06T04:56:08.354381Z","caller":"traceutil/trace.go:171","msg":"trace[1284887645] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1575; }","duration":"150.227231ms","start":"2026-03-06T04:56:08.204131Z","end":"2026-03-06T04:56:08.354359Z","steps":["trace[1284887645] 'count revisions from in-memory index tree' (duration: 149.907183ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T04:56:08.354574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.140501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-06T04:56:08.354608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.304487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-06T04:56:08.354714Z","caller":"traceutil/trace.go:171","msg":"trace[1555203471] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1575; }","duration":"193.485622ms","start":"2026-03-06T04:56:08.161205Z","end":"2026-03-06T04:56:08.354691Z","steps":["trace[1555203471] 'range keys from in-memory index tree' (duration: 193.041021ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T04:56:08.354707Z","caller":"traceutil/trace.go:171","msg":"trace[1947083261] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1575; }","duration":"208.360205ms","start":"2026-03-06T04:56:08.14632Z","end":"2026-03-06T04:56:08.35468Z","steps":["trace[1947083261] 'count revisions from in-memory index tree' (duration: 207.961037ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T04:56:08.354799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.769673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9344a862-dbea-4749-a3d1-afce1cc09e78\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-03-06T04:56:08.355462Z","caller":"traceutil/trace.go:171","msg":"trace[36995590] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9344a862-dbea-4749-a3d1-afce1cc09e78; range_end:; response_count:1; response_revision:1575; }","duration":"274.5144ms","start":"2026-03-06T04:56:08.080915Z","end":"2026-03-06T04:56:08.355429Z","steps":["trace[36995590] 'range keys from in-memory index tree' (duration: 273.366065ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T04:56:08.560636Z","caller":"traceutil/trace.go:171","msg":"trace[1592073824] transaction","detail":"{read_only:false; response_revision:1576; number_of_response:1; }","duration":"200.320774ms","start":"2026-03-06T04:56:08.360264Z","end":"2026-03-06T04:56:08.560585Z","steps":["trace[1592073824] 'process raft request' (duration: 200.115009ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T04:56:22.00609Z","caller":"traceutil/trace.go:171","msg":"trace[1952417363] linearizableReadLoop","detail":"{readStateIndex:1742; appliedIndex:1741; }","duration":"172.168384ms","start":"2026-03-06T04:56:21.833898Z","end":"2026-03-06T04:56:22.006066Z","steps":["trace[1952417363] 'read index received' (duration: 171.98034ms)","trace[1952417363] 'applied index is now lower than readState.Index' (duration: 183.924µs)"],"step_count":2} {"level":"info","ts":"2026-03-06T04:56:22.00618Z","caller":"traceutil/trace.go:171","msg":"trace[1854357895] transaction","detail":"{read_only:false; response_revision:1677; number_of_response:1; }","duration":"175.497048ms","start":"2026-03-06T04:56:21.830646Z","end":"2026-03-06T04:56:22.006144Z","steps":["trace[1854357895] 'process raft request' (duration: 175.242333ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T04:56:22.006323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.383928ms","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-03-06T04:56:22.006598Z","caller":"traceutil/trace.go:171","msg":"trace[386780084] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:1677; }","duration":"172.675845ms","start":"2026-03-06T04:56:21.83387Z","end":"2026-03-06T04:56:22.006546Z","steps":["trace[386780084] 'agreement among raft nodes before linearized reading' (duration: 172.348468ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T04:56:22.006444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.9129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-03-06T04:56:22.006702Z","caller":"traceutil/trace.go:171","msg":"trace[1066789892] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1677; }","duration":"169.293158ms","start":"2026-03-06T04:56:21.837389Z","end":"2026-03-06T04:56:22.006682Z","steps":["trace[1066789892] 'agreement among raft nodes before linearized reading' (duration: 168.845728ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T04:56:25.624251Z","caller":"traceutil/trace.go:171","msg":"trace[106503628] transaction","detail":"{read_only:false; response_revision:1704; number_of_response:1; }","duration":"309.721949ms","start":"2026-03-06T04:56:25.314501Z","end":"2026-03-06T04:56:25.624223Z","steps":["trace[106503628] 'process raft request' (duration: 309.552656ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T04:56:25.624427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T04:56:25.314459Z","time spent":"309.877593ms","remote":"127.0.0.1:37186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T04:56:58.94895Z","caller":"traceutil/trace.go:171","msg":"trace[1279564984] transaction","detail":"{read_only:false; response_revision:1873; number_of_response:1; }","duration":"131.083741ms","start":"2026-03-06T04:56:58.817831Z","end":"2026-03-06T04:56:58.948915Z","steps":["trace[1279564984] 'process raft request' (duration: 130.762784ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T04:57:05.338639Z","caller":"traceutil/trace.go:171","msg":"trace[1926983060] transaction","detail":"{read_only:false; response_revision:1902; number_of_response:1; }","duration":"313.671628ms","start":"2026-03-06T04:57:05.024931Z","end":"2026-03-06T04:57:05.338603Z","steps":["trace[1926983060] 'process raft request' (duration: 313.465774ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T04:57:05.338833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T04:57:05.024902Z","time spent":"313.830932ms","remote":"127.0.0.1:37186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T04:57:05.731242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.229765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-03-06T04:57:05.731384Z","caller":"traceutil/trace.go:171","msg":"trace[621814162] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1902; }","duration":"349.45398ms","start":"2026-03-06T04:57:05.381898Z","end":"2026-03-06T04:57:05.731352Z","steps":["trace[621814162] 'range keys from in-memory index tree' (duration: 348.908439ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T04:57:05.731445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T04:57:05.381879Z","time spent":"349.552043ms","remote":"127.0.0.1:52882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-03-06T04:57:38.403209Z","caller":"traceutil/trace.go:171","msg":"trace[2062155931] transaction","detail":"{read_only:false; response_revision:2244; number_of_response:1; }","duration":"118.635575ms","start":"2026-03-06T04:57:38.284548Z","end":"2026-03-06T04:57:38.403183Z","steps":["trace[2062155931] 'process raft request' (duration: 118.455221ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T04:58:12.710062Z","caller":"traceutil/trace.go:171","msg":"trace[222666153] transaction","detail":"{read_only:false; response_revision:2434; number_of_response:1; }","duration":"125.925939ms","start":"2026-03-06T04:58:12.584103Z","end":"2026-03-06T04:58:12.710029Z","steps":["trace[222666153] 'process raft request' (duration: 125.433328ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T04:58:24.257536Z","caller":"traceutil/trace.go:171","msg":"trace[258606427] transaction","detail":"{read_only:false; response_revision:2490; number_of_response:1; }","duration":"154.436849ms","start":"2026-03-06T04:58:24.103031Z","end":"2026-03-06T04:58:24.257467Z","steps":["trace[258606427] 'process raft request' (duration: 101.064391ms)","trace[258606427] 'compare' (duration: 53.098952ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T05:00:39.411492Z","caller":"traceutil/trace.go:171","msg":"trace[458325548] transaction","detail":"{read_only:false; response_revision:3333; number_of_response:1; }","duration":"161.645358ms","start":"2026-03-06T05:00:39.24982Z","end":"2026-03-06T05:00:39.411465Z","steps":["trace[458325548] 'process raft request' (duration: 161.464704ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T05:01:59.532976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.530497ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-06T05:01:59.533189Z","caller":"traceutil/trace.go:171","msg":"trace[638603092] transaction","detail":"{read_only:false; response_revision:4170; number_of_response:1; }","duration":"112.293413ms","start":"2026-03-06T05:01:59.420865Z","end":"2026-03-06T05:01:59.533159Z","steps":["trace[638603092] 'compare' (duration: 104.288371ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T05:02:03.551495Z","caller":"traceutil/trace.go:171","msg":"trace[1394036003] transaction","detail":"{read_only:false; response_revision:4284; number_of_response:1; }","duration":"140.617614ms","start":"2026-03-06T05:02:03.410836Z","end":"2026-03-06T05:02:03.551453Z","steps":["trace[1394036003] 'process raft request' (duration: 140.306026ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T05:02:03.801953Z","caller":"traceutil/trace.go:171","msg":"trace[1346463076] linearizableReadLoop","detail":"{readStateIndex:4464; appliedIndex:4462; }","duration":"139.358796ms","start":"2026-03-06T05:02:03.66255Z","end":"2026-03-06T05:02:03.801909Z","steps":["trace[1346463076] 'read index received' (duration: 128.94484ms)","trace[1346463076] 'applied index is now lower than readState.Index' (duration: 10.412796ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T05:02:03.802121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.55954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T05:02:03.80216Z","caller":"traceutil/trace.go:171","msg":"trace[777160424] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4291; }","duration":"139.620582ms","start":"2026-03-06T05:02:03.66253Z","end":"2026-03-06T05:02:03.802151Z","steps":["trace[777160424] 'agreement among raft nodes before linearized reading' (duration: 139.509999ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T05:02:03.802384Z","caller":"traceutil/trace.go:171","msg":"trace[1398859547] transaction","detail":"{read_only:false; response_revision:4291; number_of_response:1; }","duration":"181.023719ms","start":"2026-03-06T05:02:03.62133Z","end":"2026-03-06T05:02:03.802354Z","steps":["trace[1398859547] 'process raft request' (duration: 180.507158ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T05:02:03.802396Z","caller":"traceutil/trace.go:171","msg":"trace[1783366881] transaction","detail":"{read_only:false; response_revision:4290; number_of_response:1; }","duration":"191.815663ms","start":"2026-03-06T05:02:03.61055Z","end":"2026-03-06T05:02:03.802365Z","steps":["trace[1783366881] 'process raft request' (duration: 180.955956ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T05:02:03.804847Z","caller":"traceutil/trace.go:171","msg":"trace[225678262] transaction","detail":"{read_only:false; response_revision:4292; number_of_response:1; }","duration":"123.287571ms","start":"2026-03-06T05:02:03.681544Z","end":"2026-03-06T05:02:03.804832Z","steps":["trace[225678262] 'process raft request' (duration: 122.899733ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T05:02:05.473551Z","caller":"traceutil/trace.go:171","msg":"trace[932081005] transaction","detail":"{read_only:false; response_revision:4342; number_of_response:1; }","duration":"165.047897ms","start":"2026-03-06T05:02:05.308471Z","end":"2026-03-06T05:02:05.473518Z","steps":["trace[932081005] 'process raft request' (duration: 164.820752ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T05:02:05.581748Z","caller":"traceutil/trace.go:171","msg":"trace[586010830] linearizableReadLoop","detail":"{readStateIndex:4519; appliedIndex:4517; }","duration":"225.208199ms","start":"2026-03-06T05:02:05.356516Z","end":"2026-03-06T05:02:05.581724Z","steps":["trace[586010830] 'read index received' (duration: 116.800405ms)","trace[586010830] 'applied index is now lower than readState.Index' (duration: 108.406554ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T05:02:05.581915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.384602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T05:02:05.58196Z","caller":"traceutil/trace.go:171","msg":"trace[1586254280] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:4343; }","duration":"225.491866ms","start":"2026-03-06T05:02:05.356447Z","end":"2026-03-06T05:02:05.581939Z","steps":["trace[1586254280] 'agreement among raft nodes before linearized reading' (duration: 225.379683ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T05:02:05.581944Z","caller":"traceutil/trace.go:171","msg":"trace[577293052] transaction","detail":"{read_only:false; response_revision:4343; number_of_response:1; }","duration":"260.729784ms","start":"2026-03-06T05:02:05.321174Z","end":"2026-03-06T05:02:05.581904Z","steps":["trace[577293052] 'process raft request' (duration: 260.204382ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T05:02:07.617914Z","caller":"traceutil/trace.go:171","msg":"trace[184691480] linearizableReadLoop","detail":"{readStateIndex:4648; appliedIndex:4647; }","duration":"186.471112ms","start":"2026-03-06T05:02:07.431421Z","end":"2026-03-06T05:02:07.617892Z","steps":["trace[184691480] 'read index received' (duration: 186.285428ms)","trace[184691480] 'applied index is now lower than readState.Index' (duration: 184.594µs)"],"step_count":2} {"level":"info","ts":"2026-03-06T05:02:07.618013Z","caller":"traceutil/trace.go:171","msg":"trace[1388927041] transaction","detail":"{read_only:false; response_revision:4469; number_of_response:1; }","duration":"208.276026ms","start":"2026-03-06T05:02:07.409709Z","end":"2026-03-06T05:02:07.617985Z","steps":["trace[1388927041] 'process raft request' (duration: 208.034231ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T05:02:07.618277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.84315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:413082"} {"level":"info","ts":"2026-03-06T05:02:07.618318Z","caller":"traceutil/trace.go:171","msg":"trace[422426334] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:4469; }","duration":"186.925612ms","start":"2026-03-06T05:02:07.431378Z","end":"2026-03-06T05:02:07.618304Z","steps":["trace[422426334] 'agreement among raft nodes before linearized reading' (duration: 186.660056ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T05:02:08.093408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.151522ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-06T05:02:08.093698Z","caller":"traceutil/trace.go:171","msg":"trace[1390923160] linearizableReadLoop","detail":"{readStateIndex:4651; appliedIndex:4648; }","duration":"462.679606ms","start":"2026-03-06T05:02:07.630998Z","end":"2026-03-06T05:02:08.093677Z","steps":["trace[1390923160] 'read index received' (duration: 96.28762ms)","trace[1390923160] 'applied index is now lower than readState.Index' (duration: 366.390836ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T05:02:08.093711Z","caller":"traceutil/trace.go:171","msg":"trace[363683599] transaction","detail":"{read_only:false; response_revision:4470; number_of_response:1; }","duration":"468.310084ms","start":"2026-03-06T05:02:07.625365Z","end":"2026-03-06T05:02:08.093675Z","steps":["trace[363683599] 'process raft request' (duration: 101.772555ms)","trace[363683599] 'compare' (duration: 365.887335ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T05:02:08.093783Z","caller":"traceutil/trace.go:171","msg":"trace[287690024] transaction","detail":"{read_only:false; response_revision:4472; number_of_response:1; }","duration":"465.349797ms","start":"2026-03-06T05:02:07.628423Z","end":"2026-03-06T05:02:08.093773Z","steps":["trace[287690024] 'process raft request' (duration: 465.208893ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T05:02:08.093865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T05:02:07.628416Z","time spent":"465.407869ms","remote":"127.0.0.1:37186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T05:02:08.09387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T05:02:07.625353Z","time spent":"468.430417ms","remote":"127.0.0.1:37186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T05:02:08.094052Z","caller":"traceutil/trace.go:171","msg":"trace[617892717] transaction","detail":"{read_only:false; response_revision:4471; number_of_response:1; }","duration":"466.80182ms","start":"2026-03-06T05:02:07.626955Z","end":"2026-03-06T05:02:08.093757Z","steps":["trace[617892717] 'process raft request' (duration: 466.577865ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T05:02:08.094191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T05:02:07.626943Z","time spent":"467.173478ms","remote":"127.0.0.1:37186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T05:02:08.09448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.470624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:719280"} {"level":"info","ts":"2026-03-06T05:02:08.094557Z","caller":"traceutil/trace.go:171","msg":"trace[1801859484] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:4472; }","duration":"463.561536ms","start":"2026-03-06T05:02:07.630977Z","end":"2026-03-06T05:02:08.094539Z","steps":["trace[1801859484] 'agreement among raft nodes before linearized reading' (duration: 462.920391ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T05:02:08.094614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T05:02:07.63097Z","time spent":"463.629628ms","remote":"127.0.0.1:37006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":24,"response size":719303,"request content":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" "} {"level":"info","ts":"2026-03-06T05:02:08.095959Z","caller":"traceutil/trace.go:171","msg":"trace[1521984741] transaction","detail":"{read_only:false; response_revision:4473; number_of_response:1; }","duration":"280.836719ms","start":"2026-03-06T05:02:07.815095Z","end":"2026-03-06T05:02:08.095932Z","steps":["trace[1521984741] 'process raft request' (duration: 280.278586ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T05:02:08.096237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.481196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T05:02:08.09639Z","caller":"traceutil/trace.go:171","msg":"trace[828854123] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4474; }","duration":"433.661139ms","start":"2026-03-06T05:02:07.662698Z","end":"2026-03-06T05:02:08.096359Z","steps":["trace[828854123] 'agreement among raft nodes before linearized reading' (duration: 433.434184ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T05:02:08.096386Z","caller":"traceutil/trace.go:171","msg":"trace[200183849] transaction","detail":"{read_only:false; response_revision:4474; number_of_response:1; }","duration":"129.700697ms","start":"2026-03-06T05:02:07.96663Z","end":"2026-03-06T05:02:08.096331Z","steps":["trace[200183849] 'process raft request' (duration: 129.025162ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T05:02:08.096457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T05:02:07.662686Z","time spent":"433.761362ms","remote":"127.0.0.1:36942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-06T05:02:08.096497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.048954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T05:02:08.096621Z","caller":"traceutil/trace.go:171","msg":"trace[1408475559] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:4474; }","duration":"183.27772ms","start":"2026-03-06T05:02:07.913318Z","end":"2026-03-06T05:02:08.096595Z","steps":["trace[1408475559] 'agreement among raft nodes before linearized reading' (duration: 183.023914ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T05:02:19.196881Z","caller":"traceutil/trace.go:171","msg":"trace[659199321] transaction","detail":"{read_only:false; response_revision:4685; number_of_response:1; }","duration":"135.842846ms","start":"2026-03-06T05:02:19.060986Z","end":"2026-03-06T05:02:19.196829Z","steps":["trace[659199321] 'process raft request' (duration: 127.611529ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T05:02:27.321858Z","caller":"traceutil/trace.go:171","msg":"trace[605238560] transaction","detail":"{read_only:false; response_revision:4856; number_of_response:1; }","duration":"264.329606ms","start":"2026-03-06T05:02:27.057501Z","end":"2026-03-06T05:02:27.32183Z","steps":["trace[605238560] 'process raft request' (duration: 264.091511ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T05:02:27.571025Z","caller":"traceutil/trace.go:171","msg":"trace[624258391] transaction","detail":"{read_only:false; response_revision:4857; number_of_response:1; }","duration":"478.091066ms","start":"2026-03-06T05:02:27.092902Z","end":"2026-03-06T05:02:27.570993Z","steps":["trace[624258391] 'process raft request' (duration: 442.233804ms)","trace[624258391] 'compare' (duration: 35.648717ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T05:02:27.571215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T05:02:27.09288Z","time spent":"478.236759ms","remote":"127.0.0.1:37186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T05:02:27.571215Z","caller":"traceutil/trace.go:171","msg":"trace[1191397160] linearizableReadLoop","detail":"{readStateIndex:5048; appliedIndex:5046; }","duration":"401.798728ms","start":"2026-03-06T05:02:27.169387Z","end":"2026-03-06T05:02:27.571186Z","steps":["trace[1191397160] 'read index received' (duration: 152.288999ms)","trace[1191397160] 'applied index is now lower than readState.Index' (duration: 249.508509ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T05:02:27.571392Z","caller":"traceutil/trace.go:171","msg":"trace[246538579] transaction","detail":"{read_only:false; response_revision:4858; number_of_response:1; }","duration":"349.580826ms","start":"2026-03-06T05:02:27.221797Z","end":"2026-03-06T05:02:27.571378Z","steps":["trace[246538579] 'process raft request' (duration: 349.138306ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T05:02:27.571511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T05:02:27.221769Z","time spent":"349.649328ms","remote":"127.0.0.1:37186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T05:02:27.571568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.150787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d8f57246-4b1c-4d30-8bfe-7c562c66739c\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-03-06T05:02:27.57168Z","caller":"traceutil/trace.go:171","msg":"trace[169381828] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d8f57246-4b1c-4d30-8bfe-7c562c66739c; range_end:; response_count:1; response_revision:4858; }","duration":"402.28175ms","start":"2026-03-06T05:02:27.16938Z","end":"2026-03-06T05:02:27.571661Z","steps":["trace[169381828] 'agreement among raft nodes before linearized reading' (duration: 402.019484ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T05:02:27.571724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T05:02:27.16934Z","time spent":"402.374761ms","remote":"127.0.0.1:37106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d8f57246-4b1c-4d30-8bfe-7c562c66739c\" "} {"level":"warn","ts":"2026-03-06T05:02:27.750797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.938208ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-06T05:02:27.750916Z","caller":"traceutil/trace.go:171","msg":"trace[108191196] linearizableReadLoop","detail":"{readStateIndex:5050; appliedIndex:5049; }","duration":"179.618808ms","start":"2026-03-06T05:02:27.571278Z","end":"2026-03-06T05:02:27.750897Z","steps":["trace[108191196] 'read index received' (duration: 28.523176ms)","trace[108191196] 'applied index is now lower than readState.Index' (duration: 151.094262ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T05:02:27.751139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.946042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d8f57246-4b1c-4d30-8bfe-7c562c66739c\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-03-06T05:02:27.75117Z","caller":"traceutil/trace.go:171","msg":"trace[274961974] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d8f57246-4b1c-4d30-8bfe-7c562c66739c; range_end:; response_count:1; response_revision:4858; }","duration":"206.052606ms","start":"2026-03-06T05:02:27.545106Z","end":"2026-03-06T05:02:27.751159Z","steps":["trace[274961974] 'agreement among raft nodes before linearized reading' (duration: 205.890183ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T05:02:27.751201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.982295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/persistence-rabbitmq-keystone-server-0.189a27f73c32f57c\" ","response":"range_response_count:1 size:1012"} {"level":"info","ts":"2026-03-06T05:02:27.751265Z","caller":"traceutil/trace.go:171","msg":"trace[60152298] range","detail":"{range_begin:/registry/events/openstack/persistence-rabbitmq-keystone-server-0.189a27f73c32f57c; range_end:; response_count:1; response_revision:4858; }","duration":"176.092347ms","start":"2026-03-06T05:02:27.575155Z","end":"2026-03-06T05:02:27.751247Z","steps":["trace[60152298] 'agreement among raft nodes before linearized reading' (duration: 175.886373ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T05:02:28.078091Z","caller":"traceutil/trace.go:171","msg":"trace[556189036] linearizableReadLoop","detail":"{readStateIndex:5052; appliedIndex:5051; }","duration":"192.126141ms","start":"2026-03-06T05:02:27.885944Z","end":"2026-03-06T05:02:28.078071Z","steps":["trace[556189036] 'read index received' (duration: 191.908156ms)","trace[556189036] 'applied index is now lower than readState.Index' (duration: 214.515µs)"],"step_count":2} {"level":"warn","ts":"2026-03-06T05:02:28.078206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.253754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T05:02:28.078208Z","caller":"traceutil/trace.go:171","msg":"trace[374754962] transaction","detail":"{read_only:false; response_revision:4860; number_of_response:1; }","duration":"317.398728ms","start":"2026-03-06T05:02:27.760742Z","end":"2026-03-06T05:02:28.078141Z","steps":["trace[374754962] 'process raft request' (duration: 317.182483ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T05:02:28.07843Z","caller":"traceutil/trace.go:171","msg":"trace[1888222932] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:4860; }","duration":"192.365286ms","start":"2026-03-06T05:02:27.885872Z","end":"2026-03-06T05:02:28.078238Z","steps":["trace[1888222932] 'agreement among raft nodes before linearized reading' (duration: 192.282634ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T05:02:28.078463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T05:02:27.760724Z","time spent":"317.600242ms","remote":"127.0.0.1:37106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3748,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T05:02:28.314866Z","caller":"traceutil/trace.go:171","msg":"trace[2090029492] linearizableReadLoop","detail":"{readStateIndex:5053; appliedIndex:5052; }","duration":"129.84698ms","start":"2026-03-06T05:02:28.184968Z","end":"2026-03-06T05:02:28.314815Z","steps":["trace[2090029492] 'read index received' (duration: 84.686518ms)","trace[2090029492] 'applied index is now lower than readState.Index' (duration: 45.157992ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T05:02:28.314989Z","caller":"traceutil/trace.go:171","msg":"trace[609570782] transaction","detail":"{read_only:false; response_revision:4861; number_of_response:1; }","duration":"152.344529ms","start":"2026-03-06T05:02:28.162582Z","end":"2026-03-06T05:02:28.314926Z","steps":["trace[609570782] 'process raft request' (duration: 107.139605ms)","trace[609570782] 'compare' (duration: 44.917837ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T05:02:28.315288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.2859ms","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-03-06T05:02:28.315384Z","caller":"traceutil/trace.go:171","msg":"trace[1090179837] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4861; }","duration":"130.479794ms","start":"2026-03-06T05:02:28.184891Z","end":"2026-03-06T05:02:28.31537Z","steps":["trace[1090179837] 'agreement among raft nodes before linearized reading' (duration: 130.171127ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T05:02:28.315364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.22784ms","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-03-06T05:02:28.315445Z","caller":"traceutil/trace.go:171","msg":"trace[1225854624] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4861; }","duration":"119.425624ms","start":"2026-03-06T05:02:28.196Z","end":"2026-03-06T05:02:28.315425Z","steps":["trace[1225854624] 'agreement among raft nodes before linearized reading' (duration: 119.147058ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T05:02:29.885016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.305534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T05:02:29.885118Z","caller":"traceutil/trace.go:171","msg":"trace[817961097] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4870; }","duration":"222.475418ms","start":"2026-03-06T05:02:29.66262Z","end":"2026-03-06T05:02:29.885095Z","steps":["trace[817961097] 'range keys from in-memory index tree' (duration: 222.197072ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T05:02:35.705746Z","caller":"traceutil/trace.go:171","msg":"trace[2122360003] transaction","detail":"{read_only:false; response_revision:4970; number_of_response:1; }","duration":"105.276337ms","start":"2026-03-06T05:02:35.600429Z","end":"2026-03-06T05:02:35.705706Z","steps":["trace[2122360003] 'process raft request' (duration: 105.04082ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T05:02:40.801437Z","caller":"traceutil/trace.go:171","msg":"trace[705302320] linearizableReadLoop","detail":"{readStateIndex:5214; appliedIndex:5213; }","duration":"412.927988ms","start":"2026-03-06T05:02:40.388483Z","end":"2026-03-06T05:02:40.801411Z","steps":["trace[705302320] 'read index received' (duration: 25.090699ms)","trace[705302320] 'applied index is now lower than readState.Index' (duration: 387.835449ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T05:02:40.801587Z","caller":"traceutil/trace.go:171","msg":"trace[977276937] transaction","detail":"{read_only:false; response_revision:5019; number_of_response:1; }","duration":"415.901204ms","start":"2026-03-06T05:02:40.385666Z","end":"2026-03-06T05:02:40.801567Z","steps":["trace[977276937] 'process raft request' (duration: 377.041783ms)","trace[977276937] 'compare' (duration: 38.553244ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T05:02:40.801704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T05:02:40.385658Z","time spent":"415.958105ms","remote":"127.0.0.1:37186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T05:02:40.80198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.47821ms","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-03-06T05:02:40.802082Z","caller":"traceutil/trace.go:171","msg":"trace[1530614247] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5019; }","duration":"413.644323ms","start":"2026-03-06T05:02:40.388412Z","end":"2026-03-06T05:02:40.802056Z","steps":["trace[1530614247] 'agreement among raft nodes before linearized reading' (duration: 413.354537ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T05:02:40.802123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T05:02:40.388398Z","time spent":"413.716644ms","remote":"127.0.0.1:37186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-06T05:02:40.808166Z","caller":"traceutil/trace.go:171","msg":"trace[1733511561] transaction","detail":"{read_only:false; response_revision:5020; number_of_response:1; }","duration":"238.733399ms","start":"2026-03-06T05:02:40.569401Z","end":"2026-03-06T05:02:40.808135Z","steps":["trace[1733511561] 'process raft request' (duration: 238.431373ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T05:02:40.808308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.809184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-x2d8x\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-03-06T05:02:40.808426Z","caller":"traceutil/trace.go:171","msg":"trace[1001855551] range","detail":"{range_begin:/registry/pods/openstack/keepalived-x2d8x; range_end:; response_count:1; response_revision:5020; }","duration":"273.974698ms","start":"2026-03-06T05:02:40.534428Z","end":"2026-03-06T05:02:40.808403Z","steps":["trace[1001855551] 'agreement among raft nodes before linearized reading' (duration: 273.622171ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T05:02:40.808344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.414244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T05:02:40.808623Z","caller":"traceutil/trace.go:171","msg":"trace[315088515] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5020; }","duration":"145.743253ms","start":"2026-03-06T05:02:40.662852Z","end":"2026-03-06T05:02:40.808596Z","steps":["trace[315088515] 'agreement among raft nodes before linearized reading' (duration: 145.422946ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T05:02:40.945445Z","caller":"traceutil/trace.go:171","msg":"trace[919173717] transaction","detail":"{read_only:false; response_revision:5023; number_of_response:1; }","duration":"120.46774ms","start":"2026-03-06T05:02:40.824927Z","end":"2026-03-06T05:02:40.945395Z","steps":["trace[919173717] 'process raft request' (duration: 112.624892ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T05:02:41.939168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.370972ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-06T05:02:41.939416Z","caller":"traceutil/trace.go:171","msg":"trace[1161147083] transaction","detail":"{read_only:false; response_revision:5034; number_of_response:1; }","duration":"158.325308ms","start":"2026-03-06T05:02:41.781048Z","end":"2026-03-06T05:02:41.939373Z","steps":["trace[1161147083] 'process raft request' (duration: 43.584528ms)","trace[1161147083] 'compare' (duration: 114.121696ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T05:02:41.939675Z","caller":"traceutil/trace.go:171","msg":"trace[1749595882] transaction","detail":"{read_only:false; response_revision:5037; number_of_response:1; }","duration":"149.834995ms","start":"2026-03-06T05:02:41.789777Z","end":"2026-03-06T05:02:41.939612Z","steps":["trace[1749595882] 'process raft request' (duration: 149.744693ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T05:02:41.939731Z","caller":"traceutil/trace.go:171","msg":"trace[1453636540] transaction","detail":"{read_only:false; response_revision:5036; number_of_response:1; }","duration":"158.557823ms","start":"2026-03-06T05:02:41.781126Z","end":"2026-03-06T05:02:41.939684Z","steps":["trace[1453636540] 'process raft request' (duration: 158.265347ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T05:02:41.939699Z","caller":"traceutil/trace.go:171","msg":"trace[565729624] transaction","detail":"{read_only:false; response_revision:5035; number_of_response:1; }","duration":"158.383609ms","start":"2026-03-06T05:02:41.781145Z","end":"2026-03-06T05:02:41.939528Z","steps":["trace[565729624] 'process raft request' (duration: 158.174194ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T05:03:28.800295Z","caller":"traceutil/trace.go:171","msg":"trace[371613226] linearizableReadLoop","detail":"{readStateIndex:5604; appliedIndex:5603; }","duration":"103.911605ms","start":"2026-03-06T05:03:28.696352Z","end":"2026-03-06T05:03:28.800263Z","steps":["trace[371613226] 'read index received' (duration: 103.638089ms)","trace[371613226] 'applied index is now lower than readState.Index' (duration: 272.336µs)"],"step_count":2} {"level":"warn","ts":"2026-03-06T05:03:28.800444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.068868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-06T05:03:28.800751Z","caller":"traceutil/trace.go:171","msg":"trace[70875332] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:5397; }","duration":"104.421856ms","start":"2026-03-06T05:03:28.696305Z","end":"2026-03-06T05:03:28.800727Z","steps":["trace[70875332] 'agreement among raft nodes before linearized reading' (duration: 104.076908ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T05:03:28.800414Z","caller":"traceutil/trace.go:171","msg":"trace[600687091] transaction","detail":"{read_only:false; response_revision:5397; number_of_response:1; }","duration":"142.964449ms","start":"2026-03-06T05:03:28.657404Z","end":"2026-03-06T05:03:28.800368Z","steps":["trace[600687091] 'process raft request' (duration: 142.504048ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T05:03:28.958362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.502914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-03-06T05:03:28.95852Z","caller":"traceutil/trace.go:171","msg":"trace[2143640796] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5397; }","duration":"129.718059ms","start":"2026-03-06T05:03:28.828784Z","end":"2026-03-06T05:03:28.958502Z","steps":["trace[2143640796] 'range keys from in-memory index tree' (duration: 129.31561ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T05:04:19.110883Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2758} {"level":"info","ts":"2026-03-06T05:04:19.1682Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2758,"took":"55.883849ms","hash":67905529,"current-db-size-bytes":42250240,"current-db-size":"42 MB","current-db-size-in-use-bytes":31481856,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-03-06T05:04:19.168264Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":67905529,"revision":2758,"compact-revision":-1} {"level":"info","ts":"2026-03-06T05:08:45.36898Z","caller":"traceutil/trace.go:171","msg":"trace[627582957] transaction","detail":"{read_only:false; response_revision:7571; number_of_response:1; }","duration":"492.226064ms","start":"2026-03-06T05:08:44.876717Z","end":"2026-03-06T05:08:45.368943Z","steps":["trace[627582957] 'process raft request' (duration: 492.03232ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T05:08:45.369196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T05:08:44.876677Z","time spent":"492.413789ms","remote":"127.0.0.1:37186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T05:08:45.568532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.25342ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-06T05:08:45.568751Z","caller":"traceutil/trace.go:171","msg":"trace[584382009] linearizableReadLoop","detail":"{readStateIndex:7855; appliedIndex:7853; }","duration":"634.175075ms","start":"2026-03-06T05:08:44.934554Z","end":"2026-03-06T05:08:45.568729Z","steps":["trace[584382009] 'read index received' (duration: 434.263033ms)","trace[584382009] 'applied index is now lower than readState.Index' (duration: 199.910342ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T05:08:45.568875Z","caller":"traceutil/trace.go:171","msg":"trace[1490039416] transaction","detail":"{read_only:false; response_revision:7573; number_of_response:1; }","duration":"221.805704ms","start":"2026-03-06T05:08:45.347052Z","end":"2026-03-06T05:08:45.568857Z","steps":["trace[1490039416] 'process raft request' (duration: 221.61093ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T05:08:45.568863Z","caller":"traceutil/trace.go:171","msg":"trace[1498910827] transaction","detail":"{read_only:false; response_revision:7572; number_of_response:1; }","duration":"638.114281ms","start":"2026-03-06T05:08:44.930712Z","end":"2026-03-06T05:08:45.568826Z","steps":["trace[1498910827] 'process raft request' (duration: 479.462992ms)","trace[1498910827] 'compare' (duration: 158.113267ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T05:08:45.569051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T05:08:44.930694Z","time spent":"638.258405ms","remote":"127.0.0.1:37186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T05:08:45.569332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.764287ms","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-03-06T05:08:45.569395Z","caller":"traceutil/trace.go:171","msg":"trace[2010023402] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7573; }","duration":"634.850169ms","start":"2026-03-06T05:08:44.934529Z","end":"2026-03-06T05:08:45.56938Z","steps":["trace[2010023402] 'agreement among raft nodes before linearized reading' (duration: 634.627144ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T05:08:45.569443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T05:08:44.934516Z","time spent":"634.911891ms","remote":"127.0.0.1:37186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-06T05:08:45.569442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.432787ms","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":"warn","ts":"2026-03-06T05:08:45.569475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.602143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-03-06T05:08:45.569505Z","caller":"traceutil/trace.go:171","msg":"trace[1226619353] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:7573; }","duration":"595.53652ms","start":"2026-03-06T05:08:44.973951Z","end":"2026-03-06T05:08:45.569487Z","steps":["trace[1226619353] 'agreement among raft nodes before linearized reading' (duration: 595.421038ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T05:08:45.569512Z","caller":"traceutil/trace.go:171","msg":"trace[1914762070] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7573; }","duration":"620.701515ms","start":"2026-03-06T05:08:44.948797Z","end":"2026-03-06T05:08:45.569498Z","steps":["trace[1914762070] 'agreement among raft nodes before linearized reading' (duration: 620.551172ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T05:08:45.569546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T05:08:44.948764Z","time spent":"620.775677ms","remote":"127.0.0.1:37186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-06T05:08:45.569548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T05:08:44.973935Z","time spent":"595.603611ms","remote":"127.0.0.1:37158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":13,"response size":30,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-03-06T05:08:45.56954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.294794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-06T05:08:45.569636Z","caller":"traceutil/trace.go:171","msg":"trace[680369443] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:7573; }","duration":"524.467218ms","start":"2026-03-06T05:08:45.045144Z","end":"2026-03-06T05:08:45.569611Z","steps":["trace[680369443] 'agreement among raft nodes before linearized reading' (duration: 524.245673ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T05:08:45.569691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T05:08:45.045118Z","time spent":"524.560389ms","remote":"127.0.0.1:52882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-03-06T05:08:45.569669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.408732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-x2d8x\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-03-06T05:08:45.56979Z","caller":"traceutil/trace.go:171","msg":"trace[1631456852] range","detail":"{range_begin:/registry/pods/openstack/keepalived-x2d8x; range_end:; response_count:1; response_revision:7573; }","duration":"593.593896ms","start":"2026-03-06T05:08:44.976166Z","end":"2026-03-06T05:08:45.56976Z","steps":["trace[1631456852] 'agreement among raft nodes before linearized reading' (duration: 593.198357ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T05:08:45.569848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T05:08:44.976142Z","time spent":"593.693899ms","remote":"127.0.0.1:37106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6554,"request content":"key:\"/registry/pods/openstack/keepalived-x2d8x\" "} {"level":"info","ts":"2026-03-06T05:08:45.750043Z","caller":"traceutil/trace.go:171","msg":"trace[518050343] transaction","detail":"{read_only:false; response_revision:7574; number_of_response:1; }","duration":"176.009941ms","start":"2026-03-06T05:08:45.573666Z","end":"2026-03-06T05:08:45.749676Z","steps":["trace[518050343] 'process raft request' (duration: 175.269175ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T05:08:45.751132Z","caller":"traceutil/trace.go:171","msg":"trace[2094674853] linearizableReadLoop","detail":"{readStateIndex:7858; appliedIndex:7856; }","duration":"176.403551ms","start":"2026-03-06T05:08:45.574693Z","end":"2026-03-06T05:08:45.751097Z","steps":["trace[2094674853] 'read index received' (duration: 174.249633ms)","trace[2094674853] 'applied index is now lower than readState.Index' (duration: 2.152418ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T05:08:45.751216Z","caller":"traceutil/trace.go:171","msg":"trace[1392915457] transaction","detail":"{read_only:false; response_revision:7575; number_of_response:1; }","duration":"176.670046ms","start":"2026-03-06T05:08:45.574521Z","end":"2026-03-06T05:08:45.751191Z","steps":["trace[1392915457] 'process raft request' (duration: 176.39244ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T05:08:45.751782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.045575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:224134"} {"level":"info","ts":"2026-03-06T05:08:45.751932Z","caller":"traceutil/trace.go:171","msg":"trace[98660015] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:7575; }","duration":"177.237709ms","start":"2026-03-06T05:08:45.574667Z","end":"2026-03-06T05:08:45.751904Z","steps":["trace[98660015] 'agreement among raft nodes before linearized reading' (duration: 176.556254ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T05:08:56.211363Z","caller":"traceutil/trace.go:171","msg":"trace[180456914] linearizableReadLoop","detail":"{readStateIndex:7909; appliedIndex:7908; }","duration":"416.091102ms","start":"2026-03-06T05:08:55.795246Z","end":"2026-03-06T05:08:56.211337Z","steps":["trace[180456914] 'read index received' (duration: 415.854267ms)","trace[180456914] 'applied index is now lower than readState.Index' (duration: 235.715µs)"],"step_count":2} {"level":"info","ts":"2026-03-06T05:08:56.211416Z","caller":"traceutil/trace.go:171","msg":"trace[815989763] transaction","detail":"{read_only:false; response_revision:7623; number_of_response:1; }","duration":"420.054329ms","start":"2026-03-06T05:08:55.791321Z","end":"2026-03-06T05:08:56.211375Z","steps":["trace[815989763] 'process raft request' (duration: 419.820044ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T05:08:56.211631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T05:08:55.791288Z","time spent":"420.209882ms","remote":"127.0.0.1:37186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T05:08:56.211684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.504916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-03-06T05:08:56.21174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.447492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-x2d8x\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-03-06T05:08:56.211771Z","caller":"traceutil/trace.go:171","msg":"trace[1500367483] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7623; }","duration":"409.645669ms","start":"2026-03-06T05:08:55.802106Z","end":"2026-03-06T05:08:56.211752Z","steps":["trace[1500367483] 'agreement among raft nodes before linearized reading' (duration: 409.411214ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T05:08:56.211805Z","caller":"traceutil/trace.go:171","msg":"trace[426466686] range","detail":"{range_begin:/registry/pods/openstack/keepalived-x2d8x; range_end:; response_count:1; response_revision:7623; }","duration":"403.525684ms","start":"2026-03-06T05:08:55.80826Z","end":"2026-03-06T05:08:56.211786Z","steps":["trace[426466686] 'agreement among raft nodes before linearized reading' (duration: 403.320699ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T05:08:56.211824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.906651ms","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":"warn","ts":"2026-03-06T05:08:56.211849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T05:08:55.808252Z","time spent":"403.587865ms","remote":"127.0.0.1:37106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6554,"request content":"key:\"/registry/pods/openstack/keepalived-x2d8x\" "} {"level":"info","ts":"2026-03-06T05:08:56.211866Z","caller":"traceutil/trace.go:171","msg":"trace[2005586087] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7623; }","duration":"408.003363ms","start":"2026-03-06T05:08:55.803849Z","end":"2026-03-06T05:08:56.211852Z","steps":["trace[2005586087] 'agreement among raft nodes before linearized reading' (duration: 407.770948ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T05:08:56.211817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T05:08:55.802074Z","time spent":"409.73467ms","remote":"127.0.0.1:37186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-06T05:08:56.211901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T05:08:55.803833Z","time spent":"408.061404ms","remote":"127.0.0.1:37186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-06T05:08:56.211697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.379948ms","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-03-06T05:08:56.211934Z","caller":"traceutil/trace.go:171","msg":"trace[103797559] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7623; }","duration":"416.737166ms","start":"2026-03-06T05:08:55.795189Z","end":"2026-03-06T05:08:56.211926Z","steps":["trace[103797559] 'agreement among raft nodes before linearized reading' (duration: 416.258755ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T05:08:56.211954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T05:08:55.79517Z","time spent":"416.778986ms","remote":"127.0.0.1:37186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-06T05:08:56.433786Z","caller":"traceutil/trace.go:171","msg":"trace[1173942492] transaction","detail":"{read_only:false; response_revision:7624; number_of_response:1; }","duration":"217.973321ms","start":"2026-03-06T05:08:56.215776Z","end":"2026-03-06T05:08:56.433749Z","steps":["trace[1173942492] 'process raft request' (duration: 166.243497ms)","trace[1173942492] 'compare' (duration: 51.479458ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T05:08:56.43397Z","caller":"traceutil/trace.go:171","msg":"trace[848619796] transaction","detail":"{read_only:false; response_revision:7625; number_of_response:1; }","duration":"218.024881ms","start":"2026-03-06T05:08:56.215928Z","end":"2026-03-06T05:08:56.433953Z","steps":["trace[848619796] 'process raft request' (duration: 217.762575ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T05:08:56.434139Z","caller":"traceutil/trace.go:171","msg":"trace[745398368] linearizableReadLoop","detail":"{readStateIndex:7912; appliedIndex:7909; }","duration":"216.807124ms","start":"2026-03-06T05:08:56.217297Z","end":"2026-03-06T05:08:56.434105Z","steps":["trace[745398368] 'read index received' (duration: 164.745284ms)","trace[745398368] 'applied index is now lower than readState.Index' (duration: 52.06039ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T05:08:56.434377Z","caller":"traceutil/trace.go:171","msg":"trace[1517084001] transaction","detail":"{read_only:false; response_revision:7626; number_of_response:1; }","duration":"217.849356ms","start":"2026-03-06T05:08:56.216488Z","end":"2026-03-06T05:08:56.434337Z","steps":["trace[1517084001] 'process raft request' (duration: 217.398207ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T05:08:56.434483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.59129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T05:08:56.434531Z","caller":"traceutil/trace.go:171","msg":"trace[473202000] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7626; }","duration":"183.718803ms","start":"2026-03-06T05:08:56.250798Z","end":"2026-03-06T05:08:56.434516Z","steps":["trace[473202000] 'agreement among raft nodes before linearized reading' (duration: 183.621221ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T05:08:56.434706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.397478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:224134"} {"level":"info","ts":"2026-03-06T05:08:56.434749Z","caller":"traceutil/trace.go:171","msg":"trace[1610793409] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:7626; }","duration":"217.458449ms","start":"2026-03-06T05:08:56.217279Z","end":"2026-03-06T05:08:56.434737Z","steps":["trace[1610793409] 'agreement among raft nodes before linearized reading' (duration: 217.035749ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T05:08:57.946225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.641011ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-06T05:08:57.947024Z","caller":"traceutil/trace.go:171","msg":"trace[966140332] linearizableReadLoop","detail":"{readStateIndex:7916; appliedIndex:7915; }","duration":"488.955291ms","start":"2026-03-06T05:08:57.458037Z","end":"2026-03-06T05:08:57.946993Z","steps":["trace[966140332] 'read index received' (duration: 110.530073ms)","trace[966140332] 'applied index is now lower than readState.Index' (duration: 378.422778ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T05:08:57.947148Z","caller":"traceutil/trace.go:171","msg":"trace[1594254012] transaction","detail":"{read_only:false; response_revision:7630; number_of_response:1; }","duration":"334.865615ms","start":"2026-03-06T05:08:57.612265Z","end":"2026-03-06T05:08:57.94713Z","steps":["trace[1594254012] 'process raft request' (duration: 334.6508ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T05:08:57.947276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.231949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-03-06T05:08:57.947288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T05:08:57.612224Z","time spent":"334.972218ms","remote":"127.0.0.1:37186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T05:08:57.947298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.78639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T05:08:57.947333Z","caller":"traceutil/trace.go:171","msg":"trace[2021933938] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7630; }","duration":"489.350001ms","start":"2026-03-06T05:08:57.457969Z","end":"2026-03-06T05:08:57.947319Z","steps":["trace[2021933938] 'agreement among raft nodes before linearized reading' (duration: 489.113105ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T05:08:57.947337Z","caller":"traceutil/trace.go:171","msg":"trace[853836430] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7631; }","duration":"285.863182ms","start":"2026-03-06T05:08:57.661463Z","end":"2026-03-06T05:08:57.947326Z","steps":["trace[853836430] 'agreement among raft nodes before linearized reading' (duration: 285.77034ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T05:08:57.94737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T05:08:57.457943Z","time spent":"489.417892ms","remote":"127.0.0.1:37186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-06T05:08:57.94733Z","caller":"traceutil/trace.go:171","msg":"trace[366247069] transaction","detail":"{read_only:false; response_revision:7631; number_of_response:1; }","duration":"304.750959ms","start":"2026-03-06T05:08:57.642535Z","end":"2026-03-06T05:08:57.947286Z","steps":["trace[366247069] 'process raft request' (duration: 304.550123ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T05:08:57.947428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.831179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-06T05:08:57.94754Z","caller":"traceutil/trace.go:171","msg":"trace[644447326] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:7631; }","duration":"260.950401ms","start":"2026-03-06T05:08:57.686558Z","end":"2026-03-06T05:08:57.947508Z","steps":["trace[644447326] 'agreement among raft nodes before linearized reading' (duration: 260.746057ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T05:08:57.947568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T05:08:57.642496Z","time spent":"304.933383ms","remote":"127.0.0.1:37186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T05:08:58.258948Z","caller":"traceutil/trace.go:171","msg":"trace[246754779] transaction","detail":"{read_only:false; response_revision:7633; number_of_response:1; }","duration":"104.395098ms","start":"2026-03-06T05:08:58.154517Z","end":"2026-03-06T05:08:58.258913Z","steps":["trace[246754779] 'process raft request' (duration: 104.163743ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T05:08:58.335827Z","caller":"traceutil/trace.go:171","msg":"trace[879570097] transaction","detail":"{read_only:false; response_revision:7634; number_of_response:1; }","duration":"118.202123ms","start":"2026-03-06T05:08:58.217586Z","end":"2026-03-06T05:08:58.335788Z","steps":["trace[879570097] 'process raft request' (duration: 69.245512ms)","trace[879570097] 'compare' (duration: 48.702606ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T05:08:58.635059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.78536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-03-06T05:08:58.635075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.766123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-x2d8x\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-03-06T05:08:58.635169Z","caller":"traceutil/trace.go:171","msg":"trace[760440234] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7634; }","duration":"196.987415ms","start":"2026-03-06T05:08:58.438155Z","end":"2026-03-06T05:08:58.635143Z","steps":["trace[760440234] 'range keys from in-memory index tree' (duration: 196.424792ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T05:08:58.635177Z","caller":"traceutil/trace.go:171","msg":"trace[1792415923] range","detail":"{range_begin:/registry/pods/openstack/keepalived-x2d8x; range_end:; response_count:1; response_revision:7634; }","duration":"193.900607ms","start":"2026-03-06T05:08:58.441254Z","end":"2026-03-06T05:08:58.635155Z","steps":["trace[1792415923] 'range keys from in-memory index tree' (duration: 193.59126ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T05:08:58.635287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.056216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-03-06T05:08:58.635477Z","caller":"traceutil/trace.go:171","msg":"trace[1207058034] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7634; }","duration":"197.309591ms","start":"2026-03-06T05:08:58.438147Z","end":"2026-03-06T05:08:58.635456Z","steps":["trace[1207058034] 'range keys from in-memory index tree' (duration: 196.640697ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T05:08:58.635779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.456765ms","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-03-06T05:08:58.635882Z","caller":"traceutil/trace.go:171","msg":"trace[1663687678] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7634; }","duration":"197.633469ms","start":"2026-03-06T05:08:58.438226Z","end":"2026-03-06T05:08:58.63586Z","steps":["trace[1663687678] 'range keys from in-memory index tree' (duration: 196.502844ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T05:08:58.958779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.548411ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-06T05:08:58.959049Z","caller":"traceutil/trace.go:171","msg":"trace[10318162] linearizableReadLoop","detail":"{readStateIndex:7925; appliedIndex:7922; }","duration":"297.000156ms","start":"2026-03-06T05:08:58.662029Z","end":"2026-03-06T05:08:58.95903Z","steps":["trace[10318162] 'read index received' (duration: 126.110758ms)","trace[10318162] 'applied index is now lower than readState.Index' (duration: 170.888488ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T05:08:58.959092Z","caller":"traceutil/trace.go:171","msg":"trace[1474854493] transaction","detail":"{read_only:false; response_revision:7637; number_of_response:1; }","duration":"319.200327ms","start":"2026-03-06T05:08:58.639853Z","end":"2026-03-06T05:08:58.959053Z","steps":["trace[1474854493] 'process raft request' (duration: 319.083074ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T05:08:58.959127Z","caller":"traceutil/trace.go:171","msg":"trace[1323158353] transaction","detail":"{read_only:false; response_revision:7638; number_of_response:1; }","duration":"317.94567ms","start":"2026-03-06T05:08:58.641118Z","end":"2026-03-06T05:08:58.959064Z","steps":["trace[1323158353] 'process raft request' (duration: 317.873928ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T05:08:58.959265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.19313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-06T05:08:58.959352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T05:08:58.640984Z","time spent":"318.251417ms","remote":"127.0.0.1:37186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T05:08:58.959382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.589007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-06T05:08:58.959363Z","caller":"traceutil/trace.go:171","msg":"trace[1032761976] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7638; }","duration":"297.397135ms","start":"2026-03-06T05:08:58.661949Z","end":"2026-03-06T05:08:58.959346Z","steps":["trace[1032761976] 'agreement among raft nodes before linearized reading' (duration: 297.1901ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T05:08:58.959423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.815888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-03-06T05:08:58.959298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T05:08:58.639827Z","time spent":"319.360271ms","remote":"127.0.0.1:37186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T05:08:58.959407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.707961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T05:08:58.959136Z","caller":"traceutil/trace.go:171","msg":"trace[263186563] transaction","detail":"{read_only:false; response_revision:7636; number_of_response:1; }","duration":"319.326519ms","start":"2026-03-06T05:08:58.639795Z","end":"2026-03-06T05:08:58.959121Z","steps":["trace[263186563] 'process raft request' (duration: 148.304318ms)","trace[263186563] 'compare' (duration: 170.411518ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T05:08:58.959658Z","caller":"traceutil/trace.go:171","msg":"trace[232739123] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:7638; }","duration":"278.991838ms","start":"2026-03-06T05:08:58.680588Z","end":"2026-03-06T05:08:58.95958Z","steps":["trace[232739123] 'agreement among raft nodes before linearized reading' (duration: 278.59779ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T05:08:58.959736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T05:08:58.639766Z","time spent":"319.910042ms","remote":"127.0.0.1:37186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T05:08:58.959438Z","caller":"traceutil/trace.go:171","msg":"trace[1963793908] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:7638; }","duration":"277.672029ms","start":"2026-03-06T05:08:58.681754Z","end":"2026-03-06T05:08:58.959426Z","steps":["trace[1963793908] 'agreement among raft nodes before linearized reading' (duration: 277.584777ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T05:08:58.959454Z","caller":"traceutil/trace.go:171","msg":"trace[1493881889] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:7638; }","duration":"157.87102ms","start":"2026-03-06T05:08:58.801575Z","end":"2026-03-06T05:08:58.959446Z","steps":["trace[1493881889] 'agreement among raft nodes before linearized reading' (duration: 157.676586ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T05:09:19.117639Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5746} {"level":"info","ts":"2026-03-06T05:09:19.209633Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5746,"took":"90.019081ms","hash":3410857326,"current-db-size-bytes":43376640,"current-db-size":"43 MB","current-db-size-in-use-bytes":23502848,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-03-06T05:09:19.209729Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3410857326,"revision":5746,"compact-revision":2758} {"level":"info","ts":"2026-03-06T05:09:26.205118Z","caller":"traceutil/trace.go:171","msg":"trace[976287795] transaction","detail":"{read_only:false; response_revision:7821; number_of_response:1; }","duration":"116.23827ms","start":"2026-03-06T05:09:26.088859Z","end":"2026-03-06T05:09:26.205097Z","steps":["trace[976287795] 'process raft request' (duration: 116.100277ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T05:09:26.260553Z","caller":"traceutil/trace.go:171","msg":"trace[2636516] linearizableReadLoop","detail":"{readStateIndex:8117; appliedIndex:8115; }","duration":"109.553961ms","start":"2026-03-06T05:09:26.150966Z","end":"2026-03-06T05:09:26.26052Z","steps":["trace[2636516] 'read index received' (duration: 54.105016ms)","trace[2636516] 'applied index is now lower than readState.Index' (duration: 55.447155ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T05:09:26.260787Z","caller":"traceutil/trace.go:171","msg":"trace[474936988] transaction","detail":"{read_only:false; response_revision:7822; number_of_response:1; }","duration":"134.199227ms","start":"2026-03-06T05:09:26.12657Z","end":"2026-03-06T05:09:26.260769Z","steps":["trace[474936988] 'process raft request' (duration: 133.770696ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T05:09:26.260818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.818308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-06T05:09:26.260863Z","caller":"traceutil/trace.go:171","msg":"trace[77287977] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7822; }","duration":"109.923571ms","start":"2026-03-06T05:09:26.150924Z","end":"2026-03-06T05:09:26.260847Z","steps":["trace[77287977] 'agreement among raft nodes before linearized reading' (duration: 109.721166ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T05:09:30.425134Z","caller":"traceutil/trace.go:171","msg":"trace[172511569] transaction","detail":"{read_only:false; response_revision:7840; number_of_response:1; }","duration":"206.25986ms","start":"2026-03-06T05:09:30.218841Z","end":"2026-03-06T05:09:30.425101Z","steps":["trace[172511569] 'process raft request' (duration: 206.049625ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T05:09:30.498734Z","caller":"traceutil/trace.go:171","msg":"trace[1037845825] transaction","detail":"{read_only:false; response_revision:7841; number_of_response:1; }","duration":"221.755052ms","start":"2026-03-06T05:09:30.276935Z","end":"2026-03-06T05:09:30.49869Z","steps":["trace[1037845825] 'process raft request' (duration: 192.408024ms)","trace[1037845825] 'compare' (duration: 29.163394ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T05:09:30.498749Z","caller":"traceutil/trace.go:171","msg":"trace[979344282] linearizableReadLoop","detail":"{readStateIndex:8137; appliedIndex:8135; }","duration":"216.69631ms","start":"2026-03-06T05:09:30.282024Z","end":"2026-03-06T05:09:30.498721Z","steps":["trace[979344282] 'read index received' (duration: 142.827157ms)","trace[979344282] 'applied index is now lower than readState.Index' (duration: 73.868283ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T05:09:30.499076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.036747ms","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-03-06T05:09:30.499137Z","caller":"traceutil/trace.go:171","msg":"trace[1169746547] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7842; }","duration":"217.131611ms","start":"2026-03-06T05:09:30.281991Z","end":"2026-03-06T05:09:30.499123Z","steps":["trace[1169746547] 'agreement among raft nodes before linearized reading' (duration: 216.813583ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T05:09:30.49916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.118369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T05:09:30.499208Z","caller":"traceutil/trace.go:171","msg":"trace[1664516896] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:7842; }","duration":"207.21238ms","start":"2026-03-06T05:09:30.291979Z","end":"2026-03-06T05:09:30.499191Z","steps":["trace[1664516896] 'agreement among raft nodes before linearized reading' (duration: 207.089977ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T05:09:30.499204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.248185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-06T05:09:30.499294Z","caller":"traceutil/trace.go:171","msg":"trace[1617289819] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:7842; }","duration":"132.375627ms","start":"2026-03-06T05:09:30.366893Z","end":"2026-03-06T05:09:30.499269Z","steps":["trace[1617289819] 'agreement among raft nodes before linearized reading' (duration: 132.188053ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T05:09:38.500635Z","caller":"traceutil/trace.go:171","msg":"trace[1579314741] transaction","detail":"{read_only:false; response_revision:7920; number_of_response:1; }","duration":"200.34994ms","start":"2026-03-06T05:09:38.300256Z","end":"2026-03-06T05:09:38.500606Z","steps":["trace[1579314741] 'process raft request' (duration: 200.138195ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T05:09:38.835984Z","caller":"traceutil/trace.go:171","msg":"trace[1054619831] linearizableReadLoop","detail":"{readStateIndex:8223; appliedIndex:8221; }","duration":"307.369806ms","start":"2026-03-06T05:09:38.528577Z","end":"2026-03-06T05:09:38.835947Z","steps":["trace[1054619831] 'read index received' (duration: 239.023494ms)","trace[1054619831] 'applied index is now lower than readState.Index' (duration: 68.345192ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T05:09:38.836089Z","caller":"traceutil/trace.go:171","msg":"trace[1927583437] transaction","detail":"{read_only:false; response_revision:7921; number_of_response:1; }","duration":"530.313814ms","start":"2026-03-06T05:09:38.305737Z","end":"2026-03-06T05:09:38.836051Z","steps":["trace[1927583437] 'process raft request' (duration: 461.957582ms)","trace[1927583437] 'compare' (duration: 67.788759ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T05:09:38.836261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T05:09:38.305703Z","time spent":"530.463898ms","remote":"127.0.0.1:37106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4635,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T05:09:38.836278Z","caller":"traceutil/trace.go:171","msg":"trace[1291427109] transaction","detail":"{read_only:false; response_revision:7922; number_of_response:1; }","duration":"383.140871ms","start":"2026-03-06T05:09:38.453067Z","end":"2026-03-06T05:09:38.836208Z","steps":["trace[1291427109] 'process raft request' (duration: 382.744041ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T05:09:38.836548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T05:09:38.453038Z","time spent":"383.351235ms","remote":"127.0.0.1:37186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T05:09:38.836619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.957449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-03-06T05:09:38.83916Z","caller":"traceutil/trace.go:171","msg":"trace[56417006] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:7922; }","duration":"308.258205ms","start":"2026-03-06T05:09:38.528516Z","end":"2026-03-06T05:09:38.836774Z","steps":["trace[56417006] 'agreement among raft nodes before linearized reading' (duration: 307.698593ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T05:09:38.839398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T05:09:38.5285Z","time spent":"310.75676ms","remote":"127.0.0.1:37186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":553,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"info","ts":"2026-03-06T05:09:38.840972Z","caller":"traceutil/trace.go:171","msg":"trace[601740615] transaction","detail":"{read_only:false; response_revision:7923; number_of_response:1; }","duration":"310.290099ms","start":"2026-03-06T05:09:38.530654Z","end":"2026-03-06T05:09:38.840944Z","steps":["trace[601740615] 'process raft request' (duration: 310.161926ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T05:09:38.841034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.410528ms","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-03-06T05:09:38.841073Z","caller":"traceutil/trace.go:171","msg":"trace[1942882185] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7924; }","duration":"256.486309ms","start":"2026-03-06T05:09:38.584577Z","end":"2026-03-06T05:09:38.841064Z","steps":["trace[1942882185] 'agreement among raft nodes before linearized reading' (duration: 256.341036ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T05:09:38.841015Z","caller":"traceutil/trace.go:171","msg":"trace[793911713] transaction","detail":"{read_only:false; response_revision:7924; number_of_response:1; }","duration":"308.878738ms","start":"2026-03-06T05:09:38.532098Z","end":"2026-03-06T05:09:38.840976Z","steps":["trace[793911713] 'process raft request' (duration: 308.790356ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T05:09:38.841134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T05:09:38.530621Z","time spent":"310.427762ms","remote":"127.0.0.1:37186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T05:09:38.84122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.571449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T05:09:38.841251Z","caller":"traceutil/trace.go:171","msg":"trace[1579372242] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7924; }","duration":"179.634771ms","start":"2026-03-06T05:09:38.66161Z","end":"2026-03-06T05:09:38.841244Z","steps":["trace[1579372242] 'agreement among raft nodes before linearized reading' (duration: 179.5878ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T05:09:38.841254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T05:09:38.532081Z","time spent":"309.065032ms","remote":"127.0.0.1:37186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T05:09:39.016133Z","caller":"traceutil/trace.go:171","msg":"trace[1988033137] transaction","detail":"{read_only:false; response_revision:7928; number_of_response:1; }","duration":"104.212234ms","start":"2026-03-06T05:09:38.911877Z","end":"2026-03-06T05:09:39.01609Z","steps":["trace[1988033137] 'process raft request' (duration: 75.316935ms)","trace[1988033137] 'compare' (duration: 28.670004ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T05:09:39.979671Z","caller":"traceutil/trace.go:171","msg":"trace[75378679] linearizableReadLoop","detail":"{readStateIndex:8236; appliedIndex:8235; }","duration":"161.260656ms","start":"2026-03-06T05:09:39.818387Z","end":"2026-03-06T05:09:39.979647Z","steps":["trace[75378679] 'read index received' (duration: 161.025621ms)","trace[75378679] 'applied index is now lower than readState.Index' (duration: 233.485µs)"],"step_count":2} {"level":"info","ts":"2026-03-06T05:09:39.979746Z","caller":"traceutil/trace.go:171","msg":"trace[185574768] transaction","detail":"{read_only:false; response_revision:7935; number_of_response:1; }","duration":"290.679707ms","start":"2026-03-06T05:09:39.689015Z","end":"2026-03-06T05:09:39.979695Z","steps":["trace[185574768] 'process raft request' (duration: 290.412941ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T05:09:39.980137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.716985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-03-06T05:09:39.980249Z","caller":"traceutil/trace.go:171","msg":"trace[1682703515] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:7935; }","duration":"161.897429ms","start":"2026-03-06T05:09:39.818327Z","end":"2026-03-06T05:09:39.980225Z","steps":["trace[1682703515] 'agreement among raft nodes before linearized reading' (duration: 161.45306ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T05:10:57.336304Z","caller":"traceutil/trace.go:171","msg":"trace[139826746] transaction","detail":"{read_only:false; response_revision:8645; number_of_response:1; }","duration":"138.549321ms","start":"2026-03-06T05:10:57.197718Z","end":"2026-03-06T05:10:57.336267Z","steps":["trace[139826746] 'process raft request' (duration: 130.34131ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T05:10:57.360061Z","caller":"traceutil/trace.go:171","msg":"trace[993388330] transaction","detail":"{read_only:false; response_revision:8646; number_of_response:1; }","duration":"153.119401ms","start":"2026-03-06T05:10:57.206914Z","end":"2026-03-06T05:10:57.360033Z","steps":["trace[993388330] 'process raft request' (duration: 152.848095ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T05:10:57.360731Z","caller":"traceutil/trace.go:171","msg":"trace[1931065070] transaction","detail":"{read_only:false; response_revision:8647; number_of_response:1; }","duration":"152.480176ms","start":"2026-03-06T05:10:57.207786Z","end":"2026-03-06T05:10:57.360266Z","steps":["trace[1931065070] 'process raft request' (duration: 152.18165ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T05:13:18.437732Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"6f1fcbe50562a63a","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-06T05:13:18.443825Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-03-06T05:13:18.443954Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-03-06T05:14:19.124855Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7742} {"level":"info","ts":"2026-03-06T05:14:19.194433Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7742,"took":"67.276741ms","hash":3426769456,"current-db-size-bytes":43376640,"current-db-size":"43 MB","current-db-size-in-use-bytes":25694208,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-03-06T05:14:19.194525Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3426769456,"revision":7742,"compact-revision":5746} {"level":"info","ts":"2026-03-06T05:19:19.134487Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9982} {"level":"info","ts":"2026-03-06T05:19:19.212951Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9982,"took":"75.377957ms","hash":1202100261,"current-db-size-bytes":43376640,"current-db-size":"43 MB","current-db-size-in-use-bytes":16384000,"current-db-size-in-use":"16 MB"} {"level":"info","ts":"2026-03-06T05:19:19.213032Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1202100261,"revision":9982,"compact-revision":7742}