{"level":"warn","ts":"2026-09-19T00:55:13.30716Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-19T00:55:13.307282Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.156: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.156:2380","--initial-cluster=instance=https://162.253.55.156:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.156:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.156:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-09-19T00:55:13.307369Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-19T00:55:13.307383Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.156:2380"]} {"level":"info","ts":"2026-09-19T00:55:13.307402Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-19T00:55:13.308214Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.156:2379"]} {"level":"info","ts":"2026-09-19T00:55:13.308317Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.156:2380"],"listen-peer-urls":["https://162.253.55.156:2380"],"advertise-client-urls":["https://162.253.55.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.156:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-09-19T00:55:13.753336Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"444.719292ms"} {"level":"info","ts":"2026-09-19T00:55:14.317438Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"40fb1eca294b6a5a","cluster-id":"17c6999805fcc0eb"} {"level":"info","ts":"2026-09-19T00:55:14.317634Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a switched to configuration voters=()"} {"level":"info","ts":"2026-09-19T00:55:14.317715Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a became follower at term 0"} {"level":"info","ts":"2026-09-19T00:55:14.317737Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 40fb1eca294b6a5a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-19T00:55:14.317748Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a became follower at term 1"} {"level":"info","ts":"2026-09-19T00:55:14.317798Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a switched to configuration voters=(4682370091206797914)"} {"level":"warn","ts":"2026-09-19T00:55:14.408766Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-19T00:55:14.411552Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-19T00:55:14.413475Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-09-19T00:55:14.418849Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"40fb1eca294b6a5a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-19T00:55:14.41923Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"40fb1eca294b6a5a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-19T00:55:14.420128Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-19T00:55:14.421018Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-19T00:55:14.421064Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-19T00:55:14.421706Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a switched to configuration voters=(4682370091206797914)"} {"level":"info","ts":"2026-09-19T00:55:14.422484Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"17c6999805fcc0eb","local-member-id":"40fb1eca294b6a5a","added-peer-id":"40fb1eca294b6a5a","added-peer-peer-urls":["https://162.253.55.156:2380"]} {"level":"info","ts":"2026-09-19T00:55:14.425163Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-19T00:55:14.425266Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.156:2380"} {"level":"info","ts":"2026-09-19T00:55:14.425375Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.156:2380"} {"level":"info","ts":"2026-09-19T00:55:14.425548Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"40fb1eca294b6a5a","initial-advertise-peer-urls":["https://162.253.55.156:2380"],"listen-peer-urls":["https://162.253.55.156:2380"],"advertise-client-urls":["https://162.253.55.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-19T00:55:14.425673Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-19T00:55:15.31896Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a is starting a new election at term 1"} {"level":"info","ts":"2026-09-19T00:55:15.319052Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a became pre-candidate at term 1"} {"level":"info","ts":"2026-09-19T00:55:15.319104Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a received MsgPreVoteResp from 40fb1eca294b6a5a at term 1"} {"level":"info","ts":"2026-09-19T00:55:15.319129Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a became candidate at term 2"} {"level":"info","ts":"2026-09-19T00:55:15.319145Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a received MsgVoteResp from 40fb1eca294b6a5a at term 2"} {"level":"info","ts":"2026-09-19T00:55:15.319163Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a became leader at term 2"} {"level":"info","ts":"2026-09-19T00:55:15.319183Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 40fb1eca294b6a5a elected leader 40fb1eca294b6a5a at term 2"} {"level":"info","ts":"2026-09-19T00:55:15.320597Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"40fb1eca294b6a5a","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.156:2379]}","request-path":"/0/members/40fb1eca294b6a5a/attributes","cluster-id":"17c6999805fcc0eb","publish-timeout":"7s"} {"level":"info","ts":"2026-09-19T00:55:15.32064Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-19T00:55:15.320724Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-19T00:55:15.320854Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T00:55:15.321141Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-19T00:55:15.321193Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-19T00:55:15.322702Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"17c6999805fcc0eb","local-member-id":"40fb1eca294b6a5a","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T00:55:15.32293Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T00:55:15.322997Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T00:55:15.324455Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.156:2379"} {"level":"info","ts":"2026-09-19T00:55:15.324634Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-19T00:55:54.590554Z","caller":"traceutil/trace.go:171","msg":"trace[1701411260] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"153.858654ms","start":"2026-09-19T00:55:54.436668Z","end":"2026-09-19T00:55:54.590526Z","steps":["trace[1701411260] 'process raft request' (duration: 153.68967ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:55:54.600601Z","caller":"traceutil/trace.go:171","msg":"trace[1137082088] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"146.428261ms","start":"2026-09-19T00:55:54.451303Z","end":"2026-09-19T00:55:54.597732Z","steps":["trace[1137082088] 'process raft request' (duration: 146.03529ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:55:54.60088Z","caller":"traceutil/trace.go:171","msg":"trace[623456741] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"161.630237ms","start":"2026-09-19T00:55:54.439218Z","end":"2026-09-19T00:55:54.600848Z","steps":["trace[623456741] 'process raft request' (duration: 158.028582ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:55:54.600867Z","caller":"traceutil/trace.go:171","msg":"trace[115548004] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"143.212085ms","start":"2026-09-19T00:55:54.457593Z","end":"2026-09-19T00:55:54.600805Z","steps":["trace[115548004] 'process raft request' (duration: 139.981421ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:55:54.601429Z","caller":"traceutil/trace.go:171","msg":"trace[1338576681] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"152.894119ms","start":"2026-09-19T00:55:54.448298Z","end":"2026-09-19T00:55:54.601192Z","steps":["trace[1338576681] 'process raft request' (duration: 148.990457ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:55:54.601536Z","caller":"traceutil/trace.go:171","msg":"trace[2097153906] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"160.677583ms","start":"2026-09-19T00:55:54.437063Z","end":"2026-09-19T00:55:54.597741Z","steps":["trace[2097153906] 'process raft request' (duration: 160.073866ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:55:54.601874Z","caller":"traceutil/trace.go:171","msg":"trace[438767534] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"113.488831ms","start":"2026-09-19T00:55:54.488135Z","end":"2026-09-19T00:55:54.601624Z","steps":["trace[438767534] 'process raft request' (duration: 109.490358ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:55:54.60228Z","caller":"traceutil/trace.go:171","msg":"trace[1063161383] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"150.64511ms","start":"2026-09-19T00:55:54.451364Z","end":"2026-09-19T00:55:54.60201Z","steps":["trace[1063161383] 'process raft request' (duration: 146.063051ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:55:54.602301Z","caller":"traceutil/trace.go:171","msg":"trace[1029763120] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"150.803214ms","start":"2026-09-19T00:55:54.451388Z","end":"2026-09-19T00:55:54.602191Z","steps":["trace[1029763120] 'process raft request' (duration: 146.077661ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:55:54.614403Z","caller":"traceutil/trace.go:171","msg":"trace[953927293] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"149.125181ms","start":"2026-09-19T00:55:54.454178Z","end":"2026-09-19T00:55:54.603303Z","steps":["trace[953927293] 'process raft request' (duration: 143.320309ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:56:00.858433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.205336ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-19T00:56:00.858589Z","caller":"traceutil/trace.go:171","msg":"trace[1538779916] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:658; }","duration":"117.882948ms","start":"2026-09-19T00:56:00.740688Z","end":"2026-09-19T00:56:00.858571Z","steps":["trace[1538779916] 'read index received' (duration: 45.672µs)","trace[1538779916] 'applied index is now lower than readState.Index' (duration: 117.835906ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T00:56:00.858627Z","caller":"traceutil/trace.go:171","msg":"trace[1808501798] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"352.3473ms","start":"2026-09-19T00:56:00.506247Z","end":"2026-09-19T00:56:00.858594Z","steps":["trace[1808501798] 'process raft request' (duration: 100.34097ms)","trace[1808501798] 'compare' (duration: 250.399911ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T00:56:00.858782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:56:00.506227Z","time spent":"352.464814ms","remote":"127.0.0.1:42382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":818,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-19T00:56:00.858812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.093775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-19T00:56:00.858853Z","caller":"traceutil/trace.go:171","msg":"trace[1938081352] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:633; }","duration":"118.214328ms","start":"2026-09-19T00:56:00.740626Z","end":"2026-09-19T00:56:00.85884Z","steps":["trace[1938081352] 'agreement among raft nodes before linearized reading' (duration: 117.999ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:56:01.16406Z","caller":"traceutil/trace.go:171","msg":"trace[1046450010] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:660; }","duration":"147.366825ms","start":"2026-09-19T00:56:01.016659Z","end":"2026-09-19T00:56:01.164026Z","steps":["trace[1046450010] 'read index received' (duration: 147.198179ms)","trace[1046450010] 'applied index is now lower than readState.Index' (duration: 167.795µs)"],"step_count":2} {"level":"warn","ts":"2026-09-19T00:56:01.164178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.513381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T00:56:01.1642Z","caller":"traceutil/trace.go:171","msg":"trace[913294078] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:635; }","duration":"147.550912ms","start":"2026-09-19T00:56:01.016643Z","end":"2026-09-19T00:56:01.164194Z","steps":["trace[913294078] 'agreement among raft nodes before linearized reading' (duration: 147.48016ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:56:01.164333Z","caller":"traceutil/trace.go:171","msg":"trace[2101577619] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"212.657202ms","start":"2026-09-19T00:56:00.951669Z","end":"2026-09-19T00:56:01.164326Z","steps":["trace[2101577619] 'process raft request' (duration: 212.256818ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:56:01.775316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.884445ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T00:56:01.775454Z","caller":"traceutil/trace.go:171","msg":"trace[236874234] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"593.220293ms","start":"2026-09-19T00:56:01.182209Z","end":"2026-09-19T00:56:01.775429Z","steps":["trace[236874234] 'process raft request' (duration: 93.900674ms)","trace[236874234] 'compare' (duration: 498.382468ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T00:56:01.775786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:56:01.182186Z","time spent":"593.471151ms","remote":"127.0.0.1:42368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T00:56:01.914765Z","caller":"traceutil/trace.go:171","msg":"trace[514575852] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"545.883307ms","start":"2026-09-19T00:56:01.368846Z","end":"2026-09-19T00:56:01.914729Z","steps":["trace[514575852] 'process raft request' (duration: 535.737923ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:56:01.914808Z","caller":"traceutil/trace.go:171","msg":"trace[1971988163] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"333.096878ms","start":"2026-09-19T00:56:01.581685Z","end":"2026-09-19T00:56:01.914782Z","steps":["trace[1971988163] 'process raft request' (duration: 333.037766ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:56:01.914924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:56:01.368818Z","time spent":"546.023402ms","remote":"127.0.0.1:42252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036153,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T00:56:01.915078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:56:01.581638Z","time spent":"333.245874ms","remote":"127.0.0.1:42446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T00:56:01.914862Z","caller":"traceutil/trace.go:171","msg":"trace[22917821] linearizableReadLoop","detail":"{readStateIndex:663; appliedIndex:662; }","duration":"502.638034ms","start":"2026-09-19T00:56:01.412129Z","end":"2026-09-19T00:56:01.914767Z","steps":["trace[22917821] 'read index received' (duration: 486.201049ms)","trace[22917821] 'applied index is now lower than readState.Index' (duration: 16.434515ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T00:56:01.91569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.320706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-19T00:56:01.915902Z","caller":"traceutil/trace.go:171","msg":"trace[1886916141] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:638; }","duration":"503.76806ms","start":"2026-09-19T00:56:01.412114Z","end":"2026-09-19T00:56:01.915882Z","steps":["trace[1886916141] 'agreement among raft nodes before linearized reading' (duration: 502.79338ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:56:01.916615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:56:01.411984Z","time spent":"504.614057ms","remote":"127.0.0.1:42446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-19T00:56:01.916401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.113633ms","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-09-19T00:56:01.916873Z","caller":"traceutil/trace.go:171","msg":"trace[229314174] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:638; }","duration":"315.550947ms","start":"2026-09-19T00:56:01.60124Z","end":"2026-09-19T00:56:01.916791Z","steps":["trace[229314174] 'agreement among raft nodes before linearized reading' (duration: 314.37091ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:56:01.917194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:56:01.601228Z","time spent":"315.943549ms","remote":"127.0.0.1:42446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-19T00:56:08.231293Z","caller":"traceutil/trace.go:171","msg":"trace[977658398] linearizableReadLoop","detail":"{readStateIndex:768; appliedIndex:767; }","duration":"126.545889ms","start":"2026-09-19T00:56:08.104719Z","end":"2026-09-19T00:56:08.231265Z","steps":["trace[977658398] 'read index received' (duration: 118.636144ms)","trace[977658398] 'applied index is now lower than readState.Index' (duration: 7.908745ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T00:56:08.231404Z","caller":"traceutil/trace.go:171","msg":"trace[42983598] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"137.259609ms","start":"2026-09-19T00:56:08.09411Z","end":"2026-09-19T00:56:08.231369Z","steps":["trace[42983598] 'process raft request' (duration: 129.22893ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:56:08.231688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.83948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T00:56:08.23186Z","caller":"traceutil/trace.go:171","msg":"trace[2065692117] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:741; }","duration":"127.076408ms","start":"2026-09-19T00:56:08.104679Z","end":"2026-09-19T00:56:08.231755Z","steps":["trace[2065692117] 'agreement among raft nodes before linearized reading' (duration: 126.748936ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:56:08.250769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.927985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-19T00:56:08.250833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.866766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-19T00:56:08.250845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.349871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T00:56:08.250866Z","caller":"traceutil/trace.go:171","msg":"trace[638089450] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:742; }","duration":"128.935779ms","start":"2026-09-19T00:56:08.121919Z","end":"2026-09-19T00:56:08.250855Z","steps":["trace[638089450] 'agreement among raft nodes before linearized reading' (duration: 128.894357ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:56:08.250892Z","caller":"traceutil/trace.go:171","msg":"trace[2025271449] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:0; response_revision:742; }","duration":"145.412633ms","start":"2026-09-19T00:56:08.105465Z","end":"2026-09-19T00:56:08.250878Z","steps":["trace[2025271449] 'agreement among raft nodes before linearized reading' (duration: 145.352161ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:56:08.250865Z","caller":"traceutil/trace.go:171","msg":"trace[358183301] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:742; }","duration":"112.055859ms","start":"2026-09-19T00:56:08.13878Z","end":"2026-09-19T00:56:08.250836Z","steps":["trace[358183301] 'agreement among raft nodes before linearized reading' (duration: 111.894863ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:56:08.25107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.290936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T00:56:08.251183Z","caller":"traceutil/trace.go:171","msg":"trace[535387276] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:742; }","duration":"146.460502ms","start":"2026-09-19T00:56:08.1047Z","end":"2026-09-19T00:56:08.251161Z","steps":["trace[535387276] 'agreement among raft nodes before linearized reading' (duration: 146.301746ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:56:08.250769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.879757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-19T00:56:08.251296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.689484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T00:56:08.251356Z","caller":"traceutil/trace.go:171","msg":"trace[1014600824] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:742; }","duration":"129.475999ms","start":"2026-09-19T00:56:08.121869Z","end":"2026-09-19T00:56:08.251345Z","steps":["trace[1014600824] 'agreement among raft nodes before linearized reading' (duration: 128.853396ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:56:08.251309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.231625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-19T00:56:08.250769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.376578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T00:56:08.251476Z","caller":"traceutil/trace.go:171","msg":"trace[916573931] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:742; }","duration":"129.124596ms","start":"2026-09-19T00:56:08.122342Z","end":"2026-09-19T00:56:08.251466Z","steps":["trace[916573931] 'agreement among raft nodes before linearized reading' (duration: 128.334746ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:56:08.251448Z","caller":"traceutil/trace.go:171","msg":"trace[2118656288] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:742; }","duration":"112.394621ms","start":"2026-09-19T00:56:08.139036Z","end":"2026-09-19T00:56:08.251431Z","steps":["trace[2118656288] 'agreement among raft nodes before linearized reading' (duration: 112.229235ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:56:08.251378Z","caller":"traceutil/trace.go:171","msg":"trace[1983204393] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:742; }","duration":"111.80175ms","start":"2026-09-19T00:56:08.139558Z","end":"2026-09-19T00:56:08.25136Z","steps":["trace[1983204393] 'agreement among raft nodes before linearized reading' (duration: 111.691456ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:56:15.410848Z","caller":"traceutil/trace.go:171","msg":"trace[1387491559] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"112.506649ms","start":"2026-09-19T00:56:15.298304Z","end":"2026-09-19T00:56:15.41081Z","steps":["trace[1387491559] 'process raft request' (duration: 110.342988ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:57:14.768393Z","caller":"traceutil/trace.go:171","msg":"trace[117797590] transaction","detail":"{read_only:false; response_revision:1499; number_of_response:1; }","duration":"156.467189ms","start":"2026-09-19T00:57:14.611896Z","end":"2026-09-19T00:57:14.768363Z","steps":["trace[117797590] 'process raft request' (duration: 156.259715ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:57:14.836016Z","caller":"traceutil/trace.go:171","msg":"trace[1344560634] transaction","detail":"{read_only:false; response_revision:1500; number_of_response:1; }","duration":"202.058467ms","start":"2026-09-19T00:57:14.633845Z","end":"2026-09-19T00:57:14.835903Z","steps":["trace[1344560634] 'process raft request' (duration: 201.752427ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:57:15.01454Z","caller":"traceutil/trace.go:171","msg":"trace[1250695010] linearizableReadLoop","detail":"{readStateIndex:1550; appliedIndex:1549; }","duration":"167.371027ms","start":"2026-09-19T00:57:14.847127Z","end":"2026-09-19T00:57:15.014498Z","steps":["trace[1250695010] 'read index received' (duration: 155.962555ms)","trace[1250695010] 'applied index is now lower than readState.Index' (duration: 11.406342ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T00:57:15.014861Z","caller":"traceutil/trace.go:171","msg":"trace[58684716] transaction","detail":"{read_only:false; response_revision:1501; number_of_response:1; }","duration":"168.584898ms","start":"2026-09-19T00:57:14.84624Z","end":"2026-09-19T00:57:15.014825Z","steps":["trace[58684716] 'process raft request' (duration: 156.869975ms)","trace[58684716] 'compare' (duration: 11.031178ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T00:57:15.01496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.778775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:6399"} {"level":"info","ts":"2026-09-19T00:57:15.015007Z","caller":"traceutil/trace.go:171","msg":"trace[12335746] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1501; }","duration":"167.947216ms","start":"2026-09-19T00:57:14.847044Z","end":"2026-09-19T00:57:15.014991Z","steps":["trace[12335746] 'agreement among raft nodes before linearized reading' (duration: 167.597093ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:57:40.072894Z","caller":"traceutil/trace.go:171","msg":"trace[1241367167] linearizableReadLoop","detail":"{readStateIndex:1977; appliedIndex:1976; }","duration":"332.210491ms","start":"2026-09-19T00:57:39.740662Z","end":"2026-09-19T00:57:40.072872Z","steps":["trace[1241367167] 'read index received' (duration: 332.003385ms)","trace[1241367167] 'applied index is now lower than readState.Index' (duration: 205.866µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T00:57:40.073023Z","caller":"traceutil/trace.go:171","msg":"trace[1948338351] transaction","detail":"{read_only:false; response_revision:1900; number_of_response:1; }","duration":"343.849098ms","start":"2026-09-19T00:57:39.729127Z","end":"2026-09-19T00:57:40.072976Z","steps":["trace[1948338351] 'process raft request' (duration: 343.594939ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:57:40.073243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:57:39.729082Z","time spent":"344.026571ms","remote":"127.0.0.1:42368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T00:57:40.073193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.517963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-09-19T00:57:40.073423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.614242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18d692ef38c9df97\" ","response":"range_response_count:1 size:982"} {"level":"info","ts":"2026-09-19T00:57:40.073512Z","caller":"traceutil/trace.go:171","msg":"trace[531923473] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1900; }","duration":"332.713228ms","start":"2026-09-19T00:57:39.740603Z","end":"2026-09-19T00:57:40.073317Z","steps":["trace[531923473] 'agreement among raft nodes before linearized reading' (duration: 332.413977ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:57:40.073529Z","caller":"traceutil/trace.go:171","msg":"trace[1166365214] range","detail":"{range_begin:/registry/events/openstack/datadir-percona-xtradb-pxc-0.18d692ef38c9df97; range_end:; response_count:1; response_revision:1900; }","duration":"184.777123ms","start":"2026-09-19T00:57:39.888723Z","end":"2026-09-19T00:57:40.0735Z","steps":["trace[1166365214] 'agreement among raft nodes before linearized reading' (duration: 184.491603ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:57:40.073722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.539473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-09-19T00:57:40.073789Z","caller":"traceutil/trace.go:171","msg":"trace[785957910] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1900; }","duration":"134.628649ms","start":"2026-09-19T00:57:39.939142Z","end":"2026-09-19T00:57:40.073771Z","steps":["trace[785957910] 'agreement among raft nodes before linearized reading' (duration: 134.393232ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:57:40.073574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:57:39.740581Z","time spent":"332.980427ms","remote":"127.0.0.1:42446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-19T00:57:40.949831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:57:40.564706Z","time spent":"385.114887ms","remote":"127.0.0.1:53642","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-09-19T00:57:40.950418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.849904ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T00:57:40.9506Z","caller":"traceutil/trace.go:171","msg":"trace[1884113541] transaction","detail":"{read_only:false; response_revision:1901; number_of_response:1; }","duration":"874.769135ms","start":"2026-09-19T00:57:40.075807Z","end":"2026-09-19T00:57:40.950577Z","steps":["trace[1884113541] 'process raft request' (duration: 398.668043ms)","trace[1884113541] 'compare' (duration: 475.726315ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T00:57:40.950667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:57:40.075761Z","time spent":"874.876563ms","remote":"127.0.0.1:42242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":967,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T00:57:40.950715Z","caller":"traceutil/trace.go:171","msg":"trace[2015011603] linearizableReadLoop","detail":"{readStateIndex:1978; appliedIndex:1977; }","duration":"874.816758ms","start":"2026-09-19T00:57:40.075839Z","end":"2026-09-19T00:57:40.950655Z","steps":["trace[2015011603] 'read index received' (duration: 398.653742ms)","trace[2015011603] 'applied index is now lower than readState.Index' (duration: 476.158236ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T00:57:40.951859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"875.996284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T00:57:40.952006Z","caller":"traceutil/trace.go:171","msg":"trace[1245159051] transaction","detail":"{read_only:false; response_revision:1902; number_of_response:1; }","duration":"872.156297ms","start":"2026-09-19T00:57:40.079719Z","end":"2026-09-19T00:57:40.951876Z","steps":["trace[1245159051] 'process raft request' (duration: 870.797439ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:57:40.95193Z","caller":"traceutil/trace.go:171","msg":"trace[1178921589] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1902; }","duration":"876.10115ms","start":"2026-09-19T00:57:40.07581Z","end":"2026-09-19T00:57:40.951911Z","steps":["trace[1178921589] 'agreement among raft nodes before linearized reading' (duration: 875.12585ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:57:40.952118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:57:40.075787Z","time spent":"876.313986ms","remote":"127.0.0.1:42164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-19T00:57:40.952288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:57:40.079683Z","time spent":"872.452449ms","remote":"127.0.0.1:42446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T00:57:40.953473Z","caller":"traceutil/trace.go:171","msg":"trace[1046623514] transaction","detail":"{read_only:false; response_revision:1903; number_of_response:1; }","duration":"873.640325ms","start":"2026-09-19T00:57:40.079807Z","end":"2026-09-19T00:57:40.953448Z","steps":["trace[1046623514] 'process raft request' (duration: 871.691924ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:57:40.953583Z","caller":"traceutil/trace.go:171","msg":"trace[1241827829] transaction","detail":"{read_only:false; response_revision:1904; number_of_response:1; }","duration":"703.371367ms","start":"2026-09-19T00:57:40.250187Z","end":"2026-09-19T00:57:40.953559Z","steps":["trace[1241827829] 'process raft request' (duration: 703.082906ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:57:40.953638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:57:40.079785Z","time spent":"873.746992ms","remote":"127.0.0.1:42446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T00:57:40.954024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:57:40.250161Z","time spent":"703.581272ms","remote":"127.0.0.1:42446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T00:57:40.986671Z","caller":"traceutil/trace.go:171","msg":"trace[2069413287] transaction","detail":"{read_only:false; response_revision:1905; number_of_response:1; }","duration":"270.482338ms","start":"2026-09-19T00:57:40.716144Z","end":"2026-09-19T00:57:40.986627Z","steps":["trace[2069413287] 'process raft request' (duration: 270.277513ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:57:40.986718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"849.873672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bd44a8ba-ae8d-4a2c-aa37-daa188891dcd\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-09-19T00:57:40.986823Z","caller":"traceutil/trace.go:171","msg":"trace[1879037594] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-bd44a8ba-ae8d-4a2c-aa37-daa188891dcd; range_end:; response_count:1; response_revision:1905; }","duration":"850.032624ms","start":"2026-09-19T00:57:40.136772Z","end":"2026-09-19T00:57:40.986805Z","steps":["trace[1879037594] 'agreement among raft nodes before linearized reading' (duration: 849.759294ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:57:40.986967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.09499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-09-19T00:57:40.986936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:57:40.136748Z","time spent":"850.166134ms","remote":"127.0.0.1:42368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3769,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bd44a8ba-ae8d-4a2c-aa37-daa188891dcd\" "} {"level":"info","ts":"2026-09-19T00:57:40.987054Z","caller":"traceutil/trace.go:171","msg":"trace[1055521475] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1905; }","duration":"236.290444ms","start":"2026-09-19T00:57:40.750745Z","end":"2026-09-19T00:57:40.987036Z","steps":["trace[1055521475] 'agreement among raft nodes before linearized reading' (duration: 236.136803ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:57:40.987146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.484754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-09-19T00:57:40.987002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.522188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T00:57:40.9872Z","caller":"traceutil/trace.go:171","msg":"trace[1815945774] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1905; }","duration":"488.793458ms","start":"2026-09-19T00:57:40.498395Z","end":"2026-09-19T00:57:40.987189Z","steps":["trace[1815945774] 'agreement among raft nodes before linearized reading' (duration: 488.501297ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:57:40.987192Z","caller":"traceutil/trace.go:171","msg":"trace[1309145028] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1905; }","duration":"346.614123ms","start":"2026-09-19T00:57:40.640563Z","end":"2026-09-19T00:57:40.987177Z","steps":["trace[1309145028] 'agreement among raft nodes before linearized reading' (duration: 346.467762ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:57:40.987242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:57:40.498365Z","time spent":"488.861032ms","remote":"127.0.0.1:42322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-09-19T00:57:40.987262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:57:40.640518Z","time spent":"346.734382ms","remote":"127.0.0.1:38624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5466,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-09-19T00:57:40.987512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.23322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-09-19T00:57:40.987573Z","caller":"traceutil/trace.go:171","msg":"trace[1078273015] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1905; }","duration":"519.347098ms","start":"2026-09-19T00:57:40.468209Z","end":"2026-09-19T00:57:40.987556Z","steps":["trace[1078273015] 'agreement among raft nodes before linearized reading' (duration: 519.164795ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:57:40.986838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.75988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-09-19T00:57:40.987616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T00:57:40.46818Z","time spent":"519.424053ms","remote":"127.0.0.1:42446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-09-19T00:57:40.987715Z","caller":"traceutil/trace.go:171","msg":"trace[790269934] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1905; }","duration":"238.671525ms","start":"2026-09-19T00:57:40.749013Z","end":"2026-09-19T00:57:40.987684Z","steps":["trace[790269934] 'agreement among raft nodes before linearized reading' (duration: 237.545464ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:15.500357Z","caller":"traceutil/trace.go:171","msg":"trace[1840181945] transaction","detail":"{read_only:false; response_revision:2152; number_of_response:1; }","duration":"193.178712ms","start":"2026-09-19T00:58:15.307142Z","end":"2026-09-19T00:58:15.500321Z","steps":["trace[1840181945] 'process raft request' (duration: 192.965075ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:15.500529Z","caller":"traceutil/trace.go:171","msg":"trace[589369103] linearizableReadLoop","detail":"{readStateIndex:2236; appliedIndex:2235; }","duration":"183.790512ms","start":"2026-09-19T00:58:15.316616Z","end":"2026-09-19T00:58:15.500407Z","steps":["trace[589369103] 'read index received' (duration: 183.556604ms)","trace[589369103] 'applied index is now lower than readState.Index' (duration: 231.168µs)"],"step_count":2} {"level":"warn","ts":"2026-09-19T00:58:15.500735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.121307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-09-19T00:58:15.500821Z","caller":"traceutil/trace.go:171","msg":"trace[919352768] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2152; }","duration":"184.258348ms","start":"2026-09-19T00:58:15.31654Z","end":"2026-09-19T00:58:15.500798Z","steps":["trace[919352768] 'agreement among raft nodes before linearized reading' (duration: 183.985776ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:15.519939Z","caller":"traceutil/trace.go:171","msg":"trace[355197776] transaction","detail":"{read_only:false; response_revision:2153; number_of_response:1; }","duration":"134.458385ms","start":"2026-09-19T00:58:15.385462Z","end":"2026-09-19T00:58:15.51992Z","steps":["trace[355197776] 'process raft request' (duration: 134.347477ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:58:16.506459Z","caller":"traceutil/trace.go:171","msg":"trace[1472364348] transaction","detail":"{read_only:false; response_revision:2158; number_of_response:1; }","duration":"161.864769ms","start":"2026-09-19T00:58:16.344553Z","end":"2026-09-19T00:58:16.506418Z","steps":["trace[1472364348] 'process raft request' (duration: 161.648963ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:59:23.695519Z","caller":"traceutil/trace.go:171","msg":"trace[357290608] linearizableReadLoop","detail":"{readStateIndex:2717; appliedIndex:2716; }","duration":"204.796372ms","start":"2026-09-19T00:59:23.490688Z","end":"2026-09-19T00:59:23.695484Z","steps":["trace[357290608] 'read index received' (duration: 204.474432ms)","trace[357290608] 'applied index is now lower than readState.Index' (duration: 320.78µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T00:59:23.695628Z","caller":"traceutil/trace.go:171","msg":"trace[847832823] transaction","detail":"{read_only:false; response_revision:2618; number_of_response:1; }","duration":"244.092945ms","start":"2026-09-19T00:59:23.451509Z","end":"2026-09-19T00:59:23.695602Z","steps":["trace[847832823] 'process raft request' (duration: 243.733026ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:59:23.695852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.143523ms","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-09-19T00:59:23.695897Z","caller":"traceutil/trace.go:171","msg":"trace[1623702080] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2618; }","duration":"205.273598ms","start":"2026-09-19T00:59:23.490612Z","end":"2026-09-19T00:59:23.695885Z","steps":["trace[1623702080] 'agreement among raft nodes before linearized reading' (duration: 205.058385ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:59:23.695978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.750799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-09-19T00:59:23.696071Z","caller":"traceutil/trace.go:171","msg":"trace[1167421384] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2618; }","duration":"174.895744ms","start":"2026-09-19T00:59:23.521157Z","end":"2026-09-19T00:59:23.696053Z","steps":["trace[1167421384] 'agreement among raft nodes before linearized reading' (duration: 174.640512ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:59:23.696108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.223444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-19T00:59:23.696201Z","caller":"traceutil/trace.go:171","msg":"trace[1615575527] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2618; }","duration":"116.36604ms","start":"2026-09-19T00:59:23.579811Z","end":"2026-09-19T00:59:23.696177Z","steps":["trace[1615575527] 'agreement among raft nodes before linearized reading' (duration: 116.108727ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T00:59:58.133255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.246908ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T00:59:58.133611Z","caller":"traceutil/trace.go:171","msg":"trace[2142538198] transaction","detail":"{read_only:false; response_revision:2813; number_of_response:1; }","duration":"177.55899ms","start":"2026-09-19T00:59:57.95603Z","end":"2026-09-19T00:59:58.133589Z","steps":["trace[2142538198] 'process raft request' (duration: 53.878135ms)","trace[2142538198] 'compare' (duration: 123.086758ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T00:59:58.133906Z","caller":"traceutil/trace.go:171","msg":"trace[2115905219] transaction","detail":"{read_only:false; response_revision:2814; number_of_response:1; }","duration":"157.869077ms","start":"2026-09-19T00:59:57.975999Z","end":"2026-09-19T00:59:58.133868Z","steps":["trace[2115905219] 'process raft request' (duration: 157.46646ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T00:59:58.656168Z","caller":"traceutil/trace.go:171","msg":"trace[2066329071] transaction","detail":"{read_only:false; response_revision:2817; number_of_response:1; }","duration":"156.86291ms","start":"2026-09-19T00:59:58.499279Z","end":"2026-09-19T00:59:58.656142Z","steps":["trace[2066329071] 'process raft request' (duration: 156.724071ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:04:31.971337Z","caller":"traceutil/trace.go:171","msg":"trace[993028653] linearizableReadLoop","detail":"{readStateIndex:5034; appliedIndex:5033; }","duration":"120.367725ms","start":"2026-09-19T01:04:31.850911Z","end":"2026-09-19T01:04:31.971278Z","steps":["trace[993028653] 'read index received' (duration: 120.158252ms)","trace[993028653] 'applied index is now lower than readState.Index' (duration: 208.243µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:04:31.971399Z","caller":"traceutil/trace.go:171","msg":"trace[1052228960] transaction","detail":"{read_only:false; response_revision:4849; number_of_response:1; }","duration":"198.989359ms","start":"2026-09-19T01:04:31.772382Z","end":"2026-09-19T01:04:31.971371Z","steps":["trace[1052228960] 'process raft request' (duration: 198.730174ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:04:31.971657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.697294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1d96fedc-68bf-4b87-85fe-9efc39d3d939\" ","response":"range_response_count:1 size:3774"} {"level":"info","ts":"2026-09-19T01:04:31.971941Z","caller":"traceutil/trace.go:171","msg":"trace[1791711393] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1d96fedc-68bf-4b87-85fe-9efc39d3d939; range_end:; response_count:1; response_revision:4849; }","duration":"120.996922ms","start":"2026-09-19T01:04:31.850871Z","end":"2026-09-19T01:04:31.971868Z","steps":["trace[1791711393] 'agreement among raft nodes before linearized reading' (duration: 120.592738ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:04:32.007724Z","caller":"traceutil/trace.go:171","msg":"trace[451182048] transaction","detail":"{read_only:false; response_revision:4850; number_of_response:1; }","duration":"134.337614ms","start":"2026-09-19T01:04:31.873346Z","end":"2026-09-19T01:04:32.007683Z","steps":["trace[451182048] 'process raft request' (duration: 134.078728ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:04:32.007862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.008867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1d96fedc-68bf-4b87-85fe-9efc39d3d939\" ","response":"range_response_count:1 size:3774"} {"level":"info","ts":"2026-09-19T01:04:32.009089Z","caller":"traceutil/trace.go:171","msg":"trace[18883954] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1d96fedc-68bf-4b87-85fe-9efc39d3d939; range_end:; response_count:1; response_revision:4852; }","duration":"154.23689ms","start":"2026-09-19T01:04:31.854808Z","end":"2026-09-19T01:04:32.009044Z","steps":["trace[18883954] 'agreement among raft nodes before linearized reading' (duration: 152.907011ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:04:34.286869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.855397ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-09-19T01:04:39.360381Z","caller":"traceutil/trace.go:171","msg":"trace[1622443993] transaction","detail":"{read_only:false; response_revision:4978; number_of_response:1; }","duration":"161.822153ms","start":"2026-09-19T01:04:39.198528Z","end":"2026-09-19T01:04:39.36035Z","steps":["trace[1622443993] 'process raft request' (duration: 161.621741ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:04:39.382149Z","caller":"traceutil/trace.go:171","msg":"trace[1956156211] transaction","detail":"{read_only:false; response_revision:4979; number_of_response:1; }","duration":"100.191056ms","start":"2026-09-19T01:04:39.281904Z","end":"2026-09-19T01:04:39.382095Z","steps":["trace[1956156211] 'process raft request' (duration: 100.031596ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:04:39.534832Z","caller":"traceutil/trace.go:171","msg":"trace[1226684707] transaction","detail":"{read_only:false; response_revision:4980; number_of_response:1; }","duration":"142.943217ms","start":"2026-09-19T01:04:39.39185Z","end":"2026-09-19T01:04:39.534793Z","steps":["trace[1226684707] 'process raft request' (duration: 79.514232ms)","trace[1226684707] 'compare' (duration: 62.930325ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:04:43.167532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.116803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-15c5b8aa-12b1-4293-9386-702c866d6522\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-09-19T01:04:43.167649Z","caller":"traceutil/trace.go:171","msg":"trace[232905165] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-15c5b8aa-12b1-4293-9386-702c866d6522; range_end:; response_count:1; response_revision:5061; }","duration":"177.310875ms","start":"2026-09-19T01:04:42.990314Z","end":"2026-09-19T01:04:43.167625Z","steps":["trace[232905165] 'range keys from in-memory index tree' (duration: 176.813875ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:04:43.167807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.103959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:04:43.167908Z","caller":"traceutil/trace.go:171","msg":"trace[738643427] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5061; }","duration":"149.2841ms","start":"2026-09-19T01:04:43.018596Z","end":"2026-09-19T01:04:43.16788Z","steps":["trace[738643427] 'range keys from in-memory index tree' (duration: 148.901767ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:04:43.168051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.248294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-15c5b8aa-12b1-4293-9386-702c866d6522\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-09-19T01:04:43.16821Z","caller":"traceutil/trace.go:171","msg":"trace[850856713] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-15c5b8aa-12b1-4293-9386-702c866d6522; range_end:; response_count:1; response_revision:5061; }","duration":"169.50477ms","start":"2026-09-19T01:04:42.998655Z","end":"2026-09-19T01:04:43.16816Z","steps":["trace[850856713] 'range keys from in-memory index tree' (duration: 168.944026ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:04:43.288853Z","caller":"traceutil/trace.go:171","msg":"trace[1469339661] transaction","detail":"{read_only:false; response_revision:5062; number_of_response:1; }","duration":"112.66555ms","start":"2026-09-19T01:04:43.176083Z","end":"2026-09-19T01:04:43.288749Z","steps":["trace[1469339661] 'process raft request' (duration: 112.462348ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:04:58.049947Z","caller":"traceutil/trace.go:171","msg":"trace[994671684] linearizableReadLoop","detail":"{readStateIndex:5433; appliedIndex:5432; }","duration":"489.761432ms","start":"2026-09-19T01:04:57.560151Z","end":"2026-09-19T01:04:58.049912Z","steps":["trace[994671684] 'read index received' (duration: 489.531287ms)","trace[994671684] 'applied index is now lower than readState.Index' (duration: 228.755µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:04:58.05004Z","caller":"traceutil/trace.go:171","msg":"trace[279027998] transaction","detail":"{read_only:false; response_revision:5234; number_of_response:1; }","duration":"498.191543ms","start":"2026-09-19T01:04:57.551814Z","end":"2026-09-19T01:04:58.050005Z","steps":["trace[279027998] 'process raft request' (duration: 497.920305ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:04:58.050224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:04:57.551786Z","time spent":"498.334391ms","remote":"127.0.0.1:42446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T01:04:58.050308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.094843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls\" ","response":"range_response_count:1 size:4783"} {"level":"info","ts":"2026-09-19T01:04:58.050356Z","caller":"traceutil/trace.go:171","msg":"trace[2039565287] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls; range_end:; response_count:1; response_revision:5234; }","duration":"490.22393ms","start":"2026-09-19T01:04:57.560117Z","end":"2026-09-19T01:04:58.050341Z","steps":["trace[2039565287] 'agreement among raft nodes before linearized reading' (duration: 489.948513ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:04:58.050392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:04:57.560104Z","time spent":"490.278544ms","remote":"127.0.0.1:42252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":4805,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls\" "} {"level":"info","ts":"2026-09-19T01:04:58.217122Z","caller":"traceutil/trace.go:171","msg":"trace[1952247399] transaction","detail":"{read_only:false; response_revision:5235; number_of_response:1; }","duration":"565.38785ms","start":"2026-09-19T01:04:57.651661Z","end":"2026-09-19T01:04:58.217049Z","steps":["trace[1952247399] 'process raft request' (duration: 483.268807ms)","trace[1952247399] 'compare' (duration: 81.884548ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:04:58.217384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.301086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-19T01:04:58.217449Z","caller":"traceutil/trace.go:171","msg":"trace[1826343383] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5235; }","duration":"357.405661ms","start":"2026-09-19T01:04:57.860028Z","end":"2026-09-19T01:04:58.217433Z","steps":["trace[1826343383] 'agreement among raft nodes before linearized reading' (duration: 357.176387ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:04:58.217434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:04:57.651624Z","time spent":"565.604753ms","remote":"127.0.0.1:42446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T01:04:58.217497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:04:57.860013Z","time spent":"357.472115ms","remote":"127.0.0.1:42446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-09-19T01:04:58.21739Z","caller":"traceutil/trace.go:171","msg":"trace[1968898691] linearizableReadLoop","detail":"{readStateIndex:5434; appliedIndex:5433; }","duration":"167.161356ms","start":"2026-09-19T01:04:58.050037Z","end":"2026-09-19T01:04:58.217198Z","steps":["trace[1968898691] 'read index received' (duration: 84.792907ms)","trace[1968898691] 'applied index is now lower than readState.Index' (duration: 82.365379ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:04:58.217705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.054918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-19T01:04:58.217802Z","caller":"traceutil/trace.go:171","msg":"trace[1464678034] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5235; }","duration":"379.25184ms","start":"2026-09-19T01:04:57.838529Z","end":"2026-09-19T01:04:58.217781Z","steps":["trace[1464678034] 'agreement among raft nodes before linearized reading' (duration: 378.903729ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:04:58.21785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:04:57.838472Z","time spent":"379.368697ms","remote":"127.0.0.1:42446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-19T01:04:58.217954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.138565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-09-19T01:04:58.218018Z","caller":"traceutil/trace.go:171","msg":"trace[1170786812] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5235; }","duration":"161.283544ms","start":"2026-09-19T01:04:58.056716Z","end":"2026-09-19T01:04:58.218Z","steps":["trace[1170786812] 'agreement among raft nodes before linearized reading' (duration: 161.138884ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:04:58.217992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.170343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-5fb65b4576-74ffs\" ","response":"range_response_count:1 size:11848"} {"level":"info","ts":"2026-09-19T01:04:58.218098Z","caller":"traceutil/trace.go:171","msg":"trace[1664095154] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-grafana-5fb65b4576-74ffs; range_end:; response_count:1; response_revision:5235; }","duration":"198.337933ms","start":"2026-09-19T01:04:58.019731Z","end":"2026-09-19T01:04:58.218069Z","steps":["trace[1664095154] 'agreement among raft nodes before linearized reading' (duration: 197.827461ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:04:58.21757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.134053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-19T01:04:58.218201Z","caller":"traceutil/trace.go:171","msg":"trace[938930773] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5235; }","duration":"362.790875ms","start":"2026-09-19T01:04:57.855374Z","end":"2026-09-19T01:04:58.218164Z","steps":["trace[938930773] 'agreement among raft nodes before linearized reading' (duration: 361.948632ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:04:58.218759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:04:57.855351Z","time spent":"363.03625ms","remote":"127.0.0.1:42446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-19T01:04:58.220074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.80097ms","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-09-19T01:04:58.220199Z","caller":"traceutil/trace.go:171","msg":"trace[1348903733] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5235; }","duration":"545.927768ms","start":"2026-09-19T01:04:57.674253Z","end":"2026-09-19T01:04:58.22018Z","steps":["trace[1348903733] 'agreement among raft nodes before linearized reading' (duration: 543.475246ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:04:58.22031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:04:57.674175Z","time spent":"546.11873ms","remote":"127.0.0.1:42446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-09-19T01:04:58.356769Z","caller":"traceutil/trace.go:171","msg":"trace[1929413999] transaction","detail":"{read_only:false; response_revision:5237; number_of_response:1; }","duration":"131.098989ms","start":"2026-09-19T01:04:58.225624Z","end":"2026-09-19T01:04:58.356723Z","steps":["trace[1929413999] 'process raft request' (duration: 110.391871ms)","trace[1929413999] 'compare' (duration: 20.143764ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:04:58.356809Z","caller":"traceutil/trace.go:171","msg":"trace[1350655979] transaction","detail":"{read_only:false; response_revision:5239; number_of_response:1; }","duration":"130.925348ms","start":"2026-09-19T01:04:58.225845Z","end":"2026-09-19T01:04:58.35677Z","steps":["trace[1350655979] 'process raft request' (duration: 130.750237ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:04:58.356879Z","caller":"traceutil/trace.go:171","msg":"trace[187710102] transaction","detail":"{read_only:false; response_revision:5240; number_of_response:1; }","duration":"130.95914ms","start":"2026-09-19T01:04:58.225904Z","end":"2026-09-19T01:04:58.356863Z","steps":["trace[187710102] 'process raft request' (duration: 130.726666ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:04:58.356814Z","caller":"traceutil/trace.go:171","msg":"trace[186888380] transaction","detail":"{read_only:false; response_revision:5238; number_of_response:1; }","duration":"131.029384ms","start":"2026-09-19T01:04:58.225761Z","end":"2026-09-19T01:04:58.356791Z","steps":["trace[186888380] 'process raft request' (duration: 130.700914ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:04:58.357069Z","caller":"traceutil/trace.go:171","msg":"trace[422213399] transaction","detail":"{read_only:false; response_revision:5241; number_of_response:1; }","duration":"123.368391ms","start":"2026-09-19T01:04:58.233614Z","end":"2026-09-19T01:04:58.356982Z","steps":["trace[422213399] 'process raft request' (duration: 123.076953ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:04:58.570761Z","caller":"traceutil/trace.go:171","msg":"trace[180494994] linearizableReadLoop","detail":"{readStateIndex:5444; appliedIndex:5443; }","duration":"150.237261ms","start":"2026-09-19T01:04:58.420489Z","end":"2026-09-19T01:04:58.570726Z","steps":["trace[180494994] 'read index received' (duration: 128.938435ms)","trace[180494994] 'applied index is now lower than readState.Index' (duration: 21.297686ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:04:58.570953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.422383ms","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-09-19T01:04:58.571043Z","caller":"traceutil/trace.go:171","msg":"trace[1922965558] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:5245; }","duration":"150.587233ms","start":"2026-09-19T01:04:58.420439Z","end":"2026-09-19T01:04:58.571026Z","steps":["trace[1922965558] 'agreement among raft nodes before linearized reading' (duration: 150.401951ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:04:58.571417Z","caller":"traceutil/trace.go:171","msg":"trace[1573837488] transaction","detail":"{read_only:false; response_revision:5245; number_of_response:1; }","duration":"170.012963ms","start":"2026-09-19T01:04:58.400779Z","end":"2026-09-19T01:04:58.570791Z","steps":["trace[1573837488] 'process raft request' (duration: 148.561198ms)","trace[1573837488] 'compare' (duration: 21.228411ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:04:58.591611Z","caller":"traceutil/trace.go:171","msg":"trace[417245350] transaction","detail":"{read_only:false; response_revision:5246; number_of_response:1; }","duration":"158.451449ms","start":"2026-09-19T01:04:58.433134Z","end":"2026-09-19T01:04:58.591585Z","steps":["trace[417245350] 'process raft request' (duration: 158.269828ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:05:15.355632Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2902} {"level":"info","ts":"2026-09-19T01:05:15.47516Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2902,"took":"117.856546ms","hash":542766524,"current-db-size-bytes":52097024,"current-db-size":"52 MB","current-db-size-in-use-bytes":27627520,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-19T01:05:15.475248Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":542766524,"revision":2902,"compact-revision":-1} {"level":"info","ts":"2026-09-19T01:10:15.362702Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5379} {"level":"info","ts":"2026-09-19T01:10:15.442366Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5379,"took":"78.09306ms","hash":628768920,"current-db-size-bytes":52097024,"current-db-size":"52 MB","current-db-size-in-use-bytes":18800640,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-19T01:10:15.442488Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":628768920,"revision":5379,"compact-revision":2902}