{"level":"warn","ts":"2026-09-23T16:41:11.090313Z","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-23T16:41:11.090467Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.111: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.111:2380","--initial-cluster=instance=https://162.253.55.111:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.111:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.111: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-23T16:41:11.090606Z","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-23T16:41:11.090635Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.111:2380"]} {"level":"info","ts":"2026-09-23T16:41:11.090676Z","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-23T16:41:11.091813Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.111:2379"]} {"level":"info","ts":"2026-09-23T16:41:11.091986Z","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.111:2380"],"listen-peer-urls":["https://162.253.55.111:2380"],"advertise-client-urls":["https://162.253.55.111:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.111:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.111: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-23T16:41:11.103606Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"10.590193ms"} {"level":"info","ts":"2026-09-23T16:41:11.112882Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"299bf3744060cbd","cluster-id":"83d94f04ddaf7996"} {"level":"info","ts":"2026-09-23T16:41:11.112972Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"299bf3744060cbd switched to configuration voters=()"} {"level":"info","ts":"2026-09-23T16:41:11.113012Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"299bf3744060cbd became follower at term 0"} {"level":"info","ts":"2026-09-23T16:41:11.113036Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 299bf3744060cbd [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-23T16:41:11.113056Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"299bf3744060cbd became follower at term 1"} {"level":"info","ts":"2026-09-23T16:41:11.113118Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"299bf3744060cbd switched to configuration voters=(187391103597939901)"} {"level":"warn","ts":"2026-09-23T16:41:11.127166Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-23T16:41:11.129262Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-23T16:41:11.130993Z","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-23T16:41:11.134071Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"299bf3744060cbd","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-23T16:41:11.136437Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"299bf3744060cbd","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-23T16:41:11.136555Z","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-23T16:41:11.136591Z","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-23T16:41:11.136609Z","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-23T16:41:11.144212Z","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-23T16:41:11.144449Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"299bf3744060cbd","initial-advertise-peer-urls":["https://162.253.55.111:2380"],"listen-peer-urls":["https://162.253.55.111:2380"],"advertise-client-urls":["https://162.253.55.111:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.111:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-23T16:41:11.144483Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-23T16:41:11.144618Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"299bf3744060cbd switched to configuration voters=(187391103597939901)"} {"level":"info","ts":"2026-09-23T16:41:11.14474Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"83d94f04ddaf7996","local-member-id":"299bf3744060cbd","added-peer-id":"299bf3744060cbd","added-peer-peer-urls":["https://162.253.55.111:2380"]} {"level":"info","ts":"2026-09-23T16:41:11.144824Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.111:2380"} {"level":"info","ts":"2026-09-23T16:41:11.144839Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.111:2380"} {"level":"info","ts":"2026-09-23T16:41:11.314826Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"299bf3744060cbd is starting a new election at term 1"} {"level":"info","ts":"2026-09-23T16:41:11.314916Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"299bf3744060cbd became pre-candidate at term 1"} {"level":"info","ts":"2026-09-23T16:41:11.314946Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"299bf3744060cbd received MsgPreVoteResp from 299bf3744060cbd at term 1"} {"level":"info","ts":"2026-09-23T16:41:11.314973Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"299bf3744060cbd became candidate at term 2"} {"level":"info","ts":"2026-09-23T16:41:11.315042Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"299bf3744060cbd received MsgVoteResp from 299bf3744060cbd at term 2"} {"level":"info","ts":"2026-09-23T16:41:11.315123Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"299bf3744060cbd became leader at term 2"} {"level":"info","ts":"2026-09-23T16:41:11.315147Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 299bf3744060cbd elected leader 299bf3744060cbd at term 2"} {"level":"info","ts":"2026-09-23T16:41:11.316765Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-23T16:41:11.317157Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"299bf3744060cbd","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.111:2379]}","request-path":"/0/members/299bf3744060cbd/attributes","cluster-id":"83d94f04ddaf7996","publish-timeout":"7s"} {"level":"info","ts":"2026-09-23T16:41:11.317151Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-23T16:41:11.317179Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-23T16:41:11.317714Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-23T16:41:11.317744Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-23T16:41:11.319986Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.111:2379"} {"level":"info","ts":"2026-09-23T16:41:11.320572Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"83d94f04ddaf7996","local-member-id":"299bf3744060cbd","cluster-version":"3.5"} {"level":"info","ts":"2026-09-23T16:41:11.320712Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-23T16:41:11.320755Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-23T16:41:11.321702Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-23T16:41:59.629369Z","caller":"traceutil/trace.go:171","msg":"trace[978902845] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"168.755167ms","start":"2026-09-23T16:41:59.46058Z","end":"2026-09-23T16:41:59.629335Z","steps":["trace[978902845] 'process raft request' (duration: 168.580145ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T16:41:59.64025Z","caller":"traceutil/trace.go:171","msg":"trace[605731551] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"163.176258ms","start":"2026-09-23T16:41:59.477011Z","end":"2026-09-23T16:41:59.640187Z","steps":["trace[605731551] 'process raft request' (duration: 159.199034ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T16:41:59.640151Z","caller":"traceutil/trace.go:171","msg":"trace[1689259995] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"162.566598ms","start":"2026-09-23T16:41:59.477237Z","end":"2026-09-23T16:41:59.639803Z","steps":["trace[1689259995] 'process raft request' (duration: 159.54626ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T16:41:59.640447Z","caller":"traceutil/trace.go:171","msg":"trace[1926949033] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"171.361799ms","start":"2026-09-23T16:41:59.468465Z","end":"2026-09-23T16:41:59.639827Z","steps":["trace[1926949033] 'process raft request' (duration: 167.568748ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T16:41:59.640779Z","caller":"traceutil/trace.go:171","msg":"trace[1344620199] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"123.024826ms","start":"2026-09-23T16:41:59.51772Z","end":"2026-09-23T16:41:59.640745Z","steps":["trace[1344620199] 'process raft request' (duration: 119.240756ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T16:41:59.641029Z","caller":"traceutil/trace.go:171","msg":"trace[1831148607] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"144.61415ms","start":"2026-09-23T16:41:59.496296Z","end":"2026-09-23T16:41:59.64091Z","steps":["trace[1831148607] 'process raft request' (duration: 140.557046ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T16:41:59.641668Z","caller":"traceutil/trace.go:171","msg":"trace[1577810115] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"164.153753ms","start":"2026-09-23T16:41:59.477087Z","end":"2026-09-23T16:41:59.641241Z","steps":["trace[1577810115] 'process raft request' (duration: 159.169643ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T16:41:59.641875Z","caller":"traceutil/trace.go:171","msg":"trace[1757250021] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"180.556934ms","start":"2026-09-23T16:41:59.461206Z","end":"2026-09-23T16:41:59.641763Z","steps":["trace[1757250021] 'process raft request' (duration: 173.285729ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T16:41:59.642624Z","caller":"traceutil/trace.go:171","msg":"trace[1643013667] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"128.493323ms","start":"2026-09-23T16:41:59.513714Z","end":"2026-09-23T16:41:59.642208Z","steps":["trace[1643013667] 'process raft request' (duration: 123.198378ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T16:41:59.642933Z","caller":"traceutil/trace.go:171","msg":"trace[947933254] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"100.410694ms","start":"2026-09-23T16:41:59.542329Z","end":"2026-09-23T16:41:59.64274Z","steps":["trace[947933254] 'process raft request' (duration: 95.076869ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T16:41:59.643168Z","caller":"traceutil/trace.go:171","msg":"trace[286664663] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"118.946411ms","start":"2026-09-23T16:41:59.523772Z","end":"2026-09-23T16:41:59.642718Z","steps":["trace[286664663] 'process raft request' (duration: 113.22779ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T16:42:06.023573Z","caller":"traceutil/trace.go:171","msg":"trace[2003055191] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"150.04728ms","start":"2026-09-23T16:42:05.873468Z","end":"2026-09-23T16:42:06.023515Z","steps":["trace[2003055191] 'process raft request' (duration: 149.591043ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T16:42:06.023827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.622983ms","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-23T16:42:06.024008Z","caller":"traceutil/trace.go:171","msg":"trace[236291603] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:621; }","duration":"115.841896ms","start":"2026-09-23T16:42:05.908136Z","end":"2026-09-23T16:42:06.023978Z","steps":["trace[236291603] 'agreement among raft nodes before linearized reading' (duration: 115.505831ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T16:42:06.023911Z","caller":"traceutil/trace.go:171","msg":"trace[1397928184] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:646; }","duration":"115.207517ms","start":"2026-09-23T16:42:05.908166Z","end":"2026-09-23T16:42:06.023373Z","steps":["trace[1397928184] 'read index received' (duration: 114.791341ms)","trace[1397928184] 'applied index is now lower than readState.Index' (duration: 414.846µs)"],"step_count":2} {"level":"info","ts":"2026-09-23T16:42:06.326826Z","caller":"traceutil/trace.go:171","msg":"trace[1531294284] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"279.851023ms","start":"2026-09-23T16:42:06.046936Z","end":"2026-09-23T16:42:06.326787Z","steps":["trace[1531294284] 'process raft request' (duration: 279.250734ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T16:42:06.96517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.869081ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-23T16:42:06.96534Z","caller":"traceutil/trace.go:171","msg":"trace[138998329] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:649; }","duration":"269.86172ms","start":"2026-09-23T16:42:06.695457Z","end":"2026-09-23T16:42:06.965319Z","steps":["trace[138998329] 'read index received' (duration: 121.187216ms)","trace[138998329] 'applied index is now lower than readState.Index' (duration: 148.672404ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T16:42:06.965454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.005443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-23T16:42:06.965488Z","caller":"traceutil/trace.go:171","msg":"trace[1531129771] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:624; }","duration":"270.109225ms","start":"2026-09-23T16:42:06.695368Z","end":"2026-09-23T16:42:06.965478Z","steps":["trace[1531129771] 'agreement among raft nodes before linearized reading' (duration: 270.006043ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T16:42:06.965436Z","caller":"traceutil/trace.go:171","msg":"trace[1797802315] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"560.070672ms","start":"2026-09-23T16:42:06.405281Z","end":"2026-09-23T16:42:06.965351Z","steps":["trace[1797802315] 'process raft request' (duration: 411.481009ms)","trace[1797802315] 'compare' (duration: 147.553177ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T16:42:06.965653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T16:42:06.405247Z","time spent":"560.303965ms","remote":"127.0.0.1:58092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-23T16:43:46.542736Z","caller":"traceutil/trace.go:171","msg":"trace[1029485325] transaction","detail":"{read_only:false; response_revision:1895; number_of_response:1; }","duration":"183.112948ms","start":"2026-09-23T16:43:46.359596Z","end":"2026-09-23T16:43:46.542709Z","steps":["trace[1029485325] 'process raft request' (duration: 182.948636ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T16:43:47.07171Z","caller":"traceutil/trace.go:171","msg":"trace[404547751] linearizableReadLoop","detail":"{readStateIndex:1970; appliedIndex:1969; }","duration":"442.164363ms","start":"2026-09-23T16:43:46.629522Z","end":"2026-09-23T16:43:47.071686Z","steps":["trace[404547751] 'read index received' (duration: 441.85642ms)","trace[404547751] 'applied index is now lower than readState.Index' (duration: 306.523µs)"],"step_count":2} {"level":"info","ts":"2026-09-23T16:43:47.071774Z","caller":"traceutil/trace.go:171","msg":"trace[1396038925] transaction","detail":"{read_only:false; response_revision:1896; number_of_response:1; }","duration":"510.995315ms","start":"2026-09-23T16:43:46.560751Z","end":"2026-09-23T16:43:47.071746Z","steps":["trace[1396038925] 'process raft request' (duration: 510.748042ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T16:43:47.072019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.474486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-09-23T16:43:47.072157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T16:43:46.56071Z","time spent":"511.150216ms","remote":"127.0.0.1:57772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10970,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T16:43:47.072159Z","caller":"traceutil/trace.go:171","msg":"trace[1849738170] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1896; }","duration":"442.610157ms","start":"2026-09-23T16:43:46.62947Z","end":"2026-09-23T16:43:47.07208Z","steps":["trace[1849738170] 'agreement among raft nodes before linearized reading' (duration: 442.314544ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T16:43:47.072265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T16:43:46.629444Z","time spent":"442.807769ms","remote":"127.0.0.1:57850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-23T16:43:47.138951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.390587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e45777b7-dad4-43f8-86a8-f41b9978dff2\" ","response":"range_response_count:1 size:3741"} {"level":"warn","ts":"2026-09-23T16:43:47.138978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.376232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-23T16:43:47.139041Z","caller":"traceutil/trace.go:171","msg":"trace[985028361] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e45777b7-dad4-43f8-86a8-f41b9978dff2; range_end:; response_count:1; response_revision:1897; }","duration":"161.524208ms","start":"2026-09-23T16:43:46.977497Z","end":"2026-09-23T16:43:47.139021Z","steps":["trace[985028361] 'agreement among raft nodes before linearized reading' (duration: 161.246995ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T16:43:47.139025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.557192ms","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-09-23T16:43:47.139087Z","caller":"traceutil/trace.go:171","msg":"trace[766634730] transaction","detail":"{read_only:false; response_revision:1897; number_of_response:1; }","duration":"499.715314ms","start":"2026-09-23T16:43:46.639363Z","end":"2026-09-23T16:43:47.139079Z","steps":["trace[766634730] 'process raft request' (duration: 499.162839ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T16:43:47.139046Z","caller":"traceutil/trace.go:171","msg":"trace[1128744167] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1897; }","duration":"445.498313ms","start":"2026-09-23T16:43:46.693531Z","end":"2026-09-23T16:43:47.139029Z","steps":["trace[1128744167] 'agreement among raft nodes before linearized reading' (duration: 445.385082ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T16:43:47.139347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T16:43:46.639338Z","time spent":"499.943876ms","remote":"127.0.0.1:57850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T16:43:47.139384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T16:43:46.693508Z","time spent":"445.845406ms","remote":"127.0.0.1:36432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-09-23T16:43:47.139116Z","caller":"traceutil/trace.go:171","msg":"trace[166454084] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1897; }","duration":"300.698334ms","start":"2026-09-23T16:43:46.838396Z","end":"2026-09-23T16:43:47.139094Z","steps":["trace[166454084] 'agreement among raft nodes before linearized reading' (duration: 300.410001ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T16:43:47.140292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T16:43:46.838376Z","time spent":"301.883464ms","remote":"127.0.0.1:33150","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-09-23T16:43:59.428088Z","caller":"traceutil/trace.go:171","msg":"trace[213117047] linearizableReadLoop","detail":"{readStateIndex:2079; appliedIndex:2078; }","duration":"201.928854ms","start":"2026-09-23T16:43:59.226137Z","end":"2026-09-23T16:43:59.428066Z","steps":["trace[213117047] 'read index received' (duration: 201.777663ms)","trace[213117047] 'applied index is now lower than readState.Index' (duration: 149.801µs)"],"step_count":2} {"level":"info","ts":"2026-09-23T16:43:59.428142Z","caller":"traceutil/trace.go:171","msg":"trace[1818621535] transaction","detail":"{read_only:false; response_revision:2003; number_of_response:1; }","duration":"220.835351ms","start":"2026-09-23T16:43:59.207273Z","end":"2026-09-23T16:43:59.428109Z","steps":["trace[1818621535] 'process raft request' (duration: 220.623678ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T16:43:59.428324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.166156ms","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-09-23T16:43:59.428365Z","caller":"traceutil/trace.go:171","msg":"trace[1854388203] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2003; }","duration":"202.250177ms","start":"2026-09-23T16:43:59.226102Z","end":"2026-09-23T16:43:59.428353Z","steps":["trace[1854388203] 'agreement among raft nodes before linearized reading' (duration: 202.096465ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T16:43:59.436978Z","caller":"traceutil/trace.go:171","msg":"trace[1999206498] transaction","detail":"{read_only:false; response_revision:2004; number_of_response:1; }","duration":"177.981781ms","start":"2026-09-23T16:43:59.258978Z","end":"2026-09-23T16:43:59.436959Z","steps":["trace[1999206498] 'process raft request' (duration: 177.724569ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T16:43:59.437205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.007043ms","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-23T16:43:59.437328Z","caller":"traceutil/trace.go:171","msg":"trace[1036199627] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2005; }","duration":"206.205705ms","start":"2026-09-23T16:43:59.231077Z","end":"2026-09-23T16:43:59.437283Z","steps":["trace[1036199627] 'agreement among raft nodes before linearized reading' (duration: 205.856831ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T16:44:03.613811Z","caller":"traceutil/trace.go:171","msg":"trace[1505970577] transaction","detail":"{read_only:false; response_revision:2036; number_of_response:1; }","duration":"167.285479ms","start":"2026-09-23T16:44:03.446499Z","end":"2026-09-23T16:44:03.613785Z","steps":["trace[1505970577] 'process raft request' (duration: 167.159068ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T16:44:03.645391Z","caller":"traceutil/trace.go:171","msg":"trace[780308478] transaction","detail":"{read_only:false; response_revision:2037; number_of_response:1; }","duration":"195.626631ms","start":"2026-09-23T16:44:03.449743Z","end":"2026-09-23T16:44:03.645369Z","steps":["trace[780308478] 'process raft request' (duration: 195.441731ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T16:44:03.645631Z","caller":"traceutil/trace.go:171","msg":"trace[1156947334] transaction","detail":"{read_only:false; response_revision:2038; number_of_response:1; }","duration":"195.351229ms","start":"2026-09-23T16:44:03.45027Z","end":"2026-09-23T16:44:03.645621Z","steps":["trace[1156947334] 'process raft request' (duration: 195.066606ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T16:44:03.645671Z","caller":"traceutil/trace.go:171","msg":"trace[1857142869] linearizableReadLoop","detail":"{readStateIndex:2115; appliedIndex:2112; }","duration":"193.621122ms","start":"2026-09-23T16:44:03.452028Z","end":"2026-09-23T16:44:03.645649Z","steps":["trace[1857142869] 'read index received' (duration: 161.708757ms)","trace[1857142869] 'applied index is now lower than readState.Index' (duration: 31.911445ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T16:44:03.645744Z","caller":"traceutil/trace.go:171","msg":"trace[473544246] transaction","detail":"{read_only:false; response_revision:2039; number_of_response:1; }","duration":"102.232436ms","start":"2026-09-23T16:44:03.543495Z","end":"2026-09-23T16:44:03.645727Z","steps":["trace[473544246] 'process raft request' (duration: 102.031274ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T16:44:03.646005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.153447ms","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-23T16:44:03.646038Z","caller":"traceutil/trace.go:171","msg":"trace[1517158031] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2039; }","duration":"181.229228ms","start":"2026-09-23T16:44:03.464798Z","end":"2026-09-23T16:44:03.646027Z","steps":["trace[1517158031] 'agreement among raft nodes before linearized reading' (duration: 181.099716ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T16:44:03.646053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.024166ms","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-09-23T16:44:03.646123Z","caller":"traceutil/trace.go:171","msg":"trace[736781105] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2039; }","duration":"194.125076ms","start":"2026-09-23T16:44:03.451983Z","end":"2026-09-23T16:44:03.646108Z","steps":["trace[736781105] 'agreement among raft nodes before linearized reading' (duration: 193.785953ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T16:44:25.896224Z","caller":"traceutil/trace.go:171","msg":"trace[1647265941] transaction","detail":"{read_only:false; response_revision:2166; number_of_response:1; }","duration":"119.588958ms","start":"2026-09-23T16:44:25.776592Z","end":"2026-09-23T16:44:25.896181Z","steps":["trace[1647265941] 'process raft request' (duration: 47.977368ms)","trace[1647265941] 'compare' (duration: 71.410488ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T16:44:26.234924Z","caller":"traceutil/trace.go:171","msg":"trace[735011493] transaction","detail":"{read_only:false; response_revision:2167; number_of_response:1; }","duration":"127.101653ms","start":"2026-09-23T16:44:26.107797Z","end":"2026-09-23T16:44:26.234899Z","steps":["trace[735011493] 'process raft request' (duration: 126.950701ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T16:44:26.363824Z","caller":"traceutil/trace.go:171","msg":"trace[1799875802] transaction","detail":"{read_only:false; response_revision:2169; number_of_response:1; }","duration":"254.439309ms","start":"2026-09-23T16:44:26.10936Z","end":"2026-09-23T16:44:26.363799Z","steps":["trace[1799875802] 'process raft request' (duration: 254.386929ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T16:44:26.364111Z","caller":"traceutil/trace.go:171","msg":"trace[1862837188] transaction","detail":"{read_only:false; response_revision:2168; number_of_response:1; }","duration":"255.74471ms","start":"2026-09-23T16:44:26.108354Z","end":"2026-09-23T16:44:26.364099Z","steps":["trace[1862837188] 'process raft request' (duration: 199.867452ms)","trace[1862837188] 'compare' (duration: 55.317373ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T16:44:26.835267Z","caller":"traceutil/trace.go:171","msg":"trace[636642062] linearizableReadLoop","detail":"{readStateIndex:2253; appliedIndex:2252; }","duration":"135.227746ms","start":"2026-09-23T16:44:26.700014Z","end":"2026-09-23T16:44:26.835242Z","steps":["trace[636642062] 'read index received' (duration: 134.994674ms)","trace[636642062] 'applied index is now lower than readState.Index' (duration: 232.022µs)"],"step_count":2} {"level":"info","ts":"2026-09-23T16:44:26.835331Z","caller":"traceutil/trace.go:171","msg":"trace[875218408] transaction","detail":"{read_only:false; response_revision:2170; number_of_response:1; }","duration":"191.083855ms","start":"2026-09-23T16:44:26.644213Z","end":"2026-09-23T16:44:26.835297Z","steps":["trace[875218408] 'process raft request' (duration: 190.848723ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T16:44:26.835447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.420138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-23T16:44:26.835487Z","caller":"traceutil/trace.go:171","msg":"trace[502639446] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2170; }","duration":"135.552889ms","start":"2026-09-23T16:44:26.699923Z","end":"2026-09-23T16:44:26.835476Z","steps":["trace[502639446] 'agreement among raft nodes before linearized reading' (duration: 135.410048ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T16:44:27.129879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.269639ms","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-09-23T16:44:27.129992Z","caller":"traceutil/trace.go:171","msg":"trace[1164348082] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2170; }","duration":"225.41581ms","start":"2026-09-23T16:44:26.904555Z","end":"2026-09-23T16:44:27.12997Z","steps":["trace[1164348082] 'range keys from in-memory index tree' (duration: 224.271639ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T16:45:34.205159Z","caller":"traceutil/trace.go:171","msg":"trace[1460511015] transaction","detail":"{read_only:false; response_revision:2631; number_of_response:1; }","duration":"196.383035ms","start":"2026-09-23T16:45:34.008759Z","end":"2026-09-23T16:45:34.205142Z","steps":["trace[1460511015] 'process raft request' (duration: 196.295731ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T16:45:34.2149Z","caller":"traceutil/trace.go:171","msg":"trace[769484700] linearizableReadLoop","detail":"{readStateIndex:2731; appliedIndex:2729; }","duration":"151.471897ms","start":"2026-09-23T16:45:34.063405Z","end":"2026-09-23T16:45:34.214877Z","steps":["trace[769484700] 'read index received' (duration: 141.73652ms)","trace[769484700] 'applied index is now lower than readState.Index' (duration: 9.734437ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T16:45:34.21504Z","caller":"traceutil/trace.go:171","msg":"trace[423838996] transaction","detail":"{read_only:false; response_revision:2632; number_of_response:1; }","duration":"202.354163ms","start":"2026-09-23T16:45:34.012667Z","end":"2026-09-23T16:45:34.215021Z","steps":["trace[423838996] 'process raft request' (duration: 202.011999ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T16:45:34.215183Z","caller":"traceutil/trace.go:171","msg":"trace[1966965451] transaction","detail":"{read_only:false; response_revision:2633; number_of_response:1; }","duration":"199.402065ms","start":"2026-09-23T16:45:34.015773Z","end":"2026-09-23T16:45:34.215175Z","steps":["trace[1966965451] 'process raft request' (duration: 199.026ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T16:45:34.215417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.012816ms","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-23T16:45:34.215485Z","caller":"traceutil/trace.go:171","msg":"trace[1849585484] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2634; }","duration":"122.124751ms","start":"2026-09-23T16:45:34.093343Z","end":"2026-09-23T16:45:34.215467Z","steps":["trace[1849585484] 'agreement among raft nodes before linearized reading' (duration: 121.959344ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T16:45:34.215634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.230989ms","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-23T16:45:34.215656Z","caller":"traceutil/trace.go:171","msg":"trace[1146831401] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2634; }","duration":"152.268989ms","start":"2026-09-23T16:45:34.06338Z","end":"2026-09-23T16:45:34.215648Z","steps":["trace[1146831401] 'agreement among raft nodes before linearized reading' (duration: 152.201987ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T16:46:05.481908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.916306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-23T16:46:05.482Z","caller":"traceutil/trace.go:171","msg":"trace[611124489] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:2808; }","duration":"111.048841ms","start":"2026-09-23T16:46:05.370928Z","end":"2026-09-23T16:46:05.481977Z","steps":["trace[611124489] 'count revisions from in-memory index tree' (duration: 110.812673ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T16:46:05.482281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.747689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-23T16:46:05.482356Z","caller":"traceutil/trace.go:171","msg":"trace[1587881871] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:2808; }","duration":"126.34004ms","start":"2026-09-23T16:46:05.355977Z","end":"2026-09-23T16:46:05.482317Z","steps":["trace[1587881871] 'count revisions from in-memory index tree' (duration: 125.615515ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T16:50:36.424453Z","caller":"traceutil/trace.go:171","msg":"trace[1696378783] transaction","detail":"{read_only:false; response_revision:4818; number_of_response:1; }","duration":"100.591941ms","start":"2026-09-23T16:50:36.323831Z","end":"2026-09-23T16:50:36.424423Z","steps":["trace[1696378783] 'process raft request' (duration: 79.644599ms)","trace[1696378783] 'compare' (duration: 20.656108ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T16:50:43.534414Z","caller":"traceutil/trace.go:171","msg":"trace[506906704] transaction","detail":"{read_only:false; response_revision:4959; number_of_response:1; }","duration":"118.333619ms","start":"2026-09-23T16:50:43.416057Z","end":"2026-09-23T16:50:43.534391Z","steps":["trace[506906704] 'process raft request' (duration: 100.235461ms)","trace[506906704] 'compare' (duration: 18.013787ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T16:50:43.536307Z","caller":"traceutil/trace.go:171","msg":"trace[1897510507] linearizableReadLoop","detail":"{readStateIndex:5145; appliedIndex:5143; }","duration":"113.058922ms","start":"2026-09-23T16:50:43.423204Z","end":"2026-09-23T16:50:43.536263Z","steps":["trace[1897510507] 'read index received' (duration: 93.083036ms)","trace[1897510507] 'applied index is now lower than readState.Index' (duration: 19.973446ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T16:50:43.536515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.260185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-23T16:50:43.536506Z","caller":"traceutil/trace.go:171","msg":"trace[1808635335] transaction","detail":"{read_only:false; response_revision:4960; number_of_response:1; }","duration":"118.526432ms","start":"2026-09-23T16:50:43.417906Z","end":"2026-09-23T16:50:43.536432Z","steps":["trace[1808635335] 'process raft request' (duration: 118.108006ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T16:50:43.53657Z","caller":"traceutil/trace.go:171","msg":"trace[86838388] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:4960; }","duration":"113.377977ms","start":"2026-09-23T16:50:43.423175Z","end":"2026-09-23T16:50:43.536553Z","steps":["trace[86838388] 'agreement among raft nodes before linearized reading' (duration: 113.235115ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T16:50:49.541215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.488006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-72b0fc91-47ad-4d24-b608-10945b5f6cd9\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-09-23T16:50:49.541325Z","caller":"traceutil/trace.go:171","msg":"trace[822321948] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-72b0fc91-47ad-4d24-b608-10945b5f6cd9; range_end:; response_count:1; response_revision:5058; }","duration":"124.74573ms","start":"2026-09-23T16:50:49.416559Z","end":"2026-09-23T16:50:49.541305Z","steps":["trace[822321948] 'range keys from in-memory index tree' (duration: 124.164643ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T16:50:49.555629Z","caller":"traceutil/trace.go:171","msg":"trace[1640287107] transaction","detail":"{read_only:false; response_revision:5059; number_of_response:1; }","duration":"138.690406ms","start":"2026-09-23T16:50:49.416914Z","end":"2026-09-23T16:50:49.555604Z","steps":["trace[1640287107] 'process raft request' (duration: 138.458892ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T16:50:49.571335Z","caller":"traceutil/trace.go:171","msg":"trace[1519415828] transaction","detail":"{read_only:false; response_revision:5060; number_of_response:1; }","duration":"153.180598ms","start":"2026-09-23T16:50:49.418129Z","end":"2026-09-23T16:50:49.571309Z","steps":["trace[1519415828] 'process raft request' (duration: 153.054186ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T16:50:49.885506Z","caller":"traceutil/trace.go:171","msg":"trace[1912451395] transaction","detail":"{read_only:false; response_revision:5067; number_of_response:1; }","duration":"181.90753ms","start":"2026-09-23T16:50:49.70357Z","end":"2026-09-23T16:50:49.885477Z","steps":["trace[1912451395] 'process raft request' (duration: 132.626147ms)","trace[1912451395] 'compare' (duration: 49.142731ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T16:50:49.939375Z","caller":"traceutil/trace.go:171","msg":"trace[1131718245] transaction","detail":"{read_only:false; response_revision:5068; number_of_response:1; }","duration":"235.730329ms","start":"2026-09-23T16:50:49.703615Z","end":"2026-09-23T16:50:49.939345Z","steps":["trace[1131718245] 'process raft request' (duration: 235.514426ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T16:50:49.939638Z","caller":"traceutil/trace.go:171","msg":"trace[243084862] transaction","detail":"{read_only:false; response_revision:5069; number_of_response:1; }","duration":"235.866371ms","start":"2026-09-23T16:50:49.703746Z","end":"2026-09-23T16:50:49.939613Z","steps":["trace[243084862] 'process raft request' (duration: 235.533126ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T16:50:49.939801Z","caller":"traceutil/trace.go:171","msg":"trace[469237017] transaction","detail":"{read_only:false; response_revision:5070; number_of_response:1; }","duration":"235.957563ms","start":"2026-09-23T16:50:49.703832Z","end":"2026-09-23T16:50:49.939789Z","steps":["trace[469237017] 'process raft request' (duration: 235.669139ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T16:51:04.131574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.876918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10392"} {"level":"warn","ts":"2026-09-23T16:51:04.131593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.219613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-23T16:51:04.131658Z","caller":"traceutil/trace.go:171","msg":"trace[2099674055] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5246; }","duration":"418.0154ms","start":"2026-09-23T16:51:03.713621Z","end":"2026-09-23T16:51:04.131637Z","steps":["trace[2099674055] 'range keys from in-memory index tree' (duration: 417.766376ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T16:51:04.131673Z","caller":"traceutil/trace.go:171","msg":"trace[646468981] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:5246; }","duration":"321.361265ms","start":"2026-09-23T16:51:03.810293Z","end":"2026-09-23T16:51:04.131655Z","steps":["trace[646468981] 'count revisions from in-memory index tree' (duration: 321.124641ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T16:51:04.131696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T16:51:03.713595Z","time spent":"418.092291ms","remote":"127.0.0.1:57772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":10415,"request content":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-09-23T16:51:04.131715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T16:51:03.810272Z","time spent":"321.429396ms","remote":"127.0.0.1:57790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":69,"response size":30,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-09-23T16:51:04.1321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.679219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-09-23T16:51:04.132142Z","caller":"traceutil/trace.go:171","msg":"trace[2127376737] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5246; }","duration":"374.39972ms","start":"2026-09-23T16:51:03.757726Z","end":"2026-09-23T16:51:04.132126Z","steps":["trace[2127376737] 'range keys from in-memory index tree' (duration: 373.492737ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T16:51:04.13217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T16:51:03.757702Z","time spent":"374.459692ms","remote":"127.0.0.1:57752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-09-23T16:51:04.319511Z","caller":"traceutil/trace.go:171","msg":"trace[1673148387] transaction","detail":"{read_only:false; response_revision:5252; number_of_response:1; }","duration":"144.945329ms","start":"2026-09-23T16:51:04.174537Z","end":"2026-09-23T16:51:04.319482Z","steps":["trace[1673148387] 'process raft request' (duration: 144.818777ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T16:51:04.31951Z","caller":"traceutil/trace.go:171","msg":"trace[1255614805] transaction","detail":"{read_only:false; response_revision:5251; number_of_response:1; }","duration":"146.016025ms","start":"2026-09-23T16:51:04.173445Z","end":"2026-09-23T16:51:04.319461Z","steps":["trace[1255614805] 'process raft request' (duration: 145.767052ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T16:51:04.319975Z","caller":"traceutil/trace.go:171","msg":"trace[9513490] transaction","detail":"{read_only:false; response_revision:5253; number_of_response:1; }","duration":"145.233104ms","start":"2026-09-23T16:51:04.174721Z","end":"2026-09-23T16:51:04.319954Z","steps":["trace[9513490] 'process raft request' (duration: 144.670745ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T16:51:04.32023Z","caller":"traceutil/trace.go:171","msg":"trace[1966917919] transaction","detail":"{read_only:false; response_revision:5254; number_of_response:1; }","duration":"133.709927ms","start":"2026-09-23T16:51:04.18651Z","end":"2026-09-23T16:51:04.32022Z","steps":["trace[1966917919] 'process raft request' (duration: 132.939115ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T16:51:11.71042Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2839} {"level":"info","ts":"2026-09-23T16:51:11.801594Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2839,"took":"89.855235ms","hash":1486214848,"current-db-size-bytes":52682752,"current-db-size":"53 MB","current-db-size-in-use-bytes":27664384,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-23T16:51:11.801658Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1486214848,"revision":2839,"compact-revision":-1} {"level":"info","ts":"2026-09-23T16:54:48.695181Z","caller":"traceutil/trace.go:171","msg":"trace[1385585014] transaction","detail":"{read_only:false; response_revision:6868; number_of_response:1; }","duration":"126.53422ms","start":"2026-09-23T16:54:48.568622Z","end":"2026-09-23T16:54:48.695156Z","steps":["trace[1385585014] 'process raft request' (duration: 126.215747ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T16:54:48.695386Z","caller":"traceutil/trace.go:171","msg":"trace[727865674] transaction","detail":"{read_only:false; response_revision:6869; number_of_response:1; }","duration":"126.668075ms","start":"2026-09-23T16:54:48.568688Z","end":"2026-09-23T16:54:48.695356Z","steps":["trace[727865674] 'process raft request' (duration: 126.407914ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T16:56:11.723529Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5303} {"level":"info","ts":"2026-09-23T16:56:11.812519Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5303,"took":"85.963518ms","hash":654937943,"current-db-size-bytes":52682752,"current-db-size":"53 MB","current-db-size-in-use-bytes":18837504,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-23T16:56:11.812587Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":654937943,"revision":5303,"compact-revision":2839}