{"level":"warn","ts":"2026-09-07T10:41:57.40005Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-07T10:41:57.400193Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.52: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.52:2380","--initial-cluster=instance=https://162.253.55.52:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.52:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.52: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-09-07T10:41:57.400355Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-07T10:41:57.400397Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.52:2380"]} {"level":"info","ts":"2026-09-07T10:41:57.400522Z","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-09-07T10:41:57.401603Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.52:2379"]} {"level":"info","ts":"2026-09-07T10:41:57.401805Z","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.52:2380"],"listen-peer-urls":["https://162.253.55.52:2380"],"advertise-client-urls":["https://162.253.55.52:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.52:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.52: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-09-07T10:41:57.40803Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.820258ms"} {"level":"info","ts":"2026-09-07T10:41:57.419538Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b11e7fc5714be64b","cluster-id":"9349e4f32a52a90a"} {"level":"info","ts":"2026-09-07T10:41:57.419628Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b switched to configuration voters=()"} {"level":"info","ts":"2026-09-07T10:41:57.419657Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became follower at term 0"} {"level":"info","ts":"2026-09-07T10:41:57.419666Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b11e7fc5714be64b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-07T10:41:57.419721Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became follower at term 1"} {"level":"info","ts":"2026-09-07T10:41:57.419808Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b switched to configuration voters=(12762778880000648779)"} {"level":"warn","ts":"2026-09-07T10:41:57.427204Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-07T10:41:57.428718Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-07T10:41:57.430036Z","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-09-07T10:41:57.43397Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b11e7fc5714be64b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-07T10:41:57.437778Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b11e7fc5714be64b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-07T10:41:57.437763Z","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-09-07T10:41:57.437938Z","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-09-07T10:41:57.437961Z","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-09-07T10:41:57.438728Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b switched to configuration voters=(12762778880000648779)"} {"level":"info","ts":"2026-09-07T10:41:57.438861Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9349e4f32a52a90a","local-member-id":"b11e7fc5714be64b","added-peer-id":"b11e7fc5714be64b","added-peer-peer-urls":["https://162.253.55.52:2380"]} {"level":"info","ts":"2026-09-07T10:41:57.442742Z","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-09-07T10:41:57.442872Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.52:2380"} {"level":"info","ts":"2026-09-07T10:41:57.442911Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.52:2380"} {"level":"info","ts":"2026-09-07T10:41:57.443032Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b11e7fc5714be64b","initial-advertise-peer-urls":["https://162.253.55.52:2380"],"listen-peer-urls":["https://162.253.55.52:2380"],"advertise-client-urls":["https://162.253.55.52:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.52:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-07T10:41:57.443082Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-07T10:41:58.320601Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b is starting a new election at term 1"} {"level":"info","ts":"2026-09-07T10:41:58.320722Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became pre-candidate at term 1"} {"level":"info","ts":"2026-09-07T10:41:58.320807Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b received MsgPreVoteResp from b11e7fc5714be64b at term 1"} {"level":"info","ts":"2026-09-07T10:41:58.32087Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became candidate at term 2"} {"level":"info","ts":"2026-09-07T10:41:58.320887Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b received MsgVoteResp from b11e7fc5714be64b at term 2"} {"level":"info","ts":"2026-09-07T10:41:58.320942Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became leader at term 2"} {"level":"info","ts":"2026-09-07T10:41:58.320961Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b11e7fc5714be64b elected leader b11e7fc5714be64b at term 2"} {"level":"info","ts":"2026-09-07T10:41:58.322226Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-07T10:41:58.322237Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b11e7fc5714be64b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.52:2379]}","request-path":"/0/members/b11e7fc5714be64b/attributes","cluster-id":"9349e4f32a52a90a","publish-timeout":"7s"} {"level":"info","ts":"2026-09-07T10:41:58.322313Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-07T10:41:58.322461Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-07T10:41:58.322822Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-07T10:41:58.322876Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-07T10:41:58.325175Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9349e4f32a52a90a","local-member-id":"b11e7fc5714be64b","cluster-version":"3.5"} {"level":"info","ts":"2026-09-07T10:41:58.32554Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-07T10:41:58.325739Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-07T10:41:58.325806Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.52:2379"} {"level":"info","ts":"2026-09-07T10:41:58.327334Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-07T10:42:00.958462Z","caller":"traceutil/trace.go:171","msg":"trace[628138582] linearizableReadLoop","detail":"{readStateIndex:121; appliedIndex:120; }","duration":"123.050967ms","start":"2026-09-07T10:42:00.835383Z","end":"2026-09-07T10:42:00.958434Z","steps":["trace[628138582] 'read index received' (duration: 122.839665ms)","trace[628138582] 'applied index is now lower than readState.Index' (duration: 210.052µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:42:00.958505Z","caller":"traceutil/trace.go:171","msg":"trace[1551344925] transaction","detail":"{read_only:false; response_revision:116; number_of_response:1; }","duration":"123.199649ms","start":"2026-09-07T10:42:00.83528Z","end":"2026-09-07T10:42:00.958479Z","steps":["trace[1551344925] 'process raft request' (duration: 123.014807ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:42:00.958803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.389301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-07T10:42:00.958958Z","caller":"traceutil/trace.go:171","msg":"trace[1813949852] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:116; }","duration":"123.631314ms","start":"2026-09-07T10:42:00.835303Z","end":"2026-09-07T10:42:00.958934Z","steps":["trace[1813949852] 'agreement among raft nodes before linearized reading' (duration: 123.254741ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:42:01.127892Z","caller":"traceutil/trace.go:171","msg":"trace[588423477] transaction","detail":"{read_only:false; response_revision:117; number_of_response:1; }","duration":"163.746651ms","start":"2026-09-07T10:42:00.964118Z","end":"2026-09-07T10:42:01.127864Z","steps":["trace[588423477] 'process raft request' (duration: 156.969415ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:42:01.128143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.365997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-07T10:42:01.128249Z","caller":"traceutil/trace.go:171","msg":"trace[1111133019] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-edit; range_end:; response_count:0; response_revision:117; }","duration":"162.492388ms","start":"2026-09-07T10:42:00.965739Z","end":"2026-09-07T10:42:01.128231Z","steps":["trace[1111133019] 'agreement among raft nodes before linearized reading' (duration: 162.233526ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:42:01.127828Z","caller":"traceutil/trace.go:171","msg":"trace[1378988807] linearizableReadLoop","detail":"{readStateIndex:122; appliedIndex:121; }","duration":"162.039263ms","start":"2026-09-07T10:42:00.965755Z","end":"2026-09-07T10:42:01.127794Z","steps":["trace[1378988807] 'read index received' (duration: 155.272117ms)","trace[1378988807] 'applied index is now lower than readState.Index' (duration: 6.765576ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:42:36.367731Z","caller":"traceutil/trace.go:171","msg":"trace[699172366] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"158.124314ms","start":"2026-09-07T10:42:36.20958Z","end":"2026-09-07T10:42:36.367704Z","steps":["trace[699172366] 'process raft request' (duration: 146.556917ms)","trace[699172366] 'compare' (duration: 11.447535ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T10:42:52.697595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.999613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T10:42:52.697674Z","caller":"traceutil/trace.go:171","msg":"trace[57262817] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:648; }","duration":"276.112564ms","start":"2026-09-07T10:42:52.421544Z","end":"2026-09-07T10:42:52.697656Z","steps":["trace[57262817] 'range keys from in-memory index tree' (duration: 275.820161ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:42:52.697942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.322465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-07T10:42:52.697996Z","caller":"traceutil/trace.go:171","msg":"trace[1329044943] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:648; }","duration":"262.384296ms","start":"2026-09-07T10:42:52.435599Z","end":"2026-09-07T10:42:52.697983Z","steps":["trace[1329044943] 'range keys from in-memory index tree' (duration: 262.231905ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:42:52.69839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.817594ms","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-09-07T10:42:52.698431Z","caller":"traceutil/trace.go:171","msg":"trace[1914610509] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:648; }","duration":"139.915646ms","start":"2026-09-07T10:42:52.558507Z","end":"2026-09-07T10:42:52.698422Z","steps":["trace[1914610509] 'range keys from in-memory index tree' (duration: 139.702093ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:42:52.861869Z","caller":"traceutil/trace.go:171","msg":"trace[171473386] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"155.428672ms","start":"2026-09-07T10:42:52.706381Z","end":"2026-09-07T10:42:52.86181Z","steps":["trace[171473386] 'process raft request' (duration: 154.935547ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:42:52.862047Z","caller":"traceutil/trace.go:171","msg":"trace[112733797] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"151.700564ms","start":"2026-09-07T10:42:52.710311Z","end":"2026-09-07T10:42:52.862011Z","steps":["trace[112733797] 'process raft request' (duration: 151.457422ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:42:52.861866Z","caller":"traceutil/trace.go:171","msg":"trace[2076424345] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"153.36323ms","start":"2026-09-07T10:42:52.708478Z","end":"2026-09-07T10:42:52.861841Z","steps":["trace[2076424345] 'process raft request' (duration: 153.227729ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:42:53.601149Z","caller":"traceutil/trace.go:171","msg":"trace[1601513178] linearizableReadLoop","detail":"{readStateIndex:681; appliedIndex:680; }","duration":"458.420576ms","start":"2026-09-07T10:42:53.142686Z","end":"2026-09-07T10:42:53.601107Z","steps":["trace[1601513178] 'read index received' (duration: 457.428816ms)","trace[1601513178] 'applied index is now lower than readState.Index' (duration: 990.31µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:42:53.601262Z","caller":"traceutil/trace.go:171","msg":"trace[1738411217] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"465.006763ms","start":"2026-09-07T10:42:53.136188Z","end":"2026-09-07T10:42:53.601195Z","steps":["trace[1738411217] 'process raft request' (duration: 464.185035ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:42:53.601497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.80703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-wqvwt\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-09-07T10:42:53.601631Z","caller":"traceutil/trace.go:171","msg":"trace[102980663] range","detail":"{range_begin:/registry/pods/kube-system/cilium-wqvwt; range_end:; response_count:1; response_revision:653; }","duration":"458.987811ms","start":"2026-09-07T10:42:53.142588Z","end":"2026-09-07T10:42:53.601576Z","steps":["trace[102980663] 'agreement among raft nodes before linearized reading' (duration: 458.654518ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:42:53.601691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:42:53.142546Z","time spent":"459.135734ms","remote":"127.0.0.1:57714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-wqvwt\" "} {"level":"warn","ts":"2026-09-07T10:42:53.60216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:42:53.136054Z","time spent":"465.333056ms","remote":"127.0.0.1:57616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036109,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T10:43:08.118085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.450784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-07T10:43:08.118126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.416391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-07T10:43:08.118212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.067712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T10:43:08.118225Z","caller":"traceutil/trace.go:171","msg":"trace[1008977834] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:825; }","duration":"110.551583ms","start":"2026-09-07T10:43:08.007649Z","end":"2026-09-07T10:43:08.118201Z","steps":["trace[1008977834] 'count revisions from in-memory index tree' (duration: 110.369251ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:43:08.118267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.949782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T10:43:08.118292Z","caller":"traceutil/trace.go:171","msg":"trace[762820799] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:825; }","duration":"155.033382ms","start":"2026-09-07T10:43:07.963253Z","end":"2026-09-07T10:43:08.118287Z","steps":["trace[762820799] 'count revisions from in-memory index tree' (duration: 154.81041ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:43:08.118435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.854317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T10:43:08.118503Z","caller":"traceutil/trace.go:171","msg":"trace[634439202] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:825; }","duration":"110.958108ms","start":"2026-09-07T10:43:08.007529Z","end":"2026-09-07T10:43:08.118487Z","steps":["trace[634439202] 'range keys from in-memory index tree' (duration: 110.441802ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:43:08.118536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.470715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T10:43:08.118562Z","caller":"traceutil/trace.go:171","msg":"trace[497567749] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:825; }","duration":"135.574426ms","start":"2026-09-07T10:43:07.98298Z","end":"2026-09-07T10:43:08.118555Z","steps":["trace[497567749] 'range keys from in-memory index tree' (duration: 135.360694ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:43:08.118683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.634007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T10:43:08.118751Z","caller":"traceutil/trace.go:171","msg":"trace[903802961] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:825; }","duration":"135.749287ms","start":"2026-09-07T10:43:07.982988Z","end":"2026-09-07T10:43:08.118737Z","steps":["trace[903802961] 'count revisions from in-memory index tree' (duration: 135.492965ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:43:08.11824Z","caller":"traceutil/trace.go:171","msg":"trace[1550062208] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:825; }","duration":"155.121584ms","start":"2026-09-07T10:43:07.963111Z","end":"2026-09-07T10:43:08.118233Z","steps":["trace[1550062208] 'range keys from in-memory index tree' (duration: 155.012482ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:43:08.11817Z","caller":"traceutil/trace.go:171","msg":"trace[824816142] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:825; }","duration":"134.536896ms","start":"2026-09-07T10:43:07.983607Z","end":"2026-09-07T10:43:08.118144Z","steps":["trace[824816142] 'range keys from in-memory index tree' (duration: 134.401284ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:43:08.119738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.798587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T10:43:08.119771Z","caller":"traceutil/trace.go:171","msg":"trace[1394860740] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:825; }","duration":"111.843667ms","start":"2026-09-07T10:43:08.007916Z","end":"2026-09-07T10:43:08.11976Z","steps":["trace[1394860740] 'range keys from in-memory index tree' (duration: 111.729906ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:43:08.11846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.964512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T10:43:08.119864Z","caller":"traceutil/trace.go:171","msg":"trace[1356687978] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:825; }","duration":"156.372726ms","start":"2026-09-07T10:43:07.963476Z","end":"2026-09-07T10:43:08.119849Z","steps":["trace[1356687978] 'range keys from in-memory index tree' (duration: 154.920122ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:43:41.683102Z","caller":"traceutil/trace.go:171","msg":"trace[1115092161] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"129.899702ms","start":"2026-09-07T10:43:41.553177Z","end":"2026-09-07T10:43:41.683076Z","steps":["trace[1115092161] 'process raft request' (duration: 129.729161ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:44:05.90024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.272108ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T10:44:05.900428Z","caller":"traceutil/trace.go:171","msg":"trace[2050775987] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1502; }","duration":"266.47931ms","start":"2026-09-07T10:44:05.633918Z","end":"2026-09-07T10:44:05.900397Z","steps":["trace[2050775987] 'range keys from in-memory index tree' (duration: 266.245138ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:44:05.921972Z","caller":"traceutil/trace.go:171","msg":"trace[2091756137] transaction","detail":"{read_only:false; response_revision:1503; number_of_response:1; }","duration":"131.860186ms","start":"2026-09-07T10:44:05.790069Z","end":"2026-09-07T10:44:05.921929Z","steps":["trace[2091756137] 'process raft request' (duration: 131.533953ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:44:08.042248Z","caller":"traceutil/trace.go:171","msg":"trace[82303104] transaction","detail":"{read_only:false; response_revision:1526; number_of_response:1; }","duration":"101.69196ms","start":"2026-09-07T10:44:07.940526Z","end":"2026-09-07T10:44:08.042218Z","steps":["trace[82303104] 'process raft request' (duration: 101.381397ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:44:33.104128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.015765ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T10:44:33.104288Z","caller":"traceutil/trace.go:171","msg":"trace[424341231] linearizableReadLoop","detail":"{readStateIndex:1998; appliedIndex:1997; }","duration":"176.249718ms","start":"2026-09-07T10:44:32.92802Z","end":"2026-09-07T10:44:33.10427Z","steps":["trace[424341231] 'read index received' (duration: 4.777598ms)","trace[424341231] 'applied index is now lower than readState.Index' (duration: 171.47066ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T10:44:33.104524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.484431ms","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-09-07T10:44:33.104573Z","caller":"traceutil/trace.go:171","msg":"trace[821974716] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1919; }","duration":"176.604071ms","start":"2026-09-07T10:44:32.927957Z","end":"2026-09-07T10:44:33.104561Z","steps":["trace[821974716] 'agreement among raft nodes before linearized reading' (duration: 176.366179ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:44:33.104781Z","caller":"traceutil/trace.go:171","msg":"trace[1546113104] transaction","detail":"{read_only:false; response_revision:1919; number_of_response:1; }","duration":"215.773698ms","start":"2026-09-07T10:44:32.888557Z","end":"2026-09-07T10:44:33.10433Z","steps":["trace[1546113104] 'process raft request' (duration: 44.290838ms)","trace[1546113104] 'compare' (duration: 170.879513ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:44:41.549253Z","caller":"traceutil/trace.go:171","msg":"trace[133655948] transaction","detail":"{read_only:false; response_revision:1994; number_of_response:1; }","duration":"146.697129ms","start":"2026-09-07T10:44:41.402493Z","end":"2026-09-07T10:44:41.54919Z","steps":["trace[133655948] 'process raft request' (duration: 146.380096ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:45:02.221367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.564162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-09-07T10:45:02.221471Z","caller":"traceutil/trace.go:171","msg":"trace[1302457331] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:2130; }","duration":"100.701853ms","start":"2026-09-07T10:45:02.120748Z","end":"2026-09-07T10:45:02.22145Z","steps":["trace[1302457331] 'range keys from in-memory index tree' (duration: 100.42589ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:45:07.52462Z","caller":"traceutil/trace.go:171","msg":"trace[1297406418] linearizableReadLoop","detail":"{readStateIndex:2245; appliedIndex:2244; }","duration":"164.53956ms","start":"2026-09-07T10:45:07.36004Z","end":"2026-09-07T10:45:07.52458Z","steps":["trace[1297406418] 'read index received' (duration: 65.05179ms)","trace[1297406418] 'applied index is now lower than readState.Index' (duration: 99.48646ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:45:07.524866Z","caller":"traceutil/trace.go:171","msg":"trace[961145978] transaction","detail":"{read_only:false; response_revision:2157; number_of_response:1; }","duration":"182.022648ms","start":"2026-09-07T10:45:07.342806Z","end":"2026-09-07T10:45:07.524829Z","steps":["trace[961145978] 'process raft request' (duration: 82.381686ms)","trace[961145978] 'compare' (duration: 99.189277ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T10:45:07.525008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.852324ms","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-09-07T10:45:07.52565Z","caller":"traceutil/trace.go:171","msg":"trace[1740678716] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2157; }","duration":"165.603142ms","start":"2026-09-07T10:45:07.360006Z","end":"2026-09-07T10:45:07.525609Z","steps":["trace[1740678716] 'agreement among raft nodes before linearized reading' (duration: 164.751164ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:45:07.882916Z","caller":"traceutil/trace.go:171","msg":"trace[160241526] transaction","detail":"{read_only:false; response_revision:2162; number_of_response:1; }","duration":"149.643989ms","start":"2026-09-07T10:45:07.733252Z","end":"2026-09-07T10:45:07.882896Z","steps":["trace[160241526] 'process raft request' (duration: 149.483318ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:45:07.883011Z","caller":"traceutil/trace.go:171","msg":"trace[684477236] transaction","detail":"{read_only:false; response_revision:2161; number_of_response:1; }","duration":"149.831661ms","start":"2026-09-07T10:45:07.733174Z","end":"2026-09-07T10:45:07.883006Z","steps":["trace[684477236] 'process raft request' (duration: 102.469041ms)","trace[684477236] 'compare' (duration: 46.965186ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:45:07.883061Z","caller":"traceutil/trace.go:171","msg":"trace[704294804] transaction","detail":"{read_only:false; response_revision:2163; number_of_response:1; }","duration":"149.481328ms","start":"2026-09-07T10:45:07.733567Z","end":"2026-09-07T10:45:07.883048Z","steps":["trace[704294804] 'process raft request' (duration: 149.207685ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:51:26.078809Z","caller":"traceutil/trace.go:171","msg":"trace[720108838] linearizableReadLoop","detail":"{readStateIndex:5144; appliedIndex:5143; }","duration":"112.638635ms","start":"2026-09-07T10:51:25.966147Z","end":"2026-09-07T10:51:26.078786Z","steps":["trace[720108838] 'read index received' (duration: 19.288074ms)","trace[720108838] 'applied index is now lower than readState.Index' (duration: 93.349661ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:51:26.078928Z","caller":"traceutil/trace.go:171","msg":"trace[2048458415] transaction","detail":"{read_only:false; response_revision:4948; number_of_response:1; }","duration":"112.914461ms","start":"2026-09-07T10:51:25.965972Z","end":"2026-09-07T10:51:26.078887Z","steps":["trace[2048458415] 'process raft request' (duration: 112.636876ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:51:26.079158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.986021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-09-07T10:51:26.079207Z","caller":"traceutil/trace.go:171","msg":"trace[711556907] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:4948; }","duration":"113.050232ms","start":"2026-09-07T10:51:25.966143Z","end":"2026-09-07T10:51:26.079193Z","steps":["trace[711556907] 'agreement among raft nodes before linearized reading' (duration: 112.761807ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:51:46.332065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.755927ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T10:51:46.332371Z","caller":"traceutil/trace.go:171","msg":"trace[973912678] linearizableReadLoop","detail":"{readStateIndex:5435; appliedIndex:5434; }","duration":"330.724214ms","start":"2026-09-07T10:51:46.001622Z","end":"2026-09-07T10:51:46.332347Z","steps":["trace[973912678] 'read index received' (duration: 153.432877ms)","trace[973912678] 'applied index is now lower than readState.Index' (duration: 177.289827ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:51:46.33247Z","caller":"traceutil/trace.go:171","msg":"trace[1432437794] transaction","detail":"{read_only:false; response_revision:5232; number_of_response:1; }","duration":"362.740286ms","start":"2026-09-07T10:51:45.969619Z","end":"2026-09-07T10:51:46.33236Z","steps":["trace[1432437794] 'process raft request' (duration: 185.539901ms)","trace[1432437794] 'compare' (duration: 176.254588ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T10:51:46.332676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.05586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-07T10:51:46.33274Z","caller":"traceutil/trace.go:171","msg":"trace[256235737] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5232; }","duration":"331.229443ms","start":"2026-09-07T10:51:46.001492Z","end":"2026-09-07T10:51:46.332722Z","steps":["trace[256235737] 'agreement among raft nodes before linearized reading' (duration: 331.016479ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:51:46.332784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:51:46.001469Z","time spent":"331.302655ms","remote":"127.0.0.1:57794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-07T10:51:46.332879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.428432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-09-07T10:51:46.332925Z","caller":"traceutil/trace.go:171","msg":"trace[337754395] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5232; }","duration":"281.526134ms","start":"2026-09-07T10:51:46.051386Z","end":"2026-09-07T10:51:46.332912Z","steps":["trace[337754395] 'agreement among raft nodes before linearized reading' (duration: 281.241329ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:51:46.333077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.776877ms","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-09-07T10:51:46.333132Z","caller":"traceutil/trace.go:171","msg":"trace[1714700930] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5232; }","duration":"249.856478ms","start":"2026-09-07T10:51:46.083261Z","end":"2026-09-07T10:51:46.333118Z","steps":["trace[1714700930] 'agreement among raft nodes before linearized reading' (duration: 249.692625ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:51:46.333205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.619483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-09-07T10:51:46.333244Z","caller":"traceutil/trace.go:171","msg":"trace[1632281388] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5232; }","duration":"111.705815ms","start":"2026-09-07T10:51:46.221529Z","end":"2026-09-07T10:51:46.333235Z","steps":["trace[1632281388] 'agreement among raft nodes before linearized reading' (duration: 111.595393ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:51:46.333279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.44194ms","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-09-07T10:51:46.333326Z","caller":"traceutil/trace.go:171","msg":"trace[197348991] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:5232; }","duration":"131.541542ms","start":"2026-09-07T10:51:46.20177Z","end":"2026-09-07T10:51:46.333312Z","steps":["trace[197348991] 'agreement among raft nodes before linearized reading' (duration: 131.470401ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:51:46.333414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.933514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-09-07T10:51:46.333522Z","caller":"traceutil/trace.go:171","msg":"trace[699315913] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5232; }","duration":"228.166558ms","start":"2026-09-07T10:51:46.105336Z","end":"2026-09-07T10:51:46.333503Z","steps":["trace[699315913] 'agreement among raft nodes before linearized reading' (duration: 227.74865ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:51:46.33377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:51:45.969597Z","time spent":"363.065123ms","remote":"127.0.0.1:57794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T10:51:46.334032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.431602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10391"} {"level":"info","ts":"2026-09-07T10:51:46.334215Z","caller":"traceutil/trace.go:171","msg":"trace[820712793] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5232; }","duration":"255.686917ms","start":"2026-09-07T10:51:46.078514Z","end":"2026-09-07T10:51:46.334201Z","steps":["trace[820712793] 'agreement among raft nodes before linearized reading' (duration: 255.397922ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:51:46.334134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.442546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-07T10:51:46.334326Z","caller":"traceutil/trace.go:171","msg":"trace[480702830] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:5232; }","duration":"180.67654ms","start":"2026-09-07T10:51:46.153637Z","end":"2026-09-07T10:51:46.334314Z","steps":["trace[480702830] 'agreement among raft nodes before linearized reading' (duration: 179.329805ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:51:46.548214Z","caller":"traceutil/trace.go:171","msg":"trace[1458161514] transaction","detail":"{read_only:false; response_revision:5241; number_of_response:1; }","duration":"108.856312ms","start":"2026-09-07T10:51:46.439315Z","end":"2026-09-07T10:51:46.548171Z","steps":["trace[1458161514] 'process raft request' (duration: 108.804851ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:51:46.548568Z","caller":"traceutil/trace.go:171","msg":"trace[837246245] transaction","detail":"{read_only:false; response_revision:5240; number_of_response:1; }","duration":"109.337511ms","start":"2026-09-07T10:51:46.439219Z","end":"2026-09-07T10:51:46.548557Z","steps":["trace[837246245] 'process raft request' (duration: 108.825972ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:51:46.548853Z","caller":"traceutil/trace.go:171","msg":"trace[662298395] transaction","detail":"{read_only:false; response_revision:5239; number_of_response:1; }","duration":"109.788529ms","start":"2026-09-07T10:51:46.439054Z","end":"2026-09-07T10:51:46.548842Z","steps":["trace[662298395] 'process raft request' (duration: 108.77286ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:51:58.354094Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2857} {"level":"info","ts":"2026-09-07T10:51:58.440873Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2857,"took":"85.542425ms","hash":522098031,"current-db-size-bytes":52031488,"current-db-size":"52 MB","current-db-size-in-use-bytes":27680768,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-07T10:51:58.44094Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":522098031,"revision":2857,"compact-revision":-1} {"level":"info","ts":"2026-09-07T10:55:22.844768Z","caller":"traceutil/trace.go:171","msg":"trace[761006350] transaction","detail":"{read_only:false; response_revision:6827; number_of_response:1; }","duration":"142.539504ms","start":"2026-09-07T10:55:22.702206Z","end":"2026-09-07T10:55:22.844746Z","steps":["trace[761006350] 'process raft request' (duration: 142.403149ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:56:58.360832Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5332} {"level":"info","ts":"2026-09-07T10:56:58.477921Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5332,"took":"115.514422ms","hash":340029962,"current-db-size-bytes":52031488,"current-db-size":"52 MB","current-db-size-in-use-bytes":18829312,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-07T10:56:58.477968Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":340029962,"revision":5332,"compact-revision":2857}