{"level":"warn","ts":"2026-05-05T09:42:49.966318Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T09:42:49.966393Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.121: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://199.19.213.121:2380","--initial-cluster=instance=https://199.19.213.121:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.121:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.121: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-05-05T09:42:49.966456Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T09:42:49.966468Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.121:2380"]} {"level":"info","ts":"2026-05-05T09:42:49.966586Z","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-05-05T09:42:49.967187Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.121:2379"]} {"level":"info","ts":"2026-05-05T09:42:49.967255Z","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://199.19.213.121:2380"],"listen-peer-urls":["https://199.19.213.121:2380"],"advertise-client-urls":["https://199.19.213.121:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.121:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.121: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-05-05T09:42:49.971147Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.703564ms"} {"level":"info","ts":"2026-05-05T09:42:49.97638Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"31541ae44470b70","cluster-id":"8ae2d89c48d1447d"} {"level":"info","ts":"2026-05-05T09:42:49.976435Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 switched to configuration voters=()"} {"level":"info","ts":"2026-05-05T09:42:49.976452Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 became follower at term 0"} {"level":"info","ts":"2026-05-05T09:42:49.976461Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 31541ae44470b70 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-05T09:42:49.976468Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 became follower at term 1"} {"level":"info","ts":"2026-05-05T09:42:49.976494Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 switched to configuration voters=(222155973350329200)"} {"level":"warn","ts":"2026-05-05T09:42:49.98219Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-05T09:42:49.983271Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-05T09:42:49.984189Z","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-05-05T09:42:49.985982Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"31541ae44470b70","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-05T09:42:49.986144Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"31541ae44470b70","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-05T09:42:49.986242Z","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-05-05T09:42:49.986391Z","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-05-05T09:42:49.986405Z","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-05-05T09:42:49.987805Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 switched to configuration voters=(222155973350329200)"} {"level":"info","ts":"2026-05-05T09:42:49.987926Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8ae2d89c48d1447d","local-member-id":"31541ae44470b70","added-peer-id":"31541ae44470b70","added-peer-peer-urls":["https://199.19.213.121:2380"]} {"level":"info","ts":"2026-05-05T09:42:49.991059Z","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-05-05T09:42:49.99124Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.121:2380"} {"level":"info","ts":"2026-05-05T09:42:49.991277Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.121:2380"} {"level":"info","ts":"2026-05-05T09:42:49.991545Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"31541ae44470b70","initial-advertise-peer-urls":["https://199.19.213.121:2380"],"listen-peer-urls":["https://199.19.213.121:2380"],"advertise-client-urls":["https://199.19.213.121:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.121:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-05T09:42:49.99165Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-05T09:42:50.285142Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 is starting a new election at term 1"} {"level":"info","ts":"2026-05-05T09:42:50.285224Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-05T09:42:50.285241Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 received MsgPreVoteResp from 31541ae44470b70 at term 1"} {"level":"info","ts":"2026-05-05T09:42:50.285253Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 became candidate at term 2"} {"level":"info","ts":"2026-05-05T09:42:50.285258Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 received MsgVoteResp from 31541ae44470b70 at term 2"} {"level":"info","ts":"2026-05-05T09:42:50.285267Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 became leader at term 2"} {"level":"info","ts":"2026-05-05T09:42:50.285274Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 31541ae44470b70 elected leader 31541ae44470b70 at term 2"} {"level":"info","ts":"2026-05-05T09:42:50.438515Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T09:42:50.486854Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"31541ae44470b70","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.121:2379]}","request-path":"/0/members/31541ae44470b70/attributes","cluster-id":"8ae2d89c48d1447d","publish-timeout":"7s"} {"level":"info","ts":"2026-05-05T09:42:50.486977Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T09:42:50.487132Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T09:42:50.489505Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-05T09:42:50.48955Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-05T09:42:50.490675Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-05T09:42:50.490901Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.121:2379"} {"level":"info","ts":"2026-05-05T09:42:50.615901Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8ae2d89c48d1447d","local-member-id":"31541ae44470b70","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T09:42:50.616031Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T09:42:50.61607Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-05-05T09:42:50.863007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.229258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-05T09:42:50.863107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.457181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T09:42:50.863145Z","caller":"traceutil/trace.go:171","msg":"trace[1776238236] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1; }","duration":"131.443369ms","start":"2026-05-05T09:42:50.731663Z","end":"2026-05-05T09:42:50.863106Z","steps":["trace[1776238236] 'range keys from in-memory index tree' (duration: 131.095066ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:42:50.863171Z","caller":"traceutil/trace.go:171","msg":"trace[1934452129] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1; }","duration":"167.518599ms","start":"2026-05-05T09:42:50.695622Z","end":"2026-05-05T09:42:50.86314Z","steps":["trace[1934452129] 'range keys from in-memory index tree' (duration: 167.430251ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:42:50.863324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.171003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T09:42:50.863426Z","caller":"traceutil/trace.go:171","msg":"trace[385867206] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1; }","duration":"116.544118ms","start":"2026-05-05T09:42:50.746872Z","end":"2026-05-05T09:42:50.863416Z","steps":["trace[385867206] 'range keys from in-memory index tree' (duration: 111.211939ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:42:50.862979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.882549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-05T09:42:50.863528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.079445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T09:42:50.863552Z","caller":"traceutil/trace.go:171","msg":"trace[1813459096] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1; }","duration":"104.533112ms","start":"2026-05-05T09:42:50.759003Z","end":"2026-05-05T09:42:50.863536Z","steps":["trace[1813459096] 'count revisions from in-memory index tree' (duration: 103.825669ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:42:50.863558Z","caller":"traceutil/trace.go:171","msg":"trace[44801369] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1; }","duration":"154.108723ms","start":"2026-05-05T09:42:50.70944Z","end":"2026-05-05T09:42:50.863548Z","steps":["trace[44801369] 'count revisions from in-memory index tree' (duration: 154.004132ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:42:50.863048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.524026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-05T09:42:50.863008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.294157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T09:42:50.863781Z","caller":"traceutil/trace.go:171","msg":"trace[611805722] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1; }","duration":"143.176526ms","start":"2026-05-05T09:42:50.720594Z","end":"2026-05-05T09:42:50.863771Z","steps":["trace[611805722] 'range keys from in-memory index tree' (duration: 142.216176ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:42:50.863453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.773753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T09:42:50.864057Z","caller":"traceutil/trace.go:171","msg":"trace[300074972] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1; }","duration":"143.423605ms","start":"2026-05-05T09:42:50.720619Z","end":"2026-05-05T09:42:50.864042Z","steps":["trace[300074972] 'count revisions from in-memory index tree' (duration: 142.72684ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:42:50.864199Z","caller":"traceutil/trace.go:171","msg":"trace[1377121291] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1; }","duration":"168.710664ms","start":"2026-05-05T09:42:50.695469Z","end":"2026-05-05T09:42:50.86418Z","steps":["trace[1377121291] 'count revisions from in-memory index tree' (duration: 167.478583ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:42:50.863485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.259239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T09:42:50.864406Z","caller":"traceutil/trace.go:171","msg":"trace[1727184413] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1; }","duration":"155.200946ms","start":"2026-05-05T09:42:50.709194Z","end":"2026-05-05T09:42:50.864395Z","steps":["trace[1727184413] 'range keys from in-memory index tree' (duration: 154.213775ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:42:50.863055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.094682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T09:42:50.864517Z","caller":"traceutil/trace.go:171","msg":"trace[99898436] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1; }","duration":"105.583739ms","start":"2026-05-05T09:42:50.758926Z","end":"2026-05-05T09:42:50.864509Z","steps":["trace[99898436] 'range keys from in-memory index tree' (duration: 103.968178ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:42:50.863485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.877441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T09:42:50.864657Z","caller":"traceutil/trace.go:171","msg":"trace[2044040798] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1; }","duration":"133.065708ms","start":"2026-05-05T09:42:50.731583Z","end":"2026-05-05T09:42:50.864649Z","steps":["trace[2044040798] 'count revisions from in-memory index tree' (duration: 131.824171ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:42:50.863053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.292099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T09:42:50.864746Z","caller":"traceutil/trace.go:171","msg":"trace[2134644745] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1; }","duration":"117.999807ms","start":"2026-05-05T09:42:50.746738Z","end":"2026-05-05T09:42:50.864738Z","steps":["trace[2134644745] 'count revisions from in-memory index tree' (duration: 116.251904ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:43:21.555756Z","caller":"traceutil/trace.go:171","msg":"trace[1642385380] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"147.611284ms","start":"2026-05-05T09:43:21.408116Z","end":"2026-05-05T09:43:21.555727Z","steps":["trace[1642385380] 'process raft request' (duration: 146.938644ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:43:25.203489Z","caller":"traceutil/trace.go:171","msg":"trace[1055070119] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"216.837322ms","start":"2026-05-05T09:43:24.98662Z","end":"2026-05-05T09:43:25.203458Z","steps":["trace[1055070119] 'process raft request' (duration: 216.233648ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:43:28.481877Z","caller":"traceutil/trace.go:171","msg":"trace[935956425] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"273.62848ms","start":"2026-05-05T09:43:28.208228Z","end":"2026-05-05T09:43:28.481856Z","steps":["trace[935956425] 'process raft request' (duration: 273.458748ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:43:44.782253Z","caller":"traceutil/trace.go:171","msg":"trace[449863455] linearizableReadLoop","detail":"{readStateIndex:807; appliedIndex:806; }","duration":"229.710194ms","start":"2026-05-05T09:43:44.552516Z","end":"2026-05-05T09:43:44.782226Z","steps":["trace[449863455] 'read index received' (duration: 229.487539ms)","trace[449863455] 'applied index is now lower than readState.Index' (duration: 221.195µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:43:44.782325Z","caller":"traceutil/trace.go:171","msg":"trace[38967131] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"256.600793ms","start":"2026-05-05T09:43:44.525697Z","end":"2026-05-05T09:43:44.782298Z","steps":["trace[38967131] 'process raft request' (duration: 256.368202ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:43:44.782568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.772836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-05T09:43:44.782564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.028324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-05T09:43:44.782597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.493997ms","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-05-05T09:43:44.782623Z","caller":"traceutil/trace.go:171","msg":"trace[562030240] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:783; }","duration":"204.843716ms","start":"2026-05-05T09:43:44.577763Z","end":"2026-05-05T09:43:44.782607Z","steps":["trace[562030240] 'agreement among raft nodes before linearized reading' (duration: 204.635299ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:43:44.782641Z","caller":"traceutil/trace.go:171","msg":"trace[2071800852] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:783; }","duration":"230.163659ms","start":"2026-05-05T09:43:44.552458Z","end":"2026-05-05T09:43:44.782621Z","steps":["trace[2071800852] 'agreement among raft nodes before linearized reading' (duration: 229.913908ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:43:44.782675Z","caller":"traceutil/trace.go:171","msg":"trace[1813023980] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:783; }","duration":"158.610063ms","start":"2026-05-05T09:43:44.624044Z","end":"2026-05-05T09:43:44.782654Z","steps":["trace[1813023980] 'agreement among raft nodes before linearized reading' (duration: 158.406668ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:43:44.782596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.506444ms","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-05-05T09:43:44.782797Z","caller":"traceutil/trace.go:171","msg":"trace[1288462947] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:783; }","duration":"115.745789ms","start":"2026-05-05T09:43:44.667039Z","end":"2026-05-05T09:43:44.782785Z","steps":["trace[1288462947] 'agreement among raft nodes before linearized reading' (duration: 115.388827ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:44:58.563309Z","caller":"traceutil/trace.go:171","msg":"trace[192247351] linearizableReadLoop","detail":"{readStateIndex:1921; appliedIndex:1920; }","duration":"169.828992ms","start":"2026-05-05T09:44:58.393461Z","end":"2026-05-05T09:44:58.56329Z","steps":["trace[192247351] 'read index received' (duration: 169.653437ms)","trace[192247351] 'applied index is now lower than readState.Index' (duration: 174.765µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:44:58.563364Z","caller":"traceutil/trace.go:171","msg":"trace[1909082088] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"306.90223ms","start":"2026-05-05T09:44:58.256437Z","end":"2026-05-05T09:44:58.563339Z","steps":["trace[1909082088] 'process raft request' (duration: 306.729504ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:44:58.563451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.966654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T09:44:58.563481Z","caller":"traceutil/trace.go:171","msg":"trace[884361362] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1854; }","duration":"170.045423ms","start":"2026-05-05T09:44:58.393429Z","end":"2026-05-05T09:44:58.563474Z","steps":["trace[884361362] 'agreement among raft nodes before linearized reading' (duration: 169.964104ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:44:58.563837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:44:58.25642Z","time spent":"306.998297ms","remote":"127.0.0.1:49492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T09:44:58.766088Z","caller":"traceutil/trace.go:171","msg":"trace[1124782492] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"178.878361ms","start":"2026-05-05T09:44:58.587181Z","end":"2026-05-05T09:44:58.76606Z","steps":["trace[1124782492] 'process raft request' (duration: 178.674789ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:44:59.145842Z","caller":"traceutil/trace.go:171","msg":"trace[1151346923] linearizableReadLoop","detail":"{readStateIndex:1924; appliedIndex:1924; }","duration":"230.506222ms","start":"2026-05-05T09:44:58.915304Z","end":"2026-05-05T09:44:59.14581Z","steps":["trace[1151346923] 'read index received' (duration: 230.493924ms)","trace[1151346923] 'applied index is now lower than readState.Index' (duration: 9.588µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:44:59.146056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.678308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T09:44:59.146098Z","caller":"traceutil/trace.go:171","msg":"trace[1150668840] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1856; }","duration":"230.829917ms","start":"2026-05-05T09:44:58.915256Z","end":"2026-05-05T09:44:59.146086Z","steps":["trace[1150668840] 'agreement among raft nodes before linearized reading' (duration: 230.662211ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:44:59.146188Z","caller":"traceutil/trace.go:171","msg":"trace[1991599398] transaction","detail":"{read_only:false; response_revision:1856; number_of_response:1; }","duration":"268.579543ms","start":"2026-05-05T09:44:58.87721Z","end":"2026-05-05T09:44:59.14579Z","steps":["trace[1991599398] 'process raft request' (duration: 268.430974ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:44:59.153288Z","caller":"traceutil/trace.go:171","msg":"trace[1920038280] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"143.960191ms","start":"2026-05-05T09:44:59.009296Z","end":"2026-05-05T09:44:59.153257Z","steps":["trace[1920038280] 'process raft request' (duration: 143.832329ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:45:37.96525Z","caller":"traceutil/trace.go:171","msg":"trace[1802267840] linearizableReadLoop","detail":"{readStateIndex:2197; appliedIndex:2196; }","duration":"132.12196ms","start":"2026-05-05T09:45:37.833108Z","end":"2026-05-05T09:45:37.96523Z","steps":["trace[1802267840] 'read index received' (duration: 131.959569ms)","trace[1802267840] 'applied index is now lower than readState.Index' (duration: 161.52µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:45:37.965368Z","caller":"traceutil/trace.go:171","msg":"trace[44009779] transaction","detail":"{read_only:false; response_revision:2121; number_of_response:1; }","duration":"136.201289ms","start":"2026-05-05T09:45:37.829103Z","end":"2026-05-05T09:45:37.965305Z","steps":["trace[44009779] 'process raft request' (duration: 135.994264ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:45:37.96546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.332104ms","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-05-05T09:45:37.9655Z","caller":"traceutil/trace.go:171","msg":"trace[1954325667] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2121; }","duration":"132.422102ms","start":"2026-05-05T09:45:37.833064Z","end":"2026-05-05T09:45:37.965486Z","steps":["trace[1954325667] 'agreement among raft nodes before linearized reading' (duration: 132.261692ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:45:38.560836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.616612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T09:45:38.560938Z","caller":"traceutil/trace.go:171","msg":"trace[507167930] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:2125; }","duration":"344.752864ms","start":"2026-05-05T09:45:38.216153Z","end":"2026-05-05T09:45:38.560906Z","steps":["trace[507167930] 'count revisions from in-memory index tree' (duration: 344.528535ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:45:38.560983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:45:38.216132Z","time spent":"344.839139ms","remote":"127.0.0.1:46154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-05T09:45:38.884525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.931771ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-05T09:45:38.88463Z","caller":"traceutil/trace.go:171","msg":"trace[416078468] linearizableReadLoop","detail":"{readStateIndex:2202; appliedIndex:2201; }","duration":"133.927428ms","start":"2026-05-05T09:45:38.750686Z","end":"2026-05-05T09:45:38.884613Z","steps":["trace[416078468] 'read index received' (duration: 48.244µs)","trace[416078468] 'applied index is now lower than readState.Index' (duration: 133.877844ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:45:38.885077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.339383ms","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-05-05T09:45:38.885261Z","caller":"traceutil/trace.go:171","msg":"trace[336314691] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2125; }","duration":"134.532993ms","start":"2026-05-05T09:45:38.750645Z","end":"2026-05-05T09:45:38.885178Z","steps":["trace[336314691] 'agreement among raft nodes before linearized reading' (duration: 134.112495ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:50:40.618064Z","caller":"traceutil/trace.go:171","msg":"trace[1198123511] linearizableReadLoop","detail":"{readStateIndex:4642; appliedIndex:4640; }","duration":"133.137992ms","start":"2026-05-05T09:50:40.484901Z","end":"2026-05-05T09:50:40.618039Z","steps":["trace[1198123511] 'read index received' (duration: 97.462688ms)","trace[1198123511] 'applied index is now lower than readState.Index' (duration: 35.674233ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:50:40.618137Z","caller":"traceutil/trace.go:171","msg":"trace[2012074545] transaction","detail":"{read_only:false; response_revision:4476; number_of_response:1; }","duration":"133.356514ms","start":"2026-05-05T09:50:40.484745Z","end":"2026-05-05T09:50:40.618102Z","steps":["trace[2012074545] 'process raft request' (duration: 133.079324ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:50:40.618223Z","caller":"traceutil/trace.go:171","msg":"trace[1210370533] transaction","detail":"{read_only:false; response_revision:4477; number_of_response:1; }","duration":"118.829085ms","start":"2026-05-05T09:50:40.499342Z","end":"2026-05-05T09:50:40.618171Z","steps":["trace[1210370533] 'process raft request' (duration: 118.626257ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:50:40.618422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.503555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1261"} {"level":"info","ts":"2026-05-05T09:50:40.618538Z","caller":"traceutil/trace.go:171","msg":"trace[1007844678] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4477; }","duration":"133.638547ms","start":"2026-05-05T09:50:40.48485Z","end":"2026-05-05T09:50:40.618488Z","steps":["trace[1007844678] 'agreement among raft nodes before linearized reading' (duration: 133.36367ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:50:40.870377Z","caller":"traceutil/trace.go:171","msg":"trace[1451066934] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4478; }","duration":"249.561498ms","start":"2026-05-05T09:50:40.620792Z","end":"2026-05-05T09:50:40.870354Z","steps":["trace[1451066934] 'process raft request' (duration: 215.842162ms)","trace[1451066934] 'compare' (duration: 33.451064ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:50:40.876359Z","caller":"traceutil/trace.go:171","msg":"trace[605714018] linearizableReadLoop","detail":"{readStateIndex:4645; appliedIndex:4643; }","duration":"193.989364ms","start":"2026-05-05T09:50:40.682352Z","end":"2026-05-05T09:50:40.876341Z","steps":["trace[605714018] 'read index received' (duration: 154.301221ms)","trace[605714018] 'applied index is now lower than readState.Index' (duration: 39.687312ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:50:40.876661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.289603ms","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-05-05T09:50:40.876742Z","caller":"traceutil/trace.go:171","msg":"trace[960171137] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:4479; }","duration":"194.416217ms","start":"2026-05-05T09:50:40.682307Z","end":"2026-05-05T09:50:40.876723Z","steps":["trace[960171137] 'agreement among raft nodes before linearized reading' (duration: 194.200278ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:50:40.876971Z","caller":"traceutil/trace.go:171","msg":"trace[5271944] transaction","detail":"{read_only:false; response_revision:4479; number_of_response:1; }","duration":"253.567212ms","start":"2026-05-05T09:50:40.623389Z","end":"2026-05-05T09:50:40.876956Z","steps":["trace[5271944] 'process raft request' (duration: 252.876751ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:50:41.566968Z","caller":"traceutil/trace.go:171","msg":"trace[1098421313] linearizableReadLoop","detail":"{readStateIndex:4671; appliedIndex:4670; }","duration":"115.33347ms","start":"2026-05-05T09:50:41.451613Z","end":"2026-05-05T09:50:41.566947Z","steps":["trace[1098421313] 'read index received' (duration: 115.205506ms)","trace[1098421313] 'applied index is now lower than readState.Index' (duration: 126.863µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:50:41.567033Z","caller":"traceutil/trace.go:171","msg":"trace[615855754] transaction","detail":"{read_only:false; response_revision:4505; number_of_response:1; }","duration":"146.24078ms","start":"2026-05-05T09:50:41.42076Z","end":"2026-05-05T09:50:41.567001Z","steps":["trace[615855754] 'process raft request' (duration: 146.059823ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:50:41.567198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.566068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b5645262-94dd-433b-9359-5df67a90ddf8\" ","response":"range_response_count:1 size:4047"} {"level":"info","ts":"2026-05-05T09:50:41.567253Z","caller":"traceutil/trace.go:171","msg":"trace[1461130495] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b5645262-94dd-433b-9359-5df67a90ddf8; range_end:; response_count:1; response_revision:4505; }","duration":"115.693412ms","start":"2026-05-05T09:50:41.451546Z","end":"2026-05-05T09:50:41.56724Z","steps":["trace[1461130495] 'agreement among raft nodes before linearized reading' (duration: 115.515037ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:50:41.687355Z","caller":"traceutil/trace.go:171","msg":"trace[1391837366] linearizableReadLoop","detail":"{readStateIndex:4673; appliedIndex:4671; }","duration":"110.984461ms","start":"2026-05-05T09:50:41.57635Z","end":"2026-05-05T09:50:41.687335Z","steps":["trace[1391837366] 'read index received' (duration: 86.984161ms)","trace[1391837366] 'applied index is now lower than readState.Index' (duration: 23.999119ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:50:41.68739Z","caller":"traceutil/trace.go:171","msg":"trace[584518722] transaction","detail":"{read_only:false; response_revision:4507; number_of_response:1; }","duration":"111.212676ms","start":"2026-05-05T09:50:41.576146Z","end":"2026-05-05T09:50:41.687359Z","steps":["trace[584518722] 'process raft request' (duration: 111.067538ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:50:41.687727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.35314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-05-05T09:50:41.687775Z","caller":"traceutil/trace.go:171","msg":"trace[841597364] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4507; }","duration":"111.416492ms","start":"2026-05-05T09:50:41.576346Z","end":"2026-05-05T09:50:41.687762Z","steps":["trace[841597364] 'agreement among raft nodes before linearized reading' (duration: 111.11958ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:50:50.113302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.729847ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T09:50:50.11418Z","caller":"traceutil/trace.go:171","msg":"trace[1372034666] linearizableReadLoop","detail":"{readStateIndex:4828; appliedIndex:4827; }","duration":"370.393772ms","start":"2026-05-05T09:50:49.743764Z","end":"2026-05-05T09:50:50.114158Z","steps":["trace[1372034666] 'read index received' (duration: 159.681277ms)","trace[1372034666] 'applied index is now lower than readState.Index' (duration: 210.710023ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:50:50.114276Z","caller":"traceutil/trace.go:171","msg":"trace[49578739] transaction","detail":"{read_only:false; response_revision:4657; number_of_response:1; }","duration":"394.041982ms","start":"2026-05-05T09:50:49.720217Z","end":"2026-05-05T09:50:50.114259Z","steps":["trace[49578739] 'process raft request' (duration: 183.241485ms)","trace[49578739] 'compare' (duration: 209.365562ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:50:50.114531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:50:49.720196Z","time spent":"394.135898ms","remote":"127.0.0.1:49702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9560,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T09:50:50.114669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.481942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-05-05T09:50:50.114738Z","caller":"traceutil/trace.go:171","msg":"trace[654011051] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:4657; }","duration":"402.573026ms","start":"2026-05-05T09:50:49.712146Z","end":"2026-05-05T09:50:50.114719Z","steps":["trace[654011051] 'agreement among raft nodes before linearized reading' (duration: 402.372345ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:50:50.114784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:50:49.712136Z","time spent":"402.63629ms","remote":"127.0.0.1:49338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":89,"response count":1,"response size":731,"request content":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" "} {"level":"info","ts":"2026-05-05T09:50:50.12852Z","caller":"traceutil/trace.go:171","msg":"trace[1158733715] transaction","detail":"{read_only:false; response_revision:4658; number_of_response:1; }","duration":"379.906301ms","start":"2026-05-05T09:50:49.7486Z","end":"2026-05-05T09:50:50.128506Z","steps":["trace[1158733715] 'process raft request' (duration: 379.74674ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:50:50.128606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.665279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T09:50:50.128614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:50:49.748579Z","time spent":"379.987949ms","remote":"127.0.0.1:49324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":997,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T09:50:50.128651Z","caller":"traceutil/trace.go:171","msg":"trace[205165171] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4659; }","duration":"313.742933ms","start":"2026-05-05T09:50:49.814895Z","end":"2026-05-05T09:50:50.128638Z","steps":["trace[205165171] 'agreement among raft nodes before linearized reading' (duration: 313.657523ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:50:50.12869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:50:49.814879Z","time spent":"313.800813ms","remote":"127.0.0.1:49254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-05T09:50:50.128716Z","caller":"traceutil/trace.go:171","msg":"trace[1013954230] transaction","detail":"{read_only:false; response_revision:4659; number_of_response:1; }","duration":"375.140002ms","start":"2026-05-05T09:50:49.753564Z","end":"2026-05-05T09:50:50.128704Z","steps":["trace[1013954230] 'process raft request' (duration: 374.875147ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:50:50.12876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:50:49.753552Z","time spent":"375.180981ms","remote":"127.0.0.1:55338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3301,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T09:50:50.128825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.04941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:9427"} {"level":"info","ts":"2026-05-05T09:50:50.128885Z","caller":"traceutil/trace.go:171","msg":"trace[555611849] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:4659; }","duration":"380.156686ms","start":"2026-05-05T09:50:49.748715Z","end":"2026-05-05T09:50:50.128872Z","steps":["trace[555611849] 'agreement among raft nodes before linearized reading' (duration: 379.873167ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:50:50.128948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T09:50:49.748708Z","time spent":"380.228586ms","remote":"127.0.0.1:49396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":9450,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-05-05T09:50:50.129513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.877861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2194901"} {"level":"info","ts":"2026-05-05T09:50:50.130199Z","caller":"traceutil/trace.go:171","msg":"trace[439876302] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4659; }","duration":"280.566334ms","start":"2026-05-05T09:50:49.849614Z","end":"2026-05-05T09:50:50.13018Z","steps":["trace[439876302] 'agreement among raft nodes before linearized reading' (duration: 279.096424ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:50:50.461235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.948797ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T09:50:50.46164Z","caller":"traceutil/trace.go:171","msg":"trace[1250861494] transaction","detail":"{read_only:false; response_revision:4669; number_of_response:1; }","duration":"140.374246ms","start":"2026-05-05T09:50:50.321212Z","end":"2026-05-05T09:50:50.461586Z","steps":["trace[1250861494] 'process raft request' (duration: 38.999981ms)","trace[1250861494] 'compare' (duration: 100.806267ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:50:50.461838Z","caller":"traceutil/trace.go:171","msg":"trace[1421514777] linearizableReadLoop","detail":"{readStateIndex:4841; appliedIndex:4839; }","duration":"109.683108ms","start":"2026-05-05T09:50:50.352137Z","end":"2026-05-05T09:50:50.46182Z","steps":["trace[1421514777] 'read index received' (duration: 8.088028ms)","trace[1421514777] 'applied index is now lower than readState.Index' (duration: 101.593598ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:50:50.462065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.919092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1451"} {"level":"info","ts":"2026-05-05T09:50:50.462105Z","caller":"traceutil/trace.go:171","msg":"trace[390439441] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4670; }","duration":"110.01498ms","start":"2026-05-05T09:50:50.352077Z","end":"2026-05-05T09:50:50.462092Z","steps":["trace[390439441] 'agreement among raft nodes before linearized reading' (duration: 109.870388ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:50:50.462508Z","caller":"traceutil/trace.go:171","msg":"trace[2129432915] transaction","detail":"{read_only:false; response_revision:4670; number_of_response:1; }","duration":"131.643927ms","start":"2026-05-05T09:50:50.330806Z","end":"2026-05-05T09:50:50.46245Z","steps":["trace[2129432915] 'process raft request' (duration: 130.696443ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:50:50.772844Z","caller":"traceutil/trace.go:171","msg":"trace[1341291237] transaction","detail":"{read_only:false; response_revision:4677; number_of_response:1; }","duration":"106.835451ms","start":"2026-05-05T09:50:50.665989Z","end":"2026-05-05T09:50:50.772825Z","steps":["trace[1341291237] 'process raft request' (duration: 106.702398ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:50:50.772873Z","caller":"traceutil/trace.go:171","msg":"trace[1710567452] transaction","detail":"{read_only:false; response_revision:4678; number_of_response:1; }","duration":"106.866863ms","start":"2026-05-05T09:50:50.665983Z","end":"2026-05-05T09:50:50.77285Z","steps":["trace[1710567452] 'process raft request' (duration: 106.784896ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:50:50.774139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.297557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16233"} {"level":"info","ts":"2026-05-05T09:50:50.774197Z","caller":"traceutil/trace.go:171","msg":"trace[557105665] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4679; }","duration":"110.395196ms","start":"2026-05-05T09:50:50.663788Z","end":"2026-05-05T09:50:50.774183Z","steps":["trace[557105665] 'agreement among raft nodes before linearized reading' (duration: 109.292263ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:50:50.772995Z","caller":"traceutil/trace.go:171","msg":"trace[1691669270] transaction","detail":"{read_only:false; response_revision:4679; number_of_response:1; }","duration":"105.86249ms","start":"2026-05-05T09:50:50.667103Z","end":"2026-05-05T09:50:50.772965Z","steps":["trace[1691669270] 'process raft request' (duration: 105.696794ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:50:50.77298Z","caller":"traceutil/trace.go:171","msg":"trace[109663676] transaction","detail":"{read_only:false; response_revision:4676; number_of_response:1; }","duration":"108.371648ms","start":"2026-05-05T09:50:50.664585Z","end":"2026-05-05T09:50:50.772957Z","steps":["trace[109663676] 'process raft request' (duration: 107.399057ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:50:50.773145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.983576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-4f02c16e-4f35-4e71-82de-5db91f297bb3\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-05-05T09:50:50.775324Z","caller":"traceutil/trace.go:171","msg":"trace[614851118] range","detail":"{range_begin:/registry/persistentvolumes/pvc-4f02c16e-4f35-4e71-82de-5db91f297bb3; range_end:; response_count:1; response_revision:4679; }","duration":"111.191584ms","start":"2026-05-05T09:50:50.664108Z","end":"2026-05-05T09:50:50.7753Z","steps":["trace[614851118] 'agreement among raft nodes before linearized reading' (duration: 108.952205ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:51:03.439943Z","caller":"traceutil/trace.go:171","msg":"trace[2011058987] linearizableReadLoop","detail":"{readStateIndex:5028; appliedIndex:5027; }","duration":"208.063754ms","start":"2026-05-05T09:51:03.231758Z","end":"2026-05-05T09:51:03.439822Z","steps":["trace[2011058987] 'read index received' (duration: 154.908561ms)","trace[2011058987] 'applied index is now lower than readState.Index' (duration: 53.153542ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:51:03.439974Z","caller":"traceutil/trace.go:171","msg":"trace[1035809396] transaction","detail":"{read_only:false; response_revision:4853; number_of_response:1; }","duration":"276.30682ms","start":"2026-05-05T09:51:03.163628Z","end":"2026-05-05T09:51:03.439935Z","steps":["trace[1035809396] 'process raft request' (duration: 223.012578ms)","trace[1035809396] 'compare' (duration: 53.012493ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T09:51:03.440249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-05T09:51:03.44031Z","caller":"traceutil/trace.go:171","msg":"trace[597407424] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4853; }","duration":"208.563936ms","start":"2026-05-05T09:51:03.231722Z","end":"2026-05-05T09:51:03.440286Z","steps":["trace[597407424] 'agreement among raft nodes before linearized reading' (duration: 208.341771ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:51:03.467611Z","caller":"traceutil/trace.go:171","msg":"trace[1608836682] transaction","detail":"{read_only:false; response_revision:4854; number_of_response:1; }","duration":"234.969526ms","start":"2026-05-05T09:51:03.232616Z","end":"2026-05-05T09:51:03.467586Z","steps":["trace[1608836682] 'process raft request' (duration: 234.815259ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:51:03.467786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.87543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T09:51:03.467846Z","caller":"traceutil/trace.go:171","msg":"trace[1456593009] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:4854; }","duration":"116.970654ms","start":"2026-05-05T09:51:03.35086Z","end":"2026-05-05T09:51:03.467831Z","steps":["trace[1456593009] 'agreement among raft nodes before linearized reading' (duration: 116.815757ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:51:03.467962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.500394ms","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-05-05T09:51:03.468027Z","caller":"traceutil/trace.go:171","msg":"trace[1414629483] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4854; }","duration":"192.583862ms","start":"2026-05-05T09:51:03.275428Z","end":"2026-05-05T09:51:03.468011Z","steps":["trace[1414629483] 'agreement among raft nodes before linearized reading' (duration: 192.348969ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T09:51:03.468002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.85058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-05T09:51:03.468054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.176249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-05T09:51:03.468098Z","caller":"traceutil/trace.go:171","msg":"trace[1386257193] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4854; }","duration":"231.249079ms","start":"2026-05-05T09:51:03.236837Z","end":"2026-05-05T09:51:03.468086Z","steps":["trace[1386257193] 'agreement among raft nodes before linearized reading' (duration: 230.933011ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:51:03.468091Z","caller":"traceutil/trace.go:171","msg":"trace[537147200] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4854; }","duration":"203.987028ms","start":"2026-05-05T09:51:03.264081Z","end":"2026-05-05T09:51:03.468068Z","steps":["trace[537147200] 'agreement among raft nodes before linearized reading' (duration: 203.753946ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:51:04.022587Z","caller":"traceutil/trace.go:171","msg":"trace[1240734385] transaction","detail":"{read_only:false; response_revision:4866; number_of_response:1; }","duration":"123.699235ms","start":"2026-05-05T09:51:03.898866Z","end":"2026-05-05T09:51:04.022565Z","steps":["trace[1240734385] 'process raft request' (duration: 123.607134ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:51:04.022704Z","caller":"traceutil/trace.go:171","msg":"trace[815726958] transaction","detail":"{read_only:false; response_revision:4863; number_of_response:1; }","duration":"133.188792ms","start":"2026-05-05T09:51:03.889491Z","end":"2026-05-05T09:51:04.02268Z","steps":["trace[815726958] 'process raft request' (duration: 64.633872ms)","trace[815726958] 'compare' (duration: 68.118638ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:51:04.022853Z","caller":"traceutil/trace.go:171","msg":"trace[1433314018] transaction","detail":"{read_only:false; response_revision:4864; number_of_response:1; }","duration":"132.149194ms","start":"2026-05-05T09:51:03.890691Z","end":"2026-05-05T09:51:04.02284Z","steps":["trace[1433314018] 'process raft request' (duration: 131.688329ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:51:04.022799Z","caller":"traceutil/trace.go:171","msg":"trace[224552410] transaction","detail":"{read_only:false; response_revision:4865; number_of_response:1; }","duration":"131.952234ms","start":"2026-05-05T09:51:03.890824Z","end":"2026-05-05T09:51:04.022777Z","steps":["trace[224552410] 'process raft request' (duration: 131.604761ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T09:52:50.527939Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2940} {"level":"info","ts":"2026-05-05T09:52:50.710881Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2940,"took":"181.881803ms","hash":2293111882,"current-db-size-bytes":53223424,"current-db-size":"53 MB","current-db-size-in-use-bytes":29302784,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-05T09:52:50.710959Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2293111882,"revision":2940,"compact-revision":-1} {"level":"info","ts":"2026-05-05T09:55:00.507294Z","caller":"traceutil/trace.go:171","msg":"trace[1474388832] transaction","detail":"{read_only:false; response_revision:6583; number_of_response:1; }","duration":"134.297516ms","start":"2026-05-05T09:55:00.372957Z","end":"2026-05-05T09:55:00.507254Z","steps":["trace[1474388832] 'process raft request' (duration: 108.524788ms)","trace[1474388832] 'compare' (duration: 25.491186ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T09:57:50.535245Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5668} {"level":"info","ts":"2026-05-05T09:57:50.616085Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5668,"took":"79.345078ms","hash":411968005,"current-db-size-bytes":53223424,"current-db-size":"53 MB","current-db-size-in-use-bytes":18141184,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-05T09:57:50.616172Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":411968005,"revision":5668,"compact-revision":2940}