{"level":"warn","ts":"2026-05-19T17:48:42.660767Z","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-19T17:48:42.660885Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.44: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.44:2380","--initial-cluster=instance=https://199.204.45.44:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.44:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.44: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-19T17:48:42.660986Z","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-19T17:48:42.661014Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.44:2380"]} {"level":"info","ts":"2026-05-19T17:48:42.66105Z","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-19T17:48:42.661881Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.44:2379"]} {"level":"info","ts":"2026-05-19T17:48:42.662029Z","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.44:2380"],"listen-peer-urls":["https://199.204.45.44:2380"],"advertise-client-urls":["https://199.204.45.44:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.44:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.44: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-19T17:48:42.665416Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.130727ms"} {"level":"info","ts":"2026-05-19T17:48:42.674075Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a0a01fc7fbf6be45","cluster-id":"92fa3fee501eaaec"} {"level":"info","ts":"2026-05-19T17:48:42.674213Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=()"} {"level":"info","ts":"2026-05-19T17:48:42.67424Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became follower at term 0"} {"level":"info","ts":"2026-05-19T17:48:42.674272Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a0a01fc7fbf6be45 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-19T17:48:42.674293Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became follower at term 1"} {"level":"info","ts":"2026-05-19T17:48:42.674354Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=(11574285986128379461)"} {"level":"warn","ts":"2026-05-19T17:48:42.67981Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-19T17:48:42.681288Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-19T17:48:42.682661Z","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-19T17:48:42.684648Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a0a01fc7fbf6be45","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-19T17:48:42.684845Z","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-19T17:48:42.684971Z","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-19T17:48:42.684987Z","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-19T17:48:42.685157Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a0a01fc7fbf6be45","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-19T17:48:42.686204Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=(11574285986128379461)"} {"level":"info","ts":"2026-05-19T17:48:42.686699Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"92fa3fee501eaaec","local-member-id":"a0a01fc7fbf6be45","added-peer-id":"a0a01fc7fbf6be45","added-peer-peer-urls":["https://199.204.45.44:2380"]} {"level":"info","ts":"2026-05-19T17:48:42.689168Z","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-19T17:48:42.6894Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.44:2380"} {"level":"info","ts":"2026-05-19T17:48:42.689586Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.44:2380"} {"level":"info","ts":"2026-05-19T17:48:42.689445Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a0a01fc7fbf6be45","initial-advertise-peer-urls":["https://199.204.45.44:2380"],"listen-peer-urls":["https://199.204.45.44:2380"],"advertise-client-urls":["https://199.204.45.44:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.44:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-19T17:48:42.689511Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-19T17:48:43.675309Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 is starting a new election at term 1"} {"level":"info","ts":"2026-05-19T17:48:43.675519Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-19T17:48:43.675643Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 received MsgPreVoteResp from a0a01fc7fbf6be45 at term 1"} {"level":"info","ts":"2026-05-19T17:48:43.675765Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became candidate at term 2"} {"level":"info","ts":"2026-05-19T17:48:43.675866Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 received MsgVoteResp from a0a01fc7fbf6be45 at term 2"} {"level":"info","ts":"2026-05-19T17:48:43.675978Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became leader at term 2"} {"level":"info","ts":"2026-05-19T17:48:43.676053Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a0a01fc7fbf6be45 elected leader a0a01fc7fbf6be45 at term 2"} {"level":"info","ts":"2026-05-19T17:48:43.677036Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a0a01fc7fbf6be45","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.44:2379]}","request-path":"/0/members/a0a01fc7fbf6be45/attributes","cluster-id":"92fa3fee501eaaec","publish-timeout":"7s"} {"level":"info","ts":"2026-05-19T17:48:43.677122Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T17:48:43.677193Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T17:48:43.677222Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T17:48:43.677591Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-19T17:48:43.677677Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-19T17:48:43.678214Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"92fa3fee501eaaec","local-member-id":"a0a01fc7fbf6be45","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T17:48:43.678402Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T17:48:43.678465Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T17:48:43.682723Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.44:2379"} {"level":"info","ts":"2026-05-19T17:48:43.683967Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-05-19T17:48:45.260164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.659106ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-05-19T17:48:45.260295Z","caller":"traceutil/trace.go:171","msg":"trace[970282719] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:5; }","duration":"242.128982ms","start":"2026-05-19T17:48:45.018148Z","end":"2026-05-19T17:48:45.260277Z","steps":["trace[970282719] 'read index received' (duration: 92.750947ms)","trace[970282719] 'applied index is now lower than readState.Index' (duration: 149.376835ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:48:45.260384Z","caller":"traceutil/trace.go:171","msg":"trace[1925205237] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"286.253201ms","start":"2026-05-19T17:48:44.974094Z","end":"2026-05-19T17:48:45.260347Z","steps":["trace[1925205237] 'process raft request' (duration: 136.780355ms)","trace[1925205237] 'compare' (duration: 148.519545ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:48:45.260514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.307322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-19T17:48:45.260574Z","caller":"traceutil/trace.go:171","msg":"trace[1740099207] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:0; response_revision:3; }","duration":"286.420793ms","start":"2026-05-19T17:48:44.97414Z","end":"2026-05-19T17:48:45.260561Z","steps":["trace[1740099207] 'agreement among raft nodes before linearized reading' (duration: 286.256081ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:48:45.261267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.440212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-19T17:48:45.261315Z","caller":"traceutil/trace.go:171","msg":"trace[73056852] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"240.531163ms","start":"2026-05-19T17:48:45.020774Z","end":"2026-05-19T17:48:45.261305Z","steps":["trace[73056852] 'agreement among raft nodes before linearized reading' (duration: 240.421752ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:48:45.261491Z","caller":"traceutil/trace.go:171","msg":"trace[1645332898] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"212.706735ms","start":"2026-05-19T17:48:45.048774Z","end":"2026-05-19T17:48:45.26148Z","steps":["trace[1645332898] 'process raft request' (duration: 212.132349ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:48:45.261576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.858579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-19T17:48:45.261648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.924122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.44\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-19T17:48:45.261686Z","caller":"traceutil/trace.go:171","msg":"trace[1350280214] range","detail":"{range_begin:/registry/masterleases/199.204.45.44; range_end:; response_count:0; response_revision:11; }","duration":"214.986003ms","start":"2026-05-19T17:48:45.046691Z","end":"2026-05-19T17:48:45.261677Z","steps":["trace[1350280214] 'agreement among raft nodes before linearized reading' (duration: 214.931413ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:48:45.261705Z","caller":"traceutil/trace.go:171","msg":"trace[108093324] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"212.97389ms","start":"2026-05-19T17:48:45.04867Z","end":"2026-05-19T17:48:45.261644Z","steps":["trace[108093324] 'agreement among raft nodes before linearized reading' (duration: 212.845578ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:48:45.261646Z","caller":"traceutil/trace.go:171","msg":"trace[1831766161] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"212.592186ms","start":"2026-05-19T17:48:45.049039Z","end":"2026-05-19T17:48:45.261631Z","steps":["trace[1831766161] 'process raft request' (duration: 211.961978ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:48:45.26186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.627547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-19T17:48:45.261862Z","caller":"traceutil/trace.go:171","msg":"trace[468769223] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"195.787384ms","start":"2026-05-19T17:48:45.066039Z","end":"2026-05-19T17:48:45.261827Z","steps":["trace[468769223] 'process raft request' (duration: 195.124556ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:48:45.261891Z","caller":"traceutil/trace.go:171","msg":"trace[1350714595] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"234.670897ms","start":"2026-05-19T17:48:45.027212Z","end":"2026-05-19T17:48:45.261883Z","steps":["trace[1350714595] 'agreement among raft nodes before linearized reading' (duration: 234.634137ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:48:45.261934Z","caller":"traceutil/trace.go:171","msg":"trace[859701579] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"212.846188ms","start":"2026-05-19T17:48:45.049068Z","end":"2026-05-19T17:48:45.261914Z","steps":["trace[859701579] 'process raft request' (duration: 211.982857ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:48:45.261826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.264813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-19T17:48:45.262174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.520562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-19T17:48:45.262208Z","caller":"traceutil/trace.go:171","msg":"trace[1417662684] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"234.653077ms","start":"2026-05-19T17:48:45.027541Z","end":"2026-05-19T17:48:45.262194Z","steps":["trace[1417662684] 'agreement among raft nodes before linearized reading' (duration: 234.266312ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:48:45.262226Z","caller":"traceutil/trace.go:171","msg":"trace[1464250013] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"161.595814ms","start":"2026-05-19T17:48:45.100616Z","end":"2026-05-19T17:48:45.262212Z","steps":["trace[1464250013] 'agreement among raft nodes before linearized reading' (duration: 161.501713ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:48:45.262234Z","caller":"traceutil/trace.go:171","msg":"trace[1670630824] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"212.957809ms","start":"2026-05-19T17:48:45.049265Z","end":"2026-05-19T17:48:45.262223Z","steps":["trace[1670630824] 'process raft request' (duration: 211.823696ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:48:45.262233Z","caller":"traceutil/trace.go:171","msg":"trace[916462913] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"213.09856ms","start":"2026-05-19T17:48:45.049067Z","end":"2026-05-19T17:48:45.262165Z","steps":["trace[916462913] 'process raft request' (duration: 211.956037ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:48:45.262362Z","caller":"traceutil/trace.go:171","msg":"trace[500531055] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"213.05795ms","start":"2026-05-19T17:48:45.04929Z","end":"2026-05-19T17:48:45.262348Z","steps":["trace[500531055] 'process raft request' (duration: 211.819836ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:48:45.26242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.092929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-19T17:48:45.261505Z","caller":"traceutil/trace.go:171","msg":"trace[2122040902] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"209.276986ms","start":"2026-05-19T17:48:45.052211Z","end":"2026-05-19T17:48:45.261488Z","steps":["trace[2122040902] 'process raft request' (duration: 208.923332ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:48:45.262463Z","caller":"traceutil/trace.go:171","msg":"trace[626878639] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"212.155259ms","start":"2026-05-19T17:48:45.050298Z","end":"2026-05-19T17:48:45.262453Z","steps":["trace[626878639] 'agreement among raft nodes before linearized reading' (duration: 212.091899ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:48:45.488059Z","caller":"traceutil/trace.go:171","msg":"trace[530285511] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"219.972428ms","start":"2026-05-19T17:48:45.268058Z","end":"2026-05-19T17:48:45.48803Z","steps":["trace[530285511] 'process raft request' (duration: 186.82725ms)","trace[530285511] 'compare' (duration: 33.013816ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:48:45.490973Z","caller":"traceutil/trace.go:171","msg":"trace[1176260586] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"221.843079ms","start":"2026-05-19T17:48:45.269096Z","end":"2026-05-19T17:48:45.490939Z","steps":["trace[1176260586] 'process raft request' (duration: 221.639757ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:48:45.491169Z","caller":"traceutil/trace.go:171","msg":"trace[1219679996] linearizableReadLoop","detail":"{readStateIndex:22; appliedIndex:14; }","duration":"217.516391ms","start":"2026-05-19T17:48:45.273638Z","end":"2026-05-19T17:48:45.491154Z","steps":["trace[1219679996] 'read index received' (duration: 181.322618ms)","trace[1219679996] 'applied index is now lower than readState.Index' (duration: 36.192943ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:48:45.491209Z","caller":"traceutil/trace.go:171","msg":"trace[310241098] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"221.339565ms","start":"2026-05-19T17:48:45.269863Z","end":"2026-05-19T17:48:45.491203Z","steps":["trace[310241098] 'process raft request' (duration: 221.086971ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:48:45.491Z","caller":"traceutil/trace.go:171","msg":"trace[1029215604] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"221.842099ms","start":"2026-05-19T17:48:45.269134Z","end":"2026-05-19T17:48:45.490976Z","steps":["trace[1029215604] 'process raft request' (duration: 221.723408ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:48:45.491352Z","caller":"traceutil/trace.go:171","msg":"trace[1521686807] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"220.875269ms","start":"2026-05-19T17:48:45.270362Z","end":"2026-05-19T17:48:45.491237Z","steps":["trace[1521686807] 'process raft request' (duration: 220.699906ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:48:45.49143Z","caller":"traceutil/trace.go:171","msg":"trace[890379525] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"221.502326ms","start":"2026-05-19T17:48:45.269915Z","end":"2026-05-19T17:48:45.491417Z","steps":["trace[890379525] 'process raft request' (duration: 221.087311ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:48:45.491472Z","caller":"traceutil/trace.go:171","msg":"trace[615915340] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"217.128235ms","start":"2026-05-19T17:48:45.274325Z","end":"2026-05-19T17:48:45.491453Z","steps":["trace[615915340] 'process raft request' (duration: 216.783061ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:48:45.491621Z","caller":"traceutil/trace.go:171","msg":"trace[1545383660] transaction","detail":"{read_only:false; number_of_response:0; response_revision:17; }","duration":"221.582506ms","start":"2026-05-19T17:48:45.270029Z","end":"2026-05-19T17:48:45.491612Z","steps":["trace[1545383660] 'process raft request' (duration: 221.011019ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:48:45.491179Z","caller":"traceutil/trace.go:171","msg":"trace[755513745] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"221.373535ms","start":"2026-05-19T17:48:45.269795Z","end":"2026-05-19T17:48:45.491169Z","steps":["trace[755513745] 'process raft request' (duration: 221.100801ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:48:45.491649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.988786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-19T17:48:45.491863Z","caller":"traceutil/trace.go:171","msg":"trace[588388752] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:19; }","duration":"218.227879ms","start":"2026-05-19T17:48:45.273624Z","end":"2026-05-19T17:48:45.491852Z","steps":["trace[588388752] 'agreement among raft nodes before linearized reading' (duration: 217.977126ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:48:45.491926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.90474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-05-19T17:48:45.49199Z","caller":"traceutil/trace.go:171","msg":"trace[184068146] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:19; }","duration":"164.986511ms","start":"2026-05-19T17:48:45.326972Z","end":"2026-05-19T17:48:45.491959Z","steps":["trace[184068146] 'agreement among raft nodes before linearized reading' (duration: 164.814999ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:49:14.598517Z","caller":"traceutil/trace.go:171","msg":"trace[1533496346] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"177.784484ms","start":"2026-05-19T17:49:14.420692Z","end":"2026-05-19T17:49:14.598476Z","steps":["trace[1533496346] 'process raft request' (duration: 157.36535ms)","trace[1533496346] 'compare' (duration: 18.987663ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:49:16.441017Z","caller":"traceutil/trace.go:171","msg":"trace[1269534379] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:609; }","duration":"262.97488ms","start":"2026-05-19T17:49:16.178Z","end":"2026-05-19T17:49:16.440975Z","steps":["trace[1269534379] 'read index received' (duration: 256.099946ms)","trace[1269534379] 'applied index is now lower than readState.Index' (duration: 6.874074ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:49:16.441186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.175791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-05-19T17:49:16.441218Z","caller":"traceutil/trace.go:171","msg":"trace[2104395866] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:591; }","duration":"263.265693ms","start":"2026-05-19T17:49:16.177942Z","end":"2026-05-19T17:49:16.441207Z","steps":["trace[2104395866] 'agreement among raft nodes before linearized reading' (duration: 263.133482ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:49:16.441589Z","caller":"traceutil/trace.go:171","msg":"trace[1399603004] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"266.057214ms","start":"2026-05-19T17:49:16.175494Z","end":"2026-05-19T17:49:16.441551Z","steps":["trace[1399603004] 'process raft request' (duration: 259.638683ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:49:16.640983Z","caller":"traceutil/trace.go:171","msg":"trace[2110029065] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"340.181008ms","start":"2026-05-19T17:49:16.300764Z","end":"2026-05-19T17:49:16.640945Z","steps":["trace[2110029065] 'process raft request' (duration: 259.593384ms)","trace[2110029065] 'compare' (duration: 80.302922ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:49:16.641147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:49:16.300736Z","time spent":"340.32923ms","remote":"127.0.0.1:46658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:49:16.641166Z","caller":"traceutil/trace.go:171","msg":"trace[1745041121] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"234.113744ms","start":"2026-05-19T17:49:16.407027Z","end":"2026-05-19T17:49:16.641141Z","steps":["trace[1745041121] 'process raft request' (duration: 233.834571ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:49:16.641461Z","caller":"traceutil/trace.go:171","msg":"trace[857099180] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"151.613683ms","start":"2026-05-19T17:49:16.489818Z","end":"2026-05-19T17:49:16.641432Z","steps":["trace[857099180] 'process raft request' (duration: 151.131189ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:49:21.238384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.324219ms","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-19T17:49:21.238497Z","caller":"traceutil/trace.go:171","msg":"trace[1985114769] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:635; }","duration":"429.478201ms","start":"2026-05-19T17:49:20.808995Z","end":"2026-05-19T17:49:21.238473Z","steps":["trace[1985114769] 'range keys from in-memory index tree' (duration: 429.166368ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:49:21.238474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.733931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-19T17:49:21.238546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:49:20.808977Z","time spent":"429.560161ms","remote":"127.0.0.1:46658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-19T17:49:21.238569Z","caller":"traceutil/trace.go:171","msg":"trace[2077344788] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:635; }","duration":"358.877822ms","start":"2026-05-19T17:49:20.879664Z","end":"2026-05-19T17:49:21.238542Z","steps":["trace[2077344788] 'range keys from in-memory index tree' (duration: 358.54377ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:49:21.238611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:49:20.87964Z","time spent":"358.961193ms","remote":"127.0.0.1:46658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-19T17:50:58.099141Z","caller":"traceutil/trace.go:171","msg":"trace[1968944027] linearizableReadLoop","detail":"{readStateIndex:1904; appliedIndex:1903; }","duration":"159.131521ms","start":"2026-05-19T17:50:57.939974Z","end":"2026-05-19T17:50:58.099106Z","steps":["trace[1968944027] 'read index received' (duration: 155.280209ms)","trace[1968944027] 'applied index is now lower than readState.Index' (duration: 3.849862ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:50:58.099218Z","caller":"traceutil/trace.go:171","msg":"trace[1583851663] transaction","detail":"{read_only:false; response_revision:1834; number_of_response:1; }","duration":"179.542629ms","start":"2026-05-19T17:50:57.919633Z","end":"2026-05-19T17:50:58.099175Z","steps":["trace[1583851663] 'process raft request' (duration: 175.746167ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:58.099452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.918944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bed0dd4a-4ef4-4e0b-be2c-2d50796a45ba\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-05-19T17:50:58.099543Z","caller":"traceutil/trace.go:171","msg":"trace[688697163] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-bed0dd4a-4ef4-4e0b-be2c-2d50796a45ba; range_end:; response_count:1; response_revision:1834; }","duration":"133.056207ms","start":"2026-05-19T17:50:57.966467Z","end":"2026-05-19T17:50:58.099523Z","steps":["trace[688697163] 'agreement among raft nodes before linearized reading' (duration: 132.826363ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:58.099702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.713683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-05-19T17:50:58.099816Z","caller":"traceutil/trace.go:171","msg":"trace[1172430595] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1834; }","duration":"159.876585ms","start":"2026-05-19T17:50:57.93992Z","end":"2026-05-19T17:50:58.099796Z","steps":["trace[1172430595] 'agreement among raft nodes before linearized reading' (duration: 159.421057ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:50:58.238562Z","caller":"traceutil/trace.go:171","msg":"trace[919681022] transaction","detail":"{read_only:false; response_revision:1835; number_of_response:1; }","duration":"134.452058ms","start":"2026-05-19T17:50:58.104083Z","end":"2026-05-19T17:50:58.238535Z","steps":["trace[919681022] 'process raft request' (duration: 123.464201ms)","trace[919681022] 'compare' (duration: 10.858704ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:50:58.507734Z","caller":"traceutil/trace.go:171","msg":"trace[2025338255] linearizableReadLoop","detail":"{readStateIndex:1906; appliedIndex:1904; }","duration":"288.493983ms","start":"2026-05-19T17:50:58.219216Z","end":"2026-05-19T17:50:58.50771Z","steps":["trace[2025338255] 'read index received' (duration: 8.384598ms)","trace[2025338255] 'applied index is now lower than readState.Index' (duration: 280.108145ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:50:58.507908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.646406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:50:58.507953Z","caller":"traceutil/trace.go:171","msg":"trace[1934087472] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1836; }","duration":"288.770019ms","start":"2026-05-19T17:50:58.219171Z","end":"2026-05-19T17:50:58.507941Z","steps":["trace[1934087472] 'agreement among raft nodes before linearized reading' (duration: 288.652607ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:50:58.507789Z","caller":"traceutil/trace.go:171","msg":"trace[2102726620] transaction","detail":"{read_only:false; response_revision:1836; number_of_response:1; }","duration":"401.347793ms","start":"2026-05-19T17:50:58.106413Z","end":"2026-05-19T17:50:58.507761Z","steps":["trace[2102726620] 'process raft request' (duration: 400.181642ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:58.508416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:58.106394Z","time spent":"401.901464ms","remote":"127.0.0.1:46562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3731,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:50:58.509229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.952083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bed0dd4a-4ef4-4e0b-be2c-2d50796a45ba\" ","response":"range_response_count:1 size:3746"} {"level":"info","ts":"2026-05-19T17:50:58.509324Z","caller":"traceutil/trace.go:171","msg":"trace[2110467377] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-bed0dd4a-4ef4-4e0b-be2c-2d50796a45ba; range_end:; response_count:1; response_revision:1837; }","duration":"254.127167ms","start":"2026-05-19T17:50:58.255179Z","end":"2026-05-19T17:50:58.509306Z","steps":["trace[2110467377] 'agreement among raft nodes before linearized reading' (duration: 253.959573ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:58.510162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.784909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:50:58.510204Z","caller":"traceutil/trace.go:171","msg":"trace[1396966232] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1837; }","duration":"254.88333ms","start":"2026-05-19T17:50:58.255311Z","end":"2026-05-19T17:50:58.510194Z","steps":["trace[1396966232] 'agreement among raft nodes before linearized reading' (duration: 254.817839ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:21.378367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.221801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:51:21.378445Z","caller":"traceutil/trace.go:171","msg":"trace[1287428256] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2013; }","duration":"122.344892ms","start":"2026-05-19T17:51:21.256084Z","end":"2026-05-19T17:51:21.378429Z","steps":["trace[1287428256] 'range keys from in-memory index tree' (duration: 122.1474ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:26.687042Z","caller":"traceutil/trace.go:171","msg":"trace[1689434506] transaction","detail":"{read_only:false; response_revision:2047; number_of_response:1; }","duration":"151.989967ms","start":"2026-05-19T17:51:26.535028Z","end":"2026-05-19T17:51:26.687018Z","steps":["trace[1689434506] 'process raft request' (duration: 151.658431ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:27.897017Z","caller":"traceutil/trace.go:171","msg":"trace[350886367] transaction","detail":"{read_only:false; response_revision:2051; number_of_response:1; }","duration":"120.733054ms","start":"2026-05-19T17:51:27.776263Z","end":"2026-05-19T17:51:27.896996Z","steps":["trace[350886367] 'process raft request' (duration: 120.582922ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:27.908113Z","caller":"traceutil/trace.go:171","msg":"trace[551565405] transaction","detail":"{read_only:false; response_revision:2052; number_of_response:1; }","duration":"121.178672ms","start":"2026-05-19T17:51:27.786907Z","end":"2026-05-19T17:51:27.908086Z","steps":["trace[551565405] 'process raft request' (duration: 120.996299ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:28.327199Z","caller":"traceutil/trace.go:171","msg":"trace[1648933830] transaction","detail":"{read_only:false; response_revision:2058; number_of_response:1; }","duration":"199.265245ms","start":"2026-05-19T17:51:28.127908Z","end":"2026-05-19T17:51:28.327173Z","steps":["trace[1648933830] 'process raft request' (duration: 111.726016ms)","trace[1648933830] 'compare' (duration: 87.280126ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:51:28.500635Z","caller":"traceutil/trace.go:171","msg":"trace[786113221] transaction","detail":"{read_only:false; response_revision:2059; number_of_response:1; }","duration":"168.783573ms","start":"2026-05-19T17:51:28.331825Z","end":"2026-05-19T17:51:28.500608Z","steps":["trace[786113221] 'process raft request' (duration: 94.899651ms)","trace[786113221] 'compare' (duration: 73.668889ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:52:36.22346Z","caller":"traceutil/trace.go:171","msg":"trace[584752282] transaction","detail":"{read_only:false; response_revision:2465; number_of_response:1; }","duration":"106.094465ms","start":"2026-05-19T17:52:36.117341Z","end":"2026-05-19T17:52:36.223435Z","steps":["trace[584752282] 'process raft request' (duration: 103.148412ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:43.589558Z","caller":"traceutil/trace.go:171","msg":"trace[1560575126] linearizableReadLoop","detail":"{readStateIndex:2655; appliedIndex:2654; }","duration":"188.241898ms","start":"2026-05-19T17:52:43.401294Z","end":"2026-05-19T17:52:43.589536Z","steps":["trace[1560575126] 'read index received' (duration: 188.027436ms)","trace[1560575126] 'applied index is now lower than readState.Index' (duration: 213.242µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:52:43.589817Z","caller":"traceutil/trace.go:171","msg":"trace[1339700211] transaction","detail":"{read_only:false; response_revision:2562; number_of_response:1; }","duration":"194.174273ms","start":"2026-05-19T17:52:43.395631Z","end":"2026-05-19T17:52:43.589806Z","steps":["trace[1339700211] 'process raft request' (duration: 193.781039ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:43.590092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.777575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-05-19T17:52:43.59014Z","caller":"traceutil/trace.go:171","msg":"trace[1597314332] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2562; }","duration":"188.867585ms","start":"2026-05-19T17:52:43.401261Z","end":"2026-05-19T17:52:43.590128Z","steps":["trace[1597314332] 'agreement among raft nodes before linearized reading' (duration: 188.726813ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:43.590378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.530206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T17:52:43.590439Z","caller":"traceutil/trace.go:171","msg":"trace[1157378349] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2562; }","duration":"134.594866ms","start":"2026-05-19T17:52:43.455827Z","end":"2026-05-19T17:52:43.590422Z","steps":["trace[1157378349] 'agreement among raft nodes before linearized reading' (duration: 134.502865ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:56:56.869569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.165707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:56:56.869669Z","caller":"traceutil/trace.go:171","msg":"trace[280430530] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4535; }","duration":"108.32627ms","start":"2026-05-19T17:56:56.761322Z","end":"2026-05-19T17:56:56.869649Z","steps":["trace[280430530] 'range keys from in-memory index tree' (duration: 108.024075ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:56:58.939285Z","caller":"traceutil/trace.go:171","msg":"trace[1901411583] transaction","detail":"{read_only:false; response_revision:4591; number_of_response:1; }","duration":"105.504271ms","start":"2026-05-19T17:56:58.833758Z","end":"2026-05-19T17:56:58.939262Z","steps":["trace[1901411583] 'process raft request' (duration: 72.985116ms)","trace[1901411583] 'compare' (duration: 32.325272ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:57:04.141109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.621721ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-19T17:57:04.141227Z","caller":"traceutil/trace.go:171","msg":"trace[508874729] transaction","detail":"{read_only:false; response_revision:4715; number_of_response:1; }","duration":"185.063384ms","start":"2026-05-19T17:57:03.956139Z","end":"2026-05-19T17:57:04.141203Z","steps":["trace[508874729] 'process raft request' (duration: 55.471312ms)","trace[508874729] 'compare' (duration: 129.301758ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:57:04.42966Z","caller":"traceutil/trace.go:171","msg":"trace[1442873692] transaction","detail":"{read_only:false; response_revision:4716; number_of_response:1; }","duration":"341.408602ms","start":"2026-05-19T17:57:04.088215Z","end":"2026-05-19T17:57:04.429623Z","steps":["trace[1442873692] 'process raft request' (duration: 341.105048ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:57:04.429885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:57:04.088182Z","time spent":"341.590934ms","remote":"127.0.0.1:46658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:57:04.452947Z","caller":"traceutil/trace.go:171","msg":"trace[549780938] linearizableReadLoop","detail":"{readStateIndex:4890; appliedIndex:4888; }","duration":"248.162544ms","start":"2026-05-19T17:57:04.204761Z","end":"2026-05-19T17:57:04.452923Z","steps":["trace[549780938] 'read index received' (duration: 224.531452ms)","trace[549780938] 'applied index is now lower than readState.Index' (duration: 23.627072ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:57:04.452996Z","caller":"traceutil/trace.go:171","msg":"trace[1443344071] transaction","detail":"{read_only:false; response_revision:4717; number_of_response:1; }","duration":"278.802917ms","start":"2026-05-19T17:57:04.174153Z","end":"2026-05-19T17:57:04.452956Z","steps":["trace[1443344071] 'process raft request' (duration: 278.635595ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:57:04.453255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.859123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:57:04.453352Z","caller":"traceutil/trace.go:171","msg":"trace[1920715386] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4717; }","duration":"197.009464ms","start":"2026-05-19T17:57:04.256329Z","end":"2026-05-19T17:57:04.453339Z","steps":["trace[1920715386] 'agreement among raft nodes before linearized reading' (duration: 196.818222ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:57:04.453633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.825274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b06ae3e8-fd40-4395-bf84-d09f1f499bae\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-05-19T17:57:04.453768Z","caller":"traceutil/trace.go:171","msg":"trace[1180492060] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b06ae3e8-fd40-4395-bf84-d09f1f499bae; range_end:; response_count:1; response_revision:4717; }","duration":"249.018337ms","start":"2026-05-19T17:57:04.204717Z","end":"2026-05-19T17:57:04.453735Z","steps":["trace[1180492060] 'agreement among raft nodes before linearized reading' (duration: 248.387577ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:57:04.85261Z","caller":"traceutil/trace.go:171","msg":"trace[1476252723] linearizableReadLoop","detail":"{readStateIndex:4893; appliedIndex:4892; }","duration":"122.884194ms","start":"2026-05-19T17:57:04.729701Z","end":"2026-05-19T17:57:04.852585Z","steps":["trace[1476252723] 'read index received' (duration: 73.513521ms)","trace[1476252723] 'applied index is now lower than readState.Index' (duration: 49.369573ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:57:04.85273Z","caller":"traceutil/trace.go:171","msg":"trace[1172510606] transaction","detail":"{read_only:false; response_revision:4719; number_of_response:1; }","duration":"130.532566ms","start":"2026-05-19T17:57:04.722161Z","end":"2026-05-19T17:57:04.852693Z","steps":["trace[1172510606] 'process raft request' (duration: 102.069945ms)","trace[1172510606] 'compare' (duration: 28.217017ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:57:04.853145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.427363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-05-19T17:57:04.853207Z","caller":"traceutil/trace.go:171","msg":"trace[1451482564] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4719; }","duration":"123.534535ms","start":"2026-05-19T17:57:04.729655Z","end":"2026-05-19T17:57:04.85319Z","steps":["trace[1451482564] 'agreement among raft nodes before linearized reading' (duration: 123.016526ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:57:04.884097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.910454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T17:57:04.884162Z","caller":"traceutil/trace.go:171","msg":"trace[2058128958] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:4723; }","duration":"125.015805ms","start":"2026-05-19T17:57:04.75913Z","end":"2026-05-19T17:57:04.884146Z","steps":["trace[2058128958] 'agreement among raft nodes before linearized reading' (duration: 124.913194ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:57:05.355146Z","caller":"traceutil/trace.go:171","msg":"trace[1626663503] linearizableReadLoop","detail":"{readStateIndex:4906; appliedIndex:4905; }","duration":"133.967891ms","start":"2026-05-19T17:57:05.221145Z","end":"2026-05-19T17:57:05.355113Z","steps":["trace[1626663503] 'read index received' (duration: 125.802µs)","trace[1626663503] 'applied index is now lower than readState.Index' (duration: 133.840619ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:57:05.355425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.162051ms","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-19T17:57:05.355476Z","caller":"traceutil/trace.go:171","msg":"trace[1883285784] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4732; }","duration":"133.239632ms","start":"2026-05-19T17:57:05.222225Z","end":"2026-05-19T17:57:05.355464Z","steps":["trace[1883285784] 'agreement among raft nodes before linearized reading' (duration: 133.084539ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:57:05.355284Z","caller":"traceutil/trace.go:171","msg":"trace[889740182] transaction","detail":"{read_only:false; response_revision:4732; number_of_response:1; }","duration":"134.113953ms","start":"2026-05-19T17:57:05.221129Z","end":"2026-05-19T17:57:05.355243Z","steps":["trace[889740182] 'process raft request' (duration: 110.686335ms)","trace[889740182] 'compare' (duration: 23.144764ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:57:05.355691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.758451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"warn","ts":"2026-05-19T17:57:05.355654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.498839ms","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-19T17:57:05.355807Z","caller":"traceutil/trace.go:171","msg":"trace[770306934] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4732; }","duration":"129.897563ms","start":"2026-05-19T17:57:05.225883Z","end":"2026-05-19T17:57:05.35578Z","steps":["trace[770306934] 'agreement among raft nodes before linearized reading' (duration: 129.606399ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:57:05.355836Z","caller":"traceutil/trace.go:171","msg":"trace[5952042] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4732; }","duration":"134.712243ms","start":"2026-05-19T17:57:05.221107Z","end":"2026-05-19T17:57:05.355819Z","steps":["trace[5952042] 'agreement among raft nodes before linearized reading' (duration: 134.169394ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:57:17.891246Z","caller":"traceutil/trace.go:171","msg":"trace[130001101] linearizableReadLoop","detail":"{readStateIndex:5079; appliedIndex:5078; }","duration":"106.095246ms","start":"2026-05-19T17:57:17.785125Z","end":"2026-05-19T17:57:17.89122Z","steps":["trace[130001101] 'read index received' (duration: 105.882353ms)","trace[130001101] 'applied index is now lower than readState.Index' (duration: 211.963µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:57:17.891379Z","caller":"traceutil/trace.go:171","msg":"trace[1151839741] transaction","detail":"{read_only:false; response_revision:4899; number_of_response:1; }","duration":"130.132445ms","start":"2026-05-19T17:57:17.761205Z","end":"2026-05-19T17:57:17.891338Z","steps":["trace[1151839741] 'process raft request' (duration: 129.858631ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:57:17.891481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.329099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-19T17:57:17.891521Z","caller":"traceutil/trace.go:171","msg":"trace[284031970] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4899; }","duration":"106.431521ms","start":"2026-05-19T17:57:17.785075Z","end":"2026-05-19T17:57:17.891507Z","steps":["trace[284031970] 'agreement among raft nodes before linearized reading' (duration: 106.276589ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:58:43.711906Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2888} {"level":"info","ts":"2026-05-19T17:58:43.825342Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2888,"took":"111.664683ms","hash":4241497983,"current-db-size-bytes":53170176,"current-db-size":"53 MB","current-db-size-in-use-bytes":29372416,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-19T17:58:43.825436Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4241497983,"revision":2888,"compact-revision":-1} {"level":"info","ts":"2026-05-19T18:03:43.718124Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5611} {"level":"info","ts":"2026-05-19T18:03:43.809099Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5611,"took":"89.755774ms","hash":4149425335,"current-db-size-bytes":53170176,"current-db-size":"53 MB","current-db-size-in-use-bytes":18350080,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-19T18:03:43.809151Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4149425335,"revision":5611,"compact-revision":2888}