{"level":"warn","ts":"2026-05-01T14:04:14.854376Z","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-01T14:04:14.854452Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.19: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.204.45.19:2380","--initial-cluster=instance=https://199.204.45.19:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.19:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.19: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-01T14:04:14.854512Z","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-01T14:04:14.854523Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.19:2380"]} {"level":"info","ts":"2026-05-01T14:04:14.85454Z","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-01T14:04:14.855166Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.19:2379"]} {"level":"info","ts":"2026-05-01T14:04:14.855238Z","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.204.45.19:2380"],"listen-peer-urls":["https://199.204.45.19:2380"],"advertise-client-urls":["https://199.204.45.19:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.19:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.19: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-01T14:04:14.859248Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.816577ms"} {"level":"info","ts":"2026-05-01T14:04:14.865471Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a847c2e9c7010db6","cluster-id":"2b11a68e27507b05"} {"level":"info","ts":"2026-05-01T14:04:14.865521Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 switched to configuration voters=()"} {"level":"info","ts":"2026-05-01T14:04:14.86554Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became follower at term 0"} {"level":"info","ts":"2026-05-01T14:04:14.86556Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a847c2e9c7010db6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-01T14:04:14.865567Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became follower at term 1"} {"level":"info","ts":"2026-05-01T14:04:14.865596Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 switched to configuration voters=(12125874831040253366)"} {"level":"warn","ts":"2026-05-01T14:04:14.870654Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-01T14:04:14.871721Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-01T14:04:14.87253Z","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-01T14:04:14.87392Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a847c2e9c7010db6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-01T14:04:14.874117Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a847c2e9c7010db6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-01T14:04:14.874235Z","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-01T14:04:14.874327Z","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-01T14:04:14.874362Z","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-01T14:04:14.877807Z","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-01T14:04:14.877984Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.19:2380"} {"level":"info","ts":"2026-05-01T14:04:14.87804Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.19:2380"} {"level":"info","ts":"2026-05-01T14:04:14.878191Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a847c2e9c7010db6","initial-advertise-peer-urls":["https://199.204.45.19:2380"],"listen-peer-urls":["https://199.204.45.19:2380"],"advertise-client-urls":["https://199.204.45.19:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.19:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-01T14:04:14.878237Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-01T14:04:14.879294Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 switched to configuration voters=(12125874831040253366)"} {"level":"info","ts":"2026-05-01T14:04:14.879467Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2b11a68e27507b05","local-member-id":"a847c2e9c7010db6","added-peer-id":"a847c2e9c7010db6","added-peer-peer-urls":["https://199.204.45.19:2380"]} {"level":"info","ts":"2026-05-01T14:04:15.366346Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 is starting a new election at term 1"} {"level":"info","ts":"2026-05-01T14:04:15.366414Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-01T14:04:15.366436Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 received MsgPreVoteResp from a847c2e9c7010db6 at term 1"} {"level":"info","ts":"2026-05-01T14:04:15.366451Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became candidate at term 2"} {"level":"info","ts":"2026-05-01T14:04:15.366458Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 received MsgVoteResp from a847c2e9c7010db6 at term 2"} {"level":"info","ts":"2026-05-01T14:04:15.366469Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became leader at term 2"} {"level":"info","ts":"2026-05-01T14:04:15.366478Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a847c2e9c7010db6 elected leader a847c2e9c7010db6 at term 2"} {"level":"info","ts":"2026-05-01T14:04:15.367634Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a847c2e9c7010db6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.19:2379]}","request-path":"/0/members/a847c2e9c7010db6/attributes","cluster-id":"2b11a68e27507b05","publish-timeout":"7s"} {"level":"info","ts":"2026-05-01T14:04:15.367934Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-01T14:04:15.368234Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-01T14:04:15.368254Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-01T14:04:15.368242Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-01T14:04:15.36851Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-01T14:04:15.369232Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2b11a68e27507b05","local-member-id":"a847c2e9c7010db6","cluster-version":"3.5"} {"level":"info","ts":"2026-05-01T14:04:15.369327Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-01T14:04:15.369361Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-01T14:04:15.372146Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-01T14:04:15.372464Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.19:2379"} {"level":"warn","ts":"2026-05-01T14:04:17.908504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.575048ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-01T14:04:17.908656Z","caller":"traceutil/trace.go:171","msg":"trace[1983395586] linearizableReadLoop","detail":"{readStateIndex:77; appliedIndex:76; }","duration":"228.972725ms","start":"2026-05-01T14:04:17.679663Z","end":"2026-05-01T14:04:17.908635Z","steps":["trace[1983395586] 'read index received' (duration: 65.538012ms)","trace[1983395586] 'applied index is now lower than readState.Index' (duration: 163.433223ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T14:04:17.908677Z","caller":"traceutil/trace.go:171","msg":"trace[560826444] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"230.051824ms","start":"2026-05-01T14:04:17.678593Z","end":"2026-05-01T14:04:17.908644Z","steps":["trace[560826444] 'process raft request' (duration: 66.62558ms)","trace[560826444] 'compare' (duration: 162.391005ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T14:04:17.908869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.20238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-01T14:04:17.908891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.189903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-01T14:04:17.908926Z","caller":"traceutil/trace.go:171","msg":"trace[370104353] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:73; }","duration":"229.301371ms","start":"2026-05-01T14:04:17.679609Z","end":"2026-05-01T14:04:17.908911Z","steps":["trace[370104353] 'agreement among raft nodes before linearized reading' (duration: 229.155799ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T14:04:17.908949Z","caller":"traceutil/trace.go:171","msg":"trace[226116183] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:73; }","duration":"198.286405ms","start":"2026-05-01T14:04:17.710645Z","end":"2026-05-01T14:04:17.908932Z","steps":["trace[226116183] 'agreement among raft nodes before linearized reading' (duration: 198.179934ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T14:04:18.09031Z","caller":"traceutil/trace.go:171","msg":"trace[1707978997] transaction","detail":"{read_only:false; response_revision:74; number_of_response:1; }","duration":"169.018182ms","start":"2026-05-01T14:04:17.921249Z","end":"2026-05-01T14:04:18.090267Z","steps":["trace[1707978997] 'process raft request' (duration: 168.822769ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T14:04:44.816757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.275952ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-01T14:04:44.816888Z","caller":"traceutil/trace.go:171","msg":"trace[922486802] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"152.812473ms","start":"2026-05-01T14:04:44.664052Z","end":"2026-05-01T14:04:44.816864Z","steps":["trace[922486802] 'process raft request' (duration: 13.198575ms)","trace[922486802] 'compare' (duration: 139.13056ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T14:04:44.821977Z","caller":"traceutil/trace.go:171","msg":"trace[873470868] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"138.125534ms","start":"2026-05-01T14:04:44.683811Z","end":"2026-05-01T14:04:44.821937Z","steps":["trace[873470868] 'process raft request' (duration: 137.409843ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T14:04:45.00529Z","caller":"traceutil/trace.go:171","msg":"trace[1708504728] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"146.86109ms","start":"2026-05-01T14:04:44.858397Z","end":"2026-05-01T14:04:45.005259Z","steps":["trace[1708504728] 'process raft request' (duration: 146.598386ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T14:04:45.012625Z","caller":"traceutil/trace.go:171","msg":"trace[1093087235] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"148.229391ms","start":"2026-05-01T14:04:44.864365Z","end":"2026-05-01T14:04:45.012594Z","steps":["trace[1093087235] 'process raft request' (duration: 147.697973ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T14:04:51.473823Z","caller":"traceutil/trace.go:171","msg":"trace[284857257] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:610; }","duration":"177.72372ms","start":"2026-05-01T14:04:51.296066Z","end":"2026-05-01T14:04:51.473789Z","steps":["trace[284857257] 'read index received' (duration: 177.635896ms)","trace[284857257] 'applied index is now lower than readState.Index' (duration: 86.714µs)"],"step_count":2} {"level":"info","ts":"2026-05-01T14:04:51.47396Z","caller":"traceutil/trace.go:171","msg":"trace[941986168] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"186.005513ms","start":"2026-05-01T14:04:51.287906Z","end":"2026-05-01T14:04:51.473912Z","steps":["trace[941986168] 'process raft request' (duration: 185.708071ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T14:04:51.474212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.124075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-01T14:04:51.474276Z","caller":"traceutil/trace.go:171","msg":"trace[1724182840] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:586; }","duration":"178.23066ms","start":"2026-05-01T14:04:51.296032Z","end":"2026-05-01T14:04:51.474263Z","steps":["trace[1724182840] 'agreement among raft nodes before linearized reading' (duration: 177.894247ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T14:04:51.913318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.861245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-hrtcm\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-05-01T14:04:51.913436Z","caller":"traceutil/trace.go:171","msg":"trace[1558688129] range","detail":"{range_begin:/registry/pods/kube-system/cilium-hrtcm; range_end:; response_count:1; response_revision:587; }","duration":"356.041482ms","start":"2026-05-01T14:04:51.557364Z","end":"2026-05-01T14:04:51.913406Z","steps":["trace[1558688129] 'range keys from in-memory index tree' (duration: 355.556683ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T14:04:51.913584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T14:04:51.557326Z","time spent":"356.21324ms","remote":"127.0.0.1:49684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-hrtcm\" "} {"level":"info","ts":"2026-05-01T14:05:07.345641Z","caller":"traceutil/trace.go:171","msg":"trace[2049046008] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"146.344473ms","start":"2026-05-01T14:05:07.199264Z","end":"2026-05-01T14:05:07.345609Z","steps":["trace[2049046008] 'process raft request' (duration: 143.266338ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T14:05:41.514989Z","caller":"traceutil/trace.go:171","msg":"trace[1620399203] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"178.261396ms","start":"2026-05-01T14:05:41.336694Z","end":"2026-05-01T14:05:41.514955Z","steps":["trace[1620399203] 'process raft request' (duration: 177.997819ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T14:06:27.996226Z","caller":"traceutil/trace.go:171","msg":"trace[465799109] linearizableReadLoop","detail":"{readStateIndex:1912; appliedIndex:1911; }","duration":"244.979768ms","start":"2026-05-01T14:06:27.751212Z","end":"2026-05-01T14:06:27.996191Z","steps":["trace[465799109] 'read index received' (duration: 244.850485ms)","trace[465799109] 'applied index is now lower than readState.Index' (duration: 128.103µs)"],"step_count":2} {"level":"info","ts":"2026-05-01T14:06:27.996284Z","caller":"traceutil/trace.go:171","msg":"trace[574854560] transaction","detail":"{read_only:false; response_revision:1840; number_of_response:1; }","duration":"279.416503ms","start":"2026-05-01T14:06:27.716833Z","end":"2026-05-01T14:06:27.996249Z","steps":["trace[574854560] 'process raft request' (duration: 279.223319ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T14:06:27.99645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.228723ms","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-05-01T14:06:27.996485Z","caller":"traceutil/trace.go:171","msg":"trace[1624980176] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1840; }","duration":"245.310835ms","start":"2026-05-01T14:06:27.751164Z","end":"2026-05-01T14:06:27.996475Z","steps":["trace[1624980176] 'agreement among raft nodes before linearized reading' (duration: 245.170762ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T14:07:07.514447Z","caller":"traceutil/trace.go:171","msg":"trace[1191816931] linearizableReadLoop","detail":"{readStateIndex:2188; appliedIndex:2187; }","duration":"143.654059ms","start":"2026-05-01T14:07:07.370766Z","end":"2026-05-01T14:07:07.51442Z","steps":["trace[1191816931] 'read index received' (duration: 143.523497ms)","trace[1191816931] 'applied index is now lower than readState.Index' (duration: 129.232µs)"],"step_count":2} {"level":"info","ts":"2026-05-01T14:07:07.51465Z","caller":"traceutil/trace.go:171","msg":"trace[1931286773] transaction","detail":"{read_only:false; response_revision:2107; number_of_response:1; }","duration":"158.034423ms","start":"2026-05-01T14:07:07.356581Z","end":"2026-05-01T14:07:07.514616Z","steps":["trace[1931286773] 'process raft request' (duration: 157.657505ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T14:07:07.51478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.981116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-01T14:07:07.514979Z","caller":"traceutil/trace.go:171","msg":"trace[1623375775] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2107; }","duration":"144.225801ms","start":"2026-05-01T14:07:07.370732Z","end":"2026-05-01T14:07:07.514957Z","steps":["trace[1623375775] 'agreement among raft nodes before linearized reading' (duration: 143.794632ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T14:08:35.720201Z","caller":"traceutil/trace.go:171","msg":"trace[173617815] transaction","detail":"{read_only:false; response_revision:2695; number_of_response:1; }","duration":"154.17374ms","start":"2026-05-01T14:08:35.565997Z","end":"2026-05-01T14:08:35.720171Z","steps":["trace[173617815] 'process raft request' (duration: 153.981656ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T14:12:13.85467Z","caller":"traceutil/trace.go:171","msg":"trace[1414996027] transaction","detail":"{read_only:false; response_revision:4453; number_of_response:1; }","duration":"104.260335ms","start":"2026-05-01T14:12:13.750372Z","end":"2026-05-01T14:12:13.854633Z","steps":["trace[1414996027] 'process raft request' (duration: 104.092291ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T14:12:23.822638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.064912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8b129dfe-8e43-4b9d-80cc-3d8381ccc704\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-05-01T14:12:23.822742Z","caller":"traceutil/trace.go:171","msg":"trace[1747804912] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8b129dfe-8e43-4b9d-80cc-3d8381ccc704; range_end:; response_count:1; response_revision:4662; }","duration":"198.233516ms","start":"2026-05-01T14:12:23.624487Z","end":"2026-05-01T14:12:23.82272Z","steps":["trace[1747804912] 'range keys from in-memory index tree' (duration: 197.887079ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T14:12:23.822807Z","caller":"traceutil/trace.go:171","msg":"trace[680286873] transaction","detail":"{read_only:false; response_revision:4663; number_of_response:1; }","duration":"167.999096ms","start":"2026-05-01T14:12:23.654784Z","end":"2026-05-01T14:12:23.822783Z","steps":["trace[680286873] 'process raft request' (duration: 96.414916ms)","trace[680286873] 'compare' (duration: 71.391735ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T14:12:23.876227Z","caller":"traceutil/trace.go:171","msg":"trace[956757286] transaction","detail":"{read_only:false; response_revision:4664; number_of_response:1; }","duration":"199.865735ms","start":"2026-05-01T14:12:23.676323Z","end":"2026-05-01T14:12:23.876189Z","steps":["trace[956757286] 'process raft request' (duration: 199.700911ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T14:12:24.176349Z","caller":"traceutil/trace.go:171","msg":"trace[1704669711] transaction","detail":"{read_only:false; response_revision:4677; number_of_response:1; }","duration":"159.750899ms","start":"2026-05-01T14:12:24.016569Z","end":"2026-05-01T14:12:24.17632Z","steps":["trace[1704669711] 'process raft request' (duration: 71.743071ms)","trace[1704669711] 'compare' (duration: 87.835915ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T14:12:24.176345Z","caller":"traceutil/trace.go:171","msg":"trace[1698255477] linearizableReadLoop","detail":"{readStateIndex:4853; appliedIndex:4852; }","duration":"131.365742ms","start":"2026-05-01T14:12:24.044953Z","end":"2026-05-01T14:12:24.176318Z","steps":["trace[1698255477] 'read index received' (duration: 43.490717ms)","trace[1698255477] 'applied index is now lower than readState.Index' (duration: 87.873715ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T14:12:24.176751Z","caller":"traceutil/trace.go:171","msg":"trace[531933504] transaction","detail":"{read_only:false; response_revision:4678; number_of_response:1; }","duration":"128.127188ms","start":"2026-05-01T14:12:24.048599Z","end":"2026-05-01T14:12:24.176726Z","steps":["trace[531933504] 'process raft request' (duration: 127.673997ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T14:12:24.176789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.142248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-05-01T14:12:24.177028Z","caller":"traceutil/trace.go:171","msg":"trace[1367160520] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4677; }","duration":"163.415514ms","start":"2026-05-01T14:12:24.013597Z","end":"2026-05-01T14:12:24.177012Z","steps":["trace[1367160520] 'agreement among raft nodes before linearized reading' (duration: 162.80693ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T14:12:24.177417Z","caller":"traceutil/trace.go:171","msg":"trace[426148425] transaction","detail":"{read_only:false; response_revision:4679; number_of_response:1; }","duration":"127.662657ms","start":"2026-05-01T14:12:24.049739Z","end":"2026-05-01T14:12:24.177402Z","steps":["trace[426148425] 'process raft request' (duration: 126.90404ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T14:12:24.177568Z","caller":"traceutil/trace.go:171","msg":"trace[1199620461] transaction","detail":"{read_only:false; response_revision:4680; number_of_response:1; }","duration":"127.502073ms","start":"2026-05-01T14:12:24.050053Z","end":"2026-05-01T14:12:24.177555Z","steps":["trace[1199620461] 'process raft request' (duration: 126.902159ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T14:12:37.34177Z","caller":"traceutil/trace.go:171","msg":"trace[450096278] linearizableReadLoop","detail":"{readStateIndex:5034; appliedIndex:5033; }","duration":"337.738511ms","start":"2026-05-01T14:12:37.004003Z","end":"2026-05-01T14:12:37.341741Z","steps":["trace[450096278] 'read index received' (duration: 337.457235ms)","trace[450096278] 'applied index is now lower than readState.Index' (duration: 279.576µs)"],"step_count":2} {"level":"warn","ts":"2026-05-01T14:12:37.342254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.82878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T14:12:37.342316Z","caller":"traceutil/trace.go:171","msg":"trace[444797170] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4853; }","duration":"241.932522ms","start":"2026-05-01T14:12:37.100369Z","end":"2026-05-01T14:12:37.342301Z","steps":["trace[444797170] 'agreement among raft nodes before linearized reading' (duration: 241.82786ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T14:12:37.342441Z","caller":"traceutil/trace.go:171","msg":"trace[43883437] transaction","detail":"{read_only:false; response_revision:4853; number_of_response:1; }","duration":"354.81651ms","start":"2026-05-01T14:12:36.98761Z","end":"2026-05-01T14:12:37.342426Z","steps":["trace[43883437] 'process raft request' (duration: 353.96921ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T14:12:37.342457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.399365ms","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":"warn","ts":"2026-05-01T14:12:37.342532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T14:12:36.987583Z","time spent":"354.892552ms","remote":"127.0.0.1:49684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10669,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-01T14:12:37.342555Z","caller":"traceutil/trace.go:171","msg":"trace[170550884] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4853; }","duration":"338.57429ms","start":"2026-05-01T14:12:37.003955Z","end":"2026-05-01T14:12:37.342529Z","steps":["trace[170550884] 'agreement among raft nodes before linearized reading' (duration: 338.247502ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T14:12:37.342605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T14:12:37.003936Z","time spent":"338.659653ms","remote":"127.0.0.1:49660","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":"warn","ts":"2026-05-01T14:12:37.342642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.763241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-01T14:12:37.342702Z","caller":"traceutil/trace.go:171","msg":"trace[1094603019] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4853; }","duration":"209.840713ms","start":"2026-05-01T14:12:37.132847Z","end":"2026-05-01T14:12:37.342688Z","steps":["trace[1094603019] 'agreement among raft nodes before linearized reading' (duration: 209.69305ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T14:12:37.342806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.516589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T14:12:37.342839Z","caller":"traceutil/trace.go:171","msg":"trace[10775399] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:4853; }","duration":"210.58961ms","start":"2026-05-01T14:12:37.13224Z","end":"2026-05-01T14:12:37.34283Z","steps":["trace[10775399] 'agreement among raft nodes before linearized reading' (duration: 210.537179ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T14:12:37.887185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.682382ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-01T14:12:37.88761Z","caller":"traceutil/trace.go:171","msg":"trace[742055545] transaction","detail":"{read_only:false; response_revision:4855; number_of_response:1; }","duration":"537.235809ms","start":"2026-05-01T14:12:37.350353Z","end":"2026-05-01T14:12:37.887589Z","steps":["trace[742055545] 'process raft request' (duration: 536.976444ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T14:12:37.887691Z","caller":"traceutil/trace.go:171","msg":"trace[171296311] transaction","detail":"{read_only:false; response_revision:4856; number_of_response:1; }","duration":"536.931003ms","start":"2026-05-01T14:12:37.350735Z","end":"2026-05-01T14:12:37.887666Z","steps":["trace[171296311] 'process raft request' (duration: 536.706118ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T14:12:37.887724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T14:12:37.350341Z","time spent":"537.326541ms","remote":"127.0.0.1:49670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":951,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T14:12:37.887821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T14:12:37.350727Z","time spent":"537.026885ms","remote":"127.0.0.1:49670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":893,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-01T14:12:37.887847Z","caller":"traceutil/trace.go:171","msg":"trace[534837567] transaction","detail":"{read_only:false; response_revision:4854; number_of_response:1; }","duration":"538.983379ms","start":"2026-05-01T14:12:37.348812Z","end":"2026-05-01T14:12:37.887795Z","steps":["trace[534837567] 'process raft request' (duration: 258.581201ms)","trace[534837567] 'compare' (duration: 279.498638ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T14:12:37.887953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T14:12:37.348792Z","time spent":"539.107371ms","remote":"127.0.0.1:49762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-01T14:12:37.888054Z","caller":"traceutil/trace.go:171","msg":"trace[1826051961] transaction","detail":"{read_only:false; response_revision:4857; number_of_response:1; }","duration":"537.002464ms","start":"2026-05-01T14:12:37.351043Z","end":"2026-05-01T14:12:37.888046Z","steps":["trace[1826051961] 'process raft request' (duration: 536.481772ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T14:12:37.888095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T14:12:37.351036Z","time spent":"537.038484ms","remote":"127.0.0.1:49764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1506,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-01T14:12:37.888315Z","caller":"traceutil/trace.go:171","msg":"trace[1424531290] transaction","detail":"{read_only:false; response_revision:4858; number_of_response:1; }","duration":"535.744505ms","start":"2026-05-01T14:12:37.352549Z","end":"2026-05-01T14:12:37.888293Z","steps":["trace[1424531290] 'process raft request' (duration: 535.432338ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T14:12:37.888477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T14:12:37.352541Z","time spent":"535.862038ms","remote":"127.0.0.1:49764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1429,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-01T14:14:15.423102Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2907} {"level":"info","ts":"2026-05-01T14:14:15.531205Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2907,"took":"106.586133ms","hash":1069251850,"current-db-size-bytes":53465088,"current-db-size":"54 MB","current-db-size-in-use-bytes":29392896,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-01T14:14:15.531339Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1069251850,"revision":2907,"compact-revision":-1} {"level":"info","ts":"2026-05-01T14:19:15.428327Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5625} {"level":"info","ts":"2026-05-01T14:19:15.522812Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5625,"took":"92.064976ms","hash":1338958996,"current-db-size-bytes":53465088,"current-db-size":"54 MB","current-db-size-in-use-bytes":18145280,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-01T14:19:15.522859Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1338958996,"revision":5625,"compact-revision":2907}