{"level":"warn","ts":"2026-07-16T00:25:57.28789Z","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-16T00:25:57.288032Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.222:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.222:2380","--initial-cluster=instance=https://199.204.45.222:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.222:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.222: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-16T00:25:57.288121Z","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-16T00:25:57.288147Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.222:2380"]} {"level":"info","ts":"2026-07-16T00:25:57.288181Z","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-16T00:25:57.288806Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.222:2379"]} {"level":"info","ts":"2026-07-16T00:25:57.288935Z","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":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.222:2380"],"listen-peer-urls":["https://199.204.45.222:2380"],"advertise-client-urls":["https://199.204.45.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.222: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-16T00:25:57.293244Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.065514ms"} {"level":"info","ts":"2026-07-16T00:25:57.301731Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"380f6760faea5f9e","cluster-id":"b87e8aca83a17eab"} {"level":"info","ts":"2026-07-16T00:25:57.301904Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e switched to configuration voters=()"} {"level":"info","ts":"2026-07-16T00:25:57.301953Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became follower at term 0"} {"level":"info","ts":"2026-07-16T00:25:57.301978Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 380f6760faea5f9e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-16T00:25:57.302004Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became follower at term 1"} {"level":"info","ts":"2026-07-16T00:25:57.302077Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e switched to configuration voters=(4039561056998809502)"} {"level":"warn","ts":"2026-07-16T00:25:57.309033Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-16T00:25:57.310653Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-16T00:25:57.311863Z","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-16T00:25:57.314181Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"380f6760faea5f9e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-16T00:25:57.314699Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"380f6760faea5f9e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-16T00:25:57.314717Z","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-16T00:25:57.314907Z","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-16T00:25:57.314931Z","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-16T00:25:57.316727Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e switched to configuration voters=(4039561056998809502)"} {"level":"info","ts":"2026-07-16T00:25:57.316959Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b87e8aca83a17eab","local-member-id":"380f6760faea5f9e","added-peer-id":"380f6760faea5f9e","added-peer-peer-urls":["https://199.204.45.222:2380"]} {"level":"info","ts":"2026-07-16T00:25:57.320326Z","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-16T00:25:57.320549Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.222:2380"} {"level":"info","ts":"2026-07-16T00:25:57.320638Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.222:2380"} {"level":"info","ts":"2026-07-16T00:25:57.320941Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"380f6760faea5f9e","initial-advertise-peer-urls":["https://199.204.45.222:2380"],"listen-peer-urls":["https://199.204.45.222:2380"],"advertise-client-urls":["https://199.204.45.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-16T00:25:57.321117Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-16T00:25:58.102573Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e is starting a new election at term 1"} {"level":"info","ts":"2026-07-16T00:25:58.102624Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became pre-candidate at term 1"} {"level":"info","ts":"2026-07-16T00:25:58.102652Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e received MsgPreVoteResp from 380f6760faea5f9e at term 1"} {"level":"info","ts":"2026-07-16T00:25:58.102666Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became candidate at term 2"} {"level":"info","ts":"2026-07-16T00:25:58.102673Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e received MsgVoteResp from 380f6760faea5f9e at term 2"} {"level":"info","ts":"2026-07-16T00:25:58.102684Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became leader at term 2"} {"level":"info","ts":"2026-07-16T00:25:58.102693Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 380f6760faea5f9e elected leader 380f6760faea5f9e at term 2"} {"level":"info","ts":"2026-07-16T00:25:58.103508Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T00:25:58.105066Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"380f6760faea5f9e","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.222:2379]}","request-path":"/0/members/380f6760faea5f9e/attributes","cluster-id":"b87e8aca83a17eab","publish-timeout":"7s"} {"level":"info","ts":"2026-07-16T00:25:58.105179Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-16T00:25:58.10548Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-16T00:25:58.106754Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b87e8aca83a17eab","local-member-id":"380f6760faea5f9e","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T00:25:58.106902Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T00:25:58.106931Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-16T00:25:58.106958Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T00:25:58.106988Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-16T00:25:58.107684Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-16T00:25:58.108996Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.222:2379"} {"level":"info","ts":"2026-07-16T00:26:39.738768Z","caller":"traceutil/trace.go:171","msg":"trace[1428936515] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"154.139211ms","start":"2026-07-16T00:26:39.584589Z","end":"2026-07-16T00:26:39.738728Z","steps":["trace[1428936515] 'process raft request' (duration: 149.519476ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:26:39.738798Z","caller":"traceutil/trace.go:171","msg":"trace[1225970920] linearizableReadLoop","detail":"{readStateIndex:617; appliedIndex:616; }","duration":"118.786527ms","start":"2026-07-16T00:26:39.61993Z","end":"2026-07-16T00:26:39.738716Z","steps":["trace[1225970920] 'read index received' (duration: 113.488068ms)","trace[1225970920] 'applied index is now lower than readState.Index' (duration: 5.295089ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T00:26:39.738967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.95274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T00:26:39.739045Z","caller":"traceutil/trace.go:171","msg":"trace[328980843] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:594; }","duration":"119.137674ms","start":"2026-07-16T00:26:39.61989Z","end":"2026-07-16T00:26:39.739027Z","steps":["trace[328980843] 'agreement among raft nodes before linearized reading' (duration: 118.91704ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:26:40.417505Z","caller":"traceutil/trace.go:171","msg":"trace[220079456] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"120.957331ms","start":"2026-07-16T00:26:40.29652Z","end":"2026-07-16T00:26:40.417477Z","steps":["trace[220079456] 'process raft request' (duration: 120.809338ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:26:40.428821Z","caller":"traceutil/trace.go:171","msg":"trace[153498660] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"100.693585ms","start":"2026-07-16T00:26:40.328109Z","end":"2026-07-16T00:26:40.428802Z","steps":["trace[153498660] 'process raft request' (duration: 100.293397ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:28:09.840147Z","caller":"traceutil/trace.go:171","msg":"trace[1615352527] transaction","detail":"{read_only:false; response_revision:1825; number_of_response:1; }","duration":"107.937027ms","start":"2026-07-16T00:28:09.732185Z","end":"2026-07-16T00:28:09.840122Z","steps":["trace[1615352527] 'process raft request' (duration: 107.781754ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:29:04.669346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.857085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-16T00:29:04.669453Z","caller":"traceutil/trace.go:171","msg":"trace[1299750247] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2172; }","duration":"137.990918ms","start":"2026-07-16T00:29:04.531425Z","end":"2026-07-16T00:29:04.669416Z","steps":["trace[1299750247] 'range keys from in-memory index tree' (duration: 137.723072ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:29:04.799306Z","caller":"traceutil/trace.go:171","msg":"trace[664819825] linearizableReadLoop","detail":"{readStateIndex:2256; appliedIndex:2255; }","duration":"131.270121ms","start":"2026-07-16T00:29:04.668011Z","end":"2026-07-16T00:29:04.799281Z","steps":["trace[664819825] 'read index received' (duration: 131.22343ms)","trace[664819825] 'applied index is now lower than readState.Index' (duration: 45.741µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:29:04.79941Z","caller":"traceutil/trace.go:171","msg":"trace[1332532031] transaction","detail":"{read_only:false; response_revision:2173; number_of_response:1; }","duration":"133.565957ms","start":"2026-07-16T00:29:04.665834Z","end":"2026-07-16T00:29:04.7994Z","steps":["trace[1332532031] 'process raft request' (duration: 133.325373ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:29:04.799654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.626088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-16T00:29:04.799712Z","caller":"traceutil/trace.go:171","msg":"trace[1611734938] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2173; }","duration":"131.73777ms","start":"2026-07-16T00:29:04.66796Z","end":"2026-07-16T00:29:04.799698Z","steps":["trace[1611734938] 'agreement among raft nodes before linearized reading' (duration: 131.564357ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:29:04.833544Z","caller":"traceutil/trace.go:171","msg":"trace[635028031] transaction","detail":"{read_only:false; response_revision:2174; number_of_response:1; }","duration":"160.465557ms","start":"2026-07-16T00:29:04.673037Z","end":"2026-07-16T00:29:04.833502Z","steps":["trace[635028031] 'process raft request' (duration: 160.230322ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:29:04.833592Z","caller":"traceutil/trace.go:171","msg":"trace[439414542] transaction","detail":"{read_only:false; response_revision:2175; number_of_response:1; }","duration":"155.588278ms","start":"2026-07-16T00:29:04.677966Z","end":"2026-07-16T00:29:04.833554Z","steps":["trace[439414542] 'process raft request' (duration: 155.446175ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:29:04.833646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.672124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-16T00:29:04.833698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.345045ms","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-16T00:29:04.833745Z","caller":"traceutil/trace.go:171","msg":"trace[49508977] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2175; }","duration":"162.425226ms","start":"2026-07-16T00:29:04.671306Z","end":"2026-07-16T00:29:04.833732Z","steps":["trace[49508977] 'agreement among raft nodes before linearized reading' (duration: 162.308844ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:29:04.833741Z","caller":"traceutil/trace.go:171","msg":"trace[1801624476] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2175; }","duration":"119.799416ms","start":"2026-07-16T00:29:04.713921Z","end":"2026-07-16T00:29:04.83372Z","steps":["trace[1801624476] 'agreement among raft nodes before linearized reading' (duration: 119.584702ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:29:04.996079Z","caller":"traceutil/trace.go:171","msg":"trace[175346610] transaction","detail":"{read_only:false; response_revision:2179; number_of_response:1; }","duration":"158.289443ms","start":"2026-07-16T00:29:04.837763Z","end":"2026-07-16T00:29:04.996053Z","steps":["trace[175346610] 'process raft request' (duration: 158.250242ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:29:04.99621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.489779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-16T00:29:04.996256Z","caller":"traceutil/trace.go:171","msg":"trace[868083506] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:2179; }","duration":"188.56736ms","start":"2026-07-16T00:29:04.80768Z","end":"2026-07-16T00:29:04.996248Z","steps":["trace[868083506] 'agreement among raft nodes before linearized reading' (duration: 188.467868ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:29:04.996297Z","caller":"traceutil/trace.go:171","msg":"trace[1763722444] transaction","detail":"{read_only:false; response_revision:2178; number_of_response:1; }","duration":"158.875475ms","start":"2026-07-16T00:29:04.837398Z","end":"2026-07-16T00:29:04.996273Z","steps":["trace[1763722444] 'process raft request' (duration: 158.585439ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:29:04.996359Z","caller":"traceutil/trace.go:171","msg":"trace[2041150409] transaction","detail":"{read_only:false; response_revision:2176; number_of_response:1; }","duration":"192.811817ms","start":"2026-07-16T00:29:04.803491Z","end":"2026-07-16T00:29:04.996303Z","steps":["trace[2041150409] 'process raft request' (duration: 144.272466ms)","trace[2041150409] 'compare' (duration: 48.012181ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:29:04.996115Z","caller":"traceutil/trace.go:171","msg":"trace[2034910087] linearizableReadLoop","detail":"{readStateIndex:2259; appliedIndex:2258; }","duration":"162.631841ms","start":"2026-07-16T00:29:04.833463Z","end":"2026-07-16T00:29:04.996095Z","steps":["trace[2034910087] 'read index received' (duration: 114.347855ms)","trace[2034910087] 'applied index is now lower than readState.Index' (duration: 48.282016ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:29:04.996409Z","caller":"traceutil/trace.go:171","msg":"trace[1745636041] transaction","detail":"{read_only:false; response_revision:2177; number_of_response:1; }","duration":"160.875615ms","start":"2026-07-16T00:29:04.835501Z","end":"2026-07-16T00:29:04.996377Z","steps":["trace[1745636041] 'process raft request' (duration: 160.438066ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:29:05.188045Z","caller":"traceutil/trace.go:171","msg":"trace[1622360391] transaction","detail":"{read_only:false; response_revision:2180; number_of_response:1; }","duration":"158.828593ms","start":"2026-07-16T00:29:05.029191Z","end":"2026-07-16T00:29:05.188019Z","steps":["trace[1622360391] 'process raft request' (duration: 66.466516ms)","trace[1622360391] 'compare' (duration: 92.256774ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:30:19.475829Z","caller":"traceutil/trace.go:171","msg":"trace[1034533213] transaction","detail":"{read_only:false; response_revision:2779; number_of_response:1; }","duration":"122.243063ms","start":"2026-07-16T00:30:19.353545Z","end":"2026-07-16T00:30:19.475788Z","steps":["trace[1034533213] 'process raft request' (duration: 77.434039ms)","trace[1034533213] 'compare' (duration: 44.504637ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:34:05.663042Z","caller":"traceutil/trace.go:171","msg":"trace[1325096993] linearizableReadLoop","detail":"{readStateIndex:4835; appliedIndex:4834; }","duration":"129.392828ms","start":"2026-07-16T00:34:05.533625Z","end":"2026-07-16T00:34:05.663017Z","steps":["trace[1325096993] 'read index received' (duration: 114.713519ms)","trace[1325096993] 'applied index is now lower than readState.Index' (duration: 14.677769ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:34:05.663168Z","caller":"traceutil/trace.go:171","msg":"trace[1765282065] transaction","detail":"{read_only:false; response_revision:4657; number_of_response:1; }","duration":"130.218484ms","start":"2026-07-16T00:34:05.532883Z","end":"2026-07-16T00:34:05.663102Z","steps":["trace[1765282065] 'process raft request' (duration: 115.407263ms)","trace[1765282065] 'compare' (duration: 14.472665ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T00:34:05.663238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.594912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:16654"} {"level":"info","ts":"2026-07-16T00:34:05.663268Z","caller":"traceutil/trace.go:171","msg":"trace[1909599083] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:4657; }","duration":"129.654253ms","start":"2026-07-16T00:34:05.533607Z","end":"2026-07-16T00:34:05.663261Z","steps":["trace[1909599083] 'agreement among raft nodes before linearized reading' (duration: 129.51405ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:34:05.6675Z","caller":"traceutil/trace.go:171","msg":"trace[911873000] transaction","detail":"{read_only:false; response_revision:4658; number_of_response:1; }","duration":"124.743262ms","start":"2026-07-16T00:34:05.542728Z","end":"2026-07-16T00:34:05.667471Z","steps":["trace[911873000] 'process raft request' (duration: 124.579588ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:34:05.667896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.635832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T00:34:05.667965Z","caller":"traceutil/trace.go:171","msg":"trace[1567751467] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1; range_end:; response_count:0; response_revision:4658; }","duration":"133.713964ms","start":"2026-07-16T00:34:05.534232Z","end":"2026-07-16T00:34:05.667946Z","steps":["trace[1567751467] 'agreement among raft nodes before linearized reading' (duration: 133.607462ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:34:05.668222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.812695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:9 size:61952"} {"level":"info","ts":"2026-07-16T00:34:05.668263Z","caller":"traceutil/trace.go:171","msg":"trace[334889593] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:9; response_revision:4658; }","duration":"130.866727ms","start":"2026-07-16T00:34:05.537386Z","end":"2026-07-16T00:34:05.668253Z","steps":["trace[334889593] 'agreement among raft nodes before linearized reading' (duration: 130.665212ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:34:08.727151Z","caller":"traceutil/trace.go:171","msg":"trace[1799427653] linearizableReadLoop","detail":"{readStateIndex:4923; appliedIndex:4922; }","duration":"106.304818ms","start":"2026-07-16T00:34:08.620814Z","end":"2026-07-16T00:34:08.727118Z","steps":["trace[1799427653] 'read index received' (duration: 93.272594ms)","trace[1799427653] 'applied index is now lower than readState.Index' (duration: 13.030954ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:34:08.727304Z","caller":"traceutil/trace.go:171","msg":"trace[102506762] transaction","detail":"{read_only:false; response_revision:4743; number_of_response:1; }","duration":"113.057825ms","start":"2026-07-16T00:34:08.614228Z","end":"2026-07-16T00:34:08.727286Z","steps":["trace[102506762] 'process raft request' (duration: 99.846697ms)","trace[102506762] 'compare' (duration: 12.913172ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T00:34:08.727576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.746607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T00:34:08.727616Z","caller":"traceutil/trace.go:171","msg":"trace[2031676649] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4743; }","duration":"106.816409ms","start":"2026-07-16T00:34:08.62079Z","end":"2026-07-16T00:34:08.727606Z","steps":["trace[2031676649] 'agreement among raft nodes before linearized reading' (duration: 106.743008ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:34:28.229849Z","caller":"traceutil/trace.go:171","msg":"trace[1618915937] transaction","detail":"{read_only:false; response_revision:5202; number_of_response:1; }","duration":"484.397583ms","start":"2026-07-16T00:34:27.745421Z","end":"2026-07-16T00:34:28.229819Z","steps":["trace[1618915937] 'process raft request' (duration: 484.226579ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:34:28.230471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:34:27.745405Z","time spent":"484.543086ms","remote":"127.0.0.1:41432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8038,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T00:34:28.277312Z","caller":"traceutil/trace.go:171","msg":"trace[1055681469] transaction","detail":"{read_only:false; response_revision:5203; number_of_response:1; }","duration":"447.601707ms","start":"2026-07-16T00:34:27.829677Z","end":"2026-07-16T00:34:28.277278Z","steps":["trace[1055681469] 'process raft request' (duration: 447.323511ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:34:28.277394Z","caller":"traceutil/trace.go:171","msg":"trace[1301237566] transaction","detail":"{read_only:false; response_revision:5204; number_of_response:1; }","duration":"198.237584ms","start":"2026-07-16T00:34:28.07912Z","end":"2026-07-16T00:34:28.277357Z","steps":["trace[1301237566] 'process raft request' (duration: 198.077201ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:34:28.277282Z","caller":"traceutil/trace.go:171","msg":"trace[1683394481] linearizableReadLoop","detail":"{readStateIndex:5401; appliedIndex:5398; }","duration":"150.358612ms","start":"2026-07-16T00:34:28.126887Z","end":"2026-07-16T00:34:28.277245Z","steps":["trace[1683394481] 'read index received' (duration: 102.792706ms)","trace[1683394481] 'applied index is now lower than readState.Index' (duration: 47.564746ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T00:34:28.277541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:34:27.82965Z","time spent":"447.766539ms","remote":"127.0.0.1:41514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T00:34:28.277679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.77543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-4lsbg.18c29dee6099656f\" ","response":"range_response_count:1 size:906"} {"level":"info","ts":"2026-07-16T00:34:28.27773Z","caller":"traceutil/trace.go:171","msg":"trace[1378876655] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-4lsbg.18c29dee6099656f; range_end:; response_count:1; response_revision:5204; }","duration":"150.897172ms","start":"2026-07-16T00:34:28.126821Z","end":"2026-07-16T00:34:28.277718Z","steps":["trace[1378876655] 'agreement among raft nodes before linearized reading' (duration: 150.568546ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:34:28.345905Z","caller":"traceutil/trace.go:171","msg":"trace[1204658210] transaction","detail":"{read_only:false; response_revision:5205; number_of_response:1; }","duration":"111.545014ms","start":"2026-07-16T00:34:28.234326Z","end":"2026-07-16T00:34:28.345871Z","steps":["trace[1204658210] 'process raft request' (duration: 111.250679ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:34:28.346227Z","caller":"traceutil/trace.go:171","msg":"trace[1129661446] transaction","detail":"{read_only:false; response_revision:5206; number_of_response:1; }","duration":"111.748549ms","start":"2026-07-16T00:34:28.23445Z","end":"2026-07-16T00:34:28.346199Z","steps":["trace[1129661446] 'process raft request' (duration: 111.331131ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:34:28.346356Z","caller":"traceutil/trace.go:171","msg":"trace[561754436] transaction","detail":"{read_only:false; response_revision:5207; number_of_response:1; }","duration":"111.872441ms","start":"2026-07-16T00:34:28.23445Z","end":"2026-07-16T00:34:28.346323Z","steps":["trace[561754436] 'process raft request' (duration: 111.664017ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:34:28.346627Z","caller":"traceutil/trace.go:171","msg":"trace[949050734] transaction","detail":"{read_only:false; response_revision:5208; number_of_response:1; }","duration":"111.632847ms","start":"2026-07-16T00:34:28.234962Z","end":"2026-07-16T00:34:28.346595Z","steps":["trace[949050734] 'process raft request' (duration: 111.28964ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:35:58.324964Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2994} {"level":"info","ts":"2026-07-16T00:35:58.399395Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2994,"took":"72.485375ms","hash":4130530658,"current-db-size-bytes":58818560,"current-db-size":"59 MB","current-db-size-in-use-bytes":34275328,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-16T00:35:58.399486Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4130530658,"revision":2994,"compact-revision":-1} {"level":"info","ts":"2026-07-16T00:40:46.358029Z","caller":"traceutil/trace.go:171","msg":"trace[790372142] transaction","detail":"{read_only:false; response_revision:8222; number_of_response:1; }","duration":"314.038936ms","start":"2026-07-16T00:40:46.043948Z","end":"2026-07-16T00:40:46.357987Z","steps":["trace[790372142] 'process raft request' (duration: 298.024449ms)","trace[790372142] 'compare' (duration: 15.439206ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T00:40:46.358263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:40:46.043918Z","time spent":"314.22819ms","remote":"127.0.0.1:41514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T00:40:46.358174Z","caller":"traceutil/trace.go:171","msg":"trace[219021830] transaction","detail":"{read_only:false; response_revision:8223; number_of_response:1; }","duration":"312.404682ms","start":"2026-07-16T00:40:46.045729Z","end":"2026-07-16T00:40:46.358134Z","steps":["trace[219021830] 'process raft request' (duration: 312.178428ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:40:46.358443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:40:46.045699Z","time spent":"312.628486ms","remote":"127.0.0.1:41514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T00:40:46.358481Z","caller":"traceutil/trace.go:171","msg":"trace[945577533] linearizableReadLoop","detail":"{readStateIndex:8511; appliedIndex:8509; }","duration":"197.606221ms","start":"2026-07-16T00:40:46.160839Z","end":"2026-07-16T00:40:46.358446Z","steps":["trace[945577533] 'read index received' (duration: 181.134864ms)","trace[945577533] 'applied index is now lower than readState.Index' (duration: 16.470017ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T00:40:46.358734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.875687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-07-16T00:40:46.358775Z","caller":"traceutil/trace.go:171","msg":"trace[322487617] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8223; }","duration":"197.938618ms","start":"2026-07-16T00:40:46.16082Z","end":"2026-07-16T00:40:46.358759Z","steps":["trace[322487617] 'agreement among raft nodes before linearized reading' (duration: 197.741714ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:40:58.351448Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6118} {"level":"info","ts":"2026-07-16T00:40:58.625266Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6118,"took":"271.399097ms","hash":2204443669,"current-db-size-bytes":58818560,"current-db-size":"59 MB","current-db-size-in-use-bytes":23621632,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-07-16T00:40:58.625382Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2204443669,"revision":6118,"compact-revision":2994} {"level":"info","ts":"2026-07-16T00:40:59.032632Z","caller":"traceutil/trace.go:171","msg":"trace[923386275] linearizableReadLoop","detail":"{readStateIndex:8582; appliedIndex:8581; }","duration":"234.78729ms","start":"2026-07-16T00:40:58.797793Z","end":"2026-07-16T00:40:59.03258Z","steps":["trace[923386275] 'read index received' (duration: 227.520011ms)","trace[923386275] 'applied index is now lower than readState.Index' (duration: 7.265199ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:40:59.032662Z","caller":"traceutil/trace.go:171","msg":"trace[1674464738] transaction","detail":"{read_only:false; response_revision:8289; number_of_response:1; }","duration":"315.339573ms","start":"2026-07-16T00:40:58.717257Z","end":"2026-07-16T00:40:59.032597Z","steps":["trace[1674464738] 'process raft request' (duration: 308.106725ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:40:59.032948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:40:58.717222Z","time spent":"315.564838ms","remote":"127.0.0.1:41514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T00:40:59.033037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.316459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-07-16T00:40:59.033023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.191398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-16T00:40:59.033088Z","caller":"traceutil/trace.go:171","msg":"trace[887449380] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8289; }","duration":"231.443491ms","start":"2026-07-16T00:40:58.801631Z","end":"2026-07-16T00:40:59.033075Z","steps":["trace[887449380] 'agreement among raft nodes before linearized reading' (duration: 231.194306ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:40:59.033164Z","caller":"traceutil/trace.go:171","msg":"trace[1026890559] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8289; }","duration":"235.431552ms","start":"2026-07-16T00:40:58.797693Z","end":"2026-07-16T00:40:59.033124Z","steps":["trace[1026890559] 'agreement among raft nodes before linearized reading' (duration: 234.987274ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:41:28.34075Z","caller":"traceutil/trace.go:171","msg":"trace[1460948525] transaction","detail":"{read_only:false; response_revision:8502; number_of_response:1; }","duration":"128.373519ms","start":"2026-07-16T00:41:28.212349Z","end":"2026-07-16T00:41:28.340723Z","steps":["trace[1460948525] 'process raft request' (duration: 127.837398ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:41:42.049066Z","caller":"traceutil/trace.go:171","msg":"trace[1665669971] linearizableReadLoop","detail":"{readStateIndex:8928; appliedIndex:8927; }","duration":"123.017009ms","start":"2026-07-16T00:41:41.92602Z","end":"2026-07-16T00:41:42.049037Z","steps":["trace[1665669971] 'read index received' (duration: 56.189775ms)","trace[1665669971] 'applied index is now lower than readState.Index' (duration: 66.825604ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:41:42.049252Z","caller":"traceutil/trace.go:171","msg":"trace[974032172] transaction","detail":"{read_only:false; response_revision:8621; number_of_response:1; }","duration":"128.594343ms","start":"2026-07-16T00:41:41.920595Z","end":"2026-07-16T00:41:42.049189Z","steps":["trace[974032172] 'process raft request' (duration: 61.669367ms)","trace[974032172] 'compare' (duration: 66.60765ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T00:41:42.049629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.56908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-config-override\" ","response":"range_response_count:1 size:626"} {"level":"info","ts":"2026-07-16T00:41:42.049732Z","caller":"traceutil/trace.go:171","msg":"trace[1471652163] range","detail":"{range_begin:/registry/configmaps/openstack/rook-config-override; range_end:; response_count:1; response_revision:8621; }","duration":"123.730873ms","start":"2026-07-16T00:41:41.925978Z","end":"2026-07-16T00:41:42.049709Z","steps":["trace[1471652163] 'agreement among raft nodes before linearized reading' (duration: 123.164742ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:41:56.512212Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000236a.wal"} {"level":"info","ts":"2026-07-16T00:42:39.534426Z","caller":"traceutil/trace.go:171","msg":"trace[2007664339] transaction","detail":"{read_only:false; response_revision:9180; number_of_response:1; }","duration":"124.345007ms","start":"2026-07-16T00:42:39.410052Z","end":"2026-07-16T00:42:39.534397Z","steps":["trace[2007664339] 'process raft request' (duration: 96.484785ms)","trace[2007664339] 'compare' (duration: 27.700499ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:43:38.197335Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"380f6760faea5f9e","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-16T00:43:38.204062Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-16T00:43:38.204176Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-16T00:45:58.35888Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8285} {"level":"info","ts":"2026-07-16T00:45:58.440767Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8285,"took":"78.728641ms","hash":1036306264,"current-db-size-bytes":58818560,"current-db-size":"59 MB","current-db-size-in-use-bytes":25448448,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-07-16T00:45:58.440862Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1036306264,"revision":8285,"compact-revision":6118} {"level":"info","ts":"2026-07-16T00:46:47.78639Z","caller":"traceutil/trace.go:171","msg":"trace[1094419124] linearizableReadLoop","detail":"{readStateIndex:11636; appliedIndex:11635; }","duration":"115.537058ms","start":"2026-07-16T00:46:47.670817Z","end":"2026-07-16T00:46:47.786354Z","steps":["trace[1094419124] 'read index received' (duration: 115.15715ms)","trace[1094419124] 'applied index is now lower than readState.Index' (duration: 378.218µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:46:47.786511Z","caller":"traceutil/trace.go:171","msg":"trace[508005730] transaction","detail":"{read_only:false; response_revision:11251; number_of_response:1; }","duration":"133.033023ms","start":"2026-07-16T00:46:47.653438Z","end":"2026-07-16T00:46:47.786471Z","steps":["trace[508005730] 'process raft request' (duration: 132.505453ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:46:47.786795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.885624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-07-16T00:46:47.78691Z","caller":"traceutil/trace.go:171","msg":"trace[769289941] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:11251; }","duration":"116.136179ms","start":"2026-07-16T00:46:47.670752Z","end":"2026-07-16T00:46:47.786888Z","steps":["trace[769289941] 'agreement among raft nodes before linearized reading' (duration: 115.755752ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:50:58.367685Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10778} {"level":"info","ts":"2026-07-16T00:50:58.452027Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10778,"took":"81.148464ms","hash":1976705667,"current-db-size-bytes":58818560,"current-db-size":"59 MB","current-db-size-in-use-bytes":26963968,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-07-16T00:50:58.452132Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1976705667,"revision":10778,"compact-revision":8285} {"level":"info","ts":"2026-07-16T00:53:21.45644Z","caller":"traceutil/trace.go:171","msg":"trace[1441132320] transaction","detail":"{read_only:false; response_revision:13883; number_of_response:1; }","duration":"186.236015ms","start":"2026-07-16T00:53:21.270173Z","end":"2026-07-16T00:53:21.456409Z","steps":["trace[1441132320] 'process raft request' (duration: 186.081492ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:53:21.467129Z","caller":"traceutil/trace.go:171","msg":"trace[1415313674] transaction","detail":"{read_only:false; response_revision:13884; number_of_response:1; }","duration":"157.463961ms","start":"2026-07-16T00:53:21.309626Z","end":"2026-07-16T00:53:21.46709Z","steps":["trace[1415313674] 'process raft request' (duration: 157.294158ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:53:45.257903Z","caller":"traceutil/trace.go:171","msg":"trace[1473916024] transaction","detail":"{read_only:false; response_revision:14197; number_of_response:1; }","duration":"144.350195ms","start":"2026-07-16T00:53:45.113518Z","end":"2026-07-16T00:53:45.257868Z","steps":["trace[1473916024] 'process raft request' (duration: 135.340663ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:53:45.257883Z","caller":"traceutil/trace.go:171","msg":"trace[596597079] linearizableReadLoop","detail":"{readStateIndex:14689; appliedIndex:14688; }","duration":"113.466288ms","start":"2026-07-16T00:53:45.144375Z","end":"2026-07-16T00:53:45.257841Z","steps":["trace[596597079] 'read index received' (duration: 104.433316ms)","trace[596597079] 'applied index is now lower than readState.Index' (duration: 9.031882ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T00:53:45.258278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.884667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-07-16T00:53:45.258338Z","caller":"traceutil/trace.go:171","msg":"trace[1461139076] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:14197; }","duration":"113.961419ms","start":"2026-07-16T00:53:45.144361Z","end":"2026-07-16T00:53:45.258322Z","steps":["trace[1461139076] 'agreement among raft nodes before linearized reading' (duration: 113.615902ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:55:58.37448Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12857} {"level":"info","ts":"2026-07-16T00:55:58.448226Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12857,"took":"71.364869ms","hash":62376627,"current-db-size-bytes":58818560,"current-db-size":"59 MB","current-db-size-in-use-bytes":29908992,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-16T00:55:58.448312Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":62376627,"revision":12857,"compact-revision":10778} {"level":"info","ts":"2026-07-16T00:56:28.470311Z","caller":"traceutil/trace.go:171","msg":"trace[1004755931] linearizableReadLoop","detail":"{readStateIndex:16041; appliedIndex:16040; }","duration":"189.359104ms","start":"2026-07-16T00:56:28.280916Z","end":"2026-07-16T00:56:28.470276Z","steps":["trace[1004755931] 'read index received' (duration: 187.397074ms)","trace[1004755931] 'applied index is now lower than readState.Index' (duration: 1.96053ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:56:28.470336Z","caller":"traceutil/trace.go:171","msg":"trace[242575346] transaction","detail":"{read_only:false; response_revision:15510; number_of_response:1; }","duration":"209.319889ms","start":"2026-07-16T00:56:28.260979Z","end":"2026-07-16T00:56:28.470299Z","steps":["trace[242575346] 'process raft request' (duration: 207.40119ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:56:28.470533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.61736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-07-16T00:56:28.470575Z","caller":"traceutil/trace.go:171","msg":"trace[372558902] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15510; }","duration":"189.722572ms","start":"2026-07-16T00:56:28.280842Z","end":"2026-07-16T00:56:28.470565Z","steps":["trace[372558902] 'agreement among raft nodes before linearized reading' (duration: 189.517458ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:56:28.47071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.370149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T00:56:28.470764Z","caller":"traceutil/trace.go:171","msg":"trace[486345054] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15510; }","duration":"105.490121ms","start":"2026-07-16T00:56:28.365259Z","end":"2026-07-16T00:56:28.470749Z","steps":["trace[486345054] 'agreement among raft nodes before linearized reading' (duration: 105.266427ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:56:28.471031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.183434ms","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-16T00:56:28.47114Z","caller":"traceutil/trace.go:171","msg":"trace[981577632] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15510; }","duration":"102.375678ms","start":"2026-07-16T00:56:28.368742Z","end":"2026-07-16T00:56:28.471118Z","steps":["trace[981577632] 'agreement among raft nodes before linearized reading' (duration: 102.144563ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:56:33.136891Z","caller":"traceutil/trace.go:171","msg":"trace[1192511162] transaction","detail":"{read_only:false; response_revision:15557; number_of_response:1; }","duration":"210.064574ms","start":"2026-07-16T00:56:32.926787Z","end":"2026-07-16T00:56:33.136851Z","steps":["trace[1192511162] 'process raft request' (duration: 209.823659ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:58:04.288632Z","caller":"traceutil/trace.go:171","msg":"trace[740139777] transaction","detail":"{read_only:false; response_revision:16322; number_of_response:1; }","duration":"135.181044ms","start":"2026-07-16T00:58:04.153412Z","end":"2026-07-16T00:58:04.288593Z","steps":["trace[740139777] 'process raft request' (duration: 132.183114ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:58:04.288815Z","caller":"traceutil/trace.go:171","msg":"trace[1743160691] transaction","detail":"{read_only:false; response_revision:16323; number_of_response:1; }","duration":"135.344988ms","start":"2026-07-16T00:58:04.15344Z","end":"2026-07-16T00:58:04.288785Z","steps":["trace[1743160691] 'process raft request' (duration: 135.012292ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:58:04.291888Z","caller":"traceutil/trace.go:171","msg":"trace[617767332] transaction","detail":"{read_only:false; response_revision:16324; number_of_response:1; }","duration":"137.40518ms","start":"2026-07-16T00:58:04.154464Z","end":"2026-07-16T00:58:04.291869Z","steps":["trace[617767332] 'process raft request' (duration: 134.092403ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:58:04.292099Z","caller":"traceutil/trace.go:171","msg":"trace[1658366374] transaction","detail":"{read_only:false; response_revision:16328; number_of_response:1; }","duration":"133.854109ms","start":"2026-07-16T00:58:04.158218Z","end":"2026-07-16T00:58:04.292072Z","steps":["trace[1658366374] 'process raft request' (duration: 132.878639ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:58:04.292157Z","caller":"traceutil/trace.go:171","msg":"trace[704151199] linearizableReadLoop","detail":"{readStateIndex:16887; appliedIndex:16880; }","duration":"133.030131ms","start":"2026-07-16T00:58:04.159116Z","end":"2026-07-16T00:58:04.292146Z","steps":["trace[704151199] 'read index received' (duration: 126.492879ms)","trace[704151199] 'applied index is now lower than readState.Index' (duration: 6.536092ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:58:04.292238Z","caller":"traceutil/trace.go:171","msg":"trace[1419717001] transaction","detail":"{read_only:false; response_revision:16329; number_of_response:1; }","duration":"132.017491ms","start":"2026-07-16T00:58:04.160214Z","end":"2026-07-16T00:58:04.292231Z","steps":["trace[1419717001] 'process raft request' (duration: 131.047992ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:58:04.29233Z","caller":"traceutil/trace.go:171","msg":"trace[793352535] transaction","detail":"{read_only:false; response_revision:16325; number_of_response:1; }","duration":"137.787598ms","start":"2026-07-16T00:58:04.154531Z","end":"2026-07-16T00:58:04.292318Z","steps":["trace[793352535] 'process raft request' (duration: 136.4173ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:58:04.292367Z","caller":"traceutil/trace.go:171","msg":"trace[2132280772] transaction","detail":"{read_only:false; response_revision:16330; number_of_response:1; }","duration":"131.794606ms","start":"2026-07-16T00:58:04.160564Z","end":"2026-07-16T00:58:04.292359Z","steps":["trace[2132280772] 'process raft request' (duration: 130.734795ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:58:04.292494Z","caller":"traceutil/trace.go:171","msg":"trace[299991215] transaction","detail":"{read_only:false; response_revision:16331; number_of_response:1; }","duration":"129.784185ms","start":"2026-07-16T00:58:04.162698Z","end":"2026-07-16T00:58:04.292482Z","steps":["trace[299991215] 'process raft request' (duration: 128.623372ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:58:04.292616Z","caller":"traceutil/trace.go:171","msg":"trace[635100778] transaction","detail":"{read_only:false; response_revision:16326; number_of_response:1; }","duration":"135.363128ms","start":"2026-07-16T00:58:04.157238Z","end":"2026-07-16T00:58:04.292601Z","steps":["trace[635100778] 'process raft request' (duration: 133.765466ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:58:04.292882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.742485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-07-16T00:58:04.292923Z","caller":"traceutil/trace.go:171","msg":"trace[449623266] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:16332; }","duration":"133.817736ms","start":"2026-07-16T00:58:04.159093Z","end":"2026-07-16T00:58:04.292911Z","steps":["trace[449623266] 'agreement among raft nodes before linearized reading' (duration: 133.661883ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:58:04.292962Z","caller":"traceutil/trace.go:171","msg":"trace[1118202731] transaction","detail":"{read_only:false; response_revision:16327; number_of_response:1; }","duration":"135.44424ms","start":"2026-07-16T00:58:04.157505Z","end":"2026-07-16T00:58:04.29295Z","steps":["trace[1118202731] 'process raft request' (duration: 133.531132ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:00:58.38264Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15318} {"level":"info","ts":"2026-07-16T01:00:58.490823Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15318,"took":"103.002706ms","hash":3984171945,"current-db-size-bytes":58818560,"current-db-size":"59 MB","current-db-size-in-use-bytes":31657984,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-16T01:00:58.49091Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3984171945,"revision":15318,"compact-revision":12857} {"level":"warn","ts":"2026-07-16T01:01:22.220217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.779837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-07-16T01:01:22.220321Z","caller":"traceutil/trace.go:171","msg":"trace[1268470291] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:17757; }","duration":"236.94158ms","start":"2026-07-16T01:01:21.98336Z","end":"2026-07-16T01:01:22.220302Z","steps":["trace[1268470291] 'range keys from in-memory index tree' (duration: 236.574102ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:01:22.220748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.795379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-07-16T01:01:22.220828Z","caller":"traceutil/trace.go:171","msg":"trace[2063767942] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:17757; }","duration":"211.897242ms","start":"2026-07-16T01:01:22.008916Z","end":"2026-07-16T01:01:22.220813Z","steps":["trace[2063767942] 'count revisions from in-memory index tree' (duration: 211.651616ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:01:22.581502Z","caller":"traceutil/trace.go:171","msg":"trace[1059431738] transaction","detail":"{read_only:false; response_revision:17761; number_of_response:1; }","duration":"103.499996ms","start":"2026-07-16T01:01:22.477966Z","end":"2026-07-16T01:01:22.581466Z","steps":["trace[1059431738] 'process raft request' (duration: 103.162759ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:01:22.636543Z","caller":"traceutil/trace.go:171","msg":"trace[819944051] linearizableReadLoop","detail":"{readStateIndex:18367; appliedIndex:18365; }","duration":"142.417517ms","start":"2026-07-16T01:01:22.494099Z","end":"2026-07-16T01:01:22.636517Z","steps":["trace[819944051] 'read index received' (duration: 87.103392ms)","trace[819944051] 'applied index is now lower than readState.Index' (duration: 55.313125ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:01:22.637044Z","caller":"traceutil/trace.go:171","msg":"trace[991520635] transaction","detail":"{read_only:false; response_revision:17762; number_of_response:1; }","duration":"156.461803ms","start":"2026-07-16T01:01:22.480551Z","end":"2026-07-16T01:01:22.637013Z","steps":["trace[991520635] 'process raft request' (duration: 155.828501ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:01:22.637325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.206163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-16T01:01:22.637393Z","caller":"traceutil/trace.go:171","msg":"trace[2109687154] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:17762; }","duration":"143.337306ms","start":"2026-07-16T01:01:22.494036Z","end":"2026-07-16T01:01:22.637373Z","steps":["trace[2109687154] 'agreement among raft nodes before linearized reading' (duration: 143.198303ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:01:22.98852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.240611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/cinder-api-66f7864d86-dszq6.18c29ec0b2650728\" ","response":"range_response_count:1 size:1178"} {"level":"info","ts":"2026-07-16T01:01:22.988605Z","caller":"traceutil/trace.go:171","msg":"trace[1987848525] range","detail":"{range_begin:/registry/events/openstack/cinder-api-66f7864d86-dszq6.18c29ec0b2650728; range_end:; response_count:1; response_revision:17763; }","duration":"134.376804ms","start":"2026-07-16T01:01:22.854206Z","end":"2026-07-16T01:01:22.988583Z","steps":["trace[1987848525] 'range keys from in-memory index tree' (duration: 134.073048ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:01:23.184913Z","caller":"traceutil/trace.go:171","msg":"trace[464238661] linearizableReadLoop","detail":"{readStateIndex:18369; appliedIndex:18368; }","duration":"167.648831ms","start":"2026-07-16T01:01:23.017241Z","end":"2026-07-16T01:01:23.184889Z","steps":["trace[464238661] 'read index received' (duration: 167.388865ms)","trace[464238661] 'applied index is now lower than readState.Index' (duration: 259.066µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:01:23.185016Z","caller":"traceutil/trace.go:171","msg":"trace[646238503] transaction","detail":"{read_only:false; response_revision:17764; number_of_response:1; }","duration":"193.835295ms","start":"2026-07-16T01:01:22.991152Z","end":"2026-07-16T01:01:23.184987Z","steps":["trace[646238503] 'process raft request' (duration: 193.58571ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:01:23.185307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.048068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-7slk8\" ","response":"range_response_count:1 size:9800"} {"level":"info","ts":"2026-07-16T01:01:23.185356Z","caller":"traceutil/trace.go:171","msg":"trace[357368499] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-7slk8; range_end:; response_count:1; response_revision:17764; }","duration":"168.157441ms","start":"2026-07-16T01:01:23.017186Z","end":"2026-07-16T01:01:23.185343Z","steps":["trace[357368499] 'agreement among raft nodes before linearized reading' (duration: 167.879775ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:01:23.301704Z","caller":"traceutil/trace.go:171","msg":"trace[351055603] transaction","detail":"{read_only:false; response_revision:17766; number_of_response:1; }","duration":"225.577629ms","start":"2026-07-16T01:01:23.076109Z","end":"2026-07-16T01:01:23.301687Z","steps":["trace[351055603] 'process raft request' (duration: 225.518388ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:01:23.30177Z","caller":"traceutil/trace.go:171","msg":"trace[1869458917] transaction","detail":"{read_only:false; response_revision:17765; number_of_response:1; }","duration":"226.418736ms","start":"2026-07-16T01:01:23.075332Z","end":"2026-07-16T01:01:23.301751Z","steps":["trace[1869458917] 'process raft request' (duration: 226.169001ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:01:23.301917Z","caller":"traceutil/trace.go:171","msg":"trace[653228678] linearizableReadLoop","detail":"{readStateIndex:18372; appliedIndex:18369; }","duration":"108.447686ms","start":"2026-07-16T01:01:23.193459Z","end":"2026-07-16T01:01:23.301907Z","steps":["trace[653228678] 'read index received' (duration: 107.976816ms)","trace[653228678] 'applied index is now lower than readState.Index' (duration: 469.66µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:01:23.301966Z","caller":"traceutil/trace.go:171","msg":"trace[2139457494] transaction","detail":"{read_only:false; response_revision:17767; number_of_response:1; }","duration":"209.502732ms","start":"2026-07-16T01:01:23.092457Z","end":"2026-07-16T01:01:23.301959Z","steps":["trace[2139457494] 'process raft request' (duration: 209.201356ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:01:23.302884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.405326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:96 size:962419"} {"level":"info","ts":"2026-07-16T01:01:23.302928Z","caller":"traceutil/trace.go:171","msg":"trace[1136743777] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:96; response_revision:17767; }","duration":"109.490818ms","start":"2026-07-16T01:01:23.193426Z","end":"2026-07-16T01:01:23.302917Z","steps":["trace[1136743777] 'agreement among raft nodes before linearized reading' (duration: 108.554598ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:02:13.059062Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6890119750305447646,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-16T01:02:13.180873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.815025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-16T01:02:13.180915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.840646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/heat-db-sync-rkw9h\" ","response":"range_response_count:1 size:6795"} {"level":"info","ts":"2026-07-16T01:02:13.180964Z","caller":"traceutil/trace.go:171","msg":"trace[876377109] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-endpoints; range_end:; response_count:0; response_revision:18371; }","duration":"659.958058ms","start":"2026-07-16T01:02:12.520985Z","end":"2026-07-16T01:02:13.180943Z","steps":["trace[876377109] 'range keys from in-memory index tree' (duration: 659.706543ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:02:13.180986Z","caller":"traceutil/trace.go:171","msg":"trace[203274408] range","detail":"{range_begin:/registry/pods/openstack/heat-db-sync-rkw9h; range_end:; response_count:1; response_revision:18371; }","duration":"632.955248ms","start":"2026-07-16T01:02:12.548012Z","end":"2026-07-16T01:02:13.180967Z","steps":["trace[203274408] 'range keys from in-memory index tree' (duration: 632.705203ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:02:13.18101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:02:12.520963Z","time spent":"660.037049ms","remote":"127.0.0.1:41488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/heat-ks-endpoints\" "} {"level":"warn","ts":"2026-07-16T01:02:13.181021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:02:12.547993Z","time spent":"633.02191ms","remote":"127.0.0.1:41432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":6818,"request content":"key:\"/registry/pods/openstack/heat-db-sync-rkw9h\" "} {"level":"warn","ts":"2026-07-16T01:02:13.180993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.319835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-bootstrap\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T01:02:13.181114Z","caller":"traceutil/trace.go:171","msg":"trace[1162878055] range","detail":"{range_begin:/registry/jobs/openstack/heat-bootstrap; range_end:; response_count:0; response_revision:18371; }","duration":"659.470608ms","start":"2026-07-16T01:02:12.521613Z","end":"2026-07-16T01:02:13.181084Z","steps":["trace[1162878055] 'range keys from in-memory index tree' (duration: 659.218563ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:02:13.181213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:02:12.521553Z","time spent":"659.646551ms","remote":"127.0.0.1:41488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/heat-bootstrap\" "} {"level":"warn","ts":"2026-07-16T01:02:13.181354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"871.161234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"warn","ts":"2026-07-16T01:02:13.181576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.05592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T01:02:13.181603Z","caller":"traceutil/trace.go:171","msg":"trace[1439372487] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:18371; }","duration":"871.401299ms","start":"2026-07-16T01:02:12.310184Z","end":"2026-07-16T01:02:13.181585Z","steps":["trace[1439372487] 'range keys from in-memory index tree' (duration: 870.990622ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:02:13.18111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"909.211029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-16T01:02:13.181772Z","caller":"traceutil/trace.go:171","msg":"trace[137260113] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:18371; }","duration":"909.912993ms","start":"2026-07-16T01:02:12.271815Z","end":"2026-07-16T01:02:13.181728Z","steps":["trace[137260113] 'count revisions from in-memory index tree' (duration: 909.099786ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:02:13.181893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.086009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-db-sync\" ","response":"range_response_count:1 size:5032"} {"level":"warn","ts":"2026-07-16T01:02:13.181894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.353456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T01:02:13.181965Z","caller":"traceutil/trace.go:171","msg":"trace[1497671207] range","detail":"{range_begin:/registry/jobs/openstack/heat-db-sync; range_end:; response_count:1; response_revision:18371; }","duration":"658.178641ms","start":"2026-07-16T01:02:12.523752Z","end":"2026-07-16T01:02:13.18193Z","steps":["trace[1497671207] 'range keys from in-memory index tree' (duration: 657.999098ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:02:13.181979Z","caller":"traceutil/trace.go:171","msg":"trace[28601011] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-user; range_end:; response_count:0; response_revision:18371; }","duration":"660.444887ms","start":"2026-07-16T01:02:12.521513Z","end":"2026-07-16T01:02:13.181958Z","steps":["trace[28601011] 'range keys from in-memory index tree' (duration: 660.261124ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:02:13.181998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:02:12.523743Z","time spent":"658.248723ms","remote":"127.0.0.1:41488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5055,"request content":"key:\"/registry/jobs/openstack/heat-db-sync\" "} {"level":"warn","ts":"2026-07-16T01:02:13.18194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.074578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-16T01:02:13.182023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:02:12.521481Z","time spent":"660.53235ms","remote":"127.0.0.1:41488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/heat-ks-user\" "} {"level":"warn","ts":"2026-07-16T01:02:13.182014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:02:12.27179Z","time spent":"910.127748ms","remote":"127.0.0.1:52148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":31,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-07-16T01:02:13.18207Z","caller":"traceutil/trace.go:171","msg":"trace[293760976] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18371; }","duration":"743.248841ms","start":"2026-07-16T01:02:12.438795Z","end":"2026-07-16T01:02:13.182043Z","steps":["trace[293760976] 'range keys from in-memory index tree' (duration: 742.944706ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:02:13.182124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:02:12.438773Z","time spent":"743.338764ms","remote":"127.0.0.1:41264","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-16T01:02:13.18135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.894543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-16T01:02:13.182259Z","caller":"traceutil/trace.go:171","msg":"trace[1521598298] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18371; }","duration":"681.755301ms","start":"2026-07-16T01:02:12.500412Z","end":"2026-07-16T01:02:13.182167Z","steps":["trace[1521598298] 'range keys from in-memory index tree' (duration: 680.683999ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:02:13.18139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.461958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-16T01:02:13.182306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:02:12.50039Z","time spent":"681.902543ms","remote":"127.0.0.1:41514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-16T01:02:13.182352Z","caller":"traceutil/trace.go:171","msg":"trace[1131592056] range","detail":"{range_begin:/registry/jobs/openstack/heat-domain-ks-user; range_end:; response_count:0; response_revision:18371; }","duration":"662.433227ms","start":"2026-07-16T01:02:12.519905Z","end":"2026-07-16T01:02:13.182339Z","steps":["trace[1131592056] 'range keys from in-memory index tree' (duration: 661.389727ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:02:13.182393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:02:12.519893Z","time spent":"662.488069ms","remote":"127.0.0.1:41488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" "} {"level":"warn","ts":"2026-07-16T01:02:13.18164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:02:12.310163Z","time spent":"871.470651ms","remote":"127.0.0.1:41404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1889,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" "} {"level":"info","ts":"2026-07-16T01:02:13.181638Z","caller":"traceutil/trace.go:171","msg":"trace[965038241] range","detail":"{range_begin:/registry/jobs/openstack/heat-rabbit-init; range_end:; response_count:0; response_revision:18371; }","duration":"660.128362ms","start":"2026-07-16T01:02:12.521494Z","end":"2026-07-16T01:02:13.181622Z","steps":["trace[965038241] 'range keys from in-memory index tree' (duration: 659.972158ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:02:13.182698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:02:12.521485Z","time spent":"661.196903ms","remote":"127.0.0.1:41488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/heat-rabbit-init\" "} {"level":"info","ts":"2026-07-16T01:02:13.184636Z","caller":"traceutil/trace.go:171","msg":"trace[1405605602] linearizableReadLoop","detail":"{readStateIndex:18994; appliedIndex:18993; }","duration":"625.992585ms","start":"2026-07-16T01:02:12.558624Z","end":"2026-07-16T01:02:13.184617Z","steps":["trace[1405605602] 'read index received' (duration: 625.825442ms)","trace[1405605602] 'applied index is now lower than readState.Index' (duration: 166.113µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:02:13.184812Z","caller":"traceutil/trace.go:171","msg":"trace[1463513094] transaction","detail":"{read_only:false; response_revision:18372; number_of_response:1; }","duration":"635.736495ms","start":"2026-07-16T01:02:12.549036Z","end":"2026-07-16T01:02:13.184773Z","steps":["trace[1463513094] 'process raft request' (duration: 635.461959ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:02:13.184865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.23627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-16T01:02:13.184906Z","caller":"traceutil/trace.go:171","msg":"trace[254836713] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18372; }","duration":"626.309262ms","start":"2026-07-16T01:02:12.558586Z","end":"2026-07-16T01:02:13.184895Z","steps":["trace[254836713] 'agreement among raft nodes before linearized reading' (duration: 626.20741ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:02:13.184964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:02:12.558568Z","time spent":"626.387213ms","remote":"127.0.0.1:41514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-16T01:02:13.184972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:02:12.54902Z","time spent":"635.854267ms","remote":"127.0.0.1:41342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":847,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-16T01:02:13.185226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.305374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T01:02:13.185269Z","caller":"traceutil/trace.go:171","msg":"trace[305362419] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-endpoints; range_end:; response_count:0; response_revision:18372; }","duration":"603.369855ms","start":"2026-07-16T01:02:12.581889Z","end":"2026-07-16T01:02:13.185259Z","steps":["trace[305362419] 'agreement among raft nodes before linearized reading' (duration: 603.243293ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:02:13.185259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.330612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-16T01:02:13.185279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.169355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-bootstrap\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-16T01:02:13.185291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:02:12.581881Z","time spent":"603.404826ms","remote":"127.0.0.1:41488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/heat-ks-endpoints\" "} {"level":"info","ts":"2026-07-16T01:02:13.185317Z","caller":"traceutil/trace.go:171","msg":"trace[856760897] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18372; }","duration":"626.399314ms","start":"2026-07-16T01:02:12.558899Z","end":"2026-07-16T01:02:13.185298Z","steps":["trace[856760897] 'agreement among raft nodes before linearized reading' (duration: 626.166129ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:02:13.185356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.140535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-16T01:02:13.185364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:02:12.558893Z","time spent":"626.458675ms","remote":"127.0.0.1:41514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-16T01:02:13.185386Z","caller":"traceutil/trace.go:171","msg":"trace[332226414] range","detail":"{range_begin:/registry/jobs/openstack/heat-domain-ks-user; range_end:; response_count:0; response_revision:18372; }","duration":"586.188946ms","start":"2026-07-16T01:02:12.599191Z","end":"2026-07-16T01:02:13.18538Z","steps":["trace[332226414] 'agreement among raft nodes before linearized reading' (duration: 586.139465ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:02:13.185413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:02:12.599181Z","time spent":"586.226357ms","remote":"127.0.0.1:41488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" "} {"level":"warn","ts":"2026-07-16T01:02:13.185469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.832805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-db-sync\" ","response":"range_response_count:1 size:5032"} {"level":"warn","ts":"2026-07-16T01:02:13.185496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.234416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T01:02:13.185507Z","caller":"traceutil/trace.go:171","msg":"trace[178584271] range","detail":"{range_begin:/registry/jobs/openstack/heat-db-sync; range_end:; response_count:1; response_revision:18372; }","duration":"606.888437ms","start":"2026-07-16T01:02:12.578609Z","end":"2026-07-16T01:02:13.185497Z","steps":["trace[178584271] 'agreement among raft nodes before linearized reading' (duration: 606.704853ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:02:13.185523Z","caller":"traceutil/trace.go:171","msg":"trace[541066446] range","detail":"{range_begin:/registry/jobs/openstack/heat-rabbit-init; range_end:; response_count:0; response_revision:18372; }","duration":"610.279776ms","start":"2026-07-16T01:02:12.575235Z","end":"2026-07-16T01:02:13.185515Z","steps":["trace[541066446] 'agreement among raft nodes before linearized reading' (duration: 610.240616ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:02:13.185532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:02:12.578598Z","time spent":"606.928898ms","remote":"127.0.0.1:41488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5055,"request content":"key:\"/registry/jobs/openstack/heat-db-sync\" "} {"level":"warn","ts":"2026-07-16T01:02:13.185548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:02:12.575223Z","time spent":"610.318828ms","remote":"127.0.0.1:41488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/heat-rabbit-init\" "} {"level":"warn","ts":"2026-07-16T01:02:13.185585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.593765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-16T01:02:13.185623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.195864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-bootstrap\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T01:02:13.185636Z","caller":"traceutil/trace.go:171","msg":"trace[409099559] range","detail":"{range_begin:/registry/jobs/openstack/heat-rabbit-init; range_end:; response_count:0; response_revision:18372; }","duration":"586.669606ms","start":"2026-07-16T01:02:12.598958Z","end":"2026-07-16T01:02:13.185628Z","steps":["trace[409099559] 'agreement among raft nodes before linearized reading' (duration: 586.600434ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:02:13.185647Z","caller":"traceutil/trace.go:171","msg":"trace[1816844734] range","detail":"{range_begin:/registry/jobs/openstack/heat-bootstrap; range_end:; response_count:0; response_revision:18372; }","duration":"610.241635ms","start":"2026-07-16T01:02:12.575398Z","end":"2026-07-16T01:02:13.18564Z","steps":["trace[1816844734] 'agreement among raft nodes before linearized reading' (duration: 610.202244ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:02:13.185662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:02:12.598947Z","time spent":"586.708756ms","remote":"127.0.0.1:41488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/heat-rabbit-init\" "} {"level":"warn","ts":"2026-07-16T01:02:13.185709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.950837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-16T01:02:13.185714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.543837ms","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":"warn","ts":"2026-07-16T01:02:13.185776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.60486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-16T01:02:13.185803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.855363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-16T01:02:13.185307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.048444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T01:02:13.185817Z","caller":"traceutil/trace.go:171","msg":"trace[562494566] range","detail":"{range_begin:/registry/jobs/openstack/heat-domain-ks-user; range_end:; response_count:0; response_revision:18372; }","duration":"603.700562ms","start":"2026-07-16T01:02:12.58211Z","end":"2026-07-16T01:02:13.185811Z","steps":["trace[562494566] 'agreement among raft nodes before linearized reading' (duration: 603.626001ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:02:13.185843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:02:12.582089Z","time spent":"603.747803ms","remote":"127.0.0.1:41488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" "} {"level":"info","ts":"2026-07-16T01:02:13.185744Z","caller":"traceutil/trace.go:171","msg":"trace[265329850] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-user; range_end:; response_count:0; response_revision:18372; }","duration":"604.007258ms","start":"2026-07-16T01:02:12.581729Z","end":"2026-07-16T01:02:13.185736Z","steps":["trace[265329850] 'agreement among raft nodes before linearized reading' (duration: 603.951657ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:02:13.1859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.896037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-db-sync\" ","response":"range_response_count:1 size:5032"} {"level":"warn","ts":"2026-07-16T01:02:13.185934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.057079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-29736060-pbzf6\" ","response":"range_response_count:1 size:7040"} {"level":"warn","ts":"2026-07-16T01:02:13.185935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:02:12.581718Z","time spent":"604.208103ms","remote":"127.0.0.1:41488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/heat-ks-user\" "} {"level":"info","ts":"2026-07-16T01:02:13.185976Z","caller":"traceutil/trace.go:171","msg":"trace[1437424540] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-29736060-pbzf6; range_end:; response_count:1; response_revision:18372; }","duration":"196.098369ms","start":"2026-07-16T01:02:12.989868Z","end":"2026-07-16T01:02:13.185966Z","steps":["trace[1437424540] 'agreement among raft nodes before linearized reading' (duration: 195.988777ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:02:13.185999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.431135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-16T01:02:13.186031Z","caller":"traceutil/trace.go:171","msg":"trace[444695244] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18372; }","duration":"337.507237ms","start":"2026-07-16T01:02:12.848517Z","end":"2026-07-16T01:02:13.186024Z","steps":["trace[444695244] 'agreement among raft nodes before linearized reading' (duration: 337.440275ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:02:13.186056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:02:12.848497Z","time spent":"337.555038ms","remote":"127.0.0.1:41514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-16T01:02:13.185799Z","caller":"traceutil/trace.go:171","msg":"trace[949062822] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18372; }","duration":"163.624209ms","start":"2026-07-16T01:02:13.02215Z","end":"2026-07-16T01:02:13.185775Z","steps":["trace[949062822] 'agreement among raft nodes before linearized reading' (duration: 163.504476ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:02:13.185937Z","caller":"traceutil/trace.go:171","msg":"trace[970823728] range","detail":"{range_begin:/registry/jobs/openstack/heat-db-sync; range_end:; response_count:1; response_revision:18372; }","duration":"578.943549ms","start":"2026-07-16T01:02:12.606985Z","end":"2026-07-16T01:02:13.185929Z","steps":["trace[970823728] 'agreement among raft nodes before linearized reading' (duration: 578.845987ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:02:13.186215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:02:12.606979Z","time spent":"579.225475ms","remote":"127.0.0.1:41488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5055,"request content":"key:\"/registry/jobs/openstack/heat-db-sync\" "} {"level":"info","ts":"2026-07-16T01:02:13.18584Z","caller":"traceutil/trace.go:171","msg":"trace[1065602253] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18372; }","duration":"565.909734ms","start":"2026-07-16T01:02:12.619918Z","end":"2026-07-16T01:02:13.185827Z","steps":["trace[1065602253] 'agreement among raft nodes before linearized reading' (duration: 565.856103ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:02:13.186321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:02:12.619906Z","time spent":"566.409234ms","remote":"127.0.0.1:41276","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-16T01:02:13.185675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:02:12.575385Z","time spent":"610.280515ms","remote":"127.0.0.1:41488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/heat-bootstrap\" "} {"level":"info","ts":"2026-07-16T01:02:13.185311Z","caller":"traceutil/trace.go:171","msg":"trace[944021651] range","detail":"{range_begin:/registry/jobs/openstack/heat-bootstrap; range_end:; response_count:0; response_revision:18372; }","duration":"586.222857ms","start":"2026-07-16T01:02:12.599079Z","end":"2026-07-16T01:02:13.185301Z","steps":["trace[944021651] 'agreement among raft nodes before linearized reading' (duration: 586.170566ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:02:13.186419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:02:12.599062Z","time spent":"587.34513ms","remote":"127.0.0.1:41488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/heat-bootstrap\" "} {"level":"warn","ts":"2026-07-16T01:02:13.185811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.790128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T01:02:13.186523Z","caller":"traceutil/trace.go:171","msg":"trace[756947416] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-endpoints; range_end:; response_count:0; response_revision:18372; }","duration":"586.513093ms","start":"2026-07-16T01:02:12.599999Z","end":"2026-07-16T01:02:13.186512Z","steps":["trace[756947416] 'agreement among raft nodes before linearized reading' (duration: 585.783658ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:02:13.186597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:02:12.599989Z","time spent":"586.566974ms","remote":"127.0.0.1:41488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/heat-ks-endpoints\" "} {"level":"info","ts":"2026-07-16T01:02:13.185845Z","caller":"traceutil/trace.go:171","msg":"trace[1157627975] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-user; range_end:; response_count:0; response_revision:18372; }","duration":"586.606225ms","start":"2026-07-16T01:02:12.599228Z","end":"2026-07-16T01:02:13.185834Z","steps":["trace[1157627975] 'agreement among raft nodes before linearized reading' (duration: 585.89574ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:02:13.186669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:02:12.599219Z","time spent":"587.444772ms","remote":"127.0.0.1:41488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/heat-ks-user\" "} {"level":"info","ts":"2026-07-16T01:04:23.241446Z","caller":"traceutil/trace.go:171","msg":"trace[340590775] linearizableReadLoop","detail":"{readStateIndex:19945; appliedIndex:19944; }","duration":"347.496933ms","start":"2026-07-16T01:04:22.893915Z","end":"2026-07-16T01:04:23.241412Z","steps":["trace[340590775] 'read index received' (duration: 347.259148ms)","trace[340590775] 'applied index is now lower than readState.Index' (duration: 236.725µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:04:23.241647Z","caller":"traceutil/trace.go:171","msg":"trace[1606352851] transaction","detail":"{read_only:false; response_revision:19295; number_of_response:1; }","duration":"351.792541ms","start":"2026-07-16T01:04:22.889846Z","end":"2026-07-16T01:04:23.241639Z","steps":["trace[1606352851] 'process raft request' (duration: 351.443714ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:04:23.241725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:04:22.889831Z","time spent":"351.833631ms","remote":"127.0.0.1:41514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T01:04:23.241859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.942753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-07-16T01:04:23.241879Z","caller":"traceutil/trace.go:171","msg":"trace[1298844717] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:19295; }","duration":"347.973893ms","start":"2026-07-16T01:04:22.893898Z","end":"2026-07-16T01:04:23.241872Z","steps":["trace[1298844717] 'agreement among raft nodes before linearized reading' (duration: 347.900602ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:04:23.241896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:04:22.893886Z","time spent":"348.006214ms","remote":"127.0.0.1:41290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":163,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"info","ts":"2026-07-16T01:04:23.245891Z","caller":"traceutil/trace.go:171","msg":"trace[180085337] transaction","detail":"{read_only:false; response_revision:19296; number_of_response:1; }","duration":"351.684659ms","start":"2026-07-16T01:04:22.894192Z","end":"2026-07-16T01:04:23.245876Z","steps":["trace[180085337] 'process raft request' (duration: 351.2348ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:04:23.246002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:04:22.894175Z","time spent":"351.774221ms","remote":"127.0.0.1:41514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T01:04:31.292098Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"380f6760faea5f9e","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-16T01:04:31.296032Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-16T01:04:31.296253Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-07-16T01:05:58.389231Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17602} {"level":"info","ts":"2026-07-16T01:05:58.484716Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17602,"took":"91.233952ms","hash":723005152,"current-db-size-bytes":58818560,"current-db-size":"59 MB","current-db-size-in-use-bytes":34578432,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-07-16T01:05:58.484782Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":723005152,"revision":17602,"compact-revision":15318} {"level":"info","ts":"2026-07-16T01:07:01.314214Z","caller":"traceutil/trace.go:171","msg":"trace[1085031108] transaction","detail":"{read_only:false; response_revision:20444; number_of_response:1; }","duration":"153.066316ms","start":"2026-07-16T01:07:01.161112Z","end":"2026-07-16T01:07:01.314178Z","steps":["trace[1085031108] 'process raft request' (duration: 152.800591ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:07:17.650958Z","caller":"traceutil/trace.go:171","msg":"trace[1163466938] transaction","detail":"{read_only:false; response_revision:20529; number_of_response:1; }","duration":"131.972299ms","start":"2026-07-16T01:07:17.518951Z","end":"2026-07-16T01:07:17.650923Z","steps":["trace[1163466938] 'process raft request' (duration: 131.770585ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:07:18.204362Z","caller":"traceutil/trace.go:171","msg":"trace[961130291] transaction","detail":"{read_only:false; response_revision:20531; number_of_response:1; }","duration":"345.235617ms","start":"2026-07-16T01:07:17.859087Z","end":"2026-07-16T01:07:18.204323Z","steps":["trace[961130291] 'process raft request' (duration: 274.952431ms)","trace[961130291] 'compare' (duration: 70.022741ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T01:07:18.204616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:07:17.859059Z","time spent":"345.413712ms","remote":"127.0.0.1:41514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T01:07:18.204661Z","caller":"traceutil/trace.go:171","msg":"trace[872246447] transaction","detail":"{read_only:false; response_revision:20532; number_of_response:1; }","duration":"328.794414ms","start":"2026-07-16T01:07:17.875832Z","end":"2026-07-16T01:07:18.204627Z","steps":["trace[872246447] 'process raft request' (duration: 328.432526ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:07:18.204852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:07:17.87582Z","time spent":"328.931367ms","remote":"127.0.0.1:41514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T01:07:18.204901Z","caller":"traceutil/trace.go:171","msg":"trace[264382457] linearizableReadLoop","detail":"{readStateIndex:21225; appliedIndex:21223; }","duration":"334.034451ms","start":"2026-07-16T01:07:17.870831Z","end":"2026-07-16T01:07:18.204866Z","steps":["trace[264382457] 'read index received' (duration: 49.895713ms)","trace[264382457] 'applied index is now lower than readState.Index' (duration: 284.136218ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:07:18.204989Z","caller":"traceutil/trace.go:171","msg":"trace[1554139859] transaction","detail":"{read_only:false; response_revision:20533; number_of_response:1; }","duration":"297.581131ms","start":"2026-07-16T01:07:17.907375Z","end":"2026-07-16T01:07:18.204956Z","steps":["trace[1554139859] 'process raft request' (duration: 297.133352ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:07:18.205148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.302146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-16T01:07:18.205234Z","caller":"traceutil/trace.go:171","msg":"trace[1737311588] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20533; }","duration":"334.429609ms","start":"2026-07-16T01:07:17.870785Z","end":"2026-07-16T01:07:18.205215Z","steps":["trace[1737311588] 'agreement among raft nodes before linearized reading' (duration: 334.204454ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:07:18.205276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:07:17.870765Z","time spent":"334.5019ms","remote":"127.0.0.1:41514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-16T01:07:18.383139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.076254ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-16T01:07:18.383629Z","caller":"traceutil/trace.go:171","msg":"trace[88479888] linearizableReadLoop","detail":"{readStateIndex:21229; appliedIndex:21227; }","duration":"150.976663ms","start":"2026-07-16T01:07:18.232623Z","end":"2026-07-16T01:07:18.383599Z","steps":["trace[88479888] 'read index received' (duration: 102.912µs)","trace[88479888] 'applied index is now lower than readState.Index' (duration: 150.871701ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:07:18.383996Z","caller":"traceutil/trace.go:171","msg":"trace[865462940] transaction","detail":"{read_only:false; response_revision:20534; number_of_response:1; }","duration":"174.137886ms","start":"2026-07-16T01:07:18.209829Z","end":"2026-07-16T01:07:18.383967Z","steps":["trace[865462940] 'process raft request' (duration: 173.432041ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:07:18.441299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.605686ms","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:6"} {"level":"warn","ts":"2026-07-16T01:07:18.441308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.501939ms","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-16T01:07:18.441382Z","caller":"traceutil/trace.go:171","msg":"trace[412709198] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:20534; }","duration":"114.755129ms","start":"2026-07-16T01:07:18.326605Z","end":"2026-07-16T01:07:18.44136Z","steps":["trace[412709198] 'agreement among raft nodes before linearized reading' (duration: 57.266703ms)","trace[412709198] 'count revisions from in-memory index tree' (duration: 57.356314ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T01:07:18.441346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.670795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-07-16T01:07:18.441444Z","caller":"traceutil/trace.go:171","msg":"trace[216071677] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20534; }","duration":"209.442311ms","start":"2026-07-16T01:07:18.231983Z","end":"2026-07-16T01:07:18.441426Z","steps":["trace[216071677] 'agreement among raft nodes before linearized reading' (duration: 151.853463ms)","trace[216071677] 'range keys from in-memory index tree' (duration: 57.146519ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:07:18.441472Z","caller":"traceutil/trace.go:171","msg":"trace[373644560] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20534; }","duration":"198.656012ms","start":"2026-07-16T01:07:18.242719Z","end":"2026-07-16T01:07:18.441375Z","steps":["trace[373644560] 'agreement among raft nodes before linearized reading' (duration: 141.156356ms)","trace[373644560] 'range keys from in-memory index tree' (duration: 57.188371ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T01:07:18.441567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.878707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-07-16T01:07:18.441575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.186416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-16T01:07:18.441654Z","caller":"traceutil/trace.go:171","msg":"trace[2107011840] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20534; }","duration":"199.01072ms","start":"2026-07-16T01:07:18.24262Z","end":"2026-07-16T01:07:18.441631Z","steps":["trace[2107011840] 'agreement among raft nodes before linearized reading' (duration: 141.272668ms)","trace[2107011840] 'range keys from in-memory index tree' (duration: 57.248632ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:07:18.441717Z","caller":"traceutil/trace.go:171","msg":"trace[206072326] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20534; }","duration":"207.306258ms","start":"2026-07-16T01:07:18.234334Z","end":"2026-07-16T01:07:18.441641Z","steps":["trace[206072326] 'agreement among raft nodes before linearized reading' (duration: 149.543286ms)","trace[206072326] 'range keys from in-memory index tree' (duration: 57.541578ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:08:02.266252Z","caller":"traceutil/trace.go:171","msg":"trace[259083081] transaction","detail":"{read_only:false; response_revision:21023; number_of_response:1; }","duration":"111.597705ms","start":"2026-07-16T01:08:02.154617Z","end":"2026-07-16T01:08:02.266215Z","steps":["trace[259083081] 'process raft request' (duration: 110.785209ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:10:58.396944Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20094} {"level":"info","ts":"2026-07-16T01:10:58.51458Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20094,"took":"114.078925ms","hash":213273777,"current-db-size-bytes":58818560,"current-db-size":"59 MB","current-db-size-in-use-bytes":30736384,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-16T01:10:58.514643Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":213273777,"revision":20094,"compact-revision":17602} {"level":"info","ts":"2026-07-16T01:11:17.607847Z","caller":"traceutil/trace.go:171","msg":"trace[933468428] linearizableReadLoop","detail":"{readStateIndex:23797; appliedIndex:23796; }","duration":"217.453234ms","start":"2026-07-16T01:11:17.39037Z","end":"2026-07-16T01:11:17.607823Z","steps":["trace[933468428] 'read index received' (duration: 217.303951ms)","trace[933468428] 'applied index is now lower than readState.Index' (duration: 148.383µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:11:17.60789Z","caller":"traceutil/trace.go:171","msg":"trace[1187008491] transaction","detail":"{read_only:false; response_revision:23030; number_of_response:1; }","duration":"251.074057ms","start":"2026-07-16T01:11:17.35679Z","end":"2026-07-16T01:11:17.607864Z","steps":["trace[1187008491] 'process raft request' (duration: 250.859603ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:11:17.608026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.647448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18249"} {"level":"info","ts":"2026-07-16T01:11:17.60807Z","caller":"traceutil/trace.go:171","msg":"trace[751985194] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:23030; }","duration":"217.844712ms","start":"2026-07-16T01:11:17.390214Z","end":"2026-07-16T01:11:17.608059Z","steps":["trace[751985194] 'agreement among raft nodes before linearized reading' (duration: 217.7435ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:11:17.608292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.086422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-16T01:11:17.608461Z","caller":"traceutil/trace.go:171","msg":"trace[1789260383] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:23030; }","duration":"100.371057ms","start":"2026-07-16T01:11:17.50807Z","end":"2026-07-16T01:11:17.608441Z","steps":["trace[1789260383] 'agreement among raft nodes before linearized reading' (duration: 100.00797ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:11:28.74511Z","caller":"traceutil/trace.go:171","msg":"trace[1085197675] transaction","detail":"{read_only:false; response_revision:23152; number_of_response:1; }","duration":"116.74808ms","start":"2026-07-16T01:11:28.628341Z","end":"2026-07-16T01:11:28.745089Z","steps":["trace[1085197675] 'process raft request' (duration: 116.613288ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:11:28.746059Z","caller":"traceutil/trace.go:171","msg":"trace[1905398225] transaction","detail":"{read_only:false; response_revision:23153; number_of_response:1; }","duration":"114.024674ms","start":"2026-07-16T01:11:28.632022Z","end":"2026-07-16T01:11:28.746046Z","steps":["trace[1905398225] 'process raft request' (duration: 113.949903ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:14:42.013643Z","caller":"traceutil/trace.go:171","msg":"trace[1370115709] linearizableReadLoop","detail":"{readStateIndex:25900; appliedIndex:25899; }","duration":"390.201775ms","start":"2026-07-16T01:14:41.62341Z","end":"2026-07-16T01:14:42.013612Z","steps":["trace[1370115709] 'read index received' (duration: 390.026782ms)","trace[1370115709] 'applied index is now lower than readState.Index' (duration: 173.183µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:14:42.013714Z","caller":"traceutil/trace.go:171","msg":"trace[1585457408] transaction","detail":"{read_only:false; response_revision:25082; number_of_response:1; }","duration":"521.285626ms","start":"2026-07-16T01:14:41.492397Z","end":"2026-07-16T01:14:42.013682Z","steps":["trace[1585457408] 'process raft request' (duration: 521.02474ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:14:42.013869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.436032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T01:14:42.013911Z","caller":"traceutil/trace.go:171","msg":"trace[422213932] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25082; }","duration":"390.538423ms","start":"2026-07-16T01:14:41.623362Z","end":"2026-07-16T01:14:42.0139Z","steps":["trace[422213932] 'agreement among raft nodes before linearized reading' (duration: 390.433311ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:14:42.013909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:14:41.492371Z","time spent":"521.426609ms","remote":"127.0.0.1:41514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T01:14:42.013949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:14:41.623344Z","time spent":"390.597415ms","remote":"127.0.0.1:41264","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-16T01:14:42.309367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.480027ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-16T01:14:42.309551Z","caller":"traceutil/trace.go:171","msg":"trace[99160260] linearizableReadLoop","detail":"{readStateIndex:25904; appliedIndex:25900; }","duration":"295.774843ms","start":"2026-07-16T01:14:42.013763Z","end":"2026-07-16T01:14:42.309538Z","steps":["trace[99160260] 'read index received' (duration: 137.061361ms)","trace[99160260] 'applied index is now lower than readState.Index' (duration: 158.712372ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:14:42.309619Z","caller":"traceutil/trace.go:171","msg":"trace[1033017882] transaction","detail":"{read_only:false; response_revision:25084; number_of_response:1; }","duration":"487.844495ms","start":"2026-07-16T01:14:41.821768Z","end":"2026-07-16T01:14:42.309613Z","steps":["trace[1033017882] 'process raft request' (duration: 487.672401ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:14:42.309625Z","caller":"traceutil/trace.go:171","msg":"trace[1255827428] transaction","detail":"{read_only:false; response_revision:25083; number_of_response:1; }","duration":"514.346484ms","start":"2026-07-16T01:14:41.795189Z","end":"2026-07-16T01:14:42.309535Z","steps":["trace[1255827428] 'process raft request' (duration: 355.619262ms)","trace[1255827428] 'compare' (duration: 157.971487ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:14:42.309688Z","caller":"traceutil/trace.go:171","msg":"trace[1776811333] transaction","detail":"{read_only:false; response_revision:25085; number_of_response:1; }","duration":"479.638947ms","start":"2026-07-16T01:14:41.830044Z","end":"2026-07-16T01:14:42.309682Z","steps":["trace[1776811333] 'process raft request' (duration: 479.433333ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:14:42.30972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:14:41.830029Z","time spent":"479.674817ms","remote":"127.0.0.1:41514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T01:14:42.309804Z","caller":"traceutil/trace.go:171","msg":"trace[1340944199] transaction","detail":"{read_only:false; response_revision:25086; number_of_response:1; }","duration":"316.848642ms","start":"2026-07-16T01:14:41.992949Z","end":"2026-07-16T01:14:42.309798Z","steps":["trace[1340944199] 'process raft request' (duration: 316.552566ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:14:42.309842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:14:41.795159Z","time spent":"514.536097ms","remote":"127.0.0.1:41514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T01:14:42.30966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:14:41.821744Z","time spent":"487.894035ms","remote":"127.0.0.1:41514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T01:14:42.309979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.874165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T01:14:42.310006Z","caller":"traceutil/trace.go:171","msg":"trace[968363133] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25086; }","duration":"293.940176ms","start":"2026-07-16T01:14:42.01606Z","end":"2026-07-16T01:14:42.31Z","steps":["trace[968363133] 'agreement among raft nodes before linearized reading' (duration: 293.902626ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:14:42.31009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.17611ms","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:6"} {"level":"warn","ts":"2026-07-16T01:14:42.309856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:14:41.992916Z","time spent":"316.899744ms","remote":"127.0.0.1:41514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T01:14:42.310126Z","caller":"traceutil/trace.go:171","msg":"trace[386832889] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:25086; }","duration":"183.267652ms","start":"2026-07-16T01:14:42.126847Z","end":"2026-07-16T01:14:42.310114Z","steps":["trace[386832889] 'agreement among raft nodes before linearized reading' (duration: 183.154799ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:14:42.310129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.981207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T01:14:42.310202Z","caller":"traceutil/trace.go:171","msg":"trace[773787685] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:25086; }","duration":"241.174601ms","start":"2026-07-16T01:14:42.069009Z","end":"2026-07-16T01:14:42.310184Z","steps":["trace[773787685] 'agreement among raft nodes before linearized reading' (duration: 241.051559ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:14:42.309945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.178547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-07-16T01:14:42.310351Z","caller":"traceutil/trace.go:171","msg":"trace[809386871] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25086; }","duration":"311.619055ms","start":"2026-07-16T01:14:41.998725Z","end":"2026-07-16T01:14:42.310344Z","steps":["trace[809386871] 'agreement among raft nodes before linearized reading' (duration: 311.146336ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:14:42.310377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:14:41.998712Z","time spent":"311.658497ms","remote":"127.0.0.1:41432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-07-16T01:14:42.733334Z","caller":"traceutil/trace.go:171","msg":"trace[277006486] transaction","detail":"{read_only:false; response_revision:25087; number_of_response:1; }","duration":"302.46545ms","start":"2026-07-16T01:14:42.430835Z","end":"2026-07-16T01:14:42.7333Z","steps":["trace[277006486] 'process raft request' (duration: 302.286386ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:14:42.733514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:14:42.430815Z","time spent":"302.600522ms","remote":"127.0.0.1:41514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T01:14:42.959146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.05816ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-16T01:14:42.959255Z","caller":"traceutil/trace.go:171","msg":"trace[442603685] linearizableReadLoop","detail":"{readStateIndex:25906; appliedIndex:25905; }","duration":"106.093251ms","start":"2026-07-16T01:14:42.853146Z","end":"2026-07-16T01:14:42.959239Z","steps":["trace[442603685] 'read index received' (duration: 81.922µs)","trace[442603685] 'applied index is now lower than readState.Index' (duration: 106.009839ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:14:42.959363Z","caller":"traceutil/trace.go:171","msg":"trace[1895765227] transaction","detail":"{read_only:false; response_revision:25088; number_of_response:1; }","duration":"209.351104ms","start":"2026-07-16T01:14:42.74997Z","end":"2026-07-16T01:14:42.959321Z","steps":["trace[1895765227] 'process raft request' (duration: 74.010468ms)","trace[1895765227] 'compare' (duration: 134.829466ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T01:14:42.959463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.307795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-16T01:14:42.959545Z","caller":"traceutil/trace.go:171","msg":"trace[1910015495] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25088; }","duration":"106.441958ms","start":"2026-07-16T01:14:42.853085Z","end":"2026-07-16T01:14:42.959527Z","steps":["trace[1910015495] 'agreement among raft nodes before linearized reading' (duration: 106.223054ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:14:43.157394Z","caller":"traceutil/trace.go:171","msg":"trace[587279980] linearizableReadLoop","detail":"{readStateIndex:25907; appliedIndex:25906; }","duration":"198.080374ms","start":"2026-07-16T01:14:42.959285Z","end":"2026-07-16T01:14:43.157365Z","steps":["trace[587279980] 'read index received' (duration: 134.210763ms)","trace[587279980] 'applied index is now lower than readState.Index' (duration: 63.868551ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:14:43.157523Z","caller":"traceutil/trace.go:171","msg":"trace[339045107] transaction","detail":"{read_only:false; response_revision:25089; number_of_response:1; }","duration":"217.51941ms","start":"2026-07-16T01:14:42.939991Z","end":"2026-07-16T01:14:43.15751Z","steps":["trace[339045107] 'process raft request' (duration: 153.507317ms)","trace[339045107] 'compare' (duration: 63.515093ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T01:14:43.157702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.492273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.222\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-07-16T01:14:43.157739Z","caller":"traceutil/trace.go:171","msg":"trace[493965536] range","detail":"{range_begin:/registry/masterleases/199.204.45.222; range_end:; response_count:1; response_revision:25089; }","duration":"255.582114ms","start":"2026-07-16T01:14:42.902146Z","end":"2026-07-16T01:14:43.157728Z","steps":["trace[493965536] 'agreement among raft nodes before linearized reading' (duration: 255.448462ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:14:43.160081Z","caller":"traceutil/trace.go:171","msg":"trace[212174402] transaction","detail":"{read_only:false; response_revision:25090; number_of_response:1; }","duration":"195.110934ms","start":"2026-07-16T01:14:42.964946Z","end":"2026-07-16T01:14:43.160057Z","steps":["trace[212174402] 'process raft request' (duration: 194.869859ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:14:43.160403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.167993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-16T01:14:43.16048Z","caller":"traceutil/trace.go:171","msg":"trace[716663183] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25090; }","duration":"193.258445ms","start":"2026-07-16T01:14:42.967204Z","end":"2026-07-16T01:14:43.160463Z","steps":["trace[716663183] 'agreement among raft nodes before linearized reading' (duration: 193.0056ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:14:43.160496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.4702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T01:14:43.16059Z","caller":"traceutil/trace.go:171","msg":"trace[2012914104] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0; response_count:0; response_revision:25090; }","duration":"109.592372ms","start":"2026-07-16T01:14:43.050975Z","end":"2026-07-16T01:14:43.160567Z","steps":["trace[2012914104] 'agreement among raft nodes before linearized reading' (duration: 109.258376ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:14:43.160652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.672202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-16T01:14:43.160697Z","caller":"traceutil/trace.go:171","msg":"trace[485282892] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25090; }","duration":"187.719693ms","start":"2026-07-16T01:14:42.972964Z","end":"2026-07-16T01:14:43.160684Z","steps":["trace[485282892] 'agreement among raft nodes before linearized reading' (duration: 187.61152ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:14:43.160769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.394948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-07-16T01:14:43.160812Z","caller":"traceutil/trace.go:171","msg":"trace[710343645] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25090; }","duration":"193.501821ms","start":"2026-07-16T01:14:42.967295Z","end":"2026-07-16T01:14:43.160797Z","steps":["trace[710343645] 'agreement among raft nodes before linearized reading' (duration: 193.386789ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:14:43.452986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.014955ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-07-16T01:14:43.707654Z","caller":"traceutil/trace.go:171","msg":"trace[796742246] transaction","detail":"{read_only:false; response_revision:25091; number_of_response:1; }","duration":"543.360865ms","start":"2026-07-16T01:14:43.164251Z","end":"2026-07-16T01:14:43.707612Z","steps":["trace[796742246] 'process raft request' (duration: 521.48159ms)","trace[796742246] 'compare' (duration: 21.353035ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:14:43.70771Z","caller":"traceutil/trace.go:171","msg":"trace[1001522280] transaction","detail":"{read_only:false; response_revision:25093; number_of_response:1; }","duration":"541.411746ms","start":"2026-07-16T01:14:43.166265Z","end":"2026-07-16T01:14:43.707677Z","steps":["trace[1001522280] 'process raft request' (duration: 541.286033ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:14:43.707695Z","caller":"traceutil/trace.go:171","msg":"trace[1337534003] transaction","detail":"{read_only:false; response_revision:25092; number_of_response:1; }","duration":"541.483316ms","start":"2026-07-16T01:14:43.166175Z","end":"2026-07-16T01:14:43.707658Z","steps":["trace[1337534003] 'process raft request' (duration: 541.320453ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:14:43.707746Z","caller":"traceutil/trace.go:171","msg":"trace[2020028079] transaction","detail":"{read_only:false; response_revision:25094; number_of_response:1; }","duration":"455.401023ms","start":"2026-07-16T01:14:43.252312Z","end":"2026-07-16T01:14:43.707713Z","steps":["trace[2020028079] 'process raft request' (duration: 455.298031ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:14:43.707836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.762082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-1c1dd3c3-b0f5-4191-bb57-9e78374eb102\" ","response":"range_response_count:1 size:1320"} {"level":"warn","ts":"2026-07-16T01:14:43.707853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:14:43.16626Z","time spent":"541.505457ms","remote":"127.0.0.1:41514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T01:14:43.707886Z","caller":"traceutil/trace.go:171","msg":"trace[1811682650] range","detail":"{range_begin:/registry/persistentvolumes/pvc-1c1dd3c3-b0f5-4191-bb57-9e78374eb102; range_end:; response_count:1; response_revision:25094; }","duration":"542.832024ms","start":"2026-07-16T01:14:43.165044Z","end":"2026-07-16T01:14:43.707876Z","steps":["trace[1811682650] 'agreement among raft nodes before linearized reading' (duration: 542.679571ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:14:43.707674Z","caller":"traceutil/trace.go:171","msg":"trace[1535610164] linearizableReadLoop","detail":"{readStateIndex:25910; appliedIndex:25908; }","duration":"542.595449ms","start":"2026-07-16T01:14:43.165057Z","end":"2026-07-16T01:14:43.707652Z","steps":["trace[1535610164] 'read index received' (duration: 135.969388ms)","trace[1535610164] 'applied index is now lower than readState.Index' (duration: 406.624931ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T01:14:43.707848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:14:43.16423Z","time spent":"543.520218ms","remote":"127.0.0.1:41514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T01:14:43.707883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:14:43.252284Z","time spent":"455.522106ms","remote":"127.0.0.1:41514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T01:14:43.70792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:14:43.165037Z","time spent":"542.874964ms","remote":"127.0.0.1:41398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1343,"request content":"key:\"/registry/persistentvolumes/pvc-1c1dd3c3-b0f5-4191-bb57-9e78374eb102\" "} {"level":"warn","ts":"2026-07-16T01:14:43.707883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:14:43.166155Z","time spent":"541.63037ms","remote":"127.0.0.1:41514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T01:14:43.711743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.790992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T01:14:43.711795Z","caller":"traceutil/trace.go:171","msg":"trace[734346501] transaction","detail":"{read_only:false; response_revision:25095; number_of_response:1; }","duration":"257.328651ms","start":"2026-07-16T01:14:43.454439Z","end":"2026-07-16T01:14:43.711768Z","steps":["trace[734346501] 'process raft request' (duration: 256.963293ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:14:43.711903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.22603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-16T01:14:43.712511Z","caller":"traceutil/trace.go:171","msg":"trace[1703275380] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:25096; }","duration":"397.820241ms","start":"2026-07-16T01:14:43.31462Z","end":"2026-07-16T01:14:43.71244Z","steps":["trace[1703275380] 'agreement among raft nodes before linearized reading' (duration: 397.221639ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:14:43.712242Z","caller":"traceutil/trace.go:171","msg":"trace[1417888291] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25096; }","duration":"454.319472ms","start":"2026-07-16T01:14:43.257899Z","end":"2026-07-16T01:14:43.712219Z","steps":["trace[1417888291] 'agreement among raft nodes before linearized reading' (duration: 453.670849ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:14:43.71205Z","caller":"traceutil/trace.go:171","msg":"trace[1932116599] transaction","detail":"{read_only:false; response_revision:25096; number_of_response:1; }","duration":"244.99673ms","start":"2026-07-16T01:14:43.466975Z","end":"2026-07-16T01:14:43.711972Z","steps":["trace[1932116599] 'process raft request' (duration: 244.544151ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:14:43.71271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:14:43.314598Z","time spent":"397.979475ms","remote":"127.0.0.1:41404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":14,"response size":31,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-07-16T01:14:43.712641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:14:43.257887Z","time spent":"454.741201ms","remote":"127.0.0.1:41264","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-16T01:14:43.977474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.454925ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-16T01:14:43.977588Z","caller":"traceutil/trace.go:171","msg":"trace[1784225369] transaction","detail":"{read_only:false; response_revision:25097; number_of_response:1; }","duration":"250.584753ms","start":"2026-07-16T01:14:43.726971Z","end":"2026-07-16T01:14:43.977556Z","steps":["trace[1784225369] 'process raft request' (duration: 124.975005ms)","trace[1784225369] 'compare' (duration: 112.96129ms)","trace[1784225369] 'store kv pair into bolt db' {req_type:put; key:/registry/leases/capi-kubeadm-control-plane-system/kubeadm-control-plane-manager-leader-election-capi; req_size:625; } (duration: 12.375622ms)"],"step_count":3} {"level":"info","ts":"2026-07-16T01:14:44.456223Z","caller":"traceutil/trace.go:171","msg":"trace[809077823] transaction","detail":"{read_only:false; response_revision:25098; number_of_response:1; }","duration":"139.680533ms","start":"2026-07-16T01:14:44.316512Z","end":"2026-07-16T01:14:44.456193Z","steps":["trace[809077823] 'process raft request' (duration: 139.488849ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:14:44.457897Z","caller":"traceutil/trace.go:171","msg":"trace[1452650858] transaction","detail":"{read_only:false; response_revision:25100; number_of_response:1; }","duration":"141.246106ms","start":"2026-07-16T01:14:44.316623Z","end":"2026-07-16T01:14:44.45787Z","steps":["trace[1452650858] 'process raft request' (duration: 141.154424ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:14:44.457948Z","caller":"traceutil/trace.go:171","msg":"trace[233967336] transaction","detail":"{read_only:false; response_revision:25099; number_of_response:1; }","duration":"141.287907ms","start":"2026-07-16T01:14:44.316633Z","end":"2026-07-16T01:14:44.457921Z","steps":["trace[233967336] 'process raft request' (duration: 140.993071ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:14:44.458234Z","caller":"traceutil/trace.go:171","msg":"trace[802601359] transaction","detail":"{read_only:false; response_revision:25101; number_of_response:1; }","duration":"141.471111ms","start":"2026-07-16T01:14:44.316739Z","end":"2026-07-16T01:14:44.45821Z","steps":["trace[802601359] 'process raft request' (duration: 141.076473ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:14:49.961377Z","caller":"traceutil/trace.go:171","msg":"trace[939134527] transaction","detail":"{read_only:false; response_revision:25138; number_of_response:1; }","duration":"134.179932ms","start":"2026-07-16T01:14:49.827171Z","end":"2026-07-16T01:14:49.961351Z","steps":["trace[939134527] 'process raft request' (duration: 130.589188ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:14:50.271972Z","caller":"traceutil/trace.go:171","msg":"trace[715733164] transaction","detail":"{read_only:false; response_revision:25139; number_of_response:1; }","duration":"444.339098ms","start":"2026-07-16T01:14:49.827596Z","end":"2026-07-16T01:14:50.271935Z","steps":["trace[715733164] 'process raft request' (duration: 372.815202ms)","trace[715733164] 'compare' (duration: 71.327552ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T01:14:50.272167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:14:49.827583Z","time spent":"444.486782ms","remote":"127.0.0.1:41514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T01:14:50.272359Z","caller":"traceutil/trace.go:171","msg":"trace[322346166] transaction","detail":"{read_only:false; response_revision:25140; number_of_response:1; }","duration":"443.705907ms","start":"2026-07-16T01:14:49.828613Z","end":"2026-07-16T01:14:50.272318Z","steps":["trace[322346166] 'process raft request' (duration: 443.275687ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:14:50.272423Z","caller":"traceutil/trace.go:171","msg":"trace[513587298] transaction","detail":"{read_only:false; response_revision:25142; number_of_response:1; }","duration":"441.888349ms","start":"2026-07-16T01:14:49.830497Z","end":"2026-07-16T01:14:50.272385Z","steps":["trace[513587298] 'process raft request' (duration: 441.813838ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:14:50.272522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:14:49.828586Z","time spent":"443.825779ms","remote":"127.0.0.1:41514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T01:14:50.272652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:14:49.830486Z","time spent":"442.051162ms","remote":"127.0.0.1:41550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":871,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T01:14:50.272809Z","caller":"traceutil/trace.go:171","msg":"trace[859392658] transaction","detail":"{read_only:false; response_revision:25141; number_of_response:1; }","duration":"442.406809ms","start":"2026-07-16T01:14:49.830387Z","end":"2026-07-16T01:14:50.272794Z","steps":["trace[859392658] 'process raft request' (duration: 441.763106ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:14:50.272868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:14:49.830373Z","time spent":"442.46543ms","remote":"127.0.0.1:41550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":926,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T01:14:50.591754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.151766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/container-infra.18c2a021018b1996\" ","response":"range_response_count:1 size:695"} {"level":"info","ts":"2026-07-16T01:14:50.591816Z","caller":"traceutil/trace.go:171","msg":"trace[1404012108] range","detail":"{range_begin:/registry/events/openstack/container-infra.18c2a021018b1996; range_end:; response_count:1; response_revision:25143; }","duration":"143.257497ms","start":"2026-07-16T01:14:50.448545Z","end":"2026-07-16T01:14:50.591802Z","steps":["trace[1404012108] 'range keys from in-memory index tree' (duration: 143.014522ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:14:50.593128Z","caller":"traceutil/trace.go:171","msg":"trace[455073656] transaction","detail":"{read_only:false; response_revision:25144; number_of_response:1; }","duration":"103.701261ms","start":"2026-07-16T01:14:50.489411Z","end":"2026-07-16T01:14:50.593112Z","steps":["trace[455073656] 'process raft request' (duration: 103.569888ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:14:50.864715Z","caller":"traceutil/trace.go:171","msg":"trace[296794976] transaction","detail":"{read_only:false; response_revision:25147; number_of_response:1; }","duration":"371.759321ms","start":"2026-07-16T01:14:50.492929Z","end":"2026-07-16T01:14:50.864689Z","steps":["trace[296794976] 'process raft request' (duration: 371.71577ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:14:50.864831Z","caller":"traceutil/trace.go:171","msg":"trace[208599212] transaction","detail":"{read_only:false; response_revision:25145; number_of_response:1; }","duration":"373.354123ms","start":"2026-07-16T01:14:50.491441Z","end":"2026-07-16T01:14:50.864795Z","steps":["trace[208599212] 'process raft request' (duration: 372.985925ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:14:50.864877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:14:50.492903Z","time spent":"371.890354ms","remote":"127.0.0.1:41514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T01:14:50.865033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:14:50.491413Z","time spent":"373.505926ms","remote":"127.0.0.1:41514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T01:14:50.865097Z","caller":"traceutil/trace.go:171","msg":"trace[2115273562] transaction","detail":"{read_only:false; response_revision:25146; number_of_response:1; }","duration":"372.21586ms","start":"2026-07-16T01:14:50.492867Z","end":"2026-07-16T01:14:50.865083Z","steps":["trace[2115273562] 'process raft request' (duration: 371.734501ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:14:50.865194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:14:50.492854Z","time spent":"372.275091ms","remote":"127.0.0.1:41514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T01:14:51.111781Z","caller":"traceutil/trace.go:171","msg":"trace[895841846] linearizableReadLoop","detail":"{readStateIndex:25968; appliedIndex:25967; }","duration":"489.878936ms","start":"2026-07-16T01:14:50.621879Z","end":"2026-07-16T01:14:51.111758Z","steps":["trace[895841846] 'read index received' (duration: 242.877386ms)","trace[895841846] 'applied index is now lower than readState.Index' (duration: 247.00041ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:14:51.112358Z","caller":"traceutil/trace.go:171","msg":"trace[799895058] transaction","detail":"{read_only:false; response_revision:25148; number_of_response:1; }","duration":"517.982508ms","start":"2026-07-16T01:14:50.594332Z","end":"2026-07-16T01:14:51.112315Z","steps":["trace[799895058] 'process raft request' (duration: 423.76151ms)","trace[799895058] 'compare' (duration: 93.184167ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T01:14:51.112408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.515638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T01:14:51.11246Z","caller":"traceutil/trace.go:171","msg":"trace[274754578] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25149; }","duration":"490.633062ms","start":"2026-07-16T01:14:50.621814Z","end":"2026-07-16T01:14:51.112447Z","steps":["trace[274754578] 'agreement among raft nodes before linearized reading' (duration: 490.521979ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:14:51.112502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:14:50.621799Z","time spent":"490.695624ms","remote":"127.0.0.1:41264","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-16T01:14:51.112558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:14:50.594315Z","time spent":"518.133671ms","remote":"127.0.0.1:41342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":677,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T01:14:51.112685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.964716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/securitygroups/\" range_end:\"/registry/openstack.k-orc.cloud/securitygroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T01:14:51.112739Z","caller":"traceutil/trace.go:171","msg":"trace[1568759184] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/securitygroups/; range_end:/registry/openstack.k-orc.cloud/securitygroups0; response_count:0; response_revision:25149; }","duration":"430.028697ms","start":"2026-07-16T01:14:50.682695Z","end":"2026-07-16T01:14:51.112724Z","steps":["trace[1568759184] 'agreement among raft nodes before linearized reading' (duration: 429.933145ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:14:51.112775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:14:50.682636Z","time spent":"430.1293ms","remote":"127.0.0.1:43466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/securitygroups/\" range_end:\"/registry/openstack.k-orc.cloud/securitygroups0\" count_only:true "} {"level":"warn","ts":"2026-07-16T01:14:51.1129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.975062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T01:14:51.112696Z","caller":"traceutil/trace.go:171","msg":"trace[2144345293] transaction","detail":"{read_only:false; response_revision:25149; number_of_response:1; }","duration":"346.889994ms","start":"2026-07-16T01:14:50.765794Z","end":"2026-07-16T01:14:51.112684Z","steps":["trace[2144345293] 'process raft request' (duration: 345.897633ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:14:51.112968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.330794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-16T01:14:51.113031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:14:50.765784Z","time spent":"347.18592ms","remote":"127.0.0.1:41514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T01:14:51.113037Z","caller":"traceutil/trace.go:171","msg":"trace[704848878] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:25149; }","duration":"348.431146ms","start":"2026-07-16T01:14:50.764592Z","end":"2026-07-16T01:14:51.113023Z","steps":["trace[704848878] 'agreement among raft nodes before linearized reading' (duration: 348.260213ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:14:51.113099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.889079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-16T01:14:51.113111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.274124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-16T01:14:51.113142Z","caller":"traceutil/trace.go:171","msg":"trace[630557542] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters0; response_count:0; response_revision:25149; }","duration":"223.997171ms","start":"2026-07-16T01:14:50.889136Z","end":"2026-07-16T01:14:51.113133Z","steps":["trace[630557542] 'agreement among raft nodes before linearized reading' (duration: 223.93283ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:14:51.113179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:14:50.764577Z","time spent":"348.557928ms","remote":"127.0.0.1:33066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true "} {"level":"info","ts":"2026-07-16T01:14:51.113146Z","caller":"traceutil/trace.go:171","msg":"trace[1401614114] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25149; }","duration":"106.335365ms","start":"2026-07-16T01:14:51.006802Z","end":"2026-07-16T01:14:51.113138Z","steps":["trace[1401614114] 'agreement among raft nodes before linearized reading' (duration: 106.200233ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:14:51.112978Z","caller":"traceutil/trace.go:171","msg":"trace[480878725] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:25149; }","duration":"254.097305ms","start":"2026-07-16T01:14:50.858863Z","end":"2026-07-16T01:14:51.11296Z","steps":["trace[480878725] 'agreement among raft nodes before linearized reading' (duration: 253.955392ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:14:51.300227Z","caller":"traceutil/trace.go:171","msg":"trace[1516462247] transaction","detail":"{read_only:false; response_revision:25150; number_of_response:1; }","duration":"174.518624ms","start":"2026-07-16T01:14:51.125681Z","end":"2026-07-16T01:14:51.3002Z","steps":["trace[1516462247] 'process raft request' (duration: 160.669572ms)","trace[1516462247] 'compare' (duration: 13.747489ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T01:14:51.611344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.683625ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-16T01:14:51.611448Z","caller":"traceutil/trace.go:171","msg":"trace[118945215] linearizableReadLoop","detail":"{readStateIndex:25971; appliedIndex:25969; }","duration":"409.876077ms","start":"2026-07-16T01:14:51.201555Z","end":"2026-07-16T01:14:51.611431Z","steps":["trace[118945215] 'read index received' (duration: 84.93065ms)","trace[118945215] 'applied index is now lower than readState.Index' (duration: 324.944077ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:14:51.611541Z","caller":"traceutil/trace.go:171","msg":"trace[1741353827] transaction","detail":"{read_only:false; response_revision:25151; number_of_response:1; }","duration":"421.173468ms","start":"2026-07-16T01:14:51.190329Z","end":"2026-07-16T01:14:51.611502Z","steps":["trace[1741353827] 'process raft request' (duration: 279.234687ms)","trace[1741353827] 'compare' (duration: 141.412819ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T01:14:51.611637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.076721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-16T01:14:51.611667Z","caller":"traceutil/trace.go:171","msg":"trace[312382835] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25151; }","duration":"410.143603ms","start":"2026-07-16T01:14:51.201513Z","end":"2026-07-16T01:14:51.611657Z","steps":["trace[312382835] 'agreement among raft nodes before linearized reading' (duration: 410.01056ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:14:51.611708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:14:51.201492Z","time spent":"410.207084ms","remote":"127.0.0.1:41514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-16T01:14:51.611753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:14:51.190307Z","time spent":"421.343402ms","remote":"127.0.0.1:41514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":605,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T01:14:51.611906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.200372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-07-16T01:14:51.611944Z","caller":"traceutil/trace.go:171","msg":"trace[136683981] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0; range_end:; response_count:1; response_revision:25151; }","duration":"407.259314ms","start":"2026-07-16T01:14:51.204673Z","end":"2026-07-16T01:14:51.611932Z","steps":["trace[136683981] 'agreement among raft nodes before linearized reading' (duration: 407.161952ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:14:51.611973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:14:51.204662Z","time spent":"407.303615ms","remote":"127.0.0.1:41404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1883,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0\" "} {"level":"info","ts":"2026-07-16T01:14:52.003843Z","caller":"traceutil/trace.go:171","msg":"trace[735259461] transaction","detail":"{read_only:false; response_revision:25153; number_of_response:1; }","duration":"188.12094ms","start":"2026-07-16T01:14:51.815683Z","end":"2026-07-16T01:14:52.003804Z","steps":["trace[735259461] 'process raft request' (duration: 130.213721ms)","trace[735259461] 'compare' (duration: 57.696875ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:14:52.014909Z","caller":"traceutil/trace.go:171","msg":"trace[1046621741] transaction","detail":"{read_only:false; response_revision:25154; number_of_response:1; }","duration":"185.347686ms","start":"2026-07-16T01:14:51.829545Z","end":"2026-07-16T01:14:52.014893Z","steps":["trace[1046621741] 'process raft request' (duration: 184.827404ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:14:52.324652Z","caller":"traceutil/trace.go:171","msg":"trace[1066416237] transaction","detail":"{read_only:false; response_revision:25156; number_of_response:1; }","duration":"151.401823ms","start":"2026-07-16T01:14:52.173224Z","end":"2026-07-16T01:14:52.324626Z","steps":["trace[1066416237] 'process raft request' (duration: 150.75463ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:14:52.670411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.743516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-07-16T01:14:52.670537Z","caller":"traceutil/trace.go:171","msg":"trace[916557410] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25158; }","duration":"167.960901ms","start":"2026-07-16T01:14:52.502549Z","end":"2026-07-16T01:14:52.67051Z","steps":["trace[916557410] 'range keys from in-memory index tree' (duration: 167.451281ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:14:53.120767Z","caller":"traceutil/trace.go:171","msg":"trace[2015482938] transaction","detail":"{read_only:false; response_revision:25161; number_of_response:1; }","duration":"248.633923ms","start":"2026-07-16T01:14:52.872067Z","end":"2026-07-16T01:14:53.120701Z","steps":["trace[2015482938] 'process raft request' (duration: 238.475636ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:14:53.12097Z","caller":"traceutil/trace.go:171","msg":"trace[46727341] linearizableReadLoop","detail":"{readStateIndex:25982; appliedIndex:25980; }","duration":"217.900537ms","start":"2026-07-16T01:14:52.903049Z","end":"2026-07-16T01:14:53.12095Z","steps":["trace[46727341] 'read index received' (duration: 207.428324ms)","trace[46727341] 'applied index is now lower than readState.Index' (duration: 10.471183ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:14:53.121106Z","caller":"traceutil/trace.go:171","msg":"trace[627989448] transaction","detail":"{read_only:false; response_revision:25162; number_of_response:1; }","duration":"247.46584ms","start":"2026-07-16T01:14:52.873612Z","end":"2026-07-16T01:14:53.121078Z","steps":["trace[627989448] 'process raft request' (duration: 246.789206ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:14:53.121235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.172813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.222\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-07-16T01:14:53.121304Z","caller":"traceutil/trace.go:171","msg":"trace[207567848] range","detail":"{range_begin:/registry/masterleases/199.204.45.222; range_end:; response_count:1; response_revision:25162; }","duration":"218.292566ms","start":"2026-07-16T01:14:52.902991Z","end":"2026-07-16T01:14:53.121283Z","steps":["trace[207567848] 'agreement among raft nodes before linearized reading' (duration: 218.084721ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:14:53.432207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.536103ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-16T01:14:53.432501Z","caller":"traceutil/trace.go:171","msg":"trace[663576887] transaction","detail":"{read_only:false; response_revision:25163; number_of_response:1; }","duration":"310.603645ms","start":"2026-07-16T01:14:53.121848Z","end":"2026-07-16T01:14:53.432451Z","steps":["trace[663576887] 'process raft request' (duration: 141.626913ms)","trace[663576887] 'compare' (duration: 168.209496ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:14:53.432596Z","caller":"traceutil/trace.go:171","msg":"trace[1465586268] linearizableReadLoop","detail":"{readStateIndex:25984; appliedIndex:25982; }","duration":"160.704293ms","start":"2026-07-16T01:14:53.271863Z","end":"2026-07-16T01:14:53.432567Z","steps":["trace[1465586268] 'read index received' (duration: 49.766944ms)","trace[1465586268] 'applied index is now lower than readState.Index' (duration: 110.935299ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T01:14:53.432609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:14:53.123162Z","time spent":"309.439212ms","remote":"127.0.0.1:41290","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-16T01:14:53.43271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:14:53.121812Z","time spent":"310.784969ms","remote":"127.0.0.1:41514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T01:14:53.432808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.932768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T01:14:53.432872Z","caller":"traceutil/trace.go:171","msg":"trace[2138375814] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:25163; }","duration":"161.03349ms","start":"2026-07-16T01:14:53.271822Z","end":"2026-07-16T01:14:53.432855Z","steps":["trace[2138375814] 'agreement among raft nodes before linearized reading' (duration: 160.914768ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:14:53.433149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.736361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-16T01:14:53.433245Z","caller":"traceutil/trace.go:171","msg":"trace[1517441619] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25163; }","duration":"127.892665ms","start":"2026-07-16T01:14:53.305339Z","end":"2026-07-16T01:14:53.433231Z","steps":["trace[1517441619] 'agreement among raft nodes before linearized reading' (duration: 127.65872ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:14:53.917883Z","caller":"traceutil/trace.go:171","msg":"trace[74334102] linearizableReadLoop","detail":"{readStateIndex:25988; appliedIndex:25987; }","duration":"219.312256ms","start":"2026-07-16T01:14:53.698545Z","end":"2026-07-16T01:14:53.917857Z","steps":["trace[74334102] 'read index received' (duration: 219.236604ms)","trace[74334102] 'applied index is now lower than readState.Index' (duration: 74.622µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:14:53.917992Z","caller":"traceutil/trace.go:171","msg":"trace[2105110495] transaction","detail":"{read_only:false; response_revision:25167; number_of_response:1; }","duration":"242.035048ms","start":"2026-07-16T01:14:53.675911Z","end":"2026-07-16T01:14:53.917946Z","steps":["trace[2105110495] 'process raft request' (duration: 241.739412ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:14:53.918154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.363761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"warn","ts":"2026-07-16T01:14:53.91825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.629792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T01:14:53.918356Z","caller":"traceutil/trace.go:171","msg":"trace[370611297] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:25167; }","duration":"219.825276ms","start":"2026-07-16T01:14:53.698515Z","end":"2026-07-16T01:14:53.918341Z","steps":["trace[370611297] 'agreement among raft nodes before linearized reading' (duration: 219.456719ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:14:53.918265Z","caller":"traceutil/trace.go:171","msg":"trace[500732660] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25167; }","duration":"177.520114ms","start":"2026-07-16T01:14:53.740727Z","end":"2026-07-16T01:14:53.918247Z","steps":["trace[500732660] 'agreement among raft nodes before linearized reading' (duration: 177.28981ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:14:54.361021Z","caller":"traceutil/trace.go:171","msg":"trace[802112550] transaction","detail":"{read_only:false; response_revision:25172; number_of_response:1; }","duration":"261.964934ms","start":"2026-07-16T01:14:54.099004Z","end":"2026-07-16T01:14:54.360969Z","steps":["trace[802112550] 'process raft request' (duration: 261.78337ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:14:54.473235Z","caller":"traceutil/trace.go:171","msg":"trace[207453665] transaction","detail":"{read_only:false; response_revision:25173; number_of_response:1; }","duration":"160.692673ms","start":"2026-07-16T01:14:54.312452Z","end":"2026-07-16T01:14:54.473144Z","steps":["trace[207453665] 'process raft request' (duration: 145.665907ms)","trace[207453665] 'compare' (duration: 14.74904ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:14:54.473306Z","caller":"traceutil/trace.go:171","msg":"trace[1295246185] linearizableReadLoop","detail":"{readStateIndex:25994; appliedIndex:25993; }","duration":"130.619449ms","start":"2026-07-16T01:14:54.342659Z","end":"2026-07-16T01:14:54.473278Z","steps":["trace[1295246185] 'read index received' (duration: 18.620418ms)","trace[1295246185] 'applied index is now lower than readState.Index' (duration: 111.997181ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T01:14:54.473493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.825553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-16T01:14:54.473787Z","caller":"traceutil/trace.go:171","msg":"trace[1709132528] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25173; }","duration":"131.14859ms","start":"2026-07-16T01:14:54.34261Z","end":"2026-07-16T01:14:54.473758Z","steps":["trace[1709132528] 'agreement among raft nodes before linearized reading' (duration: 130.764712ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:14:54.473917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.578061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-16T01:14:54.474004Z","caller":"traceutil/trace.go:171","msg":"trace[1482363203] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25173; }","duration":"107.685193ms","start":"2026-07-16T01:14:54.366263Z","end":"2026-07-16T01:14:54.473949Z","steps":["trace[1482363203] 'agreement among raft nodes before linearized reading' (duration: 107.511779ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:14:54.741898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.761079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T01:14:54.741994Z","caller":"traceutil/trace.go:171","msg":"trace[223581426] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25175; }","duration":"119.914382ms","start":"2026-07-16T01:14:54.622057Z","end":"2026-07-16T01:14:54.741972Z","steps":["trace[223581426] 'range keys from in-memory index tree' (duration: 119.544585ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:14:54.832281Z","caller":"traceutil/trace.go:171","msg":"trace[1640791661] transaction","detail":"{read_only:false; response_revision:25176; number_of_response:1; }","duration":"155.909924ms","start":"2026-07-16T01:14:54.676301Z","end":"2026-07-16T01:14:54.832211Z","steps":["trace[1640791661] 'process raft request' (duration: 155.565038ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:14:55.501363Z","caller":"traceutil/trace.go:171","msg":"trace[1106010010] transaction","detail":"{read_only:false; response_revision:25178; number_of_response:1; }","duration":"374.355163ms","start":"2026-07-16T01:14:55.126982Z","end":"2026-07-16T01:14:55.501337Z","steps":["trace[1106010010] 'process raft request' (duration: 374.141809ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:14:55.501533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:14:55.12696Z","time spent":"374.490175ms","remote":"127.0.0.1:41514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T01:14:55.60883Z","caller":"traceutil/trace.go:171","msg":"trace[322421089] transaction","detail":"{read_only:false; response_revision:25180; number_of_response:1; }","duration":"168.812169ms","start":"2026-07-16T01:14:55.439951Z","end":"2026-07-16T01:14:55.608763Z","steps":["trace[322421089] 'process raft request' (duration: 168.655174ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:14:55.608739Z","caller":"traceutil/trace.go:171","msg":"trace[296399906] linearizableReadLoop","detail":"{readStateIndex:26000; appliedIndex:25998; }","duration":"223.392389ms","start":"2026-07-16T01:14:55.385319Z","end":"2026-07-16T01:14:55.608711Z","steps":["trace[296399906] 'read index received' (duration: 115.829798ms)","trace[296399906] 'applied index is now lower than readState.Index' (duration: 107.561611ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:14:55.608884Z","caller":"traceutil/trace.go:171","msg":"trace[211197450] transaction","detail":"{read_only:false; response_revision:25179; number_of_response:1; }","duration":"481.247771ms","start":"2026-07-16T01:14:55.12748Z","end":"2026-07-16T01:14:55.608728Z","steps":["trace[211197450] 'process raft request' (duration: 480.199519ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:14:55.609307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:14:55.12747Z","time spent":"481.74037ms","remote":"127.0.0.1:41514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T01:14:55.608956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.615544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T01:14:55.609511Z","caller":"traceutil/trace.go:171","msg":"trace[1374899718] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:25180; }","duration":"224.228066ms","start":"2026-07-16T01:14:55.385254Z","end":"2026-07-16T01:14:55.609482Z","steps":["trace[1374899718] 'agreement among raft nodes before linearized reading' (duration: 223.633294ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:14:55.60956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.804168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-16T01:14:55.609601Z","caller":"traceutil/trace.go:171","msg":"trace[293858479] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25180; }","duration":"141.846559ms","start":"2026-07-16T01:14:55.467741Z","end":"2026-07-16T01:14:55.609588Z","steps":["trace[293858479] 'agreement among raft nodes before linearized reading' (duration: 141.712566ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:14:55.97582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.727898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T01:14:55.975915Z","caller":"traceutil/trace.go:171","msg":"trace[1724680858] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:25182; }","duration":"145.878511ms","start":"2026-07-16T01:14:55.83001Z","end":"2026-07-16T01:14:55.975889Z","steps":["trace[1724680858] 'count revisions from in-memory index tree' (duration: 145.575824ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:14:56.250222Z","caller":"traceutil/trace.go:171","msg":"trace[315821053] transaction","detail":"{read_only:false; response_revision:25183; number_of_response:1; }","duration":"268.900966ms","start":"2026-07-16T01:14:55.981296Z","end":"2026-07-16T01:14:56.250197Z","steps":["trace[315821053] 'process raft request' (duration: 268.681371ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:14:56.338347Z","caller":"traceutil/trace.go:171","msg":"trace[1722338239] transaction","detail":"{read_only:false; response_revision:25185; number_of_response:1; }","duration":"238.069798ms","start":"2026-07-16T01:14:56.100255Z","end":"2026-07-16T01:14:56.338325Z","steps":["trace[1722338239] 'process raft request' (duration: 238.004637ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:14:56.338632Z","caller":"traceutil/trace.go:171","msg":"trace[530000885] transaction","detail":"{read_only:false; response_revision:25184; number_of_response:1; }","duration":"238.466606ms","start":"2026-07-16T01:14:56.100157Z","end":"2026-07-16T01:14:56.338623Z","steps":["trace[530000885] 'process raft request' (duration: 231.401803ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:15:03.367029Z","caller":"traceutil/trace.go:171","msg":"trace[812542713] transaction","detail":"{read_only:false; response_revision:25251; number_of_response:1; }","duration":"117.464012ms","start":"2026-07-16T01:15:03.249518Z","end":"2026-07-16T01:15:03.366982Z","steps":["trace[812542713] 'process raft request' (duration: 117.310779ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:15:03.652955Z","caller":"traceutil/trace.go:171","msg":"trace[807813738] transaction","detail":"{read_only:false; response_revision:25252; number_of_response:1; }","duration":"270.5655ms","start":"2026-07-16T01:15:03.382357Z","end":"2026-07-16T01:15:03.652923Z","steps":["trace[807813738] 'process raft request' (duration: 270.365546ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:15:03.951246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.190188ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-16T01:15:03.951482Z","caller":"traceutil/trace.go:171","msg":"trace[1359171954] transaction","detail":"{read_only:false; response_revision:25258; number_of_response:1; }","duration":"212.361984ms","start":"2026-07-16T01:15:03.739105Z","end":"2026-07-16T01:15:03.951467Z","steps":["trace[1359171954] 'process raft request' (duration: 212.321373ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:15:03.951492Z","caller":"traceutil/trace.go:171","msg":"trace[1718345490] transaction","detail":"{read_only:false; response_revision:25256; number_of_response:1; }","duration":"287.338892ms","start":"2026-07-16T01:15:03.664111Z","end":"2026-07-16T01:15:03.95145Z","steps":["trace[1718345490] 'process raft request' (duration: 166.514951ms)","trace[1718345490] 'compare' (duration: 120.059165ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:15:03.951563Z","caller":"traceutil/trace.go:171","msg":"trace[2084130650] transaction","detail":"{read_only:false; response_revision:25257; number_of_response:1; }","duration":"282.618125ms","start":"2026-07-16T01:15:03.66893Z","end":"2026-07-16T01:15:03.951548Z","steps":["trace[2084130650] 'process raft request' (duration: 282.434101ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:15:04.221325Z","caller":"traceutil/trace.go:171","msg":"trace[1552908299] transaction","detail":"{read_only:false; response_revision:25259; number_of_response:1; }","duration":"262.318172ms","start":"2026-07-16T01:15:03.95898Z","end":"2026-07-16T01:15:04.221298Z","steps":["trace[1552908299] 'process raft request' (duration: 179.634708ms)","trace[1552908299] 'compare' (duration: 82.466189ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:15:04.233549Z","caller":"traceutil/trace.go:171","msg":"trace[726662986] transaction","detail":"{read_only:false; response_revision:25260; number_of_response:1; }","duration":"134.220674ms","start":"2026-07-16T01:15:04.099317Z","end":"2026-07-16T01:15:04.233538Z","steps":["trace[726662986] 'process raft request' (duration: 134.113632ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:15:04.434334Z","caller":"traceutil/trace.go:171","msg":"trace[1483070515] transaction","detail":"{read_only:false; response_revision:25261; number_of_response:1; }","duration":"208.027687ms","start":"2026-07-16T01:15:04.226273Z","end":"2026-07-16T01:15:04.4343Z","steps":["trace[1483070515] 'process raft request' (duration: 172.558924ms)","trace[1483070515] 'compare' (duration: 35.284939ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:15:04.434362Z","caller":"traceutil/trace.go:171","msg":"trace[1395178550] transaction","detail":"{read_only:false; response_revision:25262; number_of_response:1; }","duration":"207.892333ms","start":"2026-07-16T01:15:04.226441Z","end":"2026-07-16T01:15:04.434333Z","steps":["trace[1395178550] 'process raft request' (duration: 207.766231ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:15:04.434554Z","caller":"traceutil/trace.go:171","msg":"trace[683976136] linearizableReadLoop","detail":"{readStateIndex:26086; appliedIndex:26083; }","duration":"207.781551ms","start":"2026-07-16T01:15:04.226745Z","end":"2026-07-16T01:15:04.434526Z","steps":["trace[683976136] 'read index received' (duration: 6.748127ms)","trace[683976136] 'applied index is now lower than readState.Index' (duration: 201.027744ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:15:04.434742Z","caller":"traceutil/trace.go:171","msg":"trace[2141508416] transaction","detail":"{read_only:false; response_revision:25263; number_of_response:1; }","duration":"207.838642ms","start":"2026-07-16T01:15:04.226889Z","end":"2026-07-16T01:15:04.434727Z","steps":["trace[2141508416] 'process raft request' (duration: 207.361923ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:15:04.434922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.750312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-16T01:15:04.434972Z","caller":"traceutil/trace.go:171","msg":"trace[1032316642] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25265; }","duration":"153.849183ms","start":"2026-07-16T01:15:04.281111Z","end":"2026-07-16T01:15:04.43496Z","steps":["trace[1032316642] 'agreement among raft nodes before linearized reading' (duration: 153.657739ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:15:04.435118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.363593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:131165"} {"level":"info","ts":"2026-07-16T01:15:04.435163Z","caller":"traceutil/trace.go:171","msg":"trace[139517782] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:25265; }","duration":"208.419614ms","start":"2026-07-16T01:15:04.226729Z","end":"2026-07-16T01:15:04.435149Z","steps":["trace[139517782] 'agreement among raft nodes before linearized reading' (duration: 208.23628ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:15:04.999828Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000661f.wal"} {"level":"warn","ts":"2026-07-16T01:15:05.776507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.842804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4fd31f18-1755-47a5-b8fc-7407dc123104\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-07-16T01:15:05.776597Z","caller":"traceutil/trace.go:171","msg":"trace[1358699888] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4fd31f18-1755-47a5-b8fc-7407dc123104; range_end:; response_count:1; response_revision:25326; }","duration":"155.970036ms","start":"2026-07-16T01:15:05.620604Z","end":"2026-07-16T01:15:05.776574Z","steps":["trace[1358699888] 'range keys from in-memory index tree' (duration: 155.64652ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:15:05.776786Z","caller":"traceutil/trace.go:171","msg":"trace[987527145] transaction","detail":"{read_only:false; response_revision:25327; number_of_response:1; }","duration":"105.218232ms","start":"2026-07-16T01:15:05.67153Z","end":"2026-07-16T01:15:05.776749Z","steps":["trace[987527145] 'process raft request' (duration: 77.185312ms)","trace[987527145] 'compare' (duration: 27.706754ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:15:05.777027Z","caller":"traceutil/trace.go:171","msg":"trace[355988813] transaction","detail":"{read_only:false; response_revision:25328; number_of_response:1; }","duration":"104.829214ms","start":"2026-07-16T01:15:05.672151Z","end":"2026-07-16T01:15:05.77698Z","steps":["trace[355988813] 'process raft request' (duration: 104.447136ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:15:05.777209Z","caller":"traceutil/trace.go:171","msg":"trace[1534996472] transaction","detail":"{read_only:false; response_revision:25329; number_of_response:1; }","duration":"103.519178ms","start":"2026-07-16T01:15:05.673681Z","end":"2026-07-16T01:15:05.7772Z","steps":["trace[1534996472] 'process raft request' (duration: 102.975548ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:15:08.956338Z","caller":"traceutil/trace.go:171","msg":"trace[382064683] transaction","detail":"{read_only:false; response_revision:25358; number_of_response:1; }","duration":"146.407001ms","start":"2026-07-16T01:15:08.809881Z","end":"2026-07-16T01:15:08.956288Z","steps":["trace[382064683] 'process raft request' (duration: 146.141595ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:15:09.030218Z","caller":"traceutil/trace.go:171","msg":"trace[954905575] transaction","detail":"{read_only:false; response_revision:25359; number_of_response:1; }","duration":"145.779129ms","start":"2026-07-16T01:15:08.884416Z","end":"2026-07-16T01:15:09.030195Z","steps":["trace[954905575] 'process raft request' (duration: 145.436011ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:15:10.735474Z","caller":"traceutil/trace.go:171","msg":"trace[1645165965] transaction","detail":"{read_only:false; response_revision:25387; number_of_response:1; }","duration":"154.775252ms","start":"2026-07-16T01:15:10.580641Z","end":"2026-07-16T01:15:10.735416Z","steps":["trace[1645165965] 'process raft request' (duration: 126.779631ms)","trace[1645165965] 'compare' (duration: 27.570152ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:15:10.763705Z","caller":"traceutil/trace.go:171","msg":"trace[1946484626] linearizableReadLoop","detail":"{readStateIndex:26214; appliedIndex:26212; }","duration":"142.586784ms","start":"2026-07-16T01:15:10.621084Z","end":"2026-07-16T01:15:10.763671Z","steps":["trace[1946484626] 'read index received' (duration: 86.321588ms)","trace[1946484626] 'applied index is now lower than readState.Index' (duration: 56.263996ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T01:15:10.763881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.778898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T01:15:10.763927Z","caller":"traceutil/trace.go:171","msg":"trace[1649347705] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25388; }","duration":"142.87593ms","start":"2026-07-16T01:15:10.621037Z","end":"2026-07-16T01:15:10.763913Z","steps":["trace[1649347705] 'agreement among raft nodes before linearized reading' (duration: 142.751767ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:15:10.76394Z","caller":"traceutil/trace.go:171","msg":"trace[1319045746] transaction","detail":"{read_only:false; response_revision:25388; number_of_response:1; }","duration":"181.750571ms","start":"2026-07-16T01:15:10.582143Z","end":"2026-07-16T01:15:10.763894Z","steps":["trace[1319045746] 'process raft request' (duration: 181.359893ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:15:36.065633Z","caller":"traceutil/trace.go:171","msg":"trace[1453959761] transaction","detail":"{read_only:false; response_revision:25608; number_of_response:1; }","duration":"161.62861ms","start":"2026-07-16T01:15:35.903976Z","end":"2026-07-16T01:15:36.065604Z","steps":["trace[1453959761] 'process raft request' (duration: 161.438696ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:15:36.065908Z","caller":"traceutil/trace.go:171","msg":"trace[1579070495] transaction","detail":"{read_only:false; response_revision:25609; number_of_response:1; }","duration":"161.13642ms","start":"2026-07-16T01:15:35.904734Z","end":"2026-07-16T01:15:36.065871Z","steps":["trace[1579070495] 'process raft request' (duration: 160.827354ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:15:36.305838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.899282ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-16T01:15:36.305985Z","caller":"traceutil/trace.go:171","msg":"trace[312092088] linearizableReadLoop","detail":"{readStateIndex:26441; appliedIndex:26440; }","duration":"206.410094ms","start":"2026-07-16T01:15:36.09955Z","end":"2026-07-16T01:15:36.30596Z","steps":["trace[312092088] 'read index received' (duration: 84.195496ms)","trace[312092088] 'applied index is now lower than readState.Index' (duration: 122.212558ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:15:36.306102Z","caller":"traceutil/trace.go:171","msg":"trace[660709277] transaction","detail":"{read_only:false; response_revision:25610; number_of_response:1; }","duration":"236.483486ms","start":"2026-07-16T01:15:36.06956Z","end":"2026-07-16T01:15:36.306044Z","steps":["trace[660709277] 'process raft request' (duration: 114.210846ms)","trace[660709277] 'compare' (duration: 121.583336ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T01:15:36.306202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.659079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-16T01:15:36.306237Z","caller":"traceutil/trace.go:171","msg":"trace[495551744] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25610; }","duration":"206.742491ms","start":"2026-07-16T01:15:36.099483Z","end":"2026-07-16T01:15:36.306225Z","steps":["trace[495551744] 'agreement among raft nodes before linearized reading' (duration: 206.539437ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:15:36.424459Z","caller":"traceutil/trace.go:171","msg":"trace[925432546] transaction","detail":"{read_only:false; response_revision:25611; number_of_response:1; }","duration":"113.088623ms","start":"2026-07-16T01:15:36.311341Z","end":"2026-07-16T01:15:36.424429Z","steps":["trace[925432546] 'process raft request' (duration: 112.571892ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:15:36.591127Z","caller":"traceutil/trace.go:171","msg":"trace[648310266] transaction","detail":"{read_only:false; response_revision:25612; number_of_response:1; }","duration":"133.611452ms","start":"2026-07-16T01:15:36.457466Z","end":"2026-07-16T01:15:36.591077Z","steps":["trace[648310266] 'process raft request' (duration: 106.160392ms)","trace[648310266] 'compare' (duration: 26.999361ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T01:15:36.820676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.79069ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-16T01:15:36.820787Z","caller":"traceutil/trace.go:171","msg":"trace[1650040106] linearizableReadLoop","detail":"{readStateIndex:26445; appliedIndex:26444; }","duration":"200.223738ms","start":"2026-07-16T01:15:36.620547Z","end":"2026-07-16T01:15:36.820771Z","steps":["trace[1650040106] 'read index received' (duration: 77.117311ms)","trace[1650040106] 'applied index is now lower than readState.Index' (duration: 123.104907ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:15:36.820871Z","caller":"traceutil/trace.go:171","msg":"trace[1790782283] transaction","detail":"{read_only:false; response_revision:25614; number_of_response:1; }","duration":"253.668696ms","start":"2026-07-16T01:15:36.567155Z","end":"2026-07-16T01:15:36.820824Z","steps":["trace[1790782283] 'process raft request' (duration: 130.653921ms)","trace[1790782283] 'compare' (duration: 122.655188ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T01:15:36.820994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.438482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-07-16T01:15:36.821075Z","caller":"traceutil/trace.go:171","msg":"trace[1924957446] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25614; }","duration":"200.570375ms","start":"2026-07-16T01:15:36.620482Z","end":"2026-07-16T01:15:36.821053Z","steps":["trace[1924957446] 'agreement among raft nodes before linearized reading' (duration: 200.375891ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:15:36.821191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.036744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-16T01:15:36.821307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.978922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T01:15:36.821366Z","caller":"traceutil/trace.go:171","msg":"trace[2066929066] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:25614; }","duration":"144.086754ms","start":"2026-07-16T01:15:36.677267Z","end":"2026-07-16T01:15:36.821354Z","steps":["trace[2066929066] 'agreement among raft nodes before linearized reading' (duration: 143.956791ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:15:36.821505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.687056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-16T01:15:36.821548Z","caller":"traceutil/trace.go:171","msg":"trace[1555218329] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25614; }","duration":"199.749138ms","start":"2026-07-16T01:15:36.621789Z","end":"2026-07-16T01:15:36.821538Z","steps":["trace[1555218329] 'agreement among raft nodes before linearized reading' (duration: 199.644646ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:15:36.82191Z","caller":"traceutil/trace.go:171","msg":"trace[1771716701] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25614; }","duration":"200.345329ms","start":"2026-07-16T01:15:36.620963Z","end":"2026-07-16T01:15:36.821309Z","steps":["trace[1771716701] 'agreement among raft nodes before linearized reading' (duration: 199.952011ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:15:37.050804Z","caller":"traceutil/trace.go:171","msg":"trace[1500435473] transaction","detail":"{read_only:false; response_revision:25615; number_of_response:1; }","duration":"224.800498ms","start":"2026-07-16T01:15:36.825968Z","end":"2026-07-16T01:15:37.050768Z","steps":["trace[1500435473] 'process raft request' (duration: 134.193683ms)","trace[1500435473] 'compare' (duration: 90.443401ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:15:37.084591Z","caller":"traceutil/trace.go:171","msg":"trace[655879634] linearizableReadLoop","detail":"{readStateIndex:26447; appliedIndex:26445; }","duration":"204.922193ms","start":"2026-07-16T01:15:36.879638Z","end":"2026-07-16T01:15:37.08456Z","steps":["trace[655879634] 'read index received' (duration: 80.598632ms)","trace[655879634] 'applied index is now lower than readState.Index' (duration: 124.322091ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:15:37.084723Z","caller":"traceutil/trace.go:171","msg":"trace[314889524] transaction","detail":"{read_only:false; response_revision:25616; number_of_response:1; }","duration":"257.508024ms","start":"2026-07-16T01:15:36.827169Z","end":"2026-07-16T01:15:37.084677Z","steps":["trace[314889524] 'process raft request' (duration: 257.177878ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:15:37.084945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.27623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-16T01:15:37.084983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.960924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-16T01:15:37.084997Z","caller":"traceutil/trace.go:171","msg":"trace[819364946] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25616; }","duration":"205.369732ms","start":"2026-07-16T01:15:36.879615Z","end":"2026-07-16T01:15:37.084984Z","steps":["trace[819364946] 'agreement among raft nodes before linearized reading' (duration: 205.154698ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:15:37.085008Z","caller":"traceutil/trace.go:171","msg":"trace[942558873] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25616; }","duration":"204.010205ms","start":"2026-07-16T01:15:36.880989Z","end":"2026-07-16T01:15:37.084999Z","steps":["trace[942558873] 'agreement among raft nodes before linearized reading' (duration: 203.914593ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:15:37.085092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.859707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T01:15:37.085125Z","caller":"traceutil/trace.go:171","msg":"trace[87633198] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:25616; }","duration":"164.911998ms","start":"2026-07-16T01:15:36.920202Z","end":"2026-07-16T01:15:37.085114Z","steps":["trace[87633198] 'agreement among raft nodes before linearized reading' (duration: 164.862887ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:15:37.230475Z","caller":"traceutil/trace.go:171","msg":"trace[2030500653] transaction","detail":"{read_only:false; response_revision:25618; number_of_response:1; }","duration":"139.117122ms","start":"2026-07-16T01:15:37.091336Z","end":"2026-07-16T01:15:37.230453Z","steps":["trace[2030500653] 'process raft request' (duration: 138.492469ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:15:38.106153Z","caller":"traceutil/trace.go:171","msg":"trace[1756575670] transaction","detail":"{read_only:false; response_revision:25621; number_of_response:1; }","duration":"114.573063ms","start":"2026-07-16T01:15:37.991524Z","end":"2026-07-16T01:15:38.106097Z","steps":["trace[1756575670] 'process raft request' (duration: 114.39828ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:15:38.257559Z","caller":"traceutil/trace.go:171","msg":"trace[600057172] transaction","detail":"{read_only:false; response_revision:25622; number_of_response:1; }","duration":"185.861605ms","start":"2026-07-16T01:15:38.071674Z","end":"2026-07-16T01:15:38.257535Z","steps":["trace[600057172] 'process raft request' (duration: 155.705771ms)","trace[600057172] 'compare' (duration: 29.751886ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:15:38.257769Z","caller":"traceutil/trace.go:171","msg":"trace[1146601642] transaction","detail":"{read_only:false; response_revision:25623; number_of_response:1; }","duration":"183.676691ms","start":"2026-07-16T01:15:38.074048Z","end":"2026-07-16T01:15:38.257724Z","steps":["trace[1146601642] 'process raft request' (duration: 183.438026ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:15:38.522996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.674869ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-16T01:15:38.523588Z","caller":"traceutil/trace.go:171","msg":"trace[1576115642] transaction","detail":"{read_only:false; response_revision:25624; number_of_response:1; }","duration":"212.304874ms","start":"2026-07-16T01:15:38.311227Z","end":"2026-07-16T01:15:38.523532Z","steps":["trace[1576115642] 'process raft request' (duration: 89.012113ms)","trace[1576115642] 'compare' (duration: 122.26589ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T01:15:38.677859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.755931ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-16T01:15:38.678034Z","caller":"traceutil/trace.go:171","msg":"trace[497938010] linearizableReadLoop","detail":"{readStateIndex:26456; appliedIndex:26454; }","duration":"249.530932ms","start":"2026-07-16T01:15:38.428489Z","end":"2026-07-16T01:15:38.67802Z","steps":["trace[497938010] 'read index received' (duration: 35.941µs)","trace[497938010] 'applied index is now lower than readState.Index' (duration: 249.494081ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T01:15:38.678661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.160324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-16T01:15:38.678733Z","caller":"traceutil/trace.go:171","msg":"trace[960750504] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25624; }","duration":"250.274757ms","start":"2026-07-16T01:15:38.428443Z","end":"2026-07-16T01:15:38.678718Z","steps":["trace[960750504] 'agreement among raft nodes before linearized reading' (duration: 250.084863ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:15:38.811027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.039846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-16T01:15:38.811086Z","caller":"traceutil/trace.go:171","msg":"trace[66378823] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:25625; }","duration":"317.157008ms","start":"2026-07-16T01:15:38.493915Z","end":"2026-07-16T01:15:38.811072Z","steps":["trace[66378823] 'agreement among raft nodes before linearized reading' (duration: 184.810043ms)","trace[66378823] 'count revisions from in-memory index tree' (duration: 132.254043ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T01:15:38.811112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:15:38.493888Z","time spent":"317.21816ms","remote":"127.0.0.1:41708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":54,"response size":31,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-07-16T01:15:38.811473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.675163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T01:15:38.811541Z","caller":"traceutil/trace.go:171","msg":"trace[29937032] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25625; }","duration":"189.798705ms","start":"2026-07-16T01:15:38.621726Z","end":"2026-07-16T01:15:38.811525Z","steps":["trace[29937032] 'agreement among raft nodes before linearized reading' (duration: 57.106092ms)","trace[29937032] 'range keys from in-memory index tree' (duration: 132.593991ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:15:38.811553Z","caller":"traceutil/trace.go:171","msg":"trace[550618108] transaction","detail":"{read_only:false; response_revision:25626; number_of_response:1; }","duration":"127.976766ms","start":"2026-07-16T01:15:38.683568Z","end":"2026-07-16T01:15:38.811545Z","steps":["trace[550618108] 'process raft request' (duration: 116.40356ms)","trace[550618108] 'compare' (duration: 10.852051ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T01:15:38.811598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.701002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-07-16T01:15:38.81176Z","caller":"traceutil/trace.go:171","msg":"trace[282550388] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25625; }","duration":"239.914586ms","start":"2026-07-16T01:15:38.571826Z","end":"2026-07-16T01:15:38.811741Z","steps":["trace[282550388] 'agreement among raft nodes before linearized reading' (duration: 107.04501ms)","trace[282550388] 'range keys from in-memory index tree' (duration: 132.591131ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T01:15:39.107925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.103472ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-16T01:15:39.108099Z","caller":"traceutil/trace.go:171","msg":"trace[539045618] transaction","detail":"{read_only:false; response_revision:25628; number_of_response:1; }","duration":"281.187406ms","start":"2026-07-16T01:15:38.826895Z","end":"2026-07-16T01:15:39.108082Z","steps":["trace[539045618] 'process raft request' (duration: 281.123905ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:15:39.108117Z","caller":"traceutil/trace.go:171","msg":"trace[1016320073] linearizableReadLoop","detail":"{readStateIndex:26459; appliedIndex:26458; }","duration":"295.757573ms","start":"2026-07-16T01:15:38.812333Z","end":"2026-07-16T01:15:39.108091Z","steps":["trace[1016320073] 'read index received' (duration: 131.414176ms)","trace[1016320073] 'applied index is now lower than readState.Index' (duration: 164.341927ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:15:39.108261Z","caller":"traceutil/trace.go:171","msg":"trace[793246592] transaction","detail":"{read_only:false; response_revision:25627; number_of_response:1; }","duration":"393.365151ms","start":"2026-07-16T01:15:38.714869Z","end":"2026-07-16T01:15:39.108235Z","steps":["trace[793246592] 'process raft request' (duration: 228.868891ms)","trace[793246592] 'compare' (duration: 163.797436ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T01:15:39.108385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:15:38.714851Z","time spent":"393.442343ms","remote":"127.0.0.1:41514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T01:15:39.108585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.222538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-07-16T01:15:39.108646Z","caller":"traceutil/trace.go:171","msg":"trace[1210239185] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25628; }","duration":"283.289109ms","start":"2026-07-16T01:15:38.82534Z","end":"2026-07-16T01:15:39.10863Z","steps":["trace[1210239185] 'agreement among raft nodes before linearized reading' (duration: 283.096696ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:15:39.10873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.360056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T01:15:39.10883Z","caller":"traceutil/trace.go:171","msg":"trace[458259337] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25628; }","duration":"296.498258ms","start":"2026-07-16T01:15:38.812311Z","end":"2026-07-16T01:15:39.10881Z","steps":["trace[458259337] 'agreement among raft nodes before linearized reading' (duration: 296.296084ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:15:39.4036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.984888ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-16T01:15:39.404039Z","caller":"traceutil/trace.go:171","msg":"trace[700491231] linearizableReadLoop","detail":"{readStateIndex:26463; appliedIndex:26460; }","duration":"278.005761ms","start":"2026-07-16T01:15:39.126009Z","end":"2026-07-16T01:15:39.404015Z","steps":["trace[700491231] 'read index received' (duration: 118.566304ms)","trace[700491231] 'applied index is now lower than readState.Index' (duration: 159.422387ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:15:39.404171Z","caller":"traceutil/trace.go:171","msg":"trace[197083203] transaction","detail":"{read_only:false; response_revision:25633; number_of_response:1; }","duration":"166.690294ms","start":"2026-07-16T01:15:39.237448Z","end":"2026-07-16T01:15:39.404139Z","steps":["trace[197083203] 'process raft request' (duration: 166.50412ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:15:39.40417Z","caller":"traceutil/trace.go:171","msg":"trace[604858942] transaction","detail":"{read_only:false; response_revision:25631; number_of_response:1; }","duration":"289.046596ms","start":"2026-07-16T01:15:39.115095Z","end":"2026-07-16T01:15:39.404142Z","steps":["trace[604858942] 'process raft request' (duration: 288.77149ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:15:39.404263Z","caller":"traceutil/trace.go:171","msg":"trace[1160323298] transaction","detail":"{read_only:false; response_revision:25632; number_of_response:1; }","duration":"167.4268ms","start":"2026-07-16T01:15:39.236805Z","end":"2026-07-16T01:15:39.404232Z","steps":["trace[1160323298] 'process raft request' (duration: 167.105173ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:15:39.404287Z","caller":"traceutil/trace.go:171","msg":"trace[1468709387] transaction","detail":"{read_only:false; response_revision:25630; number_of_response:1; }","duration":"290.592538ms","start":"2026-07-16T01:15:39.113655Z","end":"2026-07-16T01:15:39.404248Z","steps":["trace[1468709387] 'process raft request' (duration: 290.087728ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:15:39.404323Z","caller":"traceutil/trace.go:171","msg":"trace[43192373] transaction","detail":"{read_only:false; response_revision:25629; number_of_response:1; }","duration":"291.109449ms","start":"2026-07-16T01:15:39.113017Z","end":"2026-07-16T01:15:39.404127Z","steps":["trace[43192373] 'process raft request' (duration: 131.464897ms)","trace[43192373] 'compare' (duration: 158.636451ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T01:15:39.404406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.390529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-16T01:15:39.404442Z","caller":"traceutil/trace.go:171","msg":"trace[1906470726] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25633; }","duration":"278.501562ms","start":"2026-07-16T01:15:39.125931Z","end":"2026-07-16T01:15:39.404432Z","steps":["trace[1906470726] 'agreement among raft nodes before linearized reading' (duration: 278.307748ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:15:39.41589Z","caller":"traceutil/trace.go:171","msg":"trace[2033300465] transaction","detail":"{read_only:false; response_revision:25634; number_of_response:1; }","duration":"136.114761ms","start":"2026-07-16T01:15:39.279745Z","end":"2026-07-16T01:15:39.41586Z","steps":["trace[2033300465] 'process raft request' (duration: 135.948008ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:15:39.415971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.838792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-16T01:15:39.416042Z","caller":"traceutil/trace.go:171","msg":"trace[750555892] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25634; }","duration":"180.947944ms","start":"2026-07-16T01:15:39.235078Z","end":"2026-07-16T01:15:39.416025Z","steps":["trace[750555892] 'agreement among raft nodes before linearized reading' (duration: 180.75361ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:15:39.416142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.685108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T01:15:39.416215Z","caller":"traceutil/trace.go:171","msg":"trace[2121010844] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25634; }","duration":"233.961084ms","start":"2026-07-16T01:15:39.182238Z","end":"2026-07-16T01:15:39.416199Z","steps":["trace[2121010844] 'agreement among raft nodes before linearized reading' (duration: 233.667008ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:15:39.41636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.180977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-07-16T01:15:39.416563Z","caller":"traceutil/trace.go:171","msg":"trace[596704583] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:25634; }","duration":"285.349171ms","start":"2026-07-16T01:15:39.13111Z","end":"2026-07-16T01:15:39.416459Z","steps":["trace[596704583] 'agreement among raft nodes before linearized reading' (duration: 284.705677ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:15:39.59236Z","caller":"traceutil/trace.go:171","msg":"trace[1701724165] linearizableReadLoop","detail":"{readStateIndex:26468; appliedIndex:26467; }","duration":"166.758905ms","start":"2026-07-16T01:15:39.425572Z","end":"2026-07-16T01:15:39.592331Z","steps":["trace[1701724165] 'read index received' (duration: 144.555123ms)","trace[1701724165] 'applied index is now lower than readState.Index' (duration: 22.202382ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:15:39.592465Z","caller":"traceutil/trace.go:171","msg":"trace[631755320] transaction","detail":"{read_only:false; response_revision:25636; number_of_response:1; }","duration":"171.608694ms","start":"2026-07-16T01:15:39.420746Z","end":"2026-07-16T01:15:39.592354Z","steps":["trace[631755320] 'process raft request' (duration: 149.359561ms)","trace[631755320] 'compare' (duration: 22.003758ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T01:15:39.592781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.430001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-e3841008-fd1b-48ac-a999-28ede12ceba6\" ","response":"range_response_count:1 size:1323"} {"level":"info","ts":"2026-07-16T01:15:39.592828Z","caller":"traceutil/trace.go:171","msg":"trace[883542271] range","detail":"{range_begin:/registry/persistentvolumes/pvc-e3841008-fd1b-48ac-a999-28ede12ceba6; range_end:; response_count:1; response_revision:25637; }","duration":"172.528343ms","start":"2026-07-16T01:15:39.420287Z","end":"2026-07-16T01:15:39.592816Z","steps":["trace[883542271] 'agreement among raft nodes before linearized reading' (duration: 172.147795ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:15:39.592816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.751174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-07-16T01:15:39.592891Z","caller":"traceutil/trace.go:171","msg":"trace[951599553] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25637; }","duration":"163.896177ms","start":"2026-07-16T01:15:39.428975Z","end":"2026-07-16T01:15:39.592871Z","steps":["trace[951599553] 'agreement among raft nodes before linearized reading' (duration: 163.641252ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:15:40.228155Z","caller":"traceutil/trace.go:171","msg":"trace[655811574] transaction","detail":"{read_only:false; response_revision:25638; number_of_response:1; }","duration":"114.787127ms","start":"2026-07-16T01:15:40.113334Z","end":"2026-07-16T01:15:40.228121Z","steps":["trace[655811574] 'process raft request' (duration: 114.583903ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:15:40.412409Z","caller":"traceutil/trace.go:171","msg":"trace[1079135604] transaction","detail":"{read_only:false; response_revision:25640; number_of_response:1; }","duration":"137.135272ms","start":"2026-07-16T01:15:40.275211Z","end":"2026-07-16T01:15:40.412346Z","steps":["trace[1079135604] 'process raft request' (duration: 136.678653ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:15:40.788859Z","caller":"traceutil/trace.go:171","msg":"trace[1046212008] transaction","detail":"{read_only:false; response_revision:25642; number_of_response:1; }","duration":"103.378666ms","start":"2026-07-16T01:15:40.685437Z","end":"2026-07-16T01:15:40.788816Z","steps":["trace[1046212008] 'process raft request' (duration: 23.600451ms)","trace[1046212008] 'compare' (duration: 79.601681ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:15:41.594928Z","caller":"traceutil/trace.go:171","msg":"trace[989609214] transaction","detail":"{read_only:false; response_revision:25646; number_of_response:1; }","duration":"184.988147ms","start":"2026-07-16T01:15:41.409897Z","end":"2026-07-16T01:15:41.594885Z","steps":["trace[989609214] 'process raft request' (duration: 95.573236ms)","trace[989609214] 'compare' (duration: 89.174406ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:15:41.595165Z","caller":"traceutil/trace.go:171","msg":"trace[1956542548] transaction","detail":"{read_only:false; response_revision:25647; number_of_response:1; }","duration":"182.675021ms","start":"2026-07-16T01:15:41.412442Z","end":"2026-07-16T01:15:41.595117Z","steps":["trace[1956542548] 'process raft request' (duration: 182.381045ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:15:41.595454Z","caller":"traceutil/trace.go:171","msg":"trace[1986131941] linearizableReadLoop","detail":"{readStateIndex:26480; appliedIndex:26477; }","duration":"166.080711ms","start":"2026-07-16T01:15:41.429323Z","end":"2026-07-16T01:15:41.595403Z","steps":["trace[1986131941] 'read index received' (duration: 7.89904ms)","trace[1986131941] 'applied index is now lower than readState.Index' (duration: 158.180231ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:15:41.595502Z","caller":"traceutil/trace.go:171","msg":"trace[401587031] transaction","detail":"{read_only:false; response_revision:25648; number_of_response:1; }","duration":"182.107919ms","start":"2026-07-16T01:15:41.413366Z","end":"2026-07-16T01:15:41.595474Z","steps":["trace[401587031] 'process raft request' (duration: 181.69482ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:15:41.595732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.757684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-16T01:15:41.595774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.526391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-07-16T01:15:41.595932Z","caller":"traceutil/trace.go:171","msg":"trace[1420020804] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25648; }","duration":"166.728425ms","start":"2026-07-16T01:15:41.429179Z","end":"2026-07-16T01:15:41.595908Z","steps":["trace[1420020804] 'agreement among raft nodes before linearized reading' (duration: 166.408158ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:15:41.595856Z","caller":"traceutil/trace.go:171","msg":"trace[375862476] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:25648; }","duration":"108.868627ms","start":"2026-07-16T01:15:41.486904Z","end":"2026-07-16T01:15:41.595773Z","steps":["trace[375862476] 'agreement among raft nodes before linearized reading' (duration: 108.713124ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:15:41.831129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.485917ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-16T01:15:41.831409Z","caller":"traceutil/trace.go:171","msg":"trace[1260059803] linearizableReadLoop","detail":"{readStateIndex:26482; appliedIndex:26480; }","duration":"170.155986ms","start":"2026-07-16T01:15:41.661232Z","end":"2026-07-16T01:15:41.831388Z","steps":["trace[1260059803] 'read index received' (duration: 68.323751ms)","trace[1260059803] 'applied index is now lower than readState.Index' (duration: 101.830804ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T01:15:41.831535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.307548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T01:15:41.831571Z","caller":"traceutil/trace.go:171","msg":"trace[31689687] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:25650; }","duration":"170.428751ms","start":"2026-07-16T01:15:41.661128Z","end":"2026-07-16T01:15:41.831557Z","steps":["trace[31689687] 'agreement among raft nodes before linearized reading' (duration: 170.333769ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:15:41.831543Z","caller":"traceutil/trace.go:171","msg":"trace[1141856210] transaction","detail":"{read_only:false; response_revision:25649; number_of_response:1; }","duration":"229.454503ms","start":"2026-07-16T01:15:41.602048Z","end":"2026-07-16T01:15:41.831502Z","steps":["trace[1141856210] 'process raft request' (duration: 127.486156ms)","trace[1141856210] 'compare' (duration: 101.138149ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:15:41.831543Z","caller":"traceutil/trace.go:171","msg":"trace[1262432234] transaction","detail":"{read_only:false; response_revision:25650; number_of_response:1; }","duration":"224.87769ms","start":"2026-07-16T01:15:41.606632Z","end":"2026-07-16T01:15:41.83151Z","steps":["trace[1262432234] 'process raft request' (duration: 224.644065ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:15:42.464064Z","caller":"traceutil/trace.go:171","msg":"trace[1876006849] transaction","detail":"{read_only:false; response_revision:25652; number_of_response:1; }","duration":"109.393488ms","start":"2026-07-16T01:15:42.354628Z","end":"2026-07-16T01:15:42.464021Z","steps":["trace[1876006849] 'process raft request' (duration: 91.739089ms)","trace[1876006849] 'compare' (duration: 17.376144ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T01:15:58.691506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.285731ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-16T01:15:58.691959Z","caller":"traceutil/trace.go:171","msg":"trace[2116332120] transaction","detail":"{read_only:false; response_revision:25780; number_of_response:1; }","duration":"165.286845ms","start":"2026-07-16T01:15:58.526646Z","end":"2026-07-16T01:15:58.691933Z","steps":["trace[2116332120] 'process raft request' (duration: 165.194383ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:15:58.692065Z","caller":"traceutil/trace.go:171","msg":"trace[36925594] transaction","detail":"{read_only:false; response_revision:25779; number_of_response:1; }","duration":"288.685469ms","start":"2026-07-16T01:15:58.403262Z","end":"2026-07-16T01:15:58.691947Z","steps":["trace[36925594] 'process raft request' (duration: 288.422593ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:15:58.695905Z","caller":"traceutil/trace.go:171","msg":"trace[1448675316] transaction","detail":"{read_only:false; response_revision:25781; number_of_response:1; }","duration":"113.295777ms","start":"2026-07-16T01:15:58.582585Z","end":"2026-07-16T01:15:58.695881Z","steps":["trace[1448675316] 'process raft request' (duration: 112.818867ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:15:58.845263Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22293} {"level":"warn","ts":"2026-07-16T01:15:58.846364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.117642ms","expected-duration":"100ms","prefix":"","request":"header: compaction: ","response":"size:6"} {"level":"info","ts":"2026-07-16T01:15:58.846744Z","caller":"traceutil/trace.go:171","msg":"trace[1250008408] linearizableReadLoop","detail":"{readStateIndex:26618; appliedIndex:26617; }","duration":"149.049316ms","start":"2026-07-16T01:15:58.697669Z","end":"2026-07-16T01:15:58.846718Z","steps":["trace[1250008408] 'read index received' (duration: 10.961734ms)","trace[1250008408] 'applied index is now lower than readState.Index' (duration: 138.085502ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:15:58.846764Z","caller":"traceutil/trace.go:171","msg":"trace[1317077468] compact","detail":"{revision:22293; response_revision:25781; }","duration":"153.370484ms","start":"2026-07-16T01:15:58.693358Z","end":"2026-07-16T01:15:58.846728Z","steps":["trace[1317077468] 'check and update compact revision' (duration: 135.931069ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:15:58.847001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.311321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T01:15:58.847056Z","caller":"traceutil/trace.go:171","msg":"trace[568654207] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25781; }","duration":"149.427934ms","start":"2026-07-16T01:15:58.697609Z","end":"2026-07-16T01:15:58.847037Z","steps":["trace[568654207] 'agreement among raft nodes before linearized reading' (duration: 149.281371ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:15:58.94882Z","caller":"traceutil/trace.go:171","msg":"trace[343349403] linearizableReadLoop","detail":"{readStateIndex:26619; appliedIndex:26618; }","duration":"101.964637ms","start":"2026-07-16T01:15:58.846827Z","end":"2026-07-16T01:15:58.948791Z","steps":["trace[343349403] 'read index received' (duration: 101.765363ms)","trace[343349403] 'applied index is now lower than readState.Index' (duration: 197.794µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:15:58.948988Z","caller":"traceutil/trace.go:171","msg":"trace[42667893] transaction","detail":"{read_only:false; response_revision:25782; number_of_response:1; }","duration":"175.627337ms","start":"2026-07-16T01:15:58.773316Z","end":"2026-07-16T01:15:58.948943Z","steps":["trace[42667893] 'process raft request' (duration: 175.231499ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:15:58.94927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.43448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-16T01:15:58.949318Z","caller":"traceutil/trace.go:171","msg":"trace[301620444] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:25782; }","duration":"195.554873ms","start":"2026-07-16T01:15:58.753749Z","end":"2026-07-16T01:15:58.949304Z","steps":["trace[301620444] 'agreement among raft nodes before linearized reading' (duration: 195.149325ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:15:59.168826Z","caller":"traceutil/trace.go:171","msg":"trace[1012987045] linearizableReadLoop","detail":"{readStateIndex:26620; appliedIndex:26619; }","duration":"114.624203ms","start":"2026-07-16T01:15:59.054177Z","end":"2026-07-16T01:15:59.168802Z","steps":["trace[1012987045] 'read index received' (duration: 40.430633ms)","trace[1012987045] 'applied index is now lower than readState.Index' (duration: 74.19227ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:15:59.168909Z","caller":"traceutil/trace.go:171","msg":"trace[974695148] transaction","detail":"{read_only:false; response_revision:25783; number_of_response:1; }","duration":"318.472096ms","start":"2026-07-16T01:15:58.850396Z","end":"2026-07-16T01:15:59.168868Z","steps":["trace[974695148] 'process raft request' (duration: 244.240224ms)","trace[974695148] 'compare' (duration: 73.956125ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T01:15:59.169104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.888849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-16T01:15:59.169213Z","caller":"traceutil/trace.go:171","msg":"trace[1027872732] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25783; }","duration":"115.164995ms","start":"2026-07-16T01:15:59.054038Z","end":"2026-07-16T01:15:59.169203Z","steps":["trace[1027872732] 'agreement among raft nodes before linearized reading' (duration: 114.857439ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:15:59.169099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:15:58.850371Z","time spent":"318.616059ms","remote":"127.0.0.1:41514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T01:15:59.290807Z","caller":"traceutil/trace.go:171","msg":"trace[1237981872] transaction","detail":"{read_only:false; response_revision:25784; number_of_response:1; }","duration":"116.598283ms","start":"2026-07-16T01:15:59.174174Z","end":"2026-07-16T01:15:59.290772Z","steps":["trace[1237981872] 'process raft request' (duration: 26.158153ms)","trace[1237981872] 'compare' (duration: 88.051041ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:15:59.36307Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22293,"took":"511.499565ms","hash":275645396,"current-db-size-bytes":58818560,"current-db-size":"59 MB","current-db-size-in-use-bytes":45187072,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-07-16T01:15:59.363141Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":275645396,"revision":22293,"compact-revision":20094} {"level":"info","ts":"2026-07-16T01:16:03.33236Z","caller":"traceutil/trace.go:171","msg":"trace[2126662438] linearizableReadLoop","detail":"{readStateIndex:26653; appliedIndex:26652; }","duration":"110.829867ms","start":"2026-07-16T01:16:03.221502Z","end":"2026-07-16T01:16:03.332331Z","steps":["trace[2126662438] 'read index received' (duration: 110.672654ms)","trace[2126662438] 'applied index is now lower than readState.Index' (duration: 156.013µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:16:03.332484Z","caller":"traceutil/trace.go:171","msg":"trace[590734585] transaction","detail":"{read_only:false; response_revision:25815; number_of_response:1; }","duration":"130.381406ms","start":"2026-07-16T01:16:03.202063Z","end":"2026-07-16T01:16:03.332444Z","steps":["trace[590734585] 'process raft request' (duration: 130.08719ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:16:03.332615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.089233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-07-16T01:16:03.332658Z","caller":"traceutil/trace.go:171","msg":"trace[2146705190] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:25815; }","duration":"111.188224ms","start":"2026-07-16T01:16:03.221457Z","end":"2026-07-16T01:16:03.332645Z","steps":["trace[2146705190] 'agreement among raft nodes before linearized reading' (duration: 111.014801ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:16:08.86033Z","caller":"traceutil/trace.go:171","msg":"trace[1141894866] transaction","detail":"{read_only:false; response_revision:25854; number_of_response:1; }","duration":"131.931226ms","start":"2026-07-16T01:16:08.728326Z","end":"2026-07-16T01:16:08.860257Z","steps":["trace[1141894866] 'process raft request' (duration: 131.767963ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:16:09.211677Z","caller":"traceutil/trace.go:171","msg":"trace[1607825535] transaction","detail":"{read_only:false; response_revision:25855; number_of_response:1; }","duration":"212.760652ms","start":"2026-07-16T01:16:08.998875Z","end":"2026-07-16T01:16:09.211636Z","steps":["trace[1607825535] 'process raft request' (duration: 212.557517ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:16:09.372065Z","caller":"traceutil/trace.go:171","msg":"trace[1084418373] linearizableReadLoop","detail":"{readStateIndex:26695; appliedIndex:26694; }","duration":"132.905308ms","start":"2026-07-16T01:16:09.239129Z","end":"2026-07-16T01:16:09.372035Z","steps":["trace[1084418373] 'read index received' (duration: 118.239728ms)","trace[1084418373] 'applied index is now lower than readState.Index' (duration: 14.66444ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:16:09.372251Z","caller":"traceutil/trace.go:171","msg":"trace[115331453] transaction","detail":"{read_only:false; response_revision:25856; number_of_response:1; }","duration":"142.112475ms","start":"2026-07-16T01:16:09.230066Z","end":"2026-07-16T01:16:09.372178Z","steps":["trace[115331453] 'process raft request' (duration: 127.316103ms)","trace[115331453] 'compare' (duration: 14.381493ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T01:16:09.372437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.283995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-07-16T01:16:09.37249Z","caller":"traceutil/trace.go:171","msg":"trace[1175417532] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:25856; }","duration":"133.406758ms","start":"2026-07-16T01:16:09.239068Z","end":"2026-07-16T01:16:09.372475Z","steps":["trace[1175417532] 'agreement among raft nodes before linearized reading' (duration: 133.085721ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:16:09.5766Z","caller":"traceutil/trace.go:171","msg":"trace[1975822126] transaction","detail":"{read_only:false; response_revision:25857; number_of_response:1; }","duration":"155.671601ms","start":"2026-07-16T01:16:09.420903Z","end":"2026-07-16T01:16:09.576575Z","steps":["trace[1975822126] 'process raft request' (duration: 79.728975ms)","trace[1975822126] 'compare' (duration: 75.762483ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:16:09.576906Z","caller":"traceutil/trace.go:171","msg":"trace[1607172101] transaction","detail":"{read_only:false; response_revision:25858; number_of_response:1; }","duration":"153.562357ms","start":"2026-07-16T01:16:09.423312Z","end":"2026-07-16T01:16:09.576874Z","steps":["trace[1607172101] 'process raft request' (duration: 153.22204ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:16:09.577295Z","caller":"traceutil/trace.go:171","msg":"trace[1990059576] transaction","detail":"{read_only:false; response_revision:25859; number_of_response:1; }","duration":"116.850681ms","start":"2026-07-16T01:16:09.46039Z","end":"2026-07-16T01:16:09.57724Z","steps":["trace[1990059576] 'process raft request' (duration: 116.3234ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:16:09.659252Z","caller":"traceutil/trace.go:171","msg":"trace[1194614553] transaction","detail":"{read_only:false; response_revision:25860; number_of_response:1; }","duration":"125.993426ms","start":"2026-07-16T01:16:09.533235Z","end":"2026-07-16T01:16:09.659228Z","steps":["trace[1194614553] 'process raft request' (duration: 125.819962ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:16:09.829074Z","caller":"traceutil/trace.go:171","msg":"trace[441721481] transaction","detail":"{read_only:false; response_revision:25861; number_of_response:1; }","duration":"246.221314ms","start":"2026-07-16T01:16:09.58282Z","end":"2026-07-16T01:16:09.829041Z","steps":["trace[441721481] 'process raft request' (duration: 245.867947ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:16:09.829065Z","caller":"traceutil/trace.go:171","msg":"trace[1225395374] transaction","detail":"{read_only:false; response_revision:25862; number_of_response:1; }","duration":"206.350732ms","start":"2026-07-16T01:16:09.622677Z","end":"2026-07-16T01:16:09.829028Z","steps":["trace[1225395374] 'process raft request' (duration: 206.27593ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:16:09.879413Z","caller":"traceutil/trace.go:171","msg":"trace[638914338] linearizableReadLoop","detail":"{readStateIndex:26702; appliedIndex:26699; }","duration":"137.707124ms","start":"2026-07-16T01:16:09.741681Z","end":"2026-07-16T01:16:09.879388Z","steps":["trace[638914338] 'read index received' (duration: 87.009642ms)","trace[638914338] 'applied index is now lower than readState.Index' (duration: 50.695622ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:16:09.879545Z","caller":"traceutil/trace.go:171","msg":"trace[304877997] transaction","detail":"{read_only:false; response_revision:25864; number_of_response:1; }","duration":"122.77651ms","start":"2026-07-16T01:16:09.756755Z","end":"2026-07-16T01:16:09.879531Z","steps":["trace[304877997] 'process raft request' (duration: 122.585706ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:16:09.879561Z","caller":"traceutil/trace.go:171","msg":"trace[346444989] transaction","detail":"{read_only:false; response_revision:25863; number_of_response:1; }","duration":"170.792188ms","start":"2026-07-16T01:16:09.708719Z","end":"2026-07-16T01:16:09.879511Z","steps":["trace[346444989] 'process raft request' (duration: 170.459401ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:16:09.879819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.128452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-16T01:16:09.879865Z","caller":"traceutil/trace.go:171","msg":"trace[1489075668] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25864; }","duration":"138.239565ms","start":"2026-07-16T01:16:09.741614Z","end":"2026-07-16T01:16:09.879853Z","steps":["trace[1489075668] 'agreement among raft nodes before linearized reading' (duration: 138.067821ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:16:09.88014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.500963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-07-16T01:16:09.880257Z","caller":"traceutil/trace.go:171","msg":"trace[1162332943] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25864; }","duration":"117.642966ms","start":"2026-07-16T01:16:09.762562Z","end":"2026-07-16T01:16:09.880205Z","steps":["trace[1162332943] 'agreement among raft nodes before linearized reading' (duration: 117.36663ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:17:56.676453Z","caller":"traceutil/trace.go:171","msg":"trace[943934747] transaction","detail":"{read_only:false; response_revision:26926; number_of_response:1; }","duration":"110.108602ms","start":"2026-07-16T01:17:56.566318Z","end":"2026-07-16T01:17:56.676427Z","steps":["trace[943934747] 'process raft request' (duration: 66.974674ms)","trace[943934747] 'compare' (duration: 43.007626ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:17:56.679565Z","caller":"traceutil/trace.go:171","msg":"trace[1990354737] transaction","detail":"{read_only:false; response_revision:26927; number_of_response:1; }","duration":"109.259025ms","start":"2026-07-16T01:17:56.57028Z","end":"2026-07-16T01:17:56.679539Z","steps":["trace[1990354737] 'process raft request' (duration: 108.839837ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:20:58.853991Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25779} {"level":"info","ts":"2026-07-16T01:20:59.015919Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25779,"took":"156.851626ms","hash":1361234759,"current-db-size-bytes":59240448,"current-db-size":"59 MB","current-db-size-in-use-bytes":35749888,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-07-16T01:20:59.015991Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1361234759,"revision":25779,"compact-revision":22293} {"level":"info","ts":"2026-07-16T01:21:55.8404Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"380f6760faea5f9e","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-16T01:21:55.846266Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-07-16T01:21:55.846404Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-07-16T01:25:58.862759Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28661} {"level":"info","ts":"2026-07-16T01:25:59.007626Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28661,"took":"139.965891ms","hash":2587459802,"current-db-size-bytes":59240448,"current-db-size":"59 MB","current-db-size-in-use-bytes":31055872,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-16T01:25:59.007711Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2587459802,"revision":28661,"compact-revision":25779} {"level":"info","ts":"2026-07-16T01:30:58.871888Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30883} {"level":"info","ts":"2026-07-16T01:30:58.984446Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30883,"took":"107.864317ms","hash":2935904994,"current-db-size-bytes":59240448,"current-db-size":"59 MB","current-db-size-in-use-bytes":31555584,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-16T01:30:58.984503Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2935904994,"revision":30883,"compact-revision":28661} {"level":"info","ts":"2026-07-16T01:35:26.162198Z","caller":"traceutil/trace.go:171","msg":"trace[2002904158] transaction","detail":"{read_only:false; response_revision:35200; number_of_response:1; }","duration":"104.413078ms","start":"2026-07-16T01:35:26.05776Z","end":"2026-07-16T01:35:26.162173Z","steps":["trace[2002904158] 'process raft request' (duration: 87.87512ms)","trace[2002904158] 'compare' (duration: 16.147859ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:35:26.162323Z","caller":"traceutil/trace.go:171","msg":"trace[269112315] transaction","detail":"{read_only:false; response_revision:35201; number_of_response:1; }","duration":"104.299676ms","start":"2026-07-16T01:35:26.057983Z","end":"2026-07-16T01:35:26.162283Z","steps":["trace[269112315] 'process raft request' (duration: 104.094561ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:35:58.879849Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33147} {"level":"info","ts":"2026-07-16T01:35:59.049327Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33147,"took":"164.399118ms","hash":2029220226,"current-db-size-bytes":60284928,"current-db-size":"60 MB","current-db-size-in-use-bytes":30773248,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-16T01:35:59.049414Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2029220226,"revision":33147,"compact-revision":30883} {"level":"info","ts":"2026-07-16T01:40:58.891023Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35447} {"level":"info","ts":"2026-07-16T01:40:59.047451Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35447,"took":"150.819753ms","hash":3167448823,"current-db-size-bytes":60284928,"current-db-size":"60 MB","current-db-size-in-use-bytes":31756288,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-16T01:40:59.047547Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3167448823,"revision":35447,"compact-revision":33147} {"level":"info","ts":"2026-07-16T01:43:25.843319Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"380f6760faea5f9e","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-16T01:43:25.848938Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-07-16T01:43:25.849015Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-07-16T01:45:58.897763Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37676} {"level":"info","ts":"2026-07-16T01:45:59.025248Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37676,"took":"120.802691ms","hash":2952419191,"current-db-size-bytes":60706816,"current-db-size":"61 MB","current-db-size-in-use-bytes":32256000,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-16T01:45:59.025337Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2952419191,"revision":37676,"compact-revision":35447}