{"level":"warn","ts":"2026-09-08T15:45:50.480314Z","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-08T15:45:50.480416Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.55: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.55:2380","--initial-cluster=instance=https://162.253.55.55:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.55:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.55: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-08T15:45:50.480488Z","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-08T15:45:50.4805Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.55:2380"]} {"level":"info","ts":"2026-09-08T15:45:50.480644Z","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-08T15:45:50.481339Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.55:2379"]} {"level":"info","ts":"2026-09-08T15:45:50.481436Z","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.55:2380"],"listen-peer-urls":["https://162.253.55.55:2380"],"advertise-client-urls":["https://162.253.55.55:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.55:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.55: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-08T15:45:50.489292Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.558927ms"} {"level":"info","ts":"2026-09-08T15:45:50.497098Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"357bf0025115def9","cluster-id":"5f69314e1ae3c800"} {"level":"info","ts":"2026-09-08T15:45:50.497195Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"357bf0025115def9 switched to configuration voters=()"} {"level":"info","ts":"2026-09-08T15:45:50.497218Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"357bf0025115def9 became follower at term 0"} {"level":"info","ts":"2026-09-08T15:45:50.497228Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 357bf0025115def9 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-08T15:45:50.497234Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"357bf0025115def9 became follower at term 1"} {"level":"info","ts":"2026-09-08T15:45:50.497297Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"357bf0025115def9 switched to configuration voters=(3853937798886579961)"} {"level":"warn","ts":"2026-09-08T15:45:50.504653Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-08T15:45:50.506052Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-08T15:45:50.507629Z","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-08T15:45:50.509899Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"357bf0025115def9","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-08T15:45:50.510028Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"357bf0025115def9","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-08T15:45:50.510261Z","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-08T15:45:50.51035Z","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-08T15:45:50.510388Z","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-08T15:45:50.510949Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"357bf0025115def9 switched to configuration voters=(3853937798886579961)"} {"level":"info","ts":"2026-09-08T15:45:50.511338Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5f69314e1ae3c800","local-member-id":"357bf0025115def9","added-peer-id":"357bf0025115def9","added-peer-peer-urls":["https://162.253.55.55:2380"]} {"level":"info","ts":"2026-09-08T15:45:50.513186Z","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-08T15:45:50.513219Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.55:2380"} {"level":"info","ts":"2026-09-08T15:45:50.513251Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.55:2380"} {"level":"info","ts":"2026-09-08T15:45:50.51341Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"357bf0025115def9","initial-advertise-peer-urls":["https://162.253.55.55:2380"],"listen-peer-urls":["https://162.253.55.55:2380"],"advertise-client-urls":["https://162.253.55.55:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.55:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-08T15:45:50.513445Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-08T15:45:51.398048Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"357bf0025115def9 is starting a new election at term 1"} {"level":"info","ts":"2026-09-08T15:45:51.398114Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"357bf0025115def9 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-08T15:45:51.398182Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"357bf0025115def9 received MsgPreVoteResp from 357bf0025115def9 at term 1"} {"level":"info","ts":"2026-09-08T15:45:51.398202Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"357bf0025115def9 became candidate at term 2"} {"level":"info","ts":"2026-09-08T15:45:51.398213Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"357bf0025115def9 received MsgVoteResp from 357bf0025115def9 at term 2"} {"level":"info","ts":"2026-09-08T15:45:51.398239Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"357bf0025115def9 became leader at term 2"} {"level":"info","ts":"2026-09-08T15:45:51.398259Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 357bf0025115def9 elected leader 357bf0025115def9 at term 2"} {"level":"info","ts":"2026-09-08T15:45:51.399479Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-08T15:45:51.4004Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-08T15:45:51.400398Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"357bf0025115def9","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.55:2379]}","request-path":"/0/members/357bf0025115def9/attributes","cluster-id":"5f69314e1ae3c800","publish-timeout":"7s"} {"level":"info","ts":"2026-09-08T15:45:51.400577Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-08T15:45:51.40085Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5f69314e1ae3c800","local-member-id":"357bf0025115def9","cluster-version":"3.5"} {"level":"info","ts":"2026-09-08T15:45:51.401005Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-08T15:45:51.401076Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-08T15:45:51.401224Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-08T15:45:51.401341Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-08T15:45:51.402732Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-08T15:45:51.404439Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.55:2379"} {"level":"info","ts":"2026-09-08T15:46:00.506972Z","caller":"traceutil/trace.go:171","msg":"trace[1499993419] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"105.006032ms","start":"2026-09-08T15:46:00.401943Z","end":"2026-09-08T15:46:00.506949Z","steps":["trace[1499993419] 'process raft request' (duration: 104.887979ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:00.774064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.325216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-09-08T15:46:00.774122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.403658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-09-08T15:46:00.774181Z","caller":"traceutil/trace.go:171","msg":"trace[1423163301] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:337; }","duration":"104.5135ms","start":"2026-09-08T15:46:00.66965Z","end":"2026-09-08T15:46:00.774164Z","steps":["trace[1423163301] 'range keys from in-memory index tree' (duration: 104.207123ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:46:00.774212Z","caller":"traceutil/trace.go:171","msg":"trace[1553948449] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:337; }","duration":"222.572892ms","start":"2026-09-08T15:46:00.551618Z","end":"2026-09-08T15:46:00.774191Z","steps":["trace[1553948449] 'range keys from in-memory index tree' (duration: 222.312666ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:46:00.946338Z","caller":"traceutil/trace.go:171","msg":"trace[372977024] transaction","detail":"{read_only:false; response_revision:338; number_of_response:1; }","duration":"168.250194ms","start":"2026-09-08T15:46:00.778065Z","end":"2026-09-08T15:46:00.946316Z","steps":["trace[372977024] 'process raft request' (duration: 168.110131ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:46:00.94959Z","caller":"traceutil/trace.go:171","msg":"trace[1107119003] transaction","detail":"{read_only:false; response_revision:339; number_of_response:1; }","duration":"171.199862ms","start":"2026-09-08T15:46:00.778367Z","end":"2026-09-08T15:46:00.949566Z","steps":["trace[1107119003] 'process raft request' (duration: 170.926475ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:46:16.788477Z","caller":"traceutil/trace.go:171","msg":"trace[2122620769] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"281.645024ms","start":"2026-09-08T15:46:16.506774Z","end":"2026-09-08T15:46:16.788419Z","steps":["trace[2122620769] 'process raft request' (duration: 252.056994ms)","trace[2122620769] 'compare' (duration: 29.227503ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:46:16.789007Z","caller":"traceutil/trace.go:171","msg":"trace[1698482453] transaction","detail":"{read_only:false; response_revision:440; number_of_response:1; }","duration":"280.522942ms","start":"2026-09-08T15:46:16.508453Z","end":"2026-09-08T15:46:16.788976Z","steps":["trace[1698482453] 'process raft request' (duration: 280.296067ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:46:16.78921Z","caller":"traceutil/trace.go:171","msg":"trace[786751783] transaction","detail":"{read_only:false; response_revision:441; number_of_response:1; }","duration":"268.730658ms","start":"2026-09-08T15:46:16.520425Z","end":"2026-09-08T15:46:16.789156Z","steps":["trace[786751783] 'process raft request' (duration: 268.473013ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:46:33.180311Z","caller":"traceutil/trace.go:171","msg":"trace[253353399] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"266.795122ms","start":"2026-09-08T15:46:32.912768Z","end":"2026-09-08T15:46:33.179563Z","steps":["trace[253353399] 'process raft request' (duration: 266.560767ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:46:33.190683Z","caller":"traceutil/trace.go:171","msg":"trace[1065992468] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"244.051894ms","start":"2026-09-08T15:46:32.946606Z","end":"2026-09-08T15:46:33.190658Z","steps":["trace[1065992468] 'process raft request' (duration: 243.065605ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:39.510801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.905114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-09-08T15:46:39.510967Z","caller":"traceutil/trace.go:171","msg":"trace[2102693352] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:652; }","duration":"145.948103ms","start":"2026-09-08T15:46:39.36499Z","end":"2026-09-08T15:46:39.510938Z","steps":["trace[2102693352] 'range keys from in-memory index tree' (duration: 144.718781ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:46:40.766666Z","caller":"traceutil/trace.go:171","msg":"trace[590461758] linearizableReadLoop","detail":"{readStateIndex:679; appliedIndex:678; }","duration":"245.502084ms","start":"2026-09-08T15:46:40.521132Z","end":"2026-09-08T15:46:40.766634Z","steps":["trace[590461758] 'read index received' (duration: 245.189769ms)","trace[590461758] 'applied index is now lower than readState.Index' (duration: 310.975µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:46:40.767003Z","caller":"traceutil/trace.go:171","msg":"trace[65546552] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"272.859056ms","start":"2026-09-08T15:46:40.494116Z","end":"2026-09-08T15:46:40.766975Z","steps":["trace[65546552] 'process raft request' (duration: 272.288316ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:40.767028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.82539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-w4jwh\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-09-08T15:46:40.767232Z","caller":"traceutil/trace.go:171","msg":"trace[1459413786] range","detail":"{range_begin:/registry/pods/kube-system/cilium-w4jwh; range_end:; response_count:1; response_revision:655; }","duration":"246.056304ms","start":"2026-09-08T15:46:40.521056Z","end":"2026-09-08T15:46:40.767113Z","steps":["trace[1459413786] 'agreement among raft nodes before linearized reading' (duration: 245.751378ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:46:42.85881Z","caller":"traceutil/trace.go:171","msg":"trace[1084637451] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"138.365339ms","start":"2026-09-08T15:46:42.720403Z","end":"2026-09-08T15:46:42.858769Z","steps":["trace[1084637451] 'process raft request' (duration: 134.557092ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:47.567707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.833595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-08T15:46:47.567722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.301886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:46:47.567802Z","caller":"traceutil/trace.go:171","msg":"trace[1097318881] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:744; }","duration":"102.003548ms","start":"2026-09-08T15:46:47.465775Z","end":"2026-09-08T15:46:47.567779Z","steps":["trace[1097318881] 'agreement among raft nodes before linearized reading' (duration: 101.762764ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:46:47.567841Z","caller":"traceutil/trace.go:171","msg":"trace[1389405653] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:0; response_revision:744; }","duration":"101.460498ms","start":"2026-09-08T15:46:47.466348Z","end":"2026-09-08T15:46:47.567808Z","steps":["trace[1389405653] 'agreement among raft nodes before linearized reading' (duration: 101.156473ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:46:57.252489Z","caller":"traceutil/trace.go:171","msg":"trace[935266373] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"270.339375ms","start":"2026-09-08T15:46:56.98212Z","end":"2026-09-08T15:46:57.25246Z","steps":["trace[935266373] 'process raft request' (duration: 270.181293ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:47:32.542512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.325211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:47:32.542621Z","caller":"traceutil/trace.go:171","msg":"trace[578649076] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1240; }","duration":"143.486624ms","start":"2026-09-08T15:47:32.39911Z","end":"2026-09-08T15:47:32.542597Z","steps":["trace[578649076] 'range keys from in-memory index tree' (duration: 143.249289ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:47:32.542902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.964758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-08T15:47:32.543287Z","caller":"traceutil/trace.go:171","msg":"trace[1454172589] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1240; }","duration":"238.400564ms","start":"2026-09-08T15:47:32.304865Z","end":"2026-09-08T15:47:32.543265Z","steps":["trace[1454172589] 'range keys from in-memory index tree' (duration: 237.762375ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:48:23.595202Z","caller":"traceutil/trace.go:171","msg":"trace[1996475305] linearizableReadLoop","detail":"{readStateIndex:1968; appliedIndex:1967; }","duration":"196.582463ms","start":"2026-09-08T15:48:23.398592Z","end":"2026-09-08T15:48:23.595175Z","steps":["trace[1996475305] 'read index received' (duration: 196.430191ms)","trace[1996475305] 'applied index is now lower than readState.Index' (duration: 150.902µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:48:23.595344Z","caller":"traceutil/trace.go:171","msg":"trace[1842397735] transaction","detail":"{read_only:false; response_revision:1910; number_of_response:1; }","duration":"229.517728ms","start":"2026-09-08T15:48:23.365789Z","end":"2026-09-08T15:48:23.595307Z","steps":["trace[1842397735] 'process raft request' (duration: 229.206404ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:48:23.595537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.901947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:48:23.595659Z","caller":"traceutil/trace.go:171","msg":"trace[692941221] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1910; }","duration":"197.09358ms","start":"2026-09-08T15:48:23.39854Z","end":"2026-09-08T15:48:23.595634Z","steps":["trace[692941221] 'agreement among raft nodes before linearized reading' (duration: 196.830267ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:48:23.800937Z","caller":"traceutil/trace.go:171","msg":"trace[1696699606] transaction","detail":"{read_only:false; response_revision:1913; number_of_response:1; }","duration":"109.161283ms","start":"2026-09-08T15:48:23.691734Z","end":"2026-09-08T15:48:23.800895Z","steps":["trace[1696699606] 'process raft request' (duration: 68.072759ms)","trace[1696699606] 'compare' (duration: 40.796301ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:48:26.899291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.256331ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-08T15:48:26.899405Z","caller":"traceutil/trace.go:171","msg":"trace[1196470763] linearizableReadLoop","detail":"{readStateIndex:2007; appliedIndex:2006; }","duration":"142.87051ms","start":"2026-09-08T15:48:26.756517Z","end":"2026-09-08T15:48:26.899387Z","steps":["trace[1196470763] 'read index received' (duration: 40.993261ms)","trace[1196470763] 'applied index is now lower than readState.Index' (duration: 101.875889ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:48:26.899489Z","caller":"traceutil/trace.go:171","msg":"trace[1678012512] transaction","detail":"{read_only:false; response_revision:1947; number_of_response:1; }","duration":"220.174281ms","start":"2026-09-08T15:48:26.679304Z","end":"2026-09-08T15:48:26.899478Z","steps":["trace[1678012512] 'process raft request' (duration: 118.18415ms)","trace[1678012512] 'compare' (duration: 100.994737ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:48:26.90068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.204045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-09-08T15:48:26.90074Z","caller":"traceutil/trace.go:171","msg":"trace[744496110] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:1947; }","duration":"120.302537ms","start":"2026-09-08T15:48:26.780425Z","end":"2026-09-08T15:48:26.900728Z","steps":["trace[744496110] 'agreement among raft nodes before linearized reading' (duration: 120.126665ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:48:26.901141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.622395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:9612"} {"level":"info","ts":"2026-09-08T15:48:26.901201Z","caller":"traceutil/trace.go:171","msg":"trace[1196165209] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1947; }","duration":"144.749676ms","start":"2026-09-08T15:48:26.75644Z","end":"2026-09-08T15:48:26.901189Z","steps":["trace[1196165209] 'agreement among raft nodes before linearized reading' (duration: 143.316456ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:48:31.910001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.213128ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-09-08T15:48:56.227157Z","caller":"traceutil/trace.go:171","msg":"trace[978537256] transaction","detail":"{read_only:false; response_revision:2180; number_of_response:1; }","duration":"134.169282ms","start":"2026-09-08T15:48:56.092955Z","end":"2026-09-08T15:48:56.227125Z","steps":["trace[978537256] 'process raft request' (duration: 118.048141ms)","trace[978537256] 'compare' (duration: 15.882721ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:48:56.227799Z","caller":"traceutil/trace.go:171","msg":"trace[952138641] transaction","detail":"{read_only:false; response_revision:2181; number_of_response:1; }","duration":"134.417213ms","start":"2026-09-08T15:48:56.093356Z","end":"2026-09-08T15:48:56.227773Z","steps":["trace[952138641] 'process raft request' (duration: 133.687842ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:48:56.37841Z","caller":"traceutil/trace.go:171","msg":"trace[1704618203] transaction","detail":"{read_only:false; response_revision:2182; number_of_response:1; }","duration":"129.248799ms","start":"2026-09-08T15:48:56.248865Z","end":"2026-09-08T15:48:56.378113Z","steps":["trace[1704618203] 'process raft request' (duration: 128.197833ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:48:56.383948Z","caller":"traceutil/trace.go:171","msg":"trace[1152057384] transaction","detail":"{read_only:false; response_revision:2183; number_of_response:1; }","duration":"133.334676ms","start":"2026-09-08T15:48:56.250589Z","end":"2026-09-08T15:48:56.383924Z","steps":["trace[1152057384] 'process raft request' (duration: 133.157518ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:57.328732Z","caller":"traceutil/trace.go:171","msg":"trace[2087684039] transaction","detail":"{read_only:false; response_revision:2700; number_of_response:1; }","duration":"221.1401ms","start":"2026-09-08T15:49:57.107558Z","end":"2026-09-08T15:49:57.328698Z","steps":["trace[2087684039] 'process raft request' (duration: 220.864931ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:57.328619Z","caller":"traceutil/trace.go:171","msg":"trace[1672924221] linearizableReadLoop","detail":"{readStateIndex:2801; appliedIndex:2800; }","duration":"212.492004ms","start":"2026-09-08T15:49:57.116099Z","end":"2026-09-08T15:49:57.328591Z","steps":["trace[1672924221] 'read index received' (duration: 212.246276ms)","trace[1672924221] 'applied index is now lower than readState.Index' (duration: 244.658µs)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:49:57.329387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.274749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"} {"level":"info","ts":"2026-09-08T15:49:57.329445Z","caller":"traceutil/trace.go:171","msg":"trace[316298733] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2700; }","duration":"213.380034ms","start":"2026-09-08T15:49:57.116045Z","end":"2026-09-08T15:49:57.329425Z","steps":["trace[316298733] 'agreement among raft nodes before linearized reading' (duration: 213.215388ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:50:29.14058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.083518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:50:29.140794Z","caller":"traceutil/trace.go:171","msg":"trace[281069786] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:2921; }","duration":"118.367266ms","start":"2026-09-08T15:50:29.022399Z","end":"2026-09-08T15:50:29.140766Z","steps":["trace[281069786] 'count revisions from in-memory index tree' (duration: 117.969735ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:50:34.842473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.871039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-08T15:50:34.84292Z","caller":"traceutil/trace.go:171","msg":"trace[117374495] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2956; }","duration":"217.546988ms","start":"2026-09-08T15:50:34.625339Z","end":"2026-09-08T15:50:34.842886Z","steps":["trace[117374495] 'range keys from in-memory index tree' (duration: 216.308202ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:50:34.842999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.102403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1296"} {"level":"info","ts":"2026-09-08T15:50:34.843107Z","caller":"traceutil/trace.go:171","msg":"trace[194994839] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2956; }","duration":"199.244976ms","start":"2026-09-08T15:50:34.643836Z","end":"2026-09-08T15:50:34.843081Z","steps":["trace[194994839] 'range keys from in-memory index tree' (duration: 198.897947ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:52:57.657727Z","caller":"traceutil/trace.go:171","msg":"trace[123816286] transaction","detail":"{read_only:false; response_revision:4530; number_of_response:1; }","duration":"100.150507ms","start":"2026-09-08T15:52:57.557561Z","end":"2026-09-08T15:52:57.657711Z","steps":["trace[123816286] 'process raft request' (duration: 97.843929ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:53:00.808704Z","caller":"traceutil/trace.go:171","msg":"trace[545819377] linearizableReadLoop","detail":"{readStateIndex:4787; appliedIndex:4786; }","duration":"176.73758ms","start":"2026-09-08T15:53:00.631937Z","end":"2026-09-08T15:53:00.808674Z","steps":["trace[545819377] 'read index received' (duration: 157.839482ms)","trace[545819377] 'applied index is now lower than readState.Index' (duration: 18.895898ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:53:00.808794Z","caller":"traceutil/trace.go:171","msg":"trace[879715522] transaction","detail":"{read_only:false; response_revision:4586; number_of_response:1; }","duration":"177.162739ms","start":"2026-09-08T15:53:00.631589Z","end":"2026-09-08T15:53:00.808751Z","steps":["trace[879715522] 'process raft request' (duration: 158.272061ms)","trace[879715522] 'compare' (duration: 18.50417ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:53:00.80925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.244161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-09-08T15:53:00.809374Z","caller":"traceutil/trace.go:171","msg":"trace[1067937460] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4586; }","duration":"177.445905ms","start":"2026-09-08T15:53:00.631908Z","end":"2026-09-08T15:53:00.809354Z","steps":["trace[1067937460] 'agreement among raft nodes before linearized reading' (duration: 177.018926ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:53:00.809225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.925591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3d7bbf6f-d866-4ba5-a9ee-e28bc7e0af42\" ","response":"range_response_count:1 size:4078"} {"level":"info","ts":"2026-09-08T15:53:00.810072Z","caller":"traceutil/trace.go:171","msg":"trace[941762662] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3d7bbf6f-d866-4ba5-a9ee-e28bc7e0af42; range_end:; response_count:1; response_revision:4586; }","duration":"117.81641ms","start":"2026-09-08T15:53:00.692211Z","end":"2026-09-08T15:53:00.810027Z","steps":["trace[941762662] 'agreement among raft nodes before linearized reading' (duration: 116.756958ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:53:02.113882Z","caller":"traceutil/trace.go:171","msg":"trace[564051070] transaction","detail":"{read_only:false; response_revision:4633; number_of_response:1; }","duration":"183.226734ms","start":"2026-09-08T15:53:01.93064Z","end":"2026-09-08T15:53:02.113867Z","steps":["trace[564051070] 'process raft request' (duration: 183.116621ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:53:07.751356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.492211ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T15:53:07.751459Z","caller":"traceutil/trace.go:171","msg":"trace[1889264960] linearizableReadLoop","detail":"{readStateIndex:4955; appliedIndex:4954; }","duration":"178.880586ms","start":"2026-09-08T15:53:07.572562Z","end":"2026-09-08T15:53:07.751442Z","steps":["trace[1889264960] 'read index received' (duration: 53.079649ms)","trace[1889264960] 'applied index is now lower than readState.Index' (duration: 125.799467ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:53:07.751609Z","caller":"traceutil/trace.go:171","msg":"trace[804575960] transaction","detail":"{read_only:false; response_revision:4747; number_of_response:1; }","duration":"180.5501ms","start":"2026-09-08T15:53:07.571018Z","end":"2026-09-08T15:53:07.751568Z","steps":["trace[804575960] 'process raft request' (duration: 54.62356ms)","trace[804575960] 'compare' (duration: 125.148704ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:53:07.751669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.10254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-09-08T15:53:07.751716Z","caller":"traceutil/trace.go:171","msg":"trace[1402544040] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4747; }","duration":"179.177532ms","start":"2026-09-08T15:53:07.57252Z","end":"2026-09-08T15:53:07.751697Z","steps":["trace[1402544040] 'agreement among raft nodes before linearized reading' (duration: 179.032829ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:53:07.753063Z","caller":"traceutil/trace.go:171","msg":"trace[1442626071] transaction","detail":"{read_only:false; response_revision:4748; number_of_response:1; }","duration":"170.095158ms","start":"2026-09-08T15:53:07.582953Z","end":"2026-09-08T15:53:07.753048Z","steps":["trace[1442626071] 'process raft request' (duration: 169.995596ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:53:07.754234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.872373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-09-08T15:53:07.754315Z","caller":"traceutil/trace.go:171","msg":"trace[1757905421] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4749; }","duration":"139.995336ms","start":"2026-09-08T15:53:07.6143Z","end":"2026-09-08T15:53:07.754295Z","steps":["trace[1757905421] 'agreement among raft nodes before linearized reading' (duration: 139.768972ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:53:19.75978Z","caller":"traceutil/trace.go:171","msg":"trace[1928884600] linearizableReadLoop","detail":"{readStateIndex:5132; appliedIndex:5131; }","duration":"361.581568ms","start":"2026-09-08T15:53:19.398173Z","end":"2026-09-08T15:53:19.759754Z","steps":["trace[1928884600] 'read index received' (duration: 331.860104ms)","trace[1928884600] 'applied index is now lower than readState.Index' (duration: 29.719864ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:53:19.759823Z","caller":"traceutil/trace.go:171","msg":"trace[736476460] transaction","detail":"{read_only:false; response_revision:4916; number_of_response:1; }","duration":"570.850511ms","start":"2026-09-08T15:53:19.188914Z","end":"2026-09-08T15:53:19.759764Z","steps":["trace[736476460] 'process raft request' (duration: 541.144047ms)","trace[736476460] 'compare' (duration: 29.347327ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:53:19.759962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.783961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:53:19.759995Z","caller":"traceutil/trace.go:171","msg":"trace[1354946965] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4916; }","duration":"361.869063ms","start":"2026-09-08T15:53:19.398116Z","end":"2026-09-08T15:53:19.759985Z","steps":["trace[1354946965] 'agreement among raft nodes before linearized reading' (duration: 361.770291ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:53:19.759977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:53:19.188882Z","time spent":"571.019815ms","remote":"127.0.0.1:52144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":684,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T15:53:19.760036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:53:19.398095Z","time spent":"361.932694ms","remote":"127.0.0.1:55914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-08T15:53:19.760215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.513018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-08T15:53:19.760312Z","caller":"traceutil/trace.go:171","msg":"trace[2024082233] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:4916; }","duration":"195.660801ms","start":"2026-09-08T15:53:19.564629Z","end":"2026-09-08T15:53:19.76029Z","steps":["trace[2024082233] 'agreement among raft nodes before linearized reading' (duration: 195.502368ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:53:19.892443Z","caller":"traceutil/trace.go:171","msg":"trace[462910381] transaction","detail":"{read_only:false; response_revision:4917; number_of_response:1; }","duration":"118.903306ms","start":"2026-09-08T15:53:19.773505Z","end":"2026-09-08T15:53:19.892409Z","steps":["trace[462910381] 'process raft request' (duration: 118.640701ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:53:19.908656Z","caller":"traceutil/trace.go:171","msg":"trace[2129664575] transaction","detail":"{read_only:false; response_revision:4918; number_of_response:1; }","duration":"112.715773ms","start":"2026-09-08T15:53:19.795912Z","end":"2026-09-08T15:53:19.908628Z","steps":["trace[2129664575] 'process raft request' (duration: 112.540189ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:53:20.120683Z","caller":"traceutil/trace.go:171","msg":"trace[820633677] linearizableReadLoop","detail":"{readStateIndex:5139; appliedIndex:5138; }","duration":"144.657048ms","start":"2026-09-08T15:53:19.976003Z","end":"2026-09-08T15:53:20.12066Z","steps":["trace[820633677] 'read index received' (duration: 137.044526ms)","trace[820633677] 'applied index is now lower than readState.Index' (duration: 7.611432ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:53:20.120697Z","caller":"traceutil/trace.go:171","msg":"trace[692614886] transaction","detail":"{read_only:false; response_revision:4923; number_of_response:1; }","duration":"208.26101ms","start":"2026-09-08T15:53:19.912404Z","end":"2026-09-08T15:53:20.120665Z","steps":["trace[692614886] 'process raft request' (duration: 200.630788ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:53:20.120864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.529796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-09-08T15:53:20.121219Z","caller":"traceutil/trace.go:171","msg":"trace[210633084] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4923; }","duration":"207.945304ms","start":"2026-09-08T15:53:19.913254Z","end":"2026-09-08T15:53:20.121199Z","steps":["trace[210633084] 'agreement among raft nodes before linearized reading' (duration: 207.491715ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:53:20.121298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.195939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-09-08T15:53:20.121661Z","caller":"traceutil/trace.go:171","msg":"trace[419319664] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4923; }","duration":"201.614537ms","start":"2026-09-08T15:53:19.919996Z","end":"2026-09-08T15:53:20.121611Z","steps":["trace[419319664] 'agreement among raft nodes before linearized reading' (duration: 200.794021ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:53:20.127304Z","caller":"traceutil/trace.go:171","msg":"trace[112877594] transaction","detail":"{read_only:false; response_revision:4924; number_of_response:1; }","duration":"141.871392ms","start":"2026-09-08T15:53:19.98541Z","end":"2026-09-08T15:53:20.127281Z","steps":["trace[112877594] 'process raft request' (duration: 141.653388ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:53:20.127649Z","caller":"traceutil/trace.go:171","msg":"trace[593993101] transaction","detail":"{read_only:false; response_revision:4925; number_of_response:1; }","duration":"142.108937ms","start":"2026-09-08T15:53:19.985516Z","end":"2026-09-08T15:53:20.127625Z","steps":["trace[593993101] 'process raft request' (duration: 141.715619ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:53:20.299911Z","caller":"traceutil/trace.go:171","msg":"trace[436947760] transaction","detail":"{read_only:false; response_revision:4929; number_of_response:1; }","duration":"146.112017ms","start":"2026-09-08T15:53:20.153775Z","end":"2026-09-08T15:53:20.299887Z","steps":["trace[436947760] 'process raft request' (duration: 139.403703ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:53:53.8313Z","caller":"traceutil/trace.go:171","msg":"trace[62572707] linearizableReadLoop","detail":"{readStateIndex:5528; appliedIndex:5527; }","duration":"162.852855ms","start":"2026-09-08T15:53:53.668416Z","end":"2026-09-08T15:53:53.831269Z","steps":["trace[62572707] 'read index received' (duration: 119.456543ms)","trace[62572707] 'applied index is now lower than readState.Index' (duration: 43.395132ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:53:53.831437Z","caller":"traceutil/trace.go:171","msg":"trace[400543792] transaction","detail":"{read_only:false; number_of_response:0; response_revision:5296; }","duration":"169.815739ms","start":"2026-09-08T15:53:53.661593Z","end":"2026-09-08T15:53:53.831409Z","steps":["trace[400543792] 'process raft request' (duration: 126.319765ms)","trace[400543792] 'compare' (duration: 43.29689ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:53:53.83152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.078159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-08T15:53:53.83157Z","caller":"traceutil/trace.go:171","msg":"trace[1449647459] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/; range_end:/registry/monitoring.coreos.com/prometheuses0; response_count:0; response_revision:5296; }","duration":"163.174862ms","start":"2026-09-08T15:53:53.668377Z","end":"2026-09-08T15:53:53.831552Z","steps":["trace[1449647459] 'agreement among raft nodes before linearized reading' (duration: 163.06899ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:53:53.831873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.004202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:53:53.831947Z","caller":"traceutil/trace.go:171","msg":"trace[815927091] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:5296; }","duration":"145.132935ms","start":"2026-09-08T15:53:53.686793Z","end":"2026-09-08T15:53:53.831926Z","steps":["trace[815927091] 'agreement among raft nodes before linearized reading' (duration: 144.944761ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:53:54.104073Z","caller":"traceutil/trace.go:171","msg":"trace[1524442922] transaction","detail":"{read_only:false; response_revision:5298; number_of_response:1; }","duration":"112.616569ms","start":"2026-09-08T15:53:53.991394Z","end":"2026-09-08T15:53:54.10401Z","steps":["trace[1524442922] 'process raft request' (duration: 112.279893ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:54:08.594142Z","caller":"traceutil/trace.go:171","msg":"trace[1185177042] transaction","detail":"{read_only:false; response_revision:5419; number_of_response:1; }","duration":"157.754671ms","start":"2026-09-08T15:54:08.436361Z","end":"2026-09-08T15:54:08.594115Z","steps":["trace[1185177042] 'process raft request' (duration: 133.052344ms)","trace[1185177042] 'compare' (duration: 24.226688ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:54:08.618821Z","caller":"traceutil/trace.go:171","msg":"trace[346050043] transaction","detail":"{read_only:false; response_revision:5420; number_of_response:1; }","duration":"182.143162ms","start":"2026-09-08T15:54:08.436652Z","end":"2026-09-08T15:54:08.618795Z","steps":["trace[346050043] 'process raft request' (duration: 181.945588ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:55:51.434856Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3069} {"level":"info","ts":"2026-09-08T15:55:51.53293Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3069,"took":"96.797795ms","hash":2572395120,"current-db-size-bytes":58421248,"current-db-size":"58 MB","current-db-size-in-use-bytes":30932992,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-08T15:55:51.533005Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2572395120,"revision":3069,"compact-revision":-1} {"level":"info","ts":"2026-09-08T15:57:14.859994Z","caller":"traceutil/trace.go:171","msg":"trace[545367694] transaction","detail":"{read_only:false; response_revision:6985; number_of_response:1; }","duration":"127.419595ms","start":"2026-09-08T15:57:14.732548Z","end":"2026-09-08T15:57:14.859967Z","steps":["trace[545367694] 'process raft request' (duration: 127.249198ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:57:14.991555Z","caller":"traceutil/trace.go:171","msg":"trace[1045975154] linearizableReadLoop","detail":"{readStateIndex:7306; appliedIndex:7304; }","duration":"101.852253ms","start":"2026-09-08T15:57:14.889676Z","end":"2026-09-08T15:57:14.991528Z","steps":["trace[1045975154] 'read index received' (duration: 39.864635ms)","trace[1045975154] 'applied index is now lower than readState.Index' (duration: 61.986188ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:57:14.991822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.117524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-09-08T15:57:14.99188Z","caller":"traceutil/trace.go:171","msg":"trace[273172858] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:6986; }","duration":"102.225199ms","start":"2026-09-08T15:57:14.889635Z","end":"2026-09-08T15:57:14.991861Z","steps":["trace[273172858] 'agreement among raft nodes before linearized reading' (duration: 101.995659ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:57:14.991887Z","caller":"traceutil/trace.go:171","msg":"trace[239051861] transaction","detail":"{read_only:false; number_of_response:0; response_revision:6985; }","duration":"125.23689ms","start":"2026-09-08T15:57:14.866555Z","end":"2026-09-08T15:57:14.991792Z","steps":["trace[239051861] 'process raft request' (duration: 62.989901ms)","trace[239051861] 'compare' (duration: 61.80356ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:57:14.992247Z","caller":"traceutil/trace.go:171","msg":"trace[693578945] transaction","detail":"{read_only:false; response_revision:6986; number_of_response:1; }","duration":"115.309978ms","start":"2026-09-08T15:57:14.876918Z","end":"2026-09-08T15:57:14.992228Z","steps":["trace[693578945] 'process raft request' (duration: 114.505143ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:57:17.297737Z","caller":"traceutil/trace.go:171","msg":"trace[401798698] transaction","detail":"{read_only:false; response_revision:7003; number_of_response:1; }","duration":"128.675995ms","start":"2026-09-08T15:57:17.169031Z","end":"2026-09-08T15:57:17.297707Z","steps":["trace[401798698] 'process raft request' (duration: 128.400053ms)"],"step_count":1}