{"level":"warn","ts":"2026-05-28T07:45:03.082245Z","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-28T07:45:03.08233Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.138: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.138:2380","--initial-cluster=instance=https://199.204.45.138:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.138:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.138: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-28T07:45:03.082391Z","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-28T07:45:03.082403Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.138:2380"]} {"level":"info","ts":"2026-05-28T07:45:03.082421Z","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-28T07:45:03.08302Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"]} {"level":"info","ts":"2026-05-28T07:45:03.083088Z","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.138:2380"],"listen-peer-urls":["https://199.204.45.138:2380"],"advertise-client-urls":["https://199.204.45.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.138: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-28T07:45:03.08627Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.001438ms"} {"level":"info","ts":"2026-05-28T07:45:03.092238Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f3d52791b64f7591","cluster-id":"90076b89649fe294"} {"level":"info","ts":"2026-05-28T07:45:03.092302Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=()"} {"level":"info","ts":"2026-05-28T07:45:03.092322Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became follower at term 0"} {"level":"info","ts":"2026-05-28T07:45:03.092334Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f3d52791b64f7591 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-28T07:45:03.09234Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became follower at term 1"} {"level":"info","ts":"2026-05-28T07:45:03.092372Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=(17569993028038260113)"} {"level":"warn","ts":"2026-05-28T07:45:03.098868Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-28T07:45:03.100825Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-28T07:45:03.102886Z","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-28T07:45:03.106737Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f3d52791b64f7591","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-28T07:45:03.106963Z","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-28T07:45:03.10773Z","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-28T07:45:03.107776Z","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-28T07:45:03.107003Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f3d52791b64f7591","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-28T07:45:03.109148Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=(17569993028038260113)"} {"level":"info","ts":"2026-05-28T07:45:03.109253Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"90076b89649fe294","local-member-id":"f3d52791b64f7591","added-peer-id":"f3d52791b64f7591","added-peer-peer-urls":["https://199.204.45.138:2380"]} {"level":"info","ts":"2026-05-28T07:45:03.110534Z","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-28T07:45:03.110667Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.138:2380"} {"level":"info","ts":"2026-05-28T07:45:03.110693Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.138:2380"} {"level":"info","ts":"2026-05-28T07:45:03.110805Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f3d52791b64f7591","initial-advertise-peer-urls":["https://199.204.45.138:2380"],"listen-peer-urls":["https://199.204.45.138:2380"],"advertise-client-urls":["https://199.204.45.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-28T07:45:03.110842Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-28T07:45:04.093568Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 is starting a new election at term 1"} {"level":"info","ts":"2026-05-28T07:45:04.093615Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-28T07:45:04.093641Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 received MsgPreVoteResp from f3d52791b64f7591 at term 1"} {"level":"info","ts":"2026-05-28T07:45:04.093656Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became candidate at term 2"} {"level":"info","ts":"2026-05-28T07:45:04.093667Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 received MsgVoteResp from f3d52791b64f7591 at term 2"} {"level":"info","ts":"2026-05-28T07:45:04.093701Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became leader at term 2"} {"level":"info","ts":"2026-05-28T07:45:04.093713Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f3d52791b64f7591 elected leader f3d52791b64f7591 at term 2"} {"level":"info","ts":"2026-05-28T07:45:04.094514Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f3d52791b64f7591","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.138:2379]}","request-path":"/0/members/f3d52791b64f7591/attributes","cluster-id":"90076b89649fe294","publish-timeout":"7s"} {"level":"info","ts":"2026-05-28T07:45:04.094508Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-28T07:45:04.094527Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-28T07:45:04.094626Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-28T07:45:04.095244Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-28T07:45:04.095297Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-28T07:45:04.095468Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"90076b89649fe294","local-member-id":"f3d52791b64f7591","cluster-version":"3.5"} {"level":"info","ts":"2026-05-28T07:45:04.095564Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-28T07:45:04.095607Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-28T07:45:04.097174Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.138:2379"} {"level":"info","ts":"2026-05-28T07:45:04.097191Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-28T07:45:05.522682Z","caller":"traceutil/trace.go:171","msg":"trace[1015882559] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"242.999565ms","start":"2026-05-28T07:45:05.279658Z","end":"2026-05-28T07:45:05.522658Z","steps":["trace[1015882559] 'process raft request' (duration: 242.799833ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T07:45:05.636436Z","caller":"traceutil/trace.go:171","msg":"trace[1499401550] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"286.941328ms","start":"2026-05-28T07:45:05.349462Z","end":"2026-05-28T07:45:05.636404Z","steps":["trace[1499401550] 'process raft request' (duration: 286.839457ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T07:45:05.636485Z","caller":"traceutil/trace.go:171","msg":"trace[1424437804] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"356.68805ms","start":"2026-05-28T07:45:05.279755Z","end":"2026-05-28T07:45:05.636443Z","steps":["trace[1424437804] 'process raft request' (duration: 349.439053ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T07:45:05.63658Z","caller":"traceutil/trace.go:171","msg":"trace[1320074071] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"352.499271ms","start":"2026-05-28T07:45:05.284052Z","end":"2026-05-28T07:45:05.636551Z","steps":["trace[1320074071] 'read index received' (duration: 238.484663ms)","trace[1320074071] 'applied index is now lower than readState.Index' (duration: 114.013518ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T07:45:05.636958Z","caller":"traceutil/trace.go:171","msg":"trace[1335756427] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"284.076643ms","start":"2026-05-28T07:45:05.35285Z","end":"2026-05-28T07:45:05.636927Z","steps":["trace[1335756427] 'process raft request' (duration: 283.601978ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T07:45:05.63702Z","caller":"traceutil/trace.go:171","msg":"trace[1464540516] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"286.920559ms","start":"2026-05-28T07:45:05.350063Z","end":"2026-05-28T07:45:05.636983Z","steps":["trace[1464540516] 'process raft request' (duration: 286.298993ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T07:45:05.636973Z","caller":"traceutil/trace.go:171","msg":"trace[203570719] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"286.897229ms","start":"2026-05-28T07:45:05.35003Z","end":"2026-05-28T07:45:05.636927Z","steps":["trace[203570719] 'process raft request' (duration: 286.308783ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:45:05.637186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T07:45:05.279746Z","time spent":"356.833511ms","remote":"127.0.0.1:46774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T07:45:05.637324Z","caller":"traceutil/trace.go:171","msg":"trace[1506479632] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"265.037077ms","start":"2026-05-28T07:45:05.372277Z","end":"2026-05-28T07:45:05.637314Z","steps":["trace[1506479632] 'process raft request' (duration: 264.237899ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T07:45:05.637459Z","caller":"traceutil/trace.go:171","msg":"trace[1910373372] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"265.868846ms","start":"2026-05-28T07:45:05.371582Z","end":"2026-05-28T07:45:05.637451Z","steps":["trace[1910373372] 'process raft request' (duration: 264.898436ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T07:45:05.637545Z","caller":"traceutil/trace.go:171","msg":"trace[811731986] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"284.665288ms","start":"2026-05-28T07:45:05.352811Z","end":"2026-05-28T07:45:05.637476Z","steps":["trace[811731986] 'process raft request' (duration: 283.621038ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:45:05.637688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.33424ms","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-28T07:45:05.637762Z","caller":"traceutil/trace.go:171","msg":"trace[1111761165] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"303.43788ms","start":"2026-05-28T07:45:05.33431Z","end":"2026-05-28T07:45:05.637748Z","steps":["trace[1111761165] 'agreement among raft nodes before linearized reading' (duration: 303.283249ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:45:05.637794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T07:45:05.334295Z","time spent":"303.491911ms","remote":"127.0.0.1:46812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-28T07:45:05.637956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.899914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3171"} {"level":"info","ts":"2026-05-28T07:45:05.637989Z","caller":"traceutil/trace.go:171","msg":"trace[2020159343] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:11; }","duration":"353.947184ms","start":"2026-05-28T07:45:05.284034Z","end":"2026-05-28T07:45:05.637981Z","steps":["trace[2020159343] 'agreement among raft nodes before linearized reading' (duration: 353.884884ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:45:05.638098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.942168ms","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-28T07:45:05.638137Z","caller":"traceutil/trace.go:171","msg":"trace[612068206] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"237.976679ms","start":"2026-05-28T07:45:05.400149Z","end":"2026-05-28T07:45:05.638125Z","steps":["trace[612068206] 'agreement among raft nodes before linearized reading' (duration: 237.931038ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:45:05.638285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.746474ms","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-28T07:45:05.638321Z","caller":"traceutil/trace.go:171","msg":"trace[1690246742] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"265.803874ms","start":"2026-05-28T07:45:05.372507Z","end":"2026-05-28T07:45:05.638311Z","steps":["trace[1690246742] 'agreement among raft nodes before linearized reading' (duration: 265.756434ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:45:05.638329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.146927ms","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-28T07:45:05.638362Z","caller":"traceutil/trace.go:171","msg":"trace[1956085596] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"303.183387ms","start":"2026-05-28T07:45:05.335165Z","end":"2026-05-28T07:45:05.638349Z","steps":["trace[1956085596] 'agreement among raft nodes before linearized reading' (duration: 303.144267ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:45:05.638394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T07:45:05.335161Z","time spent":"303.228868ms","remote":"127.0.0.1:46812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-28T07:45:05.638447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.788374ms","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-28T07:45:05.638478Z","caller":"traceutil/trace.go:171","msg":"trace[533651118] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"290.843695ms","start":"2026-05-28T07:45:05.347627Z","end":"2026-05-28T07:45:05.638471Z","steps":["trace[533651118] 'agreement among raft nodes before linearized reading' (duration: 290.802054ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:45:05.638534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.103366ms","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-28T07:45:05.638574Z","caller":"traceutil/trace.go:171","msg":"trace[442581506] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"304.168046ms","start":"2026-05-28T07:45:05.33439Z","end":"2026-05-28T07:45:05.638558Z","steps":["trace[442581506] 'agreement among raft nodes before linearized reading' (duration: 304.124156ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:45:05.638606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T07:45:05.334371Z","time spent":"304.229617ms","remote":"127.0.0.1:46812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-28T07:45:05.638286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T07:45:05.284028Z","time spent":"354.246697ms","remote":"127.0.0.1:46876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3195,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-05-28T07:45:05.637983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.885975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.138\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-28T07:45:05.638933Z","caller":"traceutil/trace.go:171","msg":"trace[463230974] range","detail":"{range_begin:/registry/masterleases/199.204.45.138; range_end:; response_count:0; response_revision:11; }","duration":"291.860944ms","start":"2026-05-28T07:45:05.347057Z","end":"2026-05-28T07:45:05.638918Z","steps":["trace[463230974] 'agreement among raft nodes before linearized reading' (duration: 290.872405ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T07:45:05.642831Z","caller":"traceutil/trace.go:171","msg":"trace[151376687] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"286.575477ms","start":"2026-05-28T07:45:05.350302Z","end":"2026-05-28T07:45:05.636878Z","steps":["trace[151376687] 'process raft request' (duration: 286.103221ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T07:45:40.460533Z","caller":"traceutil/trace.go:171","msg":"trace[2057295558] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"201.229542ms","start":"2026-05-28T07:45:40.259236Z","end":"2026-05-28T07:45:40.460466Z","steps":["trace[2057295558] 'process raft request' (duration: 197.896742ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:45:43.125736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.662438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T07:45:43.125863Z","caller":"traceutil/trace.go:171","msg":"trace[1045834030] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:615; }","duration":"228.828159ms","start":"2026-05-28T07:45:42.897019Z","end":"2026-05-28T07:45:43.125847Z","steps":["trace[1045834030] 'range keys from in-memory index tree' (duration: 228.608868ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:45:43.126038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.642736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-28T07:45:43.126081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.94467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-28T07:45:43.126143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.122089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T07:45:43.126167Z","caller":"traceutil/trace.go:171","msg":"trace[1146581283] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:615; }","duration":"205.179359ms","start":"2026-05-28T07:45:42.920981Z","end":"2026-05-28T07:45:43.12616Z","steps":["trace[1146581283] 'count revisions from in-memory index tree' (duration: 205.026459ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:45:43.126209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.215285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T07:45:43.126158Z","caller":"traceutil/trace.go:171","msg":"trace[495608856] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:615; }","duration":"229.06045ms","start":"2026-05-28T07:45:42.897079Z","end":"2026-05-28T07:45:43.126139Z","steps":["trace[495608856] 'range keys from in-memory index tree' (duration: 228.849468ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:45:43.126314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.365675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T07:45:43.126359Z","caller":"traceutil/trace.go:171","msg":"trace[642852516] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:615; }","duration":"182.425685ms","start":"2026-05-28T07:45:42.943919Z","end":"2026-05-28T07:45:43.126345Z","steps":["trace[642852516] 'range keys from in-memory index tree' (duration: 182.277415ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T07:45:43.126108Z","caller":"traceutil/trace.go:171","msg":"trace[308957860] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:615; }","duration":"204.757227ms","start":"2026-05-28T07:45:42.921334Z","end":"2026-05-28T07:45:43.126092Z","steps":["trace[308957860] 'range keys from in-memory index tree' (duration: 204.563356ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:45:43.126407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.21537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-28T07:45:43.126464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.441348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T07:45:43.126557Z","caller":"traceutil/trace.go:171","msg":"trace[1032935050] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:615; }","duration":"205.440521ms","start":"2026-05-28T07:45:42.92107Z","end":"2026-05-28T07:45:43.12651Z","steps":["trace[1032935050] 'range keys from in-memory index tree' (duration: 205.131759ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T07:45:43.126703Z","caller":"traceutil/trace.go:171","msg":"trace[1733351158] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:615; }","duration":"182.263705ms","start":"2026-05-28T07:45:42.943971Z","end":"2026-05-28T07:45:43.126235Z","steps":["trace[1733351158] 'count revisions from in-memory index tree' (duration: 182.193065ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:45:43.126802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.642543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-28T07:45:43.127234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.936438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T07:45:43.127889Z","caller":"traceutil/trace.go:171","msg":"trace[426351647] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:615; }","duration":"179.897621ms","start":"2026-05-28T07:45:42.946626Z","end":"2026-05-28T07:45:43.126524Z","steps":["trace[426351647] 'range keys from in-memory index tree' (duration: 179.354428ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T07:45:43.128119Z","caller":"traceutil/trace.go:171","msg":"trace[1052057632] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:615; }","duration":"229.754535ms","start":"2026-05-28T07:45:42.897102Z","end":"2026-05-28T07:45:43.126857Z","steps":["trace[1052057632] 'count revisions from in-memory index tree' (duration: 229.580052ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T07:45:43.128259Z","caller":"traceutil/trace.go:171","msg":"trace[8786873] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:638; }","duration":"163.900788ms","start":"2026-05-28T07:45:42.963287Z","end":"2026-05-28T07:45:43.127188Z","steps":["trace[8786873] 'read index received' (duration: 163.380784ms)","trace[8786873] 'applied index is now lower than readState.Index' (duration: 519.254µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T07:45:43.128483Z","caller":"traceutil/trace.go:171","msg":"trace[590848504] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:616; }","duration":"164.011508ms","start":"2026-05-28T07:45:42.963272Z","end":"2026-05-28T07:45:43.127284Z","steps":["trace[590848504] 'agreement among raft nodes before linearized reading' (duration: 163.918898ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T07:45:43.128568Z","caller":"traceutil/trace.go:171","msg":"trace[118671027] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"170.540357ms","start":"2026-05-28T07:45:42.956752Z","end":"2026-05-28T07:45:43.127292Z","steps":["trace[118671027] 'process raft request' (duration: 169.975652ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:45:43.134813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.455531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T07:45:43.134866Z","caller":"traceutil/trace.go:171","msg":"trace[1704762316] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:617; }","duration":"171.531351ms","start":"2026-05-28T07:45:42.963323Z","end":"2026-05-28T07:45:43.134854Z","steps":["trace[1704762316] 'agreement among raft nodes before linearized reading' (duration: 171.43142ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:45:43.135125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.970921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T07:45:43.135147Z","caller":"traceutil/trace.go:171","msg":"trace[989584199] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:617; }","duration":"105.007292ms","start":"2026-05-28T07:45:43.030135Z","end":"2026-05-28T07:45:43.135142Z","steps":["trace[989584199] 'agreement among raft nodes before linearized reading' (duration: 104.975861ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:45:43.135232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.54172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T07:45:43.135248Z","caller":"traceutil/trace.go:171","msg":"trace[1336602781] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:617; }","duration":"157.56852ms","start":"2026-05-28T07:45:42.977676Z","end":"2026-05-28T07:45:43.135245Z","steps":["trace[1336602781] 'agreement among raft nodes before linearized reading' (duration: 157.540529ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:45:43.135207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.353919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T07:45:43.135279Z","caller":"traceutil/trace.go:171","msg":"trace[413708036] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:617; }","duration":"142.44298ms","start":"2026-05-28T07:45:42.992825Z","end":"2026-05-28T07:45:43.135268Z","steps":["trace[413708036] 'agreement among raft nodes before linearized reading' (duration: 142.351059ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:45:43.135323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.665011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T07:45:43.135342Z","caller":"traceutil/trace.go:171","msg":"trace[710049954] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:617; }","duration":"157.739282ms","start":"2026-05-28T07:45:42.977598Z","end":"2026-05-28T07:45:43.135337Z","steps":["trace[710049954] 'agreement among raft nodes before linearized reading' (duration: 157.710491ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:45:43.135404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.767712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T07:45:43.135421Z","caller":"traceutil/trace.go:171","msg":"trace[21470548] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:617; }","duration":"157.815072ms","start":"2026-05-28T07:45:42.977602Z","end":"2026-05-28T07:45:43.135417Z","steps":["trace[21470548] 'agreement among raft nodes before linearized reading' (duration: 157.787772ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:45:43.135457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.006848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T07:45:43.135482Z","caller":"traceutil/trace.go:171","msg":"trace[646094591] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:617; }","duration":"106.055598ms","start":"2026-05-28T07:45:43.029421Z","end":"2026-05-28T07:45:43.135476Z","steps":["trace[646094591] 'agreement among raft nodes before linearized reading' (duration: 106.017128ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:45:43.135484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.493672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T07:45:43.135499Z","caller":"traceutil/trace.go:171","msg":"trace[60247228] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:617; }","duration":"171.530443ms","start":"2026-05-28T07:45:42.963964Z","end":"2026-05-28T07:45:43.135495Z","steps":["trace[60247228] 'agreement among raft nodes before linearized reading' (duration: 171.504592ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:45:43.135609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.199539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T07:45:43.135631Z","caller":"traceutil/trace.go:171","msg":"trace[583996918] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:617; }","duration":"106.243369ms","start":"2026-05-28T07:45:43.029383Z","end":"2026-05-28T07:45:43.135627Z","steps":["trace[583996918] 'agreement among raft nodes before linearized reading' (duration: 106.204849ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:45:43.135729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.233468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T07:45:43.135747Z","caller":"traceutil/trace.go:171","msg":"trace[727684171] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:617; }","duration":"121.290508ms","start":"2026-05-28T07:45:43.014452Z","end":"2026-05-28T07:45:43.135743Z","steps":["trace[727684171] 'agreement among raft nodes before linearized reading' (duration: 121.261908ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:45:43.135821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.271703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T07:45:43.135838Z","caller":"traceutil/trace.go:171","msg":"trace[1193626306] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:617; }","duration":"122.298473ms","start":"2026-05-28T07:45:43.013535Z","end":"2026-05-28T07:45:43.135834Z","steps":["trace[1193626306] 'agreement among raft nodes before linearized reading' (duration: 122.273633ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:45:43.135935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.435464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T07:45:43.135997Z","caller":"traceutil/trace.go:171","msg":"trace[735466370] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:617; }","duration":"122.517624ms","start":"2026-05-28T07:45:43.013448Z","end":"2026-05-28T07:45:43.135966Z","steps":["trace[735466370] 'agreement among raft nodes before linearized reading' (duration: 122.432734ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:45:43.136034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.129344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-28T07:45:43.136079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.594372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T07:45:43.136134Z","caller":"traceutil/trace.go:171","msg":"trace[165358640] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:617; }","duration":"142.662512ms","start":"2026-05-28T07:45:42.993458Z","end":"2026-05-28T07:45:43.136121Z","steps":["trace[165358640] 'agreement among raft nodes before linearized reading' (duration: 142.589032ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T07:45:43.13609Z","caller":"traceutil/trace.go:171","msg":"trace[1104420294] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:617; }","duration":"143.204905ms","start":"2026-05-28T07:45:42.992872Z","end":"2026-05-28T07:45:43.136077Z","steps":["trace[1104420294] 'agreement among raft nodes before linearized reading' (duration: 143.117084ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:45:47.460063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.551439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-05-28T07:45:47.46015Z","caller":"traceutil/trace.go:171","msg":"trace[1743656537] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:653; }","duration":"305.686069ms","start":"2026-05-28T07:45:47.154448Z","end":"2026-05-28T07:45:47.460134Z","steps":["trace[1743656537] 'range keys from in-memory index tree' (duration: 305.390318ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:45:47.46019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T07:45:47.15438Z","time spent":"305.80048ms","remote":"127.0.0.1:46972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-28T07:46:02.115771Z","caller":"traceutil/trace.go:171","msg":"trace[228255449] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"246.482349ms","start":"2026-05-28T07:46:01.869268Z","end":"2026-05-28T07:46:02.11575Z","steps":["trace[228255449] 'process raft request' (duration: 246.313198ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T07:46:34.229083Z","caller":"traceutil/trace.go:171","msg":"trace[1479547589] transaction","detail":"{read_only:false; response_revision:1173; number_of_response:1; }","duration":"176.870868ms","start":"2026-05-28T07:46:34.05218Z","end":"2026-05-28T07:46:34.229051Z","steps":["trace[1479547589] 'process raft request' (duration: 176.730874ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T07:47:22.4342Z","caller":"traceutil/trace.go:171","msg":"trace[1846896960] transaction","detail":"{read_only:false; response_revision:1858; number_of_response:1; }","duration":"288.720261ms","start":"2026-05-28T07:47:22.145454Z","end":"2026-05-28T07:47:22.434174Z","steps":["trace[1846896960] 'process raft request' (duration: 288.524858ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T07:47:22.542542Z","caller":"traceutil/trace.go:171","msg":"trace[623317543] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"351.260262ms","start":"2026-05-28T07:47:22.191222Z","end":"2026-05-28T07:47:22.542482Z","steps":["trace[623317543] 'process raft request' (duration: 273.748029ms)","trace[623317543] 'compare' (duration: 77.285249ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T07:47:22.542924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T07:47:22.1912Z","time spent":"351.550197ms","remote":"127.0.0.1:46972","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-28T07:47:22.593759Z","caller":"traceutil/trace.go:171","msg":"trace[1927537799] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"118.856826ms","start":"2026-05-28T07:47:22.474875Z","end":"2026-05-28T07:47:22.593732Z","steps":["trace[1927537799] 'process raft request' (duration: 118.670933ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:47:22.816591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.529039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-28T07:47:22.816667Z","caller":"traceutil/trace.go:171","msg":"trace[1395646968] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1862; }","duration":"147.656401ms","start":"2026-05-28T07:47:22.668992Z","end":"2026-05-28T07:47:22.816649Z","steps":["trace[1395646968] 'range keys from in-memory index tree' (duration: 147.374947ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T07:47:37.376964Z","caller":"traceutil/trace.go:171","msg":"trace[1257010367] transaction","detail":"{read_only:false; response_revision:1985; number_of_response:1; }","duration":"150.870568ms","start":"2026-05-28T07:47:37.226077Z","end":"2026-05-28T07:47:37.376948Z","steps":["trace[1257010367] 'process raft request' (duration: 150.833318ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T07:47:37.376986Z","caller":"traceutil/trace.go:171","msg":"trace[1091296116] transaction","detail":"{read_only:false; response_revision:1984; number_of_response:1; }","duration":"151.680101ms","start":"2026-05-28T07:47:37.225291Z","end":"2026-05-28T07:47:37.376971Z","steps":["trace[1091296116] 'process raft request' (duration: 145.724993ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T07:47:37.661803Z","caller":"traceutil/trace.go:171","msg":"trace[1105965823] transaction","detail":"{read_only:false; response_revision:1987; number_of_response:1; }","duration":"146.617426ms","start":"2026-05-28T07:47:37.515158Z","end":"2026-05-28T07:47:37.661776Z","steps":["trace[1105965823] 'process raft request' (duration: 128.119093ms)","trace[1105965823] 'compare' (duration: 18.193529ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T07:47:37.661937Z","caller":"traceutil/trace.go:171","msg":"trace[1552154039] transaction","detail":"{read_only:false; response_revision:1988; number_of_response:1; }","duration":"117.570157ms","start":"2026-05-28T07:47:37.544357Z","end":"2026-05-28T07:47:37.661927Z","steps":["trace[1552154039] 'process raft request' (duration: 117.336594ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T07:48:01.396561Z","caller":"traceutil/trace.go:171","msg":"trace[544527444] transaction","detail":"{read_only:false; response_revision:2121; number_of_response:1; }","duration":"134.87463ms","start":"2026-05-28T07:48:01.261656Z","end":"2026-05-28T07:48:01.39653Z","steps":["trace[544527444] 'process raft request' (duration: 111.940456ms)","trace[544527444] 'compare' (duration: 22.61555ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T07:48:02.244116Z","caller":"traceutil/trace.go:171","msg":"trace[799647656] transaction","detail":"{read_only:false; response_revision:2124; number_of_response:1; }","duration":"100.424836ms","start":"2026-05-28T07:48:02.143664Z","end":"2026-05-28T07:48:02.244089Z","steps":["trace[799647656] 'process raft request' (duration: 99.91258ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T07:48:02.383904Z","caller":"traceutil/trace.go:171","msg":"trace[1966353078] transaction","detail":"{read_only:false; response_revision:2125; number_of_response:1; }","duration":"135.306813ms","start":"2026-05-28T07:48:02.248573Z","end":"2026-05-28T07:48:02.38388Z","steps":["trace[1966353078] 'process raft request' (duration: 135.184291ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T07:49:00.839137Z","caller":"traceutil/trace.go:171","msg":"trace[532282964] transaction","detail":"{read_only:false; response_revision:2544; number_of_response:1; }","duration":"195.02006ms","start":"2026-05-28T07:49:00.644088Z","end":"2026-05-28T07:49:00.839108Z","steps":["trace[532282964] 'process raft request' (duration: 194.421944ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T07:53:10.962828Z","caller":"traceutil/trace.go:171","msg":"trace[1463112314] transaction","detail":"{read_only:false; response_revision:4400; number_of_response:1; }","duration":"109.187552ms","start":"2026-05-28T07:53:10.853599Z","end":"2026-05-28T07:53:10.962787Z","steps":["trace[1463112314] 'process raft request' (duration: 68.313049ms)","trace[1463112314] 'compare' (duration: 40.788911ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T07:53:12.95607Z","caller":"traceutil/trace.go:171","msg":"trace[1785932784] transaction","detail":"{read_only:false; response_revision:4439; number_of_response:1; }","duration":"100.788281ms","start":"2026-05-28T07:53:12.85525Z","end":"2026-05-28T07:53:12.956038Z","steps":["trace[1785932784] 'process raft request' (duration: 100.038967ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:53:12.956549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.393442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T07:53:12.956603Z","caller":"traceutil/trace.go:171","msg":"trace[1021098100] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:4439; }","duration":"103.522435ms","start":"2026-05-28T07:53:12.853067Z","end":"2026-05-28T07:53:12.95659Z","steps":["trace[1021098100] 'agreement among raft nodes before linearized reading' (duration: 103.403333ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T07:53:13.075104Z","caller":"traceutil/trace.go:171","msg":"trace[1816435959] transaction","detail":"{read_only:false; response_revision:4442; number_of_response:1; }","duration":"100.01411ms","start":"2026-05-28T07:53:12.975052Z","end":"2026-05-28T07:53:13.075067Z","steps":["trace[1816435959] 'process raft request' (duration: 99.815676ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:53:23.057406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.058516ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T07:53:23.057767Z","caller":"traceutil/trace.go:171","msg":"trace[1091762581] linearizableReadLoop","detail":"{readStateIndex:4829; appliedIndex:4826; }","duration":"134.40455ms","start":"2026-05-28T07:53:22.923344Z","end":"2026-05-28T07:53:23.057749Z","steps":["trace[1091762581] 'read index received' (duration: 23.676971ms)","trace[1091762581] 'applied index is now lower than readState.Index' (duration: 110.726459ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T07:53:23.05803Z","caller":"traceutil/trace.go:171","msg":"trace[1508319457] transaction","detail":"{read_only:false; response_revision:4654; number_of_response:1; }","duration":"157.243956ms","start":"2026-05-28T07:53:22.900772Z","end":"2026-05-28T07:53:23.058016Z","steps":["trace[1508319457] 'process raft request' (duration: 156.93014ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:53:23.058191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.837588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T07:53:23.058241Z","caller":"traceutil/trace.go:171","msg":"trace[811141201] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:0; response_revision:4654; }","duration":"134.91863ms","start":"2026-05-28T07:53:22.923309Z","end":"2026-05-28T07:53:23.058228Z","steps":["trace[811141201] 'agreement among raft nodes before linearized reading' (duration: 134.840749ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T07:53:23.0584Z","caller":"traceutil/trace.go:171","msg":"trace[1908853906] transaction","detail":"{read_only:false; response_revision:4652; number_of_response:1; }","duration":"159.482959ms","start":"2026-05-28T07:53:22.898887Z","end":"2026-05-28T07:53:23.05837Z","steps":["trace[1908853906] 'process raft request' (duration: 48.20427ms)","trace[1908853906] 'compare' (duration: 109.871073ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T07:53:23.057829Z","caller":"traceutil/trace.go:171","msg":"trace[578214855] transaction","detail":"{read_only:false; response_revision:4653; number_of_response:1; }","duration":"158.840807ms","start":"2026-05-28T07:53:22.898955Z","end":"2026-05-28T07:53:23.057796Z","steps":["trace[578214855] 'process raft request' (duration: 158.667214ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T07:53:23.237464Z","caller":"traceutil/trace.go:171","msg":"trace[784789284] transaction","detail":"{read_only:false; response_revision:4656; number_of_response:1; }","duration":"172.793534ms","start":"2026-05-28T07:53:23.064648Z","end":"2026-05-28T07:53:23.237442Z","steps":["trace[784789284] 'process raft request' (duration: 172.722283ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T07:53:23.237564Z","caller":"traceutil/trace.go:171","msg":"trace[1627728989] transaction","detail":"{read_only:false; response_revision:4655; number_of_response:1; }","duration":"244.194562ms","start":"2026-05-28T07:53:22.993336Z","end":"2026-05-28T07:53:23.237531Z","steps":["trace[1627728989] 'process raft request' (duration: 180.627064ms)","trace[1627728989] 'compare' (duration: 63.301053ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T07:53:23.23768Z","caller":"traceutil/trace.go:171","msg":"trace[1816654995] linearizableReadLoop","detail":"{readStateIndex:4830; appliedIndex:4829; }","duration":"179.832328ms","start":"2026-05-28T07:53:23.057796Z","end":"2026-05-28T07:53:23.237629Z","steps":["trace[1816654995] 'read index received' (duration: 116.2642ms)","trace[1816654995] 'applied index is now lower than readState.Index' (duration: 63.565338ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T07:53:23.23778Z","caller":"traceutil/trace.go:171","msg":"trace[572196235] transaction","detail":"{read_only:false; response_revision:4657; number_of_response:1; }","duration":"172.962317ms","start":"2026-05-28T07:53:23.06481Z","end":"2026-05-28T07:53:23.237772Z","steps":["trace[572196235] 'process raft request' (duration: 172.60476ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:53:23.238177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.262533ms","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-28T07:53:23.238218Z","caller":"traceutil/trace.go:171","msg":"trace[19898166] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4658; }","duration":"203.360345ms","start":"2026-05-28T07:53:23.034848Z","end":"2026-05-28T07:53:23.238208Z","steps":["trace[19898166] 'agreement among raft nodes before linearized reading' (duration: 202.992118ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:53:23.238191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.769965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/openstack/\" range_end:\"/registry/resourcequotas/openstack0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T07:53:23.23825Z","caller":"traceutil/trace.go:171","msg":"trace[396629576] transaction","detail":"{read_only:false; response_revision:4658; number_of_response:1; }","duration":"127.311759ms","start":"2026-05-28T07:53:23.110917Z","end":"2026-05-28T07:53:23.238229Z","steps":["trace[396629576] 'process raft request' (duration: 126.82017ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T07:53:23.238268Z","caller":"traceutil/trace.go:171","msg":"trace[619212165] range","detail":"{range_begin:/registry/resourcequotas/openstack/; range_end:/registry/resourcequotas/openstack0; response_count:0; response_revision:4658; }","duration":"171.896086ms","start":"2026-05-28T07:53:23.066354Z","end":"2026-05-28T07:53:23.23825Z","steps":["trace[619212165] 'agreement among raft nodes before linearized reading' (duration: 171.762674ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:53:23.23856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.399847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-05-28T07:53:23.2386Z","caller":"traceutil/trace.go:171","msg":"trace[70974204] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:4658; }","duration":"142.469078ms","start":"2026-05-28T07:53:23.096124Z","end":"2026-05-28T07:53:23.238593Z","steps":["trace[70974204] 'agreement among raft nodes before linearized reading' (duration: 142.349876ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:53:23.2387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.075433ms","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-28T07:53:23.238742Z","caller":"traceutil/trace.go:171","msg":"trace[849642831] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4658; }","duration":"100.154933ms","start":"2026-05-28T07:53:23.138577Z","end":"2026-05-28T07:53:23.238732Z","steps":["trace[849642831] 'agreement among raft nodes before linearized reading' (duration: 100.031761ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T07:53:23.666676Z","caller":"traceutil/trace.go:171","msg":"trace[1544282078] transaction","detail":"{read_only:false; response_revision:4664; number_of_response:1; }","duration":"229.289407ms","start":"2026-05-28T07:53:23.43736Z","end":"2026-05-28T07:53:23.666649Z","steps":["trace[1544282078] 'process raft request' (duration: 229.141414ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T07:53:27.216746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.417061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9b0603a7-7630-4e1f-a9ec-15ee8cd3a322\" ","response":"range_response_count:1 size:3939"} {"level":"info","ts":"2026-05-28T07:53:27.216821Z","caller":"traceutil/trace.go:171","msg":"trace[1649676403] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9b0603a7-7630-4e1f-a9ec-15ee8cd3a322; range_end:; response_count:1; response_revision:4751; }","duration":"102.593584ms","start":"2026-05-28T07:53:27.11421Z","end":"2026-05-28T07:53:27.216803Z","steps":["trace[1649676403] 'range keys from in-memory index tree' (duration: 102.210877ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T07:55:04.123305Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2885} {"level":"info","ts":"2026-05-28T07:55:04.260447Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2885,"took":"134.363956ms","hash":2373440162,"current-db-size-bytes":53153792,"current-db-size":"53 MB","current-db-size-in-use-bytes":29614080,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-28T07:55:04.26062Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2373440162,"revision":2885,"compact-revision":-1} {"level":"info","ts":"2026-05-28T08:00:04.130008Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5615} {"level":"info","ts":"2026-05-28T08:00:04.226817Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5615,"took":"94.766799ms","hash":163536184,"current-db-size-bytes":53153792,"current-db-size":"53 MB","current-db-size-in-use-bytes":18272256,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-28T08:00:04.226917Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":163536184,"revision":5615,"compact-revision":2885}