{"level":"warn","ts":"2026-10-26T00:49:38.024527Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-26T00:49:38.024609Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.12:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.12:2380","--initial-cluster=instance=https://162.253.55.12:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.12:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.12: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-10-26T00:49:38.02471Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-26T00:49:38.024723Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.12:2380"]} {"level":"info","ts":"2026-10-26T00:49:38.024738Z","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-10-26T00:49:38.025381Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.12:2379"]} {"level":"info","ts":"2026-10-26T00:49:38.025474Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.12:2380"],"listen-peer-urls":["https://162.253.55.12:2380"],"advertise-client-urls":["https://162.253.55.12:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.12:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.12: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-10-26T00:49:38.030662Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.749229ms"} {"level":"info","ts":"2026-10-26T00:49:38.037969Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b4c6dc24a78438ca","cluster-id":"587227f9a3f73e98"} {"level":"info","ts":"2026-10-26T00:49:38.038021Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca switched to configuration voters=()"} {"level":"info","ts":"2026-10-26T00:49:38.038069Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca became follower at term 0"} {"level":"info","ts":"2026-10-26T00:49:38.038077Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b4c6dc24a78438ca [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-26T00:49:38.038083Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca became follower at term 1"} {"level":"info","ts":"2026-10-26T00:49:38.038135Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca switched to configuration voters=(13026341022203132106)"} {"level":"warn","ts":"2026-10-26T00:49:38.04404Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-26T00:49:38.04529Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-26T00:49:38.046608Z","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-10-26T00:49:38.048419Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b4c6dc24a78438ca","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-26T00:49:38.048506Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b4c6dc24a78438ca","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-26T00:49:38.04889Z","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-10-26T00:49:38.048974Z","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-10-26T00:49:38.048985Z","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-10-26T00:49:38.049803Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca switched to configuration voters=(13026341022203132106)"} {"level":"info","ts":"2026-10-26T00:49:38.050201Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"587227f9a3f73e98","local-member-id":"b4c6dc24a78438ca","added-peer-id":"b4c6dc24a78438ca","added-peer-peer-urls":["https://162.253.55.12:2380"]} {"level":"info","ts":"2026-10-26T00:49:38.051671Z","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-10-26T00:49:38.051717Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.12:2380"} {"level":"info","ts":"2026-10-26T00:49:38.051752Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.12:2380"} {"level":"info","ts":"2026-10-26T00:49:38.052349Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b4c6dc24a78438ca","initial-advertise-peer-urls":["https://162.253.55.12:2380"],"listen-peer-urls":["https://162.253.55.12:2380"],"advertise-client-urls":["https://162.253.55.12:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.12:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-26T00:49:38.052374Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-26T00:49:38.438297Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca is starting a new election at term 1"} {"level":"info","ts":"2026-10-26T00:49:38.438352Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca became pre-candidate at term 1"} {"level":"info","ts":"2026-10-26T00:49:38.438405Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca received MsgPreVoteResp from b4c6dc24a78438ca at term 1"} {"level":"info","ts":"2026-10-26T00:49:38.438428Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca became candidate at term 2"} {"level":"info","ts":"2026-10-26T00:49:38.438444Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca received MsgVoteResp from b4c6dc24a78438ca at term 2"} {"level":"info","ts":"2026-10-26T00:49:38.438459Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca became leader at term 2"} {"level":"info","ts":"2026-10-26T00:49:38.438477Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b4c6dc24a78438ca elected leader b4c6dc24a78438ca at term 2"} {"level":"info","ts":"2026-10-26T00:49:38.441718Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b4c6dc24a78438ca","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.12:2379]}","request-path":"/0/members/b4c6dc24a78438ca/attributes","cluster-id":"587227f9a3f73e98","publish-timeout":"7s"} {"level":"info","ts":"2026-10-26T00:49:38.44176Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-26T00:49:38.441773Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-26T00:49:38.441831Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T00:49:38.442267Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-26T00:49:38.442346Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-26T00:49:38.44281Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"587227f9a3f73e98","local-member-id":"b4c6dc24a78438ca","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T00:49:38.442911Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T00:49:38.442935Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T00:49:38.445214Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.12:2379"} {"level":"info","ts":"2026-10-26T00:49:38.445361Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-26T00:49:41.735864Z","caller":"traceutil/trace.go:171","msg":"trace[279356750] transaction","detail":"{read_only:false; response_revision:205; number_of_response:1; }","duration":"173.396192ms","start":"2026-10-26T00:49:41.562449Z","end":"2026-10-26T00:49:41.735845Z","steps":["trace[279356750] 'process raft request' (duration: 173.302261ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:49:42.094733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.400501ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-26T00:49:42.094833Z","caller":"traceutil/trace.go:171","msg":"trace[399502986] linearizableReadLoop","detail":"{readStateIndex:211; appliedIndex:210; }","duration":"121.735988ms","start":"2026-10-26T00:49:41.973082Z","end":"2026-10-26T00:49:42.094818Z","steps":["trace[399502986] 'read index received' (duration: 39.181µs)","trace[399502986] 'applied index is now lower than readState.Index' (duration: 121.695847ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T00:49:42.095007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.914461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:49:42.095038Z","caller":"traceutil/trace.go:171","msg":"trace[1506776709] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:206; }","duration":"121.994302ms","start":"2026-10-26T00:49:41.973034Z","end":"2026-10-26T00:49:42.095029Z","steps":["trace[1506776709] 'agreement among raft nodes before linearized reading' (duration: 121.822249ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:49:42.09523Z","caller":"traceutil/trace.go:171","msg":"trace[541082364] transaction","detail":"{read_only:false; response_revision:206; number_of_response:1; }","duration":"355.167536ms","start":"2026-10-26T00:49:41.740011Z","end":"2026-10-26T00:49:42.095179Z","steps":["trace[541082364] 'process raft request' (duration: 186.8376ms)","trace[541082364] 'compare' (duration: 167.289429ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T00:49:42.095361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:49:41.739998Z","time spent":"355.298288ms","remote":"127.0.0.1:59984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":744,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-26T00:49:42.30664Z","caller":"traceutil/trace.go:171","msg":"trace[930344131] transaction","detail":"{read_only:false; response_revision:207; number_of_response:1; }","duration":"206.304249ms","start":"2026-10-26T00:49:42.100311Z","end":"2026-10-26T00:49:42.306615Z","steps":["trace[930344131] 'process raft request' (duration: 168.523236ms)","trace[930344131] 'compare' (duration: 37.60839ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:49:42.313982Z","caller":"traceutil/trace.go:171","msg":"trace[538563829] linearizableReadLoop","detail":"{readStateIndex:213; appliedIndex:211; }","duration":"155.898001ms","start":"2026-10-26T00:49:42.158063Z","end":"2026-10-26T00:49:42.313961Z","steps":["trace[538563829] 'read index received' (duration: 110.783729ms)","trace[538563829] 'applied index is now lower than readState.Index' (duration: 45.113282ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:49:42.314211Z","caller":"traceutil/trace.go:171","msg":"trace[136753712] transaction","detail":"{read_only:false; response_revision:208; number_of_response:1; }","duration":"211.402882ms","start":"2026-10-26T00:49:42.102772Z","end":"2026-10-26T00:49:42.314175Z","steps":["trace[136753712] 'process raft request' (duration: 211.108577ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:49:42.314351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.220417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:49:42.314516Z","caller":"traceutil/trace.go:171","msg":"trace[949144212] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:208; }","duration":"156.426569ms","start":"2026-10-26T00:49:42.158022Z","end":"2026-10-26T00:49:42.314448Z","steps":["trace[949144212] 'agreement among raft nodes before linearized reading' (duration: 156.145566ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:50:24.313168Z","caller":"traceutil/trace.go:171","msg":"trace[617846836] linearizableReadLoop","detail":"{readStateIndex:675; appliedIndex:674; }","duration":"169.798452ms","start":"2026-10-26T00:50:24.143345Z","end":"2026-10-26T00:50:24.313143Z","steps":["trace[617846836] 'read index received' (duration: 168.476212ms)","trace[617846836] 'applied index is now lower than readState.Index' (duration: 1.32105ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:50:24.313237Z","caller":"traceutil/trace.go:171","msg":"trace[384104889] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"170.061366ms","start":"2026-10-26T00:50:24.14315Z","end":"2026-10-26T00:50:24.313212Z","steps":["trace[384104889] 'process raft request' (duration: 168.97368ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:50:24.31339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.018975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backends.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:18056"} {"level":"info","ts":"2026-10-26T00:50:24.313428Z","caller":"traceutil/trace.go:171","msg":"trace[97606592] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backends.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:648; }","duration":"170.083306ms","start":"2026-10-26T00:50:24.143329Z","end":"2026-10-26T00:50:24.313413Z","steps":["trace[97606592] 'agreement among raft nodes before linearized reading' (duration: 169.942814ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:50:24.320949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.598163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:50:24.321033Z","caller":"traceutil/trace.go:171","msg":"trace[1224371153] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:0; response_revision:649; }","duration":"154.718506ms","start":"2026-10-26T00:50:24.166294Z","end":"2026-10-26T00:50:24.321013Z","steps":["trace[1224371153] 'agreement among raft nodes before linearized reading' (duration: 154.576473ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:50:24.323907Z","caller":"traceutil/trace.go:171","msg":"trace[643000805] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"132.749676ms","start":"2026-10-26T00:50:24.191119Z","end":"2026-10-26T00:50:24.323868Z","steps":["trace[643000805] 'process raft request' (duration: 129.519107ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:50:30.627102Z","caller":"traceutil/trace.go:171","msg":"trace[1315330398] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"347.805457ms","start":"2026-10-26T00:50:30.279268Z","end":"2026-10-26T00:50:30.627074Z","steps":["trace[1315330398] 'process raft request' (duration: 347.512152ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:50:30.627843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:50:30.27923Z","time spent":"347.963798ms","remote":"127.0.0.1:59876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T00:50:31.436295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.306226ms","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":"warn","ts":"2026-10-26T00:50:31.43632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.895714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:50:31.436381Z","caller":"traceutil/trace.go:171","msg":"trace[766545349] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:715; }","duration":"215.411068ms","start":"2026-10-26T00:50:31.220951Z","end":"2026-10-26T00:50:31.436362Z","steps":["trace[766545349] 'range keys from in-memory index tree' (duration: 215.086303ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:50:31.436445Z","caller":"traceutil/trace.go:171","msg":"trace[1649136736] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:715; }","duration":"183.122218ms","start":"2026-10-26T00:50:31.253294Z","end":"2026-10-26T00:50:31.436416Z","steps":["trace[1649136736] 'range keys from in-memory index tree' (duration: 182.740082ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:50:47.593798Z","caller":"traceutil/trace.go:171","msg":"trace[37176637] linearizableReadLoop","detail":"{readStateIndex:863; appliedIndex:862; }","duration":"218.323816ms","start":"2026-10-26T00:50:47.375449Z","end":"2026-10-26T00:50:47.593773Z","steps":["trace[37176637] 'read index received' (duration: 218.062392ms)","trace[37176637] 'applied index is now lower than readState.Index' (duration: 260.284µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:50:47.593995Z","caller":"traceutil/trace.go:171","msg":"trace[1095736508] transaction","detail":"{read_only:false; response_revision:830; number_of_response:1; }","duration":"220.076281ms","start":"2026-10-26T00:50:47.373881Z","end":"2026-10-26T00:50:47.593958Z","steps":["trace[1095736508] 'process raft request' (duration: 219.746317ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:50:47.594199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.57295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-26T00:50:47.59421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.63347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:50:47.59428Z","caller":"traceutil/trace.go:171","msg":"trace[737628646] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:830; }","duration":"218.880173ms","start":"2026-10-26T00:50:47.375387Z","end":"2026-10-26T00:50:47.594267Z","steps":["trace[737628646] 'agreement among raft nodes before linearized reading' (duration: 218.600028ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:50:47.59432Z","caller":"traceutil/trace.go:171","msg":"trace[1965477601] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:830; }","duration":"218.790063ms","start":"2026-10-26T00:50:47.375501Z","end":"2026-10-26T00:50:47.594291Z","steps":["trace[1965477601] 'agreement among raft nodes before linearized reading' (duration: 218.5975ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:50:47.594704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.039463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-26T00:50:47.594727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.249413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:50:47.59475Z","caller":"traceutil/trace.go:171","msg":"trace[1434754544] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:830; }","duration":"186.094564ms","start":"2026-10-26T00:50:47.408638Z","end":"2026-10-26T00:50:47.594732Z","steps":["trace[1434754544] 'agreement among raft nodes before linearized reading' (duration: 186.041543ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:50:47.594806Z","caller":"traceutil/trace.go:171","msg":"trace[431206194] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:830; }","duration":"112.337724ms","start":"2026-10-26T00:50:47.482443Z","end":"2026-10-26T00:50:47.594781Z","steps":["trace[431206194] 'agreement among raft nodes before linearized reading' (duration: 112.245123ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:50:47.594987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.430492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:50:47.595029Z","caller":"traceutil/trace.go:171","msg":"trace[1223153412] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:830; }","duration":"219.495553ms","start":"2026-10-26T00:50:47.375522Z","end":"2026-10-26T00:50:47.595017Z","steps":["trace[1223153412] 'agreement among raft nodes before linearized reading' (duration: 219.430692ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:50:47.594655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.953341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:50:47.595128Z","caller":"traceutil/trace.go:171","msg":"trace[1586932799] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:830; }","duration":"186.435728ms","start":"2026-10-26T00:50:47.408675Z","end":"2026-10-26T00:50:47.595111Z","steps":["trace[1586932799] 'agreement among raft nodes before linearized reading' (duration: 185.945001ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:50:47.59462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.540797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:50:47.59564Z","caller":"traceutil/trace.go:171","msg":"trace[243399836] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:830; }","duration":"113.642634ms","start":"2026-10-26T00:50:47.481984Z","end":"2026-10-26T00:50:47.595627Z","steps":["trace[243399836] 'agreement among raft nodes before linearized reading' (duration: 112.529087ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:50:47.59545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.074324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:50:47.59582Z","caller":"traceutil/trace.go:171","msg":"trace[1754670612] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:830; }","duration":"186.539201ms","start":"2026-10-26T00:50:47.409269Z","end":"2026-10-26T00:50:47.595808Z","steps":["trace[1754670612] 'agreement among raft nodes before linearized reading' (duration: 185.098409ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:50:47.595539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.594333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:50:47.595894Z","caller":"traceutil/trace.go:171","msg":"trace[1939150784] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:830; }","duration":"113.975889ms","start":"2026-10-26T00:50:47.481896Z","end":"2026-10-26T00:50:47.595872Z","steps":["trace[1939150784] 'agreement among raft nodes before linearized reading' (duration: 113.590843ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:52:13.919229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.728193ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T00:52:13.919405Z","caller":"traceutil/trace.go:171","msg":"trace[1104752907] linearizableReadLoop","detail":"{readStateIndex:2007; appliedIndex:2006; }","duration":"266.034627ms","start":"2026-10-26T00:52:13.653348Z","end":"2026-10-26T00:52:13.919383Z","steps":["trace[1104752907] 'read index received' (duration: 55.761µs)","trace[1104752907] 'applied index is now lower than readState.Index' (duration: 265.977556ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T00:52:13.91966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.258321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:52:13.919672Z","caller":"traceutil/trace.go:171","msg":"trace[573146783] transaction","detail":"{read_only:false; response_revision:1928; number_of_response:1; }","duration":"356.486672ms","start":"2026-10-26T00:52:13.563142Z","end":"2026-10-26T00:52:13.919629Z","steps":["trace[573146783] 'process raft request' (duration: 80.256771ms)","trace[573146783] 'compare' (duration: 275.51623ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:52:13.919774Z","caller":"traceutil/trace.go:171","msg":"trace[840593405] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:1928; }","duration":"266.471763ms","start":"2026-10-26T00:52:13.653275Z","end":"2026-10-26T00:52:13.919747Z","steps":["trace[840593405] 'agreement among raft nodes before linearized reading' (duration: 266.22553ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:52:13.919697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.410725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5732"} {"level":"info","ts":"2026-10-26T00:52:13.919863Z","caller":"traceutil/trace.go:171","msg":"trace[1255143353] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1928; }","duration":"108.625239ms","start":"2026-10-26T00:52:13.81122Z","end":"2026-10-26T00:52:13.919845Z","steps":["trace[1255143353] 'agreement among raft nodes before linearized reading' (duration: 108.282464ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:52:13.919862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:52:13.563115Z","time spent":"356.649013ms","remote":"127.0.0.1:59928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T00:52:14.360957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.007135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5732"} {"level":"info","ts":"2026-10-26T00:52:14.361079Z","caller":"traceutil/trace.go:171","msg":"trace[264023282] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1931; }","duration":"145.29322ms","start":"2026-10-26T00:52:14.215761Z","end":"2026-10-26T00:52:14.361054Z","steps":["trace[264023282] 'range keys from in-memory index tree' (duration: 144.752691ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:52:28.150209Z","caller":"traceutil/trace.go:171","msg":"trace[469177832] linearizableReadLoop","detail":"{readStateIndex:2138; appliedIndex:2137; }","duration":"117.430515ms","start":"2026-10-26T00:52:28.032732Z","end":"2026-10-26T00:52:28.150163Z","steps":["trace[469177832] 'read index received' (duration: 117.204262ms)","trace[469177832] 'applied index is now lower than readState.Index' (duration: 225.083µs)"],"step_count":2} {"level":"warn","ts":"2026-10-26T00:52:28.150466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.702219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5732"} {"level":"info","ts":"2026-10-26T00:52:28.15051Z","caller":"traceutil/trace.go:171","msg":"trace[1374364042] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2056; }","duration":"117.82857ms","start":"2026-10-26T00:52:28.03267Z","end":"2026-10-26T00:52:28.150499Z","steps":["trace[1374364042] 'agreement among raft nodes before linearized reading' (duration: 117.614987ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:52:28.150866Z","caller":"traceutil/trace.go:171","msg":"trace[860740211] transaction","detail":"{read_only:false; response_revision:2056; number_of_response:1; }","duration":"156.540089ms","start":"2026-10-26T00:52:27.994314Z","end":"2026-10-26T00:52:28.150854Z","steps":["trace[860740211] 'process raft request' (duration: 155.684397ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:52:49.483272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.970209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5732"} {"level":"info","ts":"2026-10-26T00:52:49.483475Z","caller":"traceutil/trace.go:171","msg":"trace[539514602] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2177; }","duration":"174.22745ms","start":"2026-10-26T00:52:49.30922Z","end":"2026-10-26T00:52:49.483447Z","steps":["trace[539514602] 'range keys from in-memory index tree' (duration: 173.731319ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:52:49.483551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.989397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-26T00:52:49.483635Z","caller":"traceutil/trace.go:171","msg":"trace[419536061] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2177; }","duration":"185.136983ms","start":"2026-10-26T00:52:49.298481Z","end":"2026-10-26T00:52:49.483618Z","steps":["trace[419536061] 'range keys from in-memory index tree' (duration: 184.81883ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:53:55.663552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.239664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12428"} {"level":"info","ts":"2026-10-26T00:53:55.663631Z","caller":"traceutil/trace.go:171","msg":"trace[1020110957] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2635; }","duration":"274.372418ms","start":"2026-10-26T00:53:55.389242Z","end":"2026-10-26T00:53:55.663614Z","steps":["trace[1020110957] 'range keys from in-memory index tree' (duration: 273.995586ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:53:55.665125Z","caller":"traceutil/trace.go:171","msg":"trace[929416306] linearizableReadLoop","detail":"{readStateIndex:2738; appliedIndex:2737; }","duration":"150.457895ms","start":"2026-10-26T00:53:55.514656Z","end":"2026-10-26T00:53:55.665114Z","steps":["trace[929416306] 'read index received' (duration: 133.209488ms)","trace[929416306] 'applied index is now lower than readState.Index' (duration: 17.247467ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T00:53:55.665206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.546178ms","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-10-26T00:53:55.665229Z","caller":"traceutil/trace.go:171","msg":"trace[349306578] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2636; }","duration":"150.613439ms","start":"2026-10-26T00:53:55.514607Z","end":"2026-10-26T00:53:55.665221Z","steps":["trace[349306578] 'agreement among raft nodes before linearized reading' (duration: 150.544487ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:53:55.665535Z","caller":"traceutil/trace.go:171","msg":"trace[422040789] transaction","detail":"{read_only:false; response_revision:2636; number_of_response:1; }","duration":"223.09793ms","start":"2026-10-26T00:53:55.442424Z","end":"2026-10-26T00:53:55.665522Z","steps":["trace[422040789] 'process raft request' (duration: 205.404269ms)","trace[422040789] 'compare' (duration: 15.497942ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:54:26.88724Z","caller":"traceutil/trace.go:171","msg":"trace[1347952208] linearizableReadLoop","detail":"{readStateIndex:2918; appliedIndex:2917; }","duration":"199.059733ms","start":"2026-10-26T00:54:26.688156Z","end":"2026-10-26T00:54:26.887216Z","steps":["trace[1347952208] 'read index received' (duration: 198.871627ms)","trace[1347952208] 'applied index is now lower than readState.Index' (duration: 187.136µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:54:26.887298Z","caller":"traceutil/trace.go:171","msg":"trace[40430596] transaction","detail":"{read_only:false; response_revision:2810; number_of_response:1; }","duration":"199.275308ms","start":"2026-10-26T00:54:26.687991Z","end":"2026-10-26T00:54:26.887266Z","steps":["trace[40430596] 'process raft request' (duration: 199.085823ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:54:26.88741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.236218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:54:26.887491Z","caller":"traceutil/trace.go:171","msg":"trace[440019900] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2810; }","duration":"199.3284ms","start":"2026-10-26T00:54:26.688145Z","end":"2026-10-26T00:54:26.887473Z","steps":["trace[440019900] 'agreement among raft nodes before linearized reading' (duration: 199.209727ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:54:27.06623Z","caller":"traceutil/trace.go:171","msg":"trace[407981410] transaction","detail":"{read_only:false; response_revision:2811; number_of_response:1; }","duration":"173.768564ms","start":"2026-10-26T00:54:26.892432Z","end":"2026-10-26T00:54:27.066201Z","steps":["trace[407981410] 'process raft request' (duration: 169.402649ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:59:04.21619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.462678ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T00:59:04.216364Z","caller":"traceutil/trace.go:171","msg":"trace[2112032852] transaction","detail":"{read_only:false; response_revision:5030; number_of_response:1; }","duration":"210.257763ms","start":"2026-10-26T00:59:04.00608Z","end":"2026-10-26T00:59:04.216338Z","steps":["trace[2112032852] 'process raft request' (duration: 104.480879ms)","trace[2112032852] 'compare' (duration: 104.980499ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:59:04.472325Z","caller":"traceutil/trace.go:171","msg":"trace[842656138] transaction","detail":"{read_only:false; response_revision:5031; number_of_response:1; }","duration":"461.116485ms","start":"2026-10-26T00:59:04.011154Z","end":"2026-10-26T00:59:04.472271Z","steps":["trace[842656138] 'process raft request' (duration: 390.185046ms)","trace[842656138] 'compare' (duration: 70.611553ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T00:59:04.472562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:59:04.01114Z","time spent":"461.294879ms","remote":"127.0.0.1:59876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16687,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T00:59:04.472747Z","caller":"traceutil/trace.go:171","msg":"trace[691054856] transaction","detail":"{read_only:false; response_revision:5032; number_of_response:1; }","duration":"390.010022ms","start":"2026-10-26T00:59:04.082695Z","end":"2026-10-26T00:59:04.472706Z","steps":["trace[691054856] 'process raft request' (duration: 389.472362ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:59:04.472797Z","caller":"traceutil/trace.go:171","msg":"trace[1765959112] linearizableReadLoop","detail":"{readStateIndex:5227; appliedIndex:5225; }","duration":"445.631949ms","start":"2026-10-26T00:59:04.027136Z","end":"2026-10-26T00:59:04.472768Z","steps":["trace[1765959112] 'read index received' (duration: 83.487511ms)","trace[1765959112] 'applied index is now lower than readState.Index' (duration: 362.143398ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T00:59:04.473007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:59:04.082657Z","time spent":"390.196806ms","remote":"127.0.0.1:59928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T00:59:04.473062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.898254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-10-26T00:59:04.473142Z","caller":"traceutil/trace.go:171","msg":"trace[1245213024] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:5032; }","duration":"446.007607ms","start":"2026-10-26T00:59:04.027112Z","end":"2026-10-26T00:59:04.47312Z","steps":["trace[1245213024] 'agreement among raft nodes before linearized reading' (duration: 445.740362ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:59:04.4732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:59:04.027099Z","time spent":"446.090628ms","remote":"127.0.0.1:59770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":3529,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" "} {"level":"info","ts":"2026-10-26T00:59:04.473186Z","caller":"traceutil/trace.go:171","msg":"trace[1230579718] transaction","detail":"{read_only:false; response_revision:5033; number_of_response:1; }","duration":"169.584701ms","start":"2026-10-26T00:59:04.303567Z","end":"2026-10-26T00:59:04.473151Z","steps":["trace[1230579718] 'process raft request' (duration: 169.067682ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:59:04.473343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.535789ms","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-10-26T00:59:04.473397Z","caller":"traceutil/trace.go:171","msg":"trace[811757588] 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:5033; }","duration":"444.5945ms","start":"2026-10-26T00:59:04.028784Z","end":"2026-10-26T00:59:04.473379Z","steps":["trace[811757588] 'agreement among raft nodes before linearized reading' (duration: 444.327115ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:59:04.473431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:59:04.02878Z","time spent":"444.643231ms","remote":"127.0.0.1:59832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2105,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"info","ts":"2026-10-26T00:59:04.767071Z","caller":"traceutil/trace.go:171","msg":"trace[1093369629] linearizableReadLoop","detail":"{readStateIndex:5235; appliedIndex:5234; }","duration":"102.324169ms","start":"2026-10-26T00:59:04.664728Z","end":"2026-10-26T00:59:04.767053Z","steps":["trace[1093369629] 'read index received' (duration: 91.921207ms)","trace[1093369629] 'applied index is now lower than readState.Index' (duration: 10.402382ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:59:04.767159Z","caller":"traceutil/trace.go:171","msg":"trace[697415209] transaction","detail":"{read_only:false; response_revision:5039; number_of_response:1; }","duration":"132.579269ms","start":"2026-10-26T00:59:04.634545Z","end":"2026-10-26T00:59:04.767125Z","steps":["trace[697415209] 'process raft request' (duration: 122.191657ms)","trace[697415209] 'compare' (duration: 10.222719ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T00:59:04.767231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.492493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-26T00:59:04.76726Z","caller":"traceutil/trace.go:171","msg":"trace[2043242470] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5039; }","duration":"102.604094ms","start":"2026-10-26T00:59:04.66465Z","end":"2026-10-26T00:59:04.767254Z","steps":["trace[2043242470] 'agreement among raft nodes before linearized reading' (duration: 102.480332ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:59:04.907433Z","caller":"traceutil/trace.go:171","msg":"trace[1644722746] transaction","detail":"{read_only:false; response_revision:5040; number_of_response:1; }","duration":"203.101351ms","start":"2026-10-26T00:59:04.704309Z","end":"2026-10-26T00:59:04.907411Z","steps":["trace[1644722746] 'process raft request' (duration: 169.740075ms)","trace[1644722746] 'compare' (duration: 33.255784ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:59:04.93191Z","caller":"traceutil/trace.go:171","msg":"trace[609344355] linearizableReadLoop","detail":"{readStateIndex:5237; appliedIndex:5235; }","duration":"141.447361ms","start":"2026-10-26T00:59:04.790411Z","end":"2026-10-26T00:59:04.931858Z","steps":["trace[609344355] 'read index received' (duration: 83.661324ms)","trace[609344355] 'applied index is now lower than readState.Index' (duration: 57.784557ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:59:04.932216Z","caller":"traceutil/trace.go:171","msg":"trace[263809040] transaction","detail":"{read_only:false; response_revision:5041; number_of_response:1; }","duration":"159.020536ms","start":"2026-10-26T00:59:04.773174Z","end":"2026-10-26T00:59:04.932195Z","steps":["trace[263809040] 'process raft request' (duration: 158.392704ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:59:04.93227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.805458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-825aaa7f-dc2a-4337-8b75-800ae17bdab9\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-10-26T00:59:04.932582Z","caller":"traceutil/trace.go:171","msg":"trace[456551249] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-825aaa7f-dc2a-4337-8b75-800ae17bdab9; range_end:; response_count:1; response_revision:5041; }","duration":"142.160685ms","start":"2026-10-26T00:59:04.790388Z","end":"2026-10-26T00:59:04.932549Z","steps":["trace[456551249] 'agreement among raft nodes before linearized reading' (duration: 141.619005ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:59:18.976747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.841032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-26T00:59:18.976831Z","caller":"traceutil/trace.go:171","msg":"trace[1328133418] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:5218; }","duration":"146.976394ms","start":"2026-10-26T00:59:18.829836Z","end":"2026-10-26T00:59:18.976812Z","steps":["trace[1328133418] 'count revisions from in-memory index tree' (duration: 146.78164ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:59:18.976968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.879731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:59:18.977105Z","caller":"traceutil/trace.go:171","msg":"trace[1307549284] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:5218; }","duration":"196.050174ms","start":"2026-10-26T00:59:18.781016Z","end":"2026-10-26T00:59:18.977066Z","steps":["trace[1307549284] 'count revisions from in-memory index tree' (duration: 195.78902ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:59:19.176764Z","caller":"traceutil/trace.go:171","msg":"trace[67538781] transaction","detail":"{read_only:false; response_revision:5219; number_of_response:1; }","duration":"167.391487ms","start":"2026-10-26T00:59:19.009294Z","end":"2026-10-26T00:59:19.176685Z","steps":["trace[67538781] 'process raft request' (duration: 166.317667ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:59:19.207672Z","caller":"traceutil/trace.go:171","msg":"trace[287618109] transaction","detail":"{read_only:false; response_revision:5221; number_of_response:1; }","duration":"165.628245ms","start":"2026-10-26T00:59:19.042014Z","end":"2026-10-26T00:59:19.207642Z","steps":["trace[287618109] 'process raft request' (duration: 165.322689ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:59:19.207604Z","caller":"traceutil/trace.go:171","msg":"trace[1387581609] transaction","detail":"{read_only:false; response_revision:5220; number_of_response:1; }","duration":"185.856976ms","start":"2026-10-26T00:59:19.021679Z","end":"2026-10-26T00:59:19.207536Z","steps":["trace[1387581609] 'process raft request' (duration: 185.491589ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:59:38.476793Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2872} {"level":"info","ts":"2026-10-26T00:59:38.584358Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2872,"took":"105.751901ms","hash":1341944464,"current-db-size-bytes":52137984,"current-db-size":"52 MB","current-db-size-in-use-bytes":27668480,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-26T00:59:38.584427Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1341944464,"revision":2872,"compact-revision":-1} {"level":"info","ts":"2026-10-26T00:59:54.874054Z","caller":"traceutil/trace.go:171","msg":"trace[1503471214] transaction","detail":"{read_only:false; response_revision:5544; number_of_response:1; }","duration":"111.030755ms","start":"2026-10-26T00:59:54.763001Z","end":"2026-10-26T00:59:54.874032Z","steps":["trace[1503471214] 'process raft request' (duration: 110.892332ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:04:38.486396Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5353} {"level":"info","ts":"2026-10-26T01:04:38.573927Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5353,"took":"86.04503ms","hash":3368916197,"current-db-size-bytes":52137984,"current-db-size":"52 MB","current-db-size-in-use-bytes":18817024,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-10-26T01:04:38.574077Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3368916197,"revision":5353,"compact-revision":2872}