{"level":"warn","ts":"2026-07-24T04:39:41.360046Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-24T04:39:41.360208Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.242:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.242:2380","--initial-cluster=instance=https://199.19.213.242:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.242:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.242: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-07-24T04:39:41.360265Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-24T04:39:41.360278Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.242:2380"]} {"level":"info","ts":"2026-07-24T04:39:41.360295Z","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-07-24T04:39:41.361088Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.242:2379"]} {"level":"info","ts":"2026-07-24T04:39:41.361177Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.242:2380"],"listen-peer-urls":["https://199.19.213.242:2380"],"advertise-client-urls":["https://199.19.213.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.242: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-07-24T04:39:41.36531Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.960448ms"} {"level":"info","ts":"2026-07-24T04:39:41.378374Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ad427454a89beddc","cluster-id":"edc6037504b39883"} {"level":"info","ts":"2026-07-24T04:39:41.378452Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ad427454a89beddc switched to configuration voters=()"} {"level":"info","ts":"2026-07-24T04:39:41.378474Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ad427454a89beddc became follower at term 0"} {"level":"info","ts":"2026-07-24T04:39:41.378486Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ad427454a89beddc [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-24T04:39:41.378497Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ad427454a89beddc became follower at term 1"} {"level":"info","ts":"2026-07-24T04:39:41.378531Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ad427454a89beddc switched to configuration voters=(12484669023979302364)"} {"level":"warn","ts":"2026-07-24T04:39:41.388357Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-24T04:39:41.392472Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-24T04:39:41.394057Z","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-07-24T04:39:41.395825Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ad427454a89beddc","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-24T04:39:41.398154Z","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-07-24T04:39:41.400842Z","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-07-24T04:39:41.400869Z","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-07-24T04:39:41.400963Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ad427454a89beddc","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-24T04:39:41.402221Z","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-07-24T04:39:41.402413Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ad427454a89beddc","initial-advertise-peer-urls":["https://199.19.213.242:2380"],"listen-peer-urls":["https://199.19.213.242:2380"],"advertise-client-urls":["https://199.19.213.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-24T04:39:41.402454Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-24T04:39:41.402486Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.242:2380"} {"level":"info","ts":"2026-07-24T04:39:41.402509Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.242:2380"} {"level":"info","ts":"2026-07-24T04:39:41.40272Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ad427454a89beddc switched to configuration voters=(12484669023979302364)"} {"level":"info","ts":"2026-07-24T04:39:41.403588Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"edc6037504b39883","local-member-id":"ad427454a89beddc","added-peer-id":"ad427454a89beddc","added-peer-peer-urls":["https://199.19.213.242:2380"]} {"level":"info","ts":"2026-07-24T04:39:41.67987Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ad427454a89beddc is starting a new election at term 1"} {"level":"info","ts":"2026-07-24T04:39:41.679946Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ad427454a89beddc became pre-candidate at term 1"} {"level":"info","ts":"2026-07-24T04:39:41.679985Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ad427454a89beddc received MsgPreVoteResp from ad427454a89beddc at term 1"} {"level":"info","ts":"2026-07-24T04:39:41.680154Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ad427454a89beddc became candidate at term 2"} {"level":"info","ts":"2026-07-24T04:39:41.680179Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ad427454a89beddc received MsgVoteResp from ad427454a89beddc at term 2"} {"level":"info","ts":"2026-07-24T04:39:41.680196Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ad427454a89beddc became leader at term 2"} {"level":"info","ts":"2026-07-24T04:39:41.680215Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ad427454a89beddc elected leader ad427454a89beddc at term 2"} {"level":"info","ts":"2026-07-24T04:39:41.681298Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ad427454a89beddc","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.242:2379]}","request-path":"/0/members/ad427454a89beddc/attributes","cluster-id":"edc6037504b39883","publish-timeout":"7s"} {"level":"info","ts":"2026-07-24T04:39:41.681586Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-24T04:39:41.682168Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-24T04:39:41.682197Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-24T04:39:41.685419Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-24T04:39:41.685506Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-24T04:39:41.685736Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T04:39:41.687314Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.242:2379"} {"level":"info","ts":"2026-07-24T04:39:41.688573Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"edc6037504b39883","local-member-id":"ad427454a89beddc","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T04:39:41.688717Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T04:39:41.688805Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T04:39:43.142381Z","caller":"traceutil/trace.go:171","msg":"trace[1733547905] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"174.29221ms","start":"2026-07-24T04:39:42.968065Z","end":"2026-07-24T04:39:43.142357Z","steps":["trace[1733547905] 'process raft request' (duration: 174.075489ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:39:43.164696Z","caller":"traceutil/trace.go:171","msg":"trace[390420609] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"118.339888ms","start":"2026-07-24T04:39:43.046331Z","end":"2026-07-24T04:39:43.164671Z","steps":["trace[390420609] 'process raft request' (duration: 118.157317ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:39:43.164734Z","caller":"traceutil/trace.go:171","msg":"trace[1015318834] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"196.444954ms","start":"2026-07-24T04:39:42.968273Z","end":"2026-07-24T04:39:43.164718Z","steps":["trace[1015318834] 'process raft request' (duration: 196.121782ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:39:43.16589Z","caller":"traceutil/trace.go:171","msg":"trace[2078567478] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"117.28427ms","start":"2026-07-24T04:39:43.047506Z","end":"2026-07-24T04:39:43.164791Z","steps":["trace[2078567478] 'process raft request' (duration: 117.005118ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:39:43.167388Z","caller":"traceutil/trace.go:171","msg":"trace[2008003152] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"119.304823ms","start":"2026-07-24T04:39:43.047599Z","end":"2026-07-24T04:39:43.166904Z","steps":["trace[2008003152] 'process raft request' (duration: 116.962477ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:39:43.167423Z","caller":"traceutil/trace.go:171","msg":"trace[381624704] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"101.015126ms","start":"2026-07-24T04:39:43.066354Z","end":"2026-07-24T04:39:43.167369Z","steps":["trace[381624704] 'process raft request' (duration: 98.267777ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:39:43.167465Z","caller":"traceutil/trace.go:171","msg":"trace[146855839] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"198.300777ms","start":"2026-07-24T04:39:42.9691Z","end":"2026-07-24T04:39:43.167401Z","steps":["trace[146855839] 'read index received' (duration: 173.065011ms)","trace[146855839] 'applied index is now lower than readState.Index' (duration: 25.106415ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:39:43.16835Z","caller":"traceutil/trace.go:171","msg":"trace[1187049688] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"101.303618ms","start":"2026-07-24T04:39:43.066961Z","end":"2026-07-24T04:39:43.168265Z","steps":["trace[1187049688] 'process raft request' (duration: 100.263281ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:39:43.168899Z","caller":"traceutil/trace.go:171","msg":"trace[585062676] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"120.938095ms","start":"2026-07-24T04:39:43.047939Z","end":"2026-07-24T04:39:43.168878Z","steps":["trace[585062676] 'process raft request' (duration: 116.643926ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:39:43.170317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.71379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-24T04:39:43.170407Z","caller":"traceutil/trace.go:171","msg":"trace[613864171] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"148.83707ms","start":"2026-07-24T04:39:43.021545Z","end":"2026-07-24T04:39:43.170382Z","steps":["trace[613864171] 'agreement among raft nodes before linearized reading' (duration: 147.937653ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:39:43.172859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.664025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-24T04:39:43.172994Z","caller":"traceutil/trace.go:171","msg":"trace[123977460] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"203.805956ms","start":"2026-07-24T04:39:42.969173Z","end":"2026-07-24T04:39:43.172979Z","steps":["trace[123977460] 'agreement among raft nodes before linearized reading' (duration: 199.188984ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:39:43.173461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.075065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-24T04:39:43.173529Z","caller":"traceutil/trace.go:171","msg":"trace[557658220] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"128.209457ms","start":"2026-07-24T04:39:43.045304Z","end":"2026-07-24T04:39:43.173513Z","steps":["trace[557658220] 'agreement among raft nodes before linearized reading' (duration: 123.645665ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:39:43.173741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.743013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-24T04:39:43.173801Z","caller":"traceutil/trace.go:171","msg":"trace[93654422] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"127.869653ms","start":"2026-07-24T04:39:43.045918Z","end":"2026-07-24T04:39:43.173788Z","steps":["trace[93654422] 'agreement among raft nodes before linearized reading' (duration: 125.517767ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:39:43.172682Z","caller":"traceutil/trace.go:171","msg":"trace[375242530] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"119.231662ms","start":"2026-07-24T04:39:43.047591Z","end":"2026-07-24T04:39:43.166822Z","steps":["trace[375242530] 'process raft request' (duration: 116.945277ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:39:43.173379Z","caller":"traceutil/trace.go:171","msg":"trace[1626139687] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"123.439453ms","start":"2026-07-24T04:39:43.048106Z","end":"2026-07-24T04:39:43.171545Z","steps":["trace[1626139687] 'process raft request' (duration: 116.494365ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:39:43.174245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.032899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-24T04:39:43.174293Z","caller":"traceutil/trace.go:171","msg":"trace[82403980] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:11; }","duration":"203.08891ms","start":"2026-07-24T04:39:42.971192Z","end":"2026-07-24T04:39:43.174281Z","steps":["trace[82403980] 'agreement among raft nodes before linearized reading' (duration: 200.25193ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:39:43.174495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.954922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.242\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-24T04:39:43.17453Z","caller":"traceutil/trace.go:171","msg":"trace[1902994213] range","detail":"{range_begin:/registry/masterleases/199.19.213.242; range_end:; response_count:0; response_revision:11; }","duration":"130.415831ms","start":"2026-07-24T04:39:43.044105Z","end":"2026-07-24T04:39:43.174521Z","steps":["trace[1902994213] 'agreement among raft nodes before linearized reading' (duration: 126.906157ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:39:43.174583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.530134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-24T04:39:43.174668Z","caller":"traceutil/trace.go:171","msg":"trace[754540658] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:11; }","duration":"203.632774ms","start":"2026-07-24T04:39:42.971024Z","end":"2026-07-24T04:39:43.174656Z","steps":["trace[754540658] 'agreement among raft nodes before linearized reading' (duration: 200.425152ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:39:43.174724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.618428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3171"} {"level":"info","ts":"2026-07-24T04:39:43.174756Z","caller":"traceutil/trace.go:171","msg":"trace[144088301] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:11; }","duration":"205.658118ms","start":"2026-07-24T04:39:42.969091Z","end":"2026-07-24T04:39:43.174749Z","steps":["trace[144088301] 'agreement among raft nodes before linearized reading' (duration: 202.182114ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:40:19.617516Z","caller":"traceutil/trace.go:171","msg":"trace[59151611] linearizableReadLoop","detail":"{readStateIndex:548; appliedIndex:545; }","duration":"145.959719ms","start":"2026-07-24T04:40:19.471513Z","end":"2026-07-24T04:40:19.617472Z","steps":["trace[59151611] 'read index received' (duration: 139.441433ms)","trace[59151611] 'applied index is now lower than readState.Index' (duration: 6.517256ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:40:19.617542Z","caller":"traceutil/trace.go:171","msg":"trace[1657225451] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"220.990652ms","start":"2026-07-24T04:40:19.396515Z","end":"2026-07-24T04:40:19.617505Z","steps":["trace[1657225451] 'process raft request' (duration: 220.798634ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:40:19.617613Z","caller":"traceutil/trace.go:171","msg":"trace[845437705] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"173.086617ms","start":"2026-07-24T04:40:19.444514Z","end":"2026-07-24T04:40:19.617601Z","steps":["trace[845437705] 'process raft request' (duration: 172.89202ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:40:19.617516Z","caller":"traceutil/trace.go:171","msg":"trace[647520040] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"227.814171ms","start":"2026-07-24T04:40:19.389662Z","end":"2026-07-24T04:40:19.617476Z","steps":["trace[647520040] 'process raft request' (duration: 221.302214ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:40:19.61788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.591272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-24T04:40:19.618002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.425007ms","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-07-24T04:40:19.618011Z","caller":"traceutil/trace.go:171","msg":"trace[1256173791] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:527; }","duration":"142.730177ms","start":"2026-07-24T04:40:19.47521Z","end":"2026-07-24T04:40:19.61794Z","steps":["trace[1256173791] 'agreement among raft nodes before linearized reading' (duration: 142.581081ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:40:19.618058Z","caller":"traceutil/trace.go:171","msg":"trace[1935712370] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:527; }","duration":"146.602634ms","start":"2026-07-24T04:40:19.471438Z","end":"2026-07-24T04:40:19.618041Z","steps":["trace[1935712370] 'agreement among raft nodes before linearized reading' (duration: 146.23238ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:40:25.741048Z","caller":"traceutil/trace.go:171","msg":"trace[1185078604] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"180.462432ms","start":"2026-07-24T04:40:25.560521Z","end":"2026-07-24T04:40:25.740983Z","steps":["trace[1185078604] 'process raft request' (duration: 180.295537ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:40:25.770145Z","caller":"traceutil/trace.go:171","msg":"trace[346499169] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:622; }","duration":"115.863964ms","start":"2026-07-24T04:40:25.654242Z","end":"2026-07-24T04:40:25.770106Z","steps":["trace[346499169] 'read index received' (duration: 86.6814ms)","trace[346499169] 'applied index is now lower than readState.Index' (duration: 29.181104ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:40:25.770443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.174705ms","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-07-24T04:40:25.770504Z","caller":"traceutil/trace.go:171","msg":"trace[1038954614] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:601; }","duration":"116.270058ms","start":"2026-07-24T04:40:25.654215Z","end":"2026-07-24T04:40:25.770485Z","steps":["trace[1038954614] 'agreement among raft nodes before linearized reading' (duration: 116.073311ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:40:25.770586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.150014ms","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-07-24T04:40:25.77031Z","caller":"traceutil/trace.go:171","msg":"trace[2121353915] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"192.160176ms","start":"2026-07-24T04:40:25.578106Z","end":"2026-07-24T04:40:25.770266Z","steps":["trace[2121353915] 'process raft request' (duration: 191.292996ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:40:25.770744Z","caller":"traceutil/trace.go:171","msg":"trace[577597415] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:601; }","duration":"116.366361ms","start":"2026-07-24T04:40:25.654335Z","end":"2026-07-24T04:40:25.770701Z","steps":["trace[577597415] 'agreement among raft nodes before linearized reading' (duration: 116.03387ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:40:26.254635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.969984ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T04:40:26.25475Z","caller":"traceutil/trace.go:171","msg":"trace[1183629933] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"476.119976ms","start":"2026-07-24T04:40:25.778612Z","end":"2026-07-24T04:40:26.254732Z","steps":["trace[1183629933] 'process raft request' (duration: 216.471968ms)","trace[1183629933] 'compare' (duration: 258.85801ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:40:26.254817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:40:25.778591Z","time spent":"476.192689ms","remote":"127.0.0.1:40314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:40:26.291821Z","caller":"traceutil/trace.go:171","msg":"trace[428716696] linearizableReadLoop","detail":"{readStateIndex:627; appliedIndex:624; }","duration":"488.804051ms","start":"2026-07-24T04:40:25.802993Z","end":"2026-07-24T04:40:26.291797Z","steps":["trace[428716696] 'read index received' (duration: 192.168997ms)","trace[428716696] 'applied index is now lower than readState.Index' (duration: 296.633864ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:40:26.29186Z","caller":"traceutil/trace.go:171","msg":"trace[484513021] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"511.35192ms","start":"2026-07-24T04:40:25.780488Z","end":"2026-07-24T04:40:26.29184Z","steps":["trace[484513021] 'process raft request' (duration: 511.211064ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:40:26.291944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.930426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:40:26.292094Z","caller":"traceutil/trace.go:171","msg":"trace[1331955835] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"458.135835ms","start":"2026-07-24T04:40:25.83395Z","end":"2026-07-24T04:40:26.292085Z","steps":["trace[1331955835] 'process raft request' (duration: 457.795342ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:40:26.292146Z","caller":"traceutil/trace.go:171","msg":"trace[1057735244] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:605; }","duration":"489.171554ms","start":"2026-07-24T04:40:25.802963Z","end":"2026-07-24T04:40:26.292134Z","steps":["trace[1057735244] 'agreement among raft nodes before linearized reading' (duration: 488.933635ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:40:26.292213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:40:25.802948Z","time spent":"489.253697ms","remote":"127.0.0.1:40022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-24T04:40:26.292259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:40:25.833929Z","time spent":"458.256199ms","remote":"127.0.0.1:40572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-24T04:40:26.29196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:40:25.78048Z","time spent":"511.428493ms","remote":"127.0.0.1:40314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:40:26.292003Z","caller":"traceutil/trace.go:171","msg":"trace[799188434] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"512.912146ms","start":"2026-07-24T04:40:25.77908Z","end":"2026-07-24T04:40:26.291992Z","steps":["trace[799188434] 'process raft request' (duration: 512.49583ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:40:26.292519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:40:25.77906Z","time spent":"513.407933ms","remote":"127.0.0.1:40314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:40:27.816758Z","caller":"traceutil/trace.go:171","msg":"trace[1772051653] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"116.686574ms","start":"2026-07-24T04:40:27.700044Z","end":"2026-07-24T04:40:27.816731Z","steps":["trace[1772051653] 'process raft request' (duration: 116.471466ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:28.005471Z","caller":"traceutil/trace.go:171","msg":"trace[39044605] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"129.448661ms","start":"2026-07-24T04:41:27.875693Z","end":"2026-07-24T04:41:28.005142Z","steps":["trace[39044605] 'process raft request' (duration: 104.33134ms)","trace[39044605] 'compare' (duration: 24.966818ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:41:55.134451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.934396ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T04:41:55.134925Z","caller":"traceutil/trace.go:171","msg":"trace[1657802936] transaction","detail":"{read_only:false; response_revision:1812; number_of_response:1; }","duration":"460.052716ms","start":"2026-07-24T04:41:54.674835Z","end":"2026-07-24T04:41:55.134887Z","steps":["trace[1657802936] 'process raft request' (duration: 329.437838ms)","trace[1657802936] 'compare' (duration: 129.5747ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:41:55.135037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:54.67481Z","time spent":"460.170577ms","remote":"127.0.0.1:40046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:41:55.134632Z","caller":"traceutil/trace.go:171","msg":"trace[686310996] linearizableReadLoop","detail":"{readStateIndex:1865; appliedIndex:1864; }","duration":"439.671674ms","start":"2026-07-24T04:41:54.694934Z","end":"2026-07-24T04:41:55.134606Z","steps":["trace[686310996] 'read index received' (duration: 309.2312ms)","trace[686310996] 'applied index is now lower than readState.Index' (duration: 130.437694ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:41:55.135313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.353266ms","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-07-24T04:41:55.135502Z","caller":"traceutil/trace.go:171","msg":"trace[1660639093] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1812; }","duration":"440.57085ms","start":"2026-07-24T04:41:54.69488Z","end":"2026-07-24T04:41:55.135451Z","steps":["trace[1660639093] 'agreement among raft nodes before linearized reading' (duration: 440.193044ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:55.135567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:54.694854Z","time spent":"440.701392ms","remote":"127.0.0.1:40314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-24T04:41:55.13568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.305145ms","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-07-24T04:41:55.135722Z","caller":"traceutil/trace.go:171","msg":"trace[155743605] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1812; }","duration":"431.378886ms","start":"2026-07-24T04:41:54.70433Z","end":"2026-07-24T04:41:55.135709Z","steps":["trace[155743605] 'agreement among raft nodes before linearized reading' (duration: 431.172213ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:55.135783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:54.704311Z","time spent":"431.446268ms","remote":"127.0.0.1:40314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-24T04:41:55.135964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.579846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"warn","ts":"2026-07-24T04:41:55.135965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.69483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-24T04:41:55.136015Z","caller":"traceutil/trace.go:171","msg":"trace[235108329] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1812; }","duration":"257.681148ms","start":"2026-07-24T04:41:54.87831Z","end":"2026-07-24T04:41:55.135991Z","steps":["trace[235108329] 'agreement among raft nodes before linearized reading' (duration: 257.572796ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:55.136047Z","caller":"traceutil/trace.go:171","msg":"trace[1943302943] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1812; }","duration":"202.822802ms","start":"2026-07-24T04:41:54.9332Z","end":"2026-07-24T04:41:55.136023Z","steps":["trace[1943302943] 'agreement among raft nodes before linearized reading' (duration: 202.545778ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:55.136287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.75569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1429af48-86b5-4b6e-9c7c-b948930db05c\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-07-24T04:41:55.136326Z","caller":"traceutil/trace.go:171","msg":"trace[1217381634] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1429af48-86b5-4b6e-9c7c-b948930db05c; range_end:; response_count:1; response_revision:1812; }","duration":"118.797171ms","start":"2026-07-24T04:41:55.017518Z","end":"2026-07-24T04:41:55.136316Z","steps":["trace[1217381634] 'agreement among raft nodes before linearized reading' (duration: 118.680519ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:55.136408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.013688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:41:55.136516Z","caller":"traceutil/trace.go:171","msg":"trace[339567789] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1812; }","duration":"338.145471ms","start":"2026-07-24T04:41:54.798348Z","end":"2026-07-24T04:41:55.136494Z","steps":["trace[339567789] 'agreement among raft nodes before linearized reading' (duration: 337.659993ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:55.136533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.357582ms","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-07-24T04:41:55.136568Z","caller":"traceutil/trace.go:171","msg":"trace[1329459724] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1812; }","duration":"420.432914ms","start":"2026-07-24T04:41:54.716126Z","end":"2026-07-24T04:41:55.136559Z","steps":["trace[1329459724] 'agreement among raft nodes before linearized reading' (duration: 420.065168ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:55.136604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:54.716093Z","time spent":"420.502454ms","remote":"127.0.0.1:40314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-24T04:41:55.138369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:54.798329Z","time spent":"340.020682ms","remote":"127.0.0.1:40022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-24T04:41:55.424769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.17295ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T04:41:55.42503Z","caller":"traceutil/trace.go:171","msg":"trace[1759977971] transaction","detail":"{read_only:false; response_revision:1814; number_of_response:1; }","duration":"283.813699ms","start":"2026-07-24T04:41:55.141179Z","end":"2026-07-24T04:41:55.424992Z","steps":["trace[1759977971] 'process raft request' (duration: 156.327764ms)","trace[1759977971] 'compare' (duration: 127.065378ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:41:55.425109Z","caller":"traceutil/trace.go:171","msg":"trace[213739936] linearizableReadLoop","detail":"{readStateIndex:1871; appliedIndex:1866; }","duration":"265.16317ms","start":"2026-07-24T04:41:55.159928Z","end":"2026-07-24T04:41:55.425091Z","steps":["trace[213739936] 'read index received' (duration: 137.605274ms)","trace[213739936] 'applied index is now lower than readState.Index' (duration: 127.556786ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:41:55.425206Z","caller":"traceutil/trace.go:171","msg":"trace[818593053] transaction","detail":"{read_only:false; response_revision:1818; number_of_response:1; }","duration":"280.472773ms","start":"2026-07-24T04:41:55.144723Z","end":"2026-07-24T04:41:55.425196Z","steps":["trace[818593053] 'process raft request' (duration: 280.319571ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:55.42533Z","caller":"traceutil/trace.go:171","msg":"trace[1948379385] transaction","detail":"{read_only:false; response_revision:1815; number_of_response:1; }","duration":"284.014182ms","start":"2026-07-24T04:41:55.141294Z","end":"2026-07-24T04:41:55.425308Z","steps":["trace[1948379385] 'process raft request' (duration: 283.609666ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:55.425372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.445804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-24T04:41:55.425406Z","caller":"traceutil/trace.go:171","msg":"trace[1214913191] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1818; }","duration":"265.506655ms","start":"2026-07-24T04:41:55.15989Z","end":"2026-07-24T04:41:55.425396Z","steps":["trace[1214913191] 'agreement among raft nodes before linearized reading' (duration: 265.442074ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:55.425464Z","caller":"traceutil/trace.go:171","msg":"trace[282801689] transaction","detail":"{read_only:false; response_revision:1816; number_of_response:1; }","duration":"282.294233ms","start":"2026-07-24T04:41:55.143112Z","end":"2026-07-24T04:41:55.425406Z","steps":["trace[282801689] 'process raft request' (duration: 281.844166ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:55.42555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.183274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1429af48-86b5-4b6e-9c7c-b948930db05c\" ","response":"range_response_count:1 size:3744"} {"level":"info","ts":"2026-07-24T04:41:55.425618Z","caller":"traceutil/trace.go:171","msg":"trace[1788083784] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1429af48-86b5-4b6e-9c7c-b948930db05c; range_end:; response_count:1; response_revision:1818; }","duration":"208.277316ms","start":"2026-07-24T04:41:55.21732Z","end":"2026-07-24T04:41:55.425597Z","steps":["trace[1788083784] 'agreement among raft nodes before linearized reading' (duration: 208.175814ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:55.425741Z","caller":"traceutil/trace.go:171","msg":"trace[417248406] transaction","detail":"{read_only:false; response_revision:1817; number_of_response:1; }","duration":"281.48016ms","start":"2026-07-24T04:41:55.144184Z","end":"2026-07-24T04:41:55.425664Z","steps":["trace[417248406] 'process raft request' (duration: 280.806279ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:55.425685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.802008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"info","ts":"2026-07-24T04:41:55.425867Z","caller":"traceutil/trace.go:171","msg":"trace[1133796724] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1818; }","duration":"258.072782ms","start":"2026-07-24T04:41:55.167769Z","end":"2026-07-24T04:41:55.425842Z","steps":["trace[1133796724] 'agreement among raft nodes before linearized reading' (duration: 257.714286ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:42:32.525109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.544392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-07-24T04:42:32.525147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.904166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-24T04:42:32.525209Z","caller":"traceutil/trace.go:171","msg":"trace[1878089704] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:2116; }","duration":"517.713654ms","start":"2026-07-24T04:42:32.007474Z","end":"2026-07-24T04:42:32.525187Z","steps":["trace[1878089704] 'count revisions from in-memory index tree' (duration: 517.413489ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:42:32.525262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:42:32.007446Z","time spent":"517.803635ms","remote":"127.0.0.1:40576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":256,"response size":32,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-07-24T04:42:32.525262Z","caller":"traceutil/trace.go:171","msg":"trace[1525195056] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:2116; }","duration":"439.101389ms","start":"2026-07-24T04:42:32.086132Z","end":"2026-07-24T04:42:32.525233Z","steps":["trace[1525195056] 'count revisions from in-memory index tree' (duration: 438.821565ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:42:32.52532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:42:32.086101Z","time spent":"439.20738ms","remote":"127.0.0.1:40412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":31,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-24T04:42:32.52688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.96049ms","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-07-24T04:42:32.527059Z","caller":"traceutil/trace.go:171","msg":"trace[13147504] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2116; }","duration":"356.223524ms","start":"2026-07-24T04:42:32.170797Z","end":"2026-07-24T04:42:32.52702Z","steps":["trace[13147504] 'range keys from in-memory index tree' (duration: 355.574075ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:42:32.527139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:42:32.170759Z","time spent":"356.363876ms","remote":"127.0.0.1:40314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-24T04:42:32.836542Z","caller":"traceutil/trace.go:171","msg":"trace[163083999] transaction","detail":"{read_only:false; response_revision:2117; number_of_response:1; }","duration":"301.828776ms","start":"2026-07-24T04:42:32.534687Z","end":"2026-07-24T04:42:32.836516Z","steps":["trace[163083999] 'process raft request' (duration: 301.646013ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:42:32.836712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:42:32.534664Z","time spent":"301.955778ms","remote":"127.0.0.1:40314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:42:32.852725Z","caller":"traceutil/trace.go:171","msg":"trace[1968021564] transaction","detail":"{read_only:false; response_revision:2118; number_of_response:1; }","duration":"195.721408ms","start":"2026-07-24T04:42:32.656967Z","end":"2026-07-24T04:42:32.852689Z","steps":["trace[1968021564] 'process raft request' (duration: 195.485954ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:42:32.852852Z","caller":"traceutil/trace.go:171","msg":"trace[1019666286] linearizableReadLoop","detail":"{readStateIndex:2185; appliedIndex:2183; }","duration":"163.5845ms","start":"2026-07-24T04:42:32.689028Z","end":"2026-07-24T04:42:32.852613Z","steps":["trace[1019666286] 'read index received' (duration: 147.215302ms)","trace[1019666286] 'applied index is now lower than readState.Index' (duration: 16.367458ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:42:32.853429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.417551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-07-24T04:42:32.853483Z","caller":"traceutil/trace.go:171","msg":"trace[688786644] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2118; }","duration":"103.563093ms","start":"2026-07-24T04:42:32.749904Z","end":"2026-07-24T04:42:32.853467Z","steps":["trace[688786644] 'agreement among raft nodes before linearized reading' (duration: 103.3669ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:42:32.854381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.133671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"info","ts":"2026-07-24T04:42:32.854562Z","caller":"traceutil/trace.go:171","msg":"trace[414342797] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2118; }","duration":"165.560647ms","start":"2026-07-24T04:42:32.688941Z","end":"2026-07-24T04:42:32.854502Z","steps":["trace[414342797] 'agreement among raft nodes before linearized reading' (duration: 163.851204ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:42:33.131317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.085317ms","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-07-24T04:42:33.131683Z","caller":"traceutil/trace.go:171","msg":"trace[1139152068] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2120; }","duration":"171.510743ms","start":"2026-07-24T04:42:32.960106Z","end":"2026-07-24T04:42:33.131616Z","steps":["trace[1139152068] 'range keys from in-memory index tree' (duration: 170.744542ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:14.714759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.837659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.242\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-24T04:43:14.714843Z","caller":"traceutil/trace.go:171","msg":"trace[1133939002] range","detail":"{range_begin:/registry/masterleases/199.19.213.242; range_end:; response_count:1; response_revision:2455; }","duration":"112.96875ms","start":"2026-07-24T04:43:14.601857Z","end":"2026-07-24T04:43:14.714826Z","steps":["trace[1133939002] 'range keys from in-memory index tree' (duration: 112.672507ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:14.714966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.238888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-c47d949f5-wk94k\" ","response":"range_response_count:1 size:7813"} {"level":"info","ts":"2026-07-24T04:43:14.715046Z","caller":"traceutil/trace.go:171","msg":"trace[1371584640] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-c47d949f5-wk94k; range_end:; response_count:1; response_revision:2455; }","duration":"267.343509ms","start":"2026-07-24T04:43:14.447683Z","end":"2026-07-24T04:43:14.715026Z","steps":["trace[1371584640] 'range keys from in-memory index tree' (duration: 267.040286ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:43:24.260473Z","caller":"traceutil/trace.go:171","msg":"trace[643092853] transaction","detail":"{read_only:false; response_revision:2571; number_of_response:1; }","duration":"356.776674ms","start":"2026-07-24T04:43:23.903662Z","end":"2026-07-24T04:43:24.260438Z","steps":["trace[643092853] 'process raft request' (duration: 356.561552ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:24.260698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:43:23.903631Z","time spent":"356.951046ms","remote":"127.0.0.1:40314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:43:24.399256Z","caller":"traceutil/trace.go:171","msg":"trace[1135948740] transaction","detail":"{read_only:false; response_revision:2572; number_of_response:1; }","duration":"493.262038ms","start":"2026-07-24T04:43:23.905967Z","end":"2026-07-24T04:43:24.399229Z","steps":["trace[1135948740] 'process raft request' (duration: 493.055496ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:43:24.399331Z","caller":"traceutil/trace.go:171","msg":"trace[89542889] transaction","detail":"{read_only:false; response_revision:2573; number_of_response:1; }","duration":"492.602249ms","start":"2026-07-24T04:43:23.90669Z","end":"2026-07-24T04:43:24.399292Z","steps":["trace[89542889] 'process raft request' (duration: 492.464288ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:24.399409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:43:23.905955Z","time spent":"493.377ms","remote":"127.0.0.1:40314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:43:24.399547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:43:23.906683Z","time spent":"492.736901ms","remote":"127.0.0.1:40314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:43:50.812029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.575713ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T04:43:50.812204Z","caller":"traceutil/trace.go:171","msg":"trace[2083666045] linearizableReadLoop","detail":"{readStateIndex:2856; appliedIndex:2855; }","duration":"271.991963ms","start":"2026-07-24T04:43:50.540185Z","end":"2026-07-24T04:43:50.812177Z","steps":["trace[2083666045] 'read index received' (duration: 48.962495ms)","trace[2083666045] 'applied index is now lower than readState.Index' (duration: 223.027638ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:43:50.812325Z","caller":"traceutil/trace.go:171","msg":"trace[128762527] transaction","detail":"{read_only:false; response_revision:2755; number_of_response:1; }","duration":"274.599823ms","start":"2026-07-24T04:43:50.537712Z","end":"2026-07-24T04:43:50.812312Z","steps":["trace[128762527] 'process raft request' (duration: 51.545135ms)","trace[128762527] 'compare' (duration: 222.374101ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:43:50.812914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.69939ms","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-07-24T04:43:50.813056Z","caller":"traceutil/trace.go:171","msg":"trace[189986861] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2755; }","duration":"272.887023ms","start":"2026-07-24T04:43:50.540137Z","end":"2026-07-24T04:43:50.813024Z","steps":["trace[189986861] 'agreement among raft nodes before linearized reading' (duration: 272.522469ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:43:51.095127Z","caller":"traceutil/trace.go:171","msg":"trace[654117546] transaction","detail":"{read_only:false; response_revision:2758; number_of_response:1; }","duration":"128.14513ms","start":"2026-07-24T04:43:50.966954Z","end":"2026-07-24T04:43:51.095099Z","steps":["trace[654117546] 'process raft request' (duration: 128.003718ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:51.397814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.894064ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T04:43:51.398042Z","caller":"traceutil/trace.go:171","msg":"trace[379943522] transaction","detail":"{read_only:false; response_revision:2759; number_of_response:1; }","duration":"362.29118ms","start":"2026-07-24T04:43:51.035727Z","end":"2026-07-24T04:43:51.398018Z","steps":["trace[379943522] 'process raft request' (duration: 196.085852ms)","trace[379943522] 'compare' (duration: 165.741973ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:43:51.398126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:43:51.035711Z","time spent":"362.379242ms","remote":"127.0.0.1:40222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9324,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:43:51.399458Z","caller":"traceutil/trace.go:171","msg":"trace[442551807] transaction","detail":"{read_only:false; response_revision:2760; number_of_response:1; }","duration":"178.297368ms","start":"2026-07-24T04:43:51.221128Z","end":"2026-07-24T04:43:51.399426Z","steps":["trace[442551807] 'process raft request' (duration: 176.82151ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:43:51.64625Z","caller":"traceutil/trace.go:171","msg":"trace[1406923645] transaction","detail":"{read_only:false; response_revision:2762; number_of_response:1; }","duration":"209.119664ms","start":"2026-07-24T04:43:51.437106Z","end":"2026-07-24T04:43:51.646226Z","steps":["trace[1406923645] 'process raft request' (duration: 200.644696ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:52.139273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.116122ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T04:43:52.139412Z","caller":"traceutil/trace.go:171","msg":"trace[2102687574] linearizableReadLoop","detail":"{readStateIndex:2864; appliedIndex:2862; }","duration":"650.235882ms","start":"2026-07-24T04:43:51.489152Z","end":"2026-07-24T04:43:52.139388Z","steps":["trace[2102687574] 'read index received' (duration: 148.610415ms)","trace[2102687574] 'applied index is now lower than readState.Index' (duration: 501.623417ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:43:52.139645Z","caller":"traceutil/trace.go:171","msg":"trace[901889812] transaction","detail":"{read_only:false; response_revision:2763; number_of_response:1; }","duration":"688.871047ms","start":"2026-07-24T04:43:51.450722Z","end":"2026-07-24T04:43:52.139593Z","steps":["trace[901889812] 'process raft request' (duration: 316.29574ms)","trace[901889812] 'compare' (duration: 371.788838ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:43:52.139851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.691557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:43:52.139897Z","caller":"traceutil/trace.go:171","msg":"trace[1284429307] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:2763; }","duration":"650.775687ms","start":"2026-07-24T04:43:51.489108Z","end":"2026-07-24T04:43:52.139884Z","steps":["trace[1284429307] 'agreement among raft nodes before linearized reading' (duration: 650.680386ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:52.139883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:43:51.450663Z","time spent":"689.09435ms","remote":"127.0.0.1:40314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:43:52.13994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:43:51.489091Z","time spent":"650.837438ms","remote":"127.0.0.1:44070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-07-24T04:43:52.140495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.756011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1296"} {"level":"warn","ts":"2026-07-24T04:43:52.140565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.05638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-24T04:43:52.140567Z","caller":"traceutil/trace.go:171","msg":"trace[1151539924] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2763; }","duration":"354.865963ms","start":"2026-07-24T04:43:51.785684Z","end":"2026-07-24T04:43:52.14055Z","steps":["trace[1151539924] 'agreement among raft nodes before linearized reading' (duration: 354.508669ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:43:52.140604Z","caller":"traceutil/trace.go:171","msg":"trace[1233760907] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:2763; }","duration":"183.123981ms","start":"2026-07-24T04:43:51.957466Z","end":"2026-07-24T04:43:52.14059Z","steps":["trace[1233760907] 'agreement among raft nodes before linearized reading' (duration: 183.064741ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:52.140618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:43:51.785661Z","time spent":"354.945503ms","remote":"127.0.0.1:40240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1320,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-07-24T04:43:52.140759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.990405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:43:52.140878Z","caller":"traceutil/trace.go:171","msg":"trace[83603611] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2763; }","duration":"342.171986ms","start":"2026-07-24T04:43:51.798684Z","end":"2026-07-24T04:43:52.140856Z","steps":["trace[83603611] 'agreement among raft nodes before linearized reading' (duration: 341.998734ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:52.140501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.937133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-24T04:43:52.140924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:43:51.798654Z","time spent":"342.261178ms","remote":"127.0.0.1:40022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-24T04:43:52.140925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.755266ms","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-07-24T04:43:52.140964Z","caller":"traceutil/trace.go:171","msg":"trace[780844528] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2763; }","duration":"322.421509ms","start":"2026-07-24T04:43:51.818526Z","end":"2026-07-24T04:43:52.140948Z","steps":["trace[780844528] 'agreement among raft nodes before linearized reading' (duration: 321.846863ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:52.1405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.591579ms","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":"warn","ts":"2026-07-24T04:43:52.141017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:43:51.818513Z","time spent":"322.48932ms","remote":"127.0.0.1:40314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-24T04:43:52.141041Z","caller":"traceutil/trace.go:171","msg":"trace[592234615] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2763; }","duration":"153.170445ms","start":"2026-07-24T04:43:51.987855Z","end":"2026-07-24T04:43:52.141025Z","steps":["trace[592234615] 'agreement among raft nodes before linearized reading' (duration: 152.484907ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:43:52.141075Z","caller":"traceutil/trace.go:171","msg":"trace[776289559] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:2763; }","duration":"331.859068ms","start":"2026-07-24T04:43:51.809119Z","end":"2026-07-24T04:43:52.140978Z","steps":["trace[776289559] 'agreement among raft nodes before linearized reading' (duration: 331.750696ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:52.141118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:43:51.809106Z","time spent":"331.999129ms","remote":"127.0.0.1:56304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-07-24T04:43:52.645471Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17139749733091793537,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T04:43:52.67012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:43:52.1436Z","time spent":"526.514908ms","remote":"127.0.0.1:40102","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-24T04:43:52.670849Z","caller":"traceutil/trace.go:171","msg":"trace[1666743099] linearizableReadLoop","detail":"{readStateIndex:2866; appliedIndex:2864; }","duration":"525.578938ms","start":"2026-07-24T04:43:52.145253Z","end":"2026-07-24T04:43:52.670832Z","steps":["trace[1666743099] 'read index received' (duration: 515.40935ms)","trace[1666743099] 'applied index is now lower than readState.Index' (duration: 10.168358ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:43:52.671182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.912342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" ","response":"range_response_count:1 size:1375"} {"level":"info","ts":"2026-07-24T04:43:52.671216Z","caller":"traceutil/trace.go:171","msg":"trace[741080535] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-metrics; range_end:; response_count:1; response_revision:2764; }","duration":"525.969822ms","start":"2026-07-24T04:43:52.145234Z","end":"2026-07-24T04:43:52.671204Z","steps":["trace[741080535] 'agreement among raft nodes before linearized reading' (duration: 525.847351ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:52.671243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:43:52.145227Z","time spent":"526.010782ms","remote":"127.0.0.1:40240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":1399,"request content":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" "} {"level":"info","ts":"2026-07-24T04:43:52.671566Z","caller":"traceutil/trace.go:171","msg":"trace[545348289] transaction","detail":"{read_only:false; response_revision:2764; number_of_response:1; }","duration":"524.769789ms","start":"2026-07-24T04:43:52.146216Z","end":"2026-07-24T04:43:52.670986Z","steps":["trace[545348289] 'process raft request' (duration: 523.718156ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:52.671645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.837952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-24T04:43:52.671661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.42686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-24T04:43:52.67168Z","caller":"traceutil/trace.go:171","msg":"trace[1257183932] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2764; }","duration":"257.911232ms","start":"2026-07-24T04:43:52.413757Z","end":"2026-07-24T04:43:52.671668Z","steps":["trace[1257183932] 'agreement among raft nodes before linearized reading' (duration: 257.774371ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:43:52.671694Z","caller":"traceutil/trace.go:171","msg":"trace[1632035395] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:2764; }","duration":"344.46586ms","start":"2026-07-24T04:43:52.327221Z","end":"2026-07-24T04:43:52.671687Z","steps":["trace[1632035395] 'agreement among raft nodes before linearized reading' (duration: 344.39398ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:52.67172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:43:52.327149Z","time spent":"344.564901ms","remote":"127.0.0.1:44016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":15,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-07-24T04:43:52.671775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.057735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-24T04:43:52.671758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:43:52.146195Z","time spent":"525.457996ms","remote":"127.0.0.1:40314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:43:52.671813Z","caller":"traceutil/trace.go:171","msg":"trace[1438531035] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:2764; }","duration":"121.141216ms","start":"2026-07-24T04:43:52.550659Z","end":"2026-07-24T04:43:52.671801Z","steps":["trace[1438531035] 'agreement among raft nodes before linearized reading' (duration: 121.083135ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:52.671947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.156405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-24T04:43:52.671979Z","caller":"traceutil/trace.go:171","msg":"trace[191694194] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:2764; }","duration":"468.235277ms","start":"2026-07-24T04:43:52.203737Z","end":"2026-07-24T04:43:52.671972Z","steps":["trace[191694194] 'agreement among raft nodes before linearized reading' (duration: 468.170776ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:52.672005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:43:52.203715Z","time spent":"468.283617ms","remote":"127.0.0.1:40126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":24,"response size":31,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-07-24T04:43:52.672502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.873292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:552"} {"level":"info","ts":"2026-07-24T04:43:52.672562Z","caller":"traceutil/trace.go:171","msg":"trace[1288742026] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2764; }","duration":"526.946893ms","start":"2026-07-24T04:43:52.145606Z","end":"2026-07-24T04:43:52.672553Z","steps":["trace[1288742026] 'agreement among raft nodes before linearized reading' (duration: 525.909532ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:52.672581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:43:52.145601Z","time spent":"526.975583ms","remote":"127.0.0.1:40314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":576,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-07-24T04:46:19.653444Z","caller":"traceutil/trace.go:171","msg":"trace[1908093359] linearizableReadLoop","detail":"{readStateIndex:4561; appliedIndex:4560; }","duration":"113.092766ms","start":"2026-07-24T04:46:19.54033Z","end":"2026-07-24T04:46:19.653423Z","steps":["trace[1908093359] 'read index received' (duration: 113.002744ms)","trace[1908093359] 'applied index is now lower than readState.Index' (duration: 88.892µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:46:19.653486Z","caller":"traceutil/trace.go:171","msg":"trace[618256975] transaction","detail":"{read_only:false; response_revision:4377; number_of_response:1; }","duration":"128.530219ms","start":"2026-07-24T04:46:19.524934Z","end":"2026-07-24T04:46:19.653464Z","steps":["trace[618256975] 'process raft request' (duration: 128.347055ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:46:19.653698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.332853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-24T04:46:19.653723Z","caller":"traceutil/trace.go:171","msg":"trace[1551081638] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4377; }","duration":"113.432685ms","start":"2026-07-24T04:46:19.540283Z","end":"2026-07-24T04:46:19.653716Z","steps":["trace[1551081638] 'agreement among raft nodes before linearized reading' (duration: 113.270181ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:46:21.584469Z","caller":"traceutil/trace.go:171","msg":"trace[1804913878] linearizableReadLoop","detail":"{readStateIndex:4625; appliedIndex:4624; }","duration":"146.925561ms","start":"2026-07-24T04:46:21.437507Z","end":"2026-07-24T04:46:21.584433Z","steps":["trace[1804913878] 'read index received' (duration: 146.692114ms)","trace[1804913878] 'applied index is now lower than readState.Index' (duration: 232.347µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:46:21.58461Z","caller":"traceutil/trace.go:171","msg":"trace[198814065] transaction","detail":"{read_only:false; response_revision:4436; number_of_response:1; }","duration":"171.75508ms","start":"2026-07-24T04:46:21.412796Z","end":"2026-07-24T04:46:21.584551Z","steps":["trace[198814065] 'process raft request' (duration: 171.426841ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:46:21.584812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.255748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-bch97\" ","response":"range_response_count:1 size:9757"} {"level":"info","ts":"2026-07-24T04:46:21.584876Z","caller":"traceutil/trace.go:171","msg":"trace[438098828] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-bch97; range_end:; response_count:1; response_revision:4436; }","duration":"147.409422ms","start":"2026-07-24T04:46:21.437447Z","end":"2026-07-24T04:46:21.584856Z","steps":["trace[438098828] 'agreement among raft nodes before linearized reading' (duration: 147.130125ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:46:21.75457Z","caller":"traceutil/trace.go:171","msg":"trace[159233405] transaction","detail":"{read_only:false; response_revision:4437; number_of_response:1; }","duration":"315.316236ms","start":"2026-07-24T04:46:21.439216Z","end":"2026-07-24T04:46:21.754533Z","steps":["trace[159233405] 'process raft request' (duration: 311.8099ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:46:21.754795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:46:21.439201Z","time spent":"315.48933ms","remote":"127.0.0.1:40102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":947,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T04:46:21.759636Z","caller":"traceutil/trace.go:171","msg":"trace[1281422656] linearizableReadLoop","detail":"{readStateIndex:4628; appliedIndex:4625; }","duration":"101.247558ms","start":"2026-07-24T04:46:21.658361Z","end":"2026-07-24T04:46:21.759609Z","steps":["trace[1281422656] 'read index received' (duration: 92.640096ms)","trace[1281422656] 'applied index is now lower than readState.Index' (duration: 8.606391ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:46:21.759726Z","caller":"traceutil/trace.go:171","msg":"trace[1393665984] transaction","detail":"{read_only:false; response_revision:4439; number_of_response:1; }","duration":"159.756215ms","start":"2026-07-24T04:46:21.599947Z","end":"2026-07-24T04:46:21.759703Z","steps":["trace[1393665984] 'process raft request' (duration: 159.609371ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:46:21.759745Z","caller":"traceutil/trace.go:171","msg":"trace[1477000235] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4438; }","duration":"172.281572ms","start":"2026-07-24T04:46:21.5874Z","end":"2026-07-24T04:46:21.759682Z","steps":["trace[1477000235] 'process raft request' (duration: 172.014926ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:46:21.759882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.500483ms","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-07-24T04:46:21.759917Z","caller":"traceutil/trace.go:171","msg":"trace[1663452367] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4439; }","duration":"101.593866ms","start":"2026-07-24T04:46:21.658314Z","end":"2026-07-24T04:46:21.759908Z","steps":["trace[1663452367] 'agreement among raft nodes before linearized reading' (duration: 101.416541ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:46:27.14411Z","caller":"traceutil/trace.go:171","msg":"trace[1329084856] linearizableReadLoop","detail":"{readStateIndex:4758; appliedIndex:4757; }","duration":"118.995635ms","start":"2026-07-24T04:46:27.025088Z","end":"2026-07-24T04:46:27.144083Z","steps":["trace[1329084856] 'read index received' (duration: 117.741676ms)","trace[1329084856] 'applied index is now lower than readState.Index' (duration: 1.252859ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:46:27.14446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.270013ms","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:7"} {"level":"info","ts":"2026-07-24T04:46:27.144529Z","caller":"traceutil/trace.go:171","msg":"trace[837822431] transaction","detail":"{read_only:false; response_revision:4566; number_of_response:1; }","duration":"171.80286ms","start":"2026-07-24T04:46:26.972701Z","end":"2026-07-24T04:46:27.144504Z","steps":["trace[837822431] 'process raft request' (duration: 170.08603ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:46:27.144562Z","caller":"traceutil/trace.go:171","msg":"trace[580662093] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:4566; }","duration":"119.486447ms","start":"2026-07-24T04:46:27.025051Z","end":"2026-07-24T04:46:27.144538Z","steps":["trace[580662093] 'agreement among raft nodes before linearized reading' (duration: 119.240191ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:46:27.882064Z","caller":"traceutil/trace.go:171","msg":"trace[1149587215] transaction","detail":"{read_only:false; response_revision:4571; number_of_response:1; }","duration":"164.797889ms","start":"2026-07-24T04:46:27.717242Z","end":"2026-07-24T04:46:27.882039Z","steps":["trace[1149587215] 'process raft request' (duration: 164.540763ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:46:30.61372Z","caller":"traceutil/trace.go:171","msg":"trace[1911170324] linearizableReadLoop","detail":"{readStateIndex:4812; appliedIndex:4811; }","duration":"127.82536ms","start":"2026-07-24T04:46:30.485867Z","end":"2026-07-24T04:46:30.613692Z","steps":["trace[1911170324] 'read index received' (duration: 127.616645ms)","trace[1911170324] 'applied index is now lower than readState.Index' (duration: 207.065µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:46:30.613836Z","caller":"traceutil/trace.go:171","msg":"trace[2088562049] transaction","detail":"{read_only:false; response_revision:4618; number_of_response:1; }","duration":"132.248325ms","start":"2026-07-24T04:46:30.481544Z","end":"2026-07-24T04:46:30.613793Z","steps":["trace[2088562049] 'process raft request' (duration: 131.960928ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:46:30.61409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.165258ms","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-07-24T04:46:30.614175Z","caller":"traceutil/trace.go:171","msg":"trace[399289670] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4618; }","duration":"128.334682ms","start":"2026-07-24T04:46:30.485821Z","end":"2026-07-24T04:46:30.614155Z","steps":["trace[399289670] 'agreement among raft nodes before linearized reading' (duration: 128.027755ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:46:38.66358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.190851ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T04:46:38.663684Z","caller":"traceutil/trace.go:171","msg":"trace[1326636869] linearizableReadLoop","detail":"{readStateIndex:4948; appliedIndex:4947; }","duration":"455.059036ms","start":"2026-07-24T04:46:38.208609Z","end":"2026-07-24T04:46:38.663668Z","steps":["trace[1326636869] 'read index received' (duration: 182.713921ms)","trace[1326636869] 'applied index is now lower than readState.Index' (duration: 272.344115ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:46:38.663857Z","caller":"traceutil/trace.go:171","msg":"trace[2089765858] transaction","detail":"{read_only:false; response_revision:4748; number_of_response:1; }","duration":"460.288027ms","start":"2026-07-24T04:46:38.203558Z","end":"2026-07-24T04:46:38.663846Z","steps":["trace[2089765858] 'process raft request' (duration: 187.803548ms)","trace[2089765858] 'compare' (duration: 271.599538ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:46:38.663908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:46:38.203531Z","time spent":"460.354399ms","remote":"127.0.0.1:40314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:46:38.664147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.542996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11500"} {"level":"info","ts":"2026-07-24T04:46:38.664172Z","caller":"traceutil/trace.go:171","msg":"trace[518132927] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4748; }","duration":"455.581867ms","start":"2026-07-24T04:46:38.208583Z","end":"2026-07-24T04:46:38.664165Z","steps":["trace[518132927] 'agreement among raft nodes before linearized reading' (duration: 455.476845ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:46:38.664193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:46:38.208571Z","time spent":"455.617659ms","remote":"127.0.0.1:40228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":11524,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-07-24T04:46:38.665414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.754258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-24T04:46:38.665449Z","caller":"traceutil/trace.go:171","msg":"trace[1136032538] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4748; }","duration":"224.84083ms","start":"2026-07-24T04:46:38.440601Z","end":"2026-07-24T04:46:38.665442Z","steps":["trace[1136032538] 'agreement among raft nodes before linearized reading' (duration: 224.767969ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:46:38.665916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.43143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls\" ","response":"range_response_count:1 size:2318"} {"level":"info","ts":"2026-07-24T04:46:38.66596Z","caller":"traceutil/trace.go:171","msg":"trace[670091029] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls; range_end:; response_count:1; response_revision:4748; }","duration":"380.528762ms","start":"2026-07-24T04:46:38.285423Z","end":"2026-07-24T04:46:38.665952Z","steps":["trace[670091029] 'agreement among raft nodes before linearized reading' (duration: 378.880344ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:46:38.66598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:46:38.285393Z","time spent":"380.581523ms","remote":"127.0.0.1:55746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":1,"response size":2342,"request content":"key:\"/registry/cert-manager.io/certificates/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls\" "} {"level":"info","ts":"2026-07-24T04:46:38.801109Z","caller":"traceutil/trace.go:171","msg":"trace[1257004559] linearizableReadLoop","detail":"{readStateIndex:4950; appliedIndex:4949; }","duration":"123.247268ms","start":"2026-07-24T04:46:38.677814Z","end":"2026-07-24T04:46:38.801061Z","steps":["trace[1257004559] 'read index received' (duration: 52.723587ms)","trace[1257004559] 'applied index is now lower than readState.Index' (duration: 70.521301ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:46:38.80127Z","caller":"traceutil/trace.go:171","msg":"trace[434141084] transaction","detail":"{read_only:false; response_revision:4751; number_of_response:1; }","duration":"122.784616ms","start":"2026-07-24T04:46:38.678468Z","end":"2026-07-24T04:46:38.801253Z","steps":["trace[434141084] 'process raft request' (duration: 122.415628ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:46:38.801389Z","caller":"traceutil/trace.go:171","msg":"trace[1113524525] transaction","detail":"{read_only:false; response_revision:4750; number_of_response:1; }","duration":"127.112937ms","start":"2026-07-24T04:46:38.674223Z","end":"2026-07-24T04:46:38.801336Z","steps":["trace[1113524525] 'process raft request' (duration: 126.350839ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:46:38.801563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.716309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls\" ","response":"range_response_count:1 size:4787"} {"level":"info","ts":"2026-07-24T04:46:38.801718Z","caller":"traceutil/trace.go:171","msg":"trace[895774021] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls; range_end:; response_count:1; response_revision:4752; }","duration":"123.902333ms","start":"2026-07-24T04:46:38.677789Z","end":"2026-07-24T04:46:38.801691Z","steps":["trace[895774021] 'agreement among raft nodes before linearized reading' (duration: 123.601035ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:46:39.414109Z","caller":"traceutil/trace.go:171","msg":"trace[1715105417] transaction","detail":"{read_only:false; response_revision:4762; number_of_response:1; }","duration":"110.8011ms","start":"2026-07-24T04:46:39.302789Z","end":"2026-07-24T04:46:39.413591Z","steps":["trace[1715105417] 'process raft request' (duration: 95.474945ms)","trace[1715105417] 'compare' (duration: 14.974227ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:47:00.446202Z","caller":"traceutil/trace.go:171","msg":"trace[186190539] transaction","detail":"{read_only:false; response_revision:4932; number_of_response:1; }","duration":"321.445091ms","start":"2026-07-24T04:47:00.124724Z","end":"2026-07-24T04:47:00.446169Z","steps":["trace[186190539] 'process raft request' (duration: 315.697316ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:47:00.446511Z","caller":"traceutil/trace.go:171","msg":"trace[2033220387] linearizableReadLoop","detail":"{readStateIndex:5143; appliedIndex:5141; }","duration":"181.178312ms","start":"2026-07-24T04:47:00.265303Z","end":"2026-07-24T04:47:00.446481Z","steps":["trace[2033220387] 'read index received' (duration: 175.10823ms)","trace[2033220387] 'applied index is now lower than readState.Index' (duration: 6.068112ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:47:00.446531Z","caller":"traceutil/trace.go:171","msg":"trace[283474417] transaction","detail":"{read_only:false; response_revision:4933; number_of_response:1; }","duration":"321.445391ms","start":"2026-07-24T04:47:00.125046Z","end":"2026-07-24T04:47:00.446492Z","steps":["trace[283474417] 'process raft request' (duration: 321.026472ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:47:00.446671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.345306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/keystone-openid-metadata\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-24T04:47:00.446706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:47:00.12471Z","time spent":"321.572454ms","remote":"127.0.0.1:40314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:47:00.446786Z","caller":"traceutil/trace.go:171","msg":"trace[1966136730] range","detail":"{range_begin:/registry/configmaps/openstack/keystone-openid-metadata; range_end:; response_count:0; response_revision:4933; }","duration":"181.464758ms","start":"2026-07-24T04:47:00.265262Z","end":"2026-07-24T04:47:00.446726Z","steps":["trace[1966136730] 'agreement among raft nodes before linearized reading' (duration: 181.322065ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:47:00.446799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:47:00.125036Z","time spent":"321.635355ms","remote":"127.0.0.1:40314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:47:00.623026Z","caller":"traceutil/trace.go:171","msg":"trace[1968135412] transaction","detail":"{read_only:false; response_revision:4934; number_of_response:1; }","duration":"170.491859ms","start":"2026-07-24T04:47:00.452512Z","end":"2026-07-24T04:47:00.623003Z","steps":["trace[1968135412] 'process raft request' (duration: 170.232544ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:47:01.551961Z","caller":"traceutil/trace.go:171","msg":"trace[802215155] transaction","detail":"{read_only:false; response_revision:4940; number_of_response:1; }","duration":"206.133094ms","start":"2026-07-24T04:47:01.345791Z","end":"2026-07-24T04:47:01.551924Z","steps":["trace[802215155] 'process raft request' (duration: 205.867508ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:47:06.941381Z","caller":"traceutil/trace.go:171","msg":"trace[1468604481] transaction","detail":"{read_only:false; response_revision:5072; number_of_response:1; }","duration":"111.686267ms","start":"2026-07-24T04:47:06.829667Z","end":"2026-07-24T04:47:06.941353Z","steps":["trace[1468604481] 'process raft request' (duration: 111.449521ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:47:16.159126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.582053ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T04:47:16.159234Z","caller":"traceutil/trace.go:171","msg":"trace[463665518] linearizableReadLoop","detail":"{readStateIndex:5350; appliedIndex:5349; }","duration":"358.190318ms","start":"2026-07-24T04:47:15.801029Z","end":"2026-07-24T04:47:16.15922Z","steps":["trace[463665518] 'read index received' (duration: 3.3447ms)","trace[463665518] 'applied index is now lower than readState.Index' (duration: 354.844168ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:47:16.15934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.31534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:47:16.159384Z","caller":"traceutil/trace.go:171","msg":"trace[1848642830] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5133; }","duration":"358.378452ms","start":"2026-07-24T04:47:15.800985Z","end":"2026-07-24T04:47:16.159364Z","steps":["trace[1848642830] 'agreement among raft nodes before linearized reading' (duration: 358.317851ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:47:16.159422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:47:15.800968Z","time spent":"358.446763ms","remote":"127.0.0.1:40022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-24T04:47:16.159312Z","caller":"traceutil/trace.go:171","msg":"trace[2038661733] transaction","detail":"{read_only:false; response_revision:5133; number_of_response:1; }","duration":"446.232383ms","start":"2026-07-24T04:47:15.713034Z","end":"2026-07-24T04:47:16.159266Z","steps":["trace[2038661733] 'process raft request' (duration: 91.426825ms)","trace[2038661733] 'compare' (duration: 354.309928ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:47:16.159624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:47:15.713015Z","time spent":"446.475698ms","remote":"127.0.0.1:40140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:47:16.159855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.152247ms","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-07-24T04:47:16.160124Z","caller":"traceutil/trace.go:171","msg":"trace[3023088] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5133; }","duration":"137.277719ms","start":"2026-07-24T04:47:16.022637Z","end":"2026-07-24T04:47:16.159915Z","steps":["trace[3023088] 'agreement among raft nodes before linearized reading' (duration: 136.769239ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:49:41.773002Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3188} {"level":"info","ts":"2026-07-24T04:49:41.86919Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3188,"took":"94.266186ms","hash":61616691,"current-db-size-bytes":58974208,"current-db-size":"59 MB","current-db-size-in-use-bytes":31412224,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-24T04:49:41.869344Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":61616691,"revision":3188,"compact-revision":-1} {"level":"info","ts":"2026-07-24T04:50:07.341236Z","caller":"traceutil/trace.go:171","msg":"trace[811841471] linearizableReadLoop","detail":"{readStateIndex:6861; appliedIndex:6860; }","duration":"162.4041ms","start":"2026-07-24T04:50:07.178803Z","end":"2026-07-24T04:50:07.341207Z","steps":["trace[811841471] 'read index received' (duration: 162.208767ms)","trace[811841471] 'applied index is now lower than readState.Index' (duration: 194.263µs)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:50:07.341499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.658054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-24T04:50:07.341544Z","caller":"traceutil/trace.go:171","msg":"trace[97779713] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:6571; }","duration":"162.782416ms","start":"2026-07-24T04:50:07.17875Z","end":"2026-07-24T04:50:07.341532Z","steps":["trace[97779713] 'agreement among raft nodes before linearized reading' (duration: 162.634344ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:50:07.341675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.855655ms","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-07-24T04:50:07.341772Z","caller":"traceutil/trace.go:171","msg":"trace[1079517039] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6571; }","duration":"149.060808ms","start":"2026-07-24T04:50:07.192692Z","end":"2026-07-24T04:50:07.341753Z","steps":["trace[1079517039] 'agreement among raft nodes before linearized reading' (duration: 148.742893ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:50:07.342062Z","caller":"traceutil/trace.go:171","msg":"trace[2126142433] transaction","detail":"{read_only:false; response_revision:6571; number_of_response:1; }","duration":"258.625429ms","start":"2026-07-24T04:50:07.083339Z","end":"2026-07-24T04:50:07.341964Z","steps":["trace[2126142433] 'process raft request' (duration: 257.701214ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:50:42.358934Z","caller":"traceutil/trace.go:171","msg":"trace[774695752] transaction","detail":"{read_only:false; response_revision:6859; number_of_response:1; }","duration":"112.436488ms","start":"2026-07-24T04:50:42.246456Z","end":"2026-07-24T04:50:42.358892Z","steps":["trace[774695752] 'process raft request' (duration: 56.734167ms)","trace[774695752] 'compare' (duration: 55.319585ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:50:42.956346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.784451ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T04:50:42.956519Z","caller":"traceutil/trace.go:171","msg":"trace[1996227514] transaction","detail":"{read_only:false; response_revision:6863; number_of_response:1; }","duration":"263.744888ms","start":"2026-07-24T04:50:42.692737Z","end":"2026-07-24T04:50:42.956481Z","steps":["trace[1996227514] 'process raft request' (duration: 79.728864ms)","trace[1996227514] 'compare' (duration: 183.257922ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:50:42.956487Z","caller":"traceutil/trace.go:171","msg":"trace[47009900] linearizableReadLoop","detail":"{readStateIndex:7171; appliedIndex:7170; }","duration":"157.775019ms","start":"2026-07-24T04:50:42.798678Z","end":"2026-07-24T04:50:42.956453Z","steps":["trace[47009900] 'read index received' (duration: 39.691µs)","trace[47009900] 'applied index is now lower than readState.Index' (duration: 157.733108ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:50:42.956823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.140895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:50:42.956864Z","caller":"traceutil/trace.go:171","msg":"trace[1897295285] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6863; }","duration":"158.217486ms","start":"2026-07-24T04:50:42.798636Z","end":"2026-07-24T04:50:42.956853Z","steps":["trace[1897295285] 'agreement among raft nodes before linearized reading' (duration: 158.076523ms)"],"step_count":1}