{"level":"warn","ts":"2026-04-17T04:32:30.723247Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-17T04:32:30.723537Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.250: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.250:2380","--initial-cluster=instance=https://199.204.45.250:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.250:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.250: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-04-17T04:32:30.723641Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-17T04:32:30.72366Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.250:2380"]} {"level":"info","ts":"2026-04-17T04:32:30.723689Z","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-04-17T04:32:30.724742Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.250:2379"]} {"level":"info","ts":"2026-04-17T04:32:30.724859Z","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.250:2380"],"listen-peer-urls":["https://199.204.45.250:2380"],"advertise-client-urls":["https://199.204.45.250:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.250:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.250: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-04-17T04:32:30.729517Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.347638ms"} {"level":"info","ts":"2026-04-17T04:32:30.740595Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d98d5f2c752cc52d","cluster-id":"c785b4aaf4bd3b11"} {"level":"info","ts":"2026-04-17T04:32:30.740742Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d switched to configuration voters=()"} {"level":"info","ts":"2026-04-17T04:32:30.740787Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became follower at term 0"} {"level":"info","ts":"2026-04-17T04:32:30.740807Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d98d5f2c752cc52d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-17T04:32:30.740825Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became follower at term 1"} {"level":"info","ts":"2026-04-17T04:32:30.740897Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d switched to configuration voters=(15676290522495632685)"} {"level":"warn","ts":"2026-04-17T04:32:30.749679Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-17T04:32:30.751178Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-17T04:32:30.752919Z","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-04-17T04:32:30.755233Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d98d5f2c752cc52d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-17T04:32:30.756382Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d98d5f2c752cc52d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-17T04:32:30.759125Z","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-04-17T04:32:30.759282Z","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-04-17T04:32:30.759305Z","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-04-17T04:32:30.75966Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d switched to configuration voters=(15676290522495632685)"} {"level":"info","ts":"2026-04-17T04:32:30.759881Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c785b4aaf4bd3b11","local-member-id":"d98d5f2c752cc52d","added-peer-id":"d98d5f2c752cc52d","added-peer-peer-urls":["https://199.204.45.250:2380"]} {"level":"info","ts":"2026-04-17T04:32:30.762321Z","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-04-17T04:32:30.762443Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.250:2380"} {"level":"info","ts":"2026-04-17T04:32:30.762497Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.250:2380"} {"level":"info","ts":"2026-04-17T04:32:30.762639Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d98d5f2c752cc52d","initial-advertise-peer-urls":["https://199.204.45.250:2380"],"listen-peer-urls":["https://199.204.45.250:2380"],"advertise-client-urls":["https://199.204.45.250:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.250:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-17T04:32:30.762689Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-17T04:32:31.241593Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d is starting a new election at term 1"} {"level":"info","ts":"2026-04-17T04:32:31.241695Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became pre-candidate at term 1"} {"level":"info","ts":"2026-04-17T04:32:31.241803Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d received MsgPreVoteResp from d98d5f2c752cc52d at term 1"} {"level":"info","ts":"2026-04-17T04:32:31.241842Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became candidate at term 2"} {"level":"info","ts":"2026-04-17T04:32:31.241865Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d received MsgVoteResp from d98d5f2c752cc52d at term 2"} {"level":"info","ts":"2026-04-17T04:32:31.241888Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became leader at term 2"} {"level":"info","ts":"2026-04-17T04:32:31.241909Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d98d5f2c752cc52d elected leader d98d5f2c752cc52d at term 2"} {"level":"info","ts":"2026-04-17T04:32:31.24312Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d98d5f2c752cc52d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.250:2379]}","request-path":"/0/members/d98d5f2c752cc52d/attributes","cluster-id":"c785b4aaf4bd3b11","publish-timeout":"7s"} {"level":"info","ts":"2026-04-17T04:32:31.243128Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-17T04:32:31.243187Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-17T04:32:31.243396Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-17T04:32:31.243149Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T04:32:31.243442Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-17T04:32:31.244702Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c785b4aaf4bd3b11","local-member-id":"d98d5f2c752cc52d","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T04:32:31.244899Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T04:32:31.244954Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T04:32:31.24671Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.250:2379"} {"level":"info","ts":"2026-04-17T04:32:31.247197Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-17T04:32:33.336981Z","caller":"traceutil/trace.go:171","msg":"trace[1182649782] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"145.383304ms","start":"2026-04-17T04:32:33.19155Z","end":"2026-04-17T04:32:33.336933Z","steps":["trace[1182649782] 'process raft request' (duration: 131.047394ms)","trace[1182649782] 'compare' (duration: 14.118555ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:32:33.337238Z","caller":"traceutil/trace.go:171","msg":"trace[506431216] linearizableReadLoop","detail":"{readStateIndex:25; appliedIndex:15; }","duration":"101.891812ms","start":"2026-04-17T04:32:33.235318Z","end":"2026-04-17T04:32:33.337209Z","steps":["trace[506431216] 'read index received' (duration: 87.390999ms)","trace[506431216] 'applied index is now lower than readState.Index' (duration: 14.499793ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:32:33.337328Z","caller":"traceutil/trace.go:171","msg":"trace[752840287] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"142.709259ms","start":"2026-04-17T04:32:33.194608Z","end":"2026-04-17T04:32:33.337317Z","steps":["trace[752840287] 'process raft request' (duration: 142.405783ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:32:33.337118Z","caller":"traceutil/trace.go:171","msg":"trace[2002091174] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"145.447744ms","start":"2026-04-17T04:32:33.191611Z","end":"2026-04-17T04:32:33.337059Z","steps":["trace[2002091174] 'process raft request' (duration: 145.267711ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:32:33.337264Z","caller":"traceutil/trace.go:171","msg":"trace[295632552] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"144.040536ms","start":"2026-04-17T04:32:33.193031Z","end":"2026-04-17T04:32:33.337071Z","steps":["trace[295632552] 'process raft request' (duration: 143.888093ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:32:33.337564Z","caller":"traceutil/trace.go:171","msg":"trace[2139758953] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"144.507755ms","start":"2026-04-17T04:32:33.193047Z","end":"2026-04-17T04:32:33.337555Z","steps":["trace[2139758953] 'process raft request' (duration: 143.903903ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:32:33.337793Z","caller":"traceutil/trace.go:171","msg":"trace[1622135160] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"144.418224ms","start":"2026-04-17T04:32:33.193358Z","end":"2026-04-17T04:32:33.337776Z","steps":["trace[1622135160] 'process raft request' (duration: 143.633098ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:32:33.337853Z","caller":"traceutil/trace.go:171","msg":"trace[1382801811] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"144.579827ms","start":"2026-04-17T04:32:33.193262Z","end":"2026-04-17T04:32:33.337842Z","steps":["trace[1382801811] 'process raft request' (duration: 143.70968ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:32:33.337874Z","caller":"traceutil/trace.go:171","msg":"trace[1048535300] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"143.156898ms","start":"2026-04-17T04:32:33.194707Z","end":"2026-04-17T04:32:33.337864Z","steps":["trace[1048535300] 'process raft request' (duration: 142.345362ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:32:33.337978Z","caller":"traceutil/trace.go:171","msg":"trace[556160453] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"140.465365ms","start":"2026-04-17T04:32:33.197507Z","end":"2026-04-17T04:32:33.337972Z","steps":["trace[556160453] 'process raft request' (duration: 139.658157ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:32:33.337876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.482215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-04-17T04:32:33.338035Z","caller":"traceutil/trace.go:171","msg":"trace[92300788] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:21; }","duration":"102.766841ms","start":"2026-04-17T04:32:33.23525Z","end":"2026-04-17T04:32:33.338017Z","steps":["trace[92300788] 'agreement among raft nodes before linearized reading' (duration: 102.065866ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:32:33.338119Z","caller":"traceutil/trace.go:171","msg":"trace[1191213133] transaction","detail":"{read_only:false; number_of_response:0; response_revision:20; }","duration":"143.259501ms","start":"2026-04-17T04:32:33.194723Z","end":"2026-04-17T04:32:33.337983Z","steps":["trace[1191213133] 'process raft request' (duration: 142.421373ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:32:54.97509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.747758ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T04:32:54.975237Z","caller":"traceutil/trace.go:171","msg":"trace[146413498] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"171.124826ms","start":"2026-04-17T04:32:54.804093Z","end":"2026-04-17T04:32:54.975218Z","steps":["trace[146413498] 'process raft request' (duration: 24.605212ms)","trace[146413498] 'compare' (duration: 145.560065ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:32:59.155512Z","caller":"traceutil/trace.go:171","msg":"trace[1782382125] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"110.528684ms","start":"2026-04-17T04:32:59.044956Z","end":"2026-04-17T04:32:59.155485Z","steps":["trace[1782382125] 'process raft request' (duration: 107.83385ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:32:59.156969Z","caller":"traceutil/trace.go:171","msg":"trace[1117142608] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"111.30999ms","start":"2026-04-17T04:32:59.045626Z","end":"2026-04-17T04:32:59.156936Z","steps":["trace[1117142608] 'process raft request' (duration: 111.057775ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:33:00.203108Z","caller":"traceutil/trace.go:171","msg":"trace[1889494310] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"110.092085ms","start":"2026-04-17T04:33:00.092992Z","end":"2026-04-17T04:33:00.203084Z","steps":["trace[1889494310] 'process raft request' (duration: 109.577694ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:33:00.203838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.635775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:33:00.203894Z","caller":"traceutil/trace.go:171","msg":"trace[777826317] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:570; }","duration":"101.744678ms","start":"2026-04-17T04:33:00.102136Z","end":"2026-04-17T04:33:00.20388Z","steps":["trace[777826317] 'agreement among raft nodes before linearized reading' (duration: 101.637596ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:33:00.204853Z","caller":"traceutil/trace.go:171","msg":"trace[728480712] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"100.029183ms","start":"2026-04-17T04:33:00.104787Z","end":"2026-04-17T04:33:00.204816Z","steps":["trace[728480712] 'process raft request' (duration: 99.86365ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:33:00.203732Z","caller":"traceutil/trace.go:171","msg":"trace[1574715644] linearizableReadLoop","detail":"{readStateIndex:589; appliedIndex:588; }","duration":"100.841249ms","start":"2026-04-17T04:33:00.102178Z","end":"2026-04-17T04:33:00.203019Z","steps":["trace[1574715644] 'read index received' (duration: 100.266368ms)","trace[1574715644] 'applied index is now lower than readState.Index' (duration: 573.661µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:33:23.633861Z","caller":"traceutil/trace.go:171","msg":"trace[819435347] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"158.25686ms","start":"2026-04-17T04:33:23.475579Z","end":"2026-04-17T04:33:23.633836Z","steps":["trace[819435347] 'process raft request' (duration: 158.095466ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:38:29.778119Z","caller":"traceutil/trace.go:171","msg":"trace[1875333114] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"121.88289ms","start":"2026-04-17T04:38:29.656211Z","end":"2026-04-17T04:38:29.778094Z","steps":["trace[1875333114] 'process raft request' (duration: 121.718696ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:40:13.616139Z","caller":"traceutil/trace.go:171","msg":"trace[2047672125] transaction","detail":"{read_only:false; response_revision:2195; number_of_response:1; }","duration":"180.205481ms","start":"2026-04-17T04:40:13.435907Z","end":"2026-04-17T04:40:13.616112Z","steps":["trace[2047672125] 'process raft request' (duration: 180.047978ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:40:13.812167Z","caller":"traceutil/trace.go:171","msg":"trace[1409462127] linearizableReadLoop","detail":"{readStateIndex:2304; appliedIndex:2303; }","duration":"160.546102ms","start":"2026-04-17T04:40:13.651594Z","end":"2026-04-17T04:40:13.812141Z","steps":["trace[1409462127] 'read index received' (duration: 160.42402ms)","trace[1409462127] 'applied index is now lower than readState.Index' (duration: 120.742µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:40:13.812291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.679266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:40:13.812235Z","caller":"traceutil/trace.go:171","msg":"trace[2146837918] transaction","detail":"{read_only:false; response_revision:2196; number_of_response:1; }","duration":"262.59327ms","start":"2026-04-17T04:40:13.549593Z","end":"2026-04-17T04:40:13.812186Z","steps":["trace[2146837918] 'process raft request' (duration: 262.407116ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:40:13.81236Z","caller":"traceutil/trace.go:171","msg":"trace[222807325] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2196; }","duration":"160.730437ms","start":"2026-04-17T04:40:13.651585Z","end":"2026-04-17T04:40:13.812315Z","steps":["trace[222807325] 'agreement among raft nodes before linearized reading' (duration: 160.657395ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:40:18.546834Z","caller":"traceutil/trace.go:171","msg":"trace[1154845254] transaction","detail":"{read_only:false; response_revision:2212; number_of_response:1; }","duration":"191.889407ms","start":"2026-04-17T04:40:18.354908Z","end":"2026-04-17T04:40:18.546797Z","steps":["trace[1154845254] 'process raft request' (duration: 191.704143ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:40:21.951576Z","caller":"traceutil/trace.go:171","msg":"trace[558632603] transaction","detail":"{read_only:false; response_revision:2222; number_of_response:1; }","duration":"117.094774ms","start":"2026-04-17T04:40:21.834463Z","end":"2026-04-17T04:40:21.951558Z","steps":["trace[558632603] 'process raft request' (duration: 116.976011ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:40:23.199456Z","caller":"traceutil/trace.go:171","msg":"trace[87362224] transaction","detail":"{read_only:false; response_revision:2226; number_of_response:1; }","duration":"115.604253ms","start":"2026-04-17T04:40:23.083803Z","end":"2026-04-17T04:40:23.199407Z","steps":["trace[87362224] 'process raft request' (duration: 115.394849ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:40:28.811356Z","caller":"traceutil/trace.go:171","msg":"trace[388355829] transaction","detail":"{read_only:false; response_revision:2245; number_of_response:1; }","duration":"109.395879ms","start":"2026-04-17T04:40:28.701924Z","end":"2026-04-17T04:40:28.81132Z","steps":["trace[388355829] 'process raft request' (duration: 109.200045ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:40:29.209238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.038821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:40:29.209326Z","caller":"traceutil/trace.go:171","msg":"trace[1605797172] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2246; }","duration":"109.173554ms","start":"2026-04-17T04:40:29.100132Z","end":"2026-04-17T04:40:29.209306Z","steps":["trace[1605797172] 'range keys from in-memory index tree' (duration: 108.95172ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:40:29.425097Z","caller":"traceutil/trace.go:171","msg":"trace[57281713] transaction","detail":"{read_only:false; response_revision:2248; number_of_response:1; }","duration":"112.810708ms","start":"2026-04-17T04:40:29.312263Z","end":"2026-04-17T04:40:29.425074Z","steps":["trace[57281713] 'process raft request' (duration: 80.324305ms)","trace[57281713] 'compare' (duration: 32.369381ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:40:36.30836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.151395ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T04:40:36.308503Z","caller":"traceutil/trace.go:171","msg":"trace[1849219161] transaction","detail":"{read_only:false; response_revision:2270; number_of_response:1; }","duration":"134.167797ms","start":"2026-04-17T04:40:36.174305Z","end":"2026-04-17T04:40:36.308473Z","steps":["trace[1849219161] 'process raft request' (duration: 14.764977ms)","trace[1849219161] 'compare' (duration: 118.971842ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:40:39.460613Z","caller":"traceutil/trace.go:171","msg":"trace[1732430644] transaction","detail":"{read_only:false; response_revision:2280; number_of_response:1; }","duration":"111.617163ms","start":"2026-04-17T04:40:39.348977Z","end":"2026-04-17T04:40:39.460594Z","steps":["trace[1732430644] 'process raft request' (duration: 111.506681ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:40:42.502201Z","caller":"traceutil/trace.go:171","msg":"trace[2133434639] transaction","detail":"{read_only:false; response_revision:2288; number_of_response:1; }","duration":"120.579384ms","start":"2026-04-17T04:40:42.381604Z","end":"2026-04-17T04:40:42.502183Z","steps":["trace[2133434639] 'process raft request' (duration: 120.467332ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:40:42.692797Z","caller":"traceutil/trace.go:171","msg":"trace[999986895] transaction","detail":"{read_only:false; response_revision:2289; number_of_response:1; }","duration":"144.801501ms","start":"2026-04-17T04:40:42.547968Z","end":"2026-04-17T04:40:42.69277Z","steps":["trace[999986895] 'process raft request' (duration: 144.655408ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:40:43.261459Z","caller":"traceutil/trace.go:171","msg":"trace[650086738] transaction","detail":"{read_only:false; response_revision:2290; number_of_response:1; }","duration":"159.433015ms","start":"2026-04-17T04:40:43.102007Z","end":"2026-04-17T04:40:43.26144Z","steps":["trace[650086738] 'process raft request' (duration: 158.954996ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:40:44.997883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.656171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.250\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-17T04:40:44.997978Z","caller":"traceutil/trace.go:171","msg":"trace[407800708] range","detail":"{range_begin:/registry/masterleases/199.204.45.250; range_end:; response_count:1; response_revision:2296; }","duration":"182.781574ms","start":"2026-04-17T04:40:44.815165Z","end":"2026-04-17T04:40:44.997946Z","steps":["trace[407800708] 'range keys from in-memory index tree' (duration: 182.487328ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:40:45.233691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.946972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:40:45.233778Z","caller":"traceutil/trace.go:171","msg":"trace[331929176] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2297; }","duration":"132.078724ms","start":"2026-04-17T04:40:45.101681Z","end":"2026-04-17T04:40:45.23376Z","steps":["trace[331929176] 'range keys from in-memory index tree' (duration: 131.891251ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:40:45.514048Z","caller":"traceutil/trace.go:171","msg":"trace[1911046092] transaction","detail":"{read_only:false; response_revision:2299; number_of_response:1; }","duration":"242.240299ms","start":"2026-04-17T04:40:45.271788Z","end":"2026-04-17T04:40:45.514029Z","steps":["trace[1911046092] 'process raft request' (duration: 171.343604ms)","trace[1911046092] 'compare' (duration: 70.792793ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:40:50.001083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.553845ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-17T04:40:53.965929Z","caller":"traceutil/trace.go:171","msg":"trace[268729704] linearizableReadLoop","detail":"{readStateIndex:2441; appliedIndex:2440; }","duration":"181.468858ms","start":"2026-04-17T04:40:53.784426Z","end":"2026-04-17T04:40:53.965895Z","steps":["trace[268729704] 'read index received' (duration: 181.224663ms)","trace[268729704] 'applied index is now lower than readState.Index' (duration: 242.655µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:40:53.965963Z","caller":"traceutil/trace.go:171","msg":"trace[1273606910] transaction","detail":"{read_only:false; response_revision:2325; number_of_response:1; }","duration":"342.035365ms","start":"2026-04-17T04:40:53.623898Z","end":"2026-04-17T04:40:53.965933Z","steps":["trace[1273606910] 'process raft request' (duration: 341.844492ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:40:53.966106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:40:53.623876Z","time spent":"342.152737ms","remote":"127.0.0.1:37306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T04:40:53.966266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.993566ms","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-04-17T04:40:53.966337Z","caller":"traceutil/trace.go:171","msg":"trace[1496647435] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2325; }","duration":"164.126008ms","start":"2026-04-17T04:40:53.802198Z","end":"2026-04-17T04:40:53.966324Z","steps":["trace[1496647435] 'agreement among raft nodes before linearized reading' (duration: 163.878503ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:40:53.966259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.803444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-17T04:40:53.96641Z","caller":"traceutil/trace.go:171","msg":"trace[782343225] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2325; }","duration":"182.009758ms","start":"2026-04-17T04:40:53.784375Z","end":"2026-04-17T04:40:53.966385Z","steps":["trace[782343225] 'agreement among raft nodes before linearized reading' (duration: 181.686372ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:40:54.162027Z","caller":"traceutil/trace.go:171","msg":"trace[1018504294] transaction","detail":"{read_only:false; response_revision:2326; number_of_response:1; }","duration":"191.55349ms","start":"2026-04-17T04:40:53.970438Z","end":"2026-04-17T04:40:54.161992Z","steps":["trace[1018504294] 'process raft request' (duration: 121.428701ms)","trace[1018504294] 'compare' (duration: 69.839864ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:40:54.162041Z","caller":"traceutil/trace.go:171","msg":"trace[984496316] transaction","detail":"{read_only:false; response_revision:2327; number_of_response:1; }","duration":"190.730274ms","start":"2026-04-17T04:40:53.971285Z","end":"2026-04-17T04:40:54.162015Z","steps":["trace[984496316] 'process raft request' (duration: 190.602461ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:41:04.828917Z","caller":"traceutil/trace.go:171","msg":"trace[1208238176] transaction","detail":"{read_only:false; response_revision:2361; number_of_response:1; }","duration":"116.678016ms","start":"2026-04-17T04:41:04.712203Z","end":"2026-04-17T04:41:04.828881Z","steps":["trace[1208238176] 'process raft request' (duration: 116.504653ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:41:10.19734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.43213ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-17T04:41:12.614089Z","caller":"traceutil/trace.go:171","msg":"trace[1536148253] transaction","detail":"{read_only:false; response_revision:2388; number_of_response:1; }","duration":"104.755346ms","start":"2026-04-17T04:41:12.509314Z","end":"2026-04-17T04:41:12.614069Z","steps":["trace[1536148253] 'process raft request' (duration: 27.551274ms)","trace[1536148253] 'compare' (duration: 77.125991ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:41:13.824165Z","caller":"traceutil/trace.go:171","msg":"trace[991930493] transaction","detail":"{read_only:false; response_revision:2391; number_of_response:1; }","duration":"119.817599ms","start":"2026-04-17T04:41:13.704332Z","end":"2026-04-17T04:41:13.824149Z","steps":["trace[991930493] 'process raft request' (duration: 119.471032ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:41:15.151096Z","caller":"traceutil/trace.go:171","msg":"trace[308820260] linearizableReadLoop","detail":"{readStateIndex:2518; appliedIndex:2517; }","duration":"121.328459ms","start":"2026-04-17T04:41:15.029745Z","end":"2026-04-17T04:41:15.151073Z","steps":["trace[308820260] 'read index received' (duration: 121.059314ms)","trace[308820260] 'applied index is now lower than readState.Index' (duration: 213.494µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:41:15.15121Z","caller":"traceutil/trace.go:171","msg":"trace[2033519491] transaction","detail":"{read_only:false; response_revision:2397; number_of_response:1; }","duration":"199.862698ms","start":"2026-04-17T04:41:14.951316Z","end":"2026-04-17T04:41:15.151179Z","steps":["trace[2033519491] 'process raft request' (duration: 199.577432ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:41:15.151257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.494153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:41:15.151293Z","caller":"traceutil/trace.go:171","msg":"trace[1851912824] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:2397; }","duration":"121.579134ms","start":"2026-04-17T04:41:15.029702Z","end":"2026-04-17T04:41:15.151282Z","steps":["trace[1851912824] 'agreement among raft nodes before linearized reading' (duration: 121.502013ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:41:20.027713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.694353ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-17T04:41:20.027852Z","caller":"traceutil/trace.go:171","msg":"trace[1409823148] linearizableReadLoop","detail":"{readStateIndex:2533; appliedIndex:2532; }","duration":"136.040441ms","start":"2026-04-17T04:41:19.891783Z","end":"2026-04-17T04:41:20.027823Z","steps":["trace[1409823148] 'read index received' (duration: 18.178885ms)","trace[1409823148] 'applied index is now lower than readState.Index' (duration: 117.859396ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:41:20.028008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.220804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-17T04:41:20.028041Z","caller":"traceutil/trace.go:171","msg":"trace[1036297701] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2411; }","duration":"136.301316ms","start":"2026-04-17T04:41:19.891732Z","end":"2026-04-17T04:41:20.028033Z","steps":["trace[1036297701] 'agreement among raft nodes before linearized reading' (duration: 136.161643ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:41:20.152126Z","caller":"traceutil/trace.go:171","msg":"trace[718974748] transaction","detail":"{read_only:false; response_revision:2412; number_of_response:1; }","duration":"120.579511ms","start":"2026-04-17T04:41:20.031514Z","end":"2026-04-17T04:41:20.152094Z","steps":["trace[718974748] 'process raft request' (duration: 120.259414ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:41:20.558853Z","caller":"traceutil/trace.go:171","msg":"trace[1066811365] transaction","detail":"{read_only:false; response_revision:2413; number_of_response:1; }","duration":"134.693024ms","start":"2026-04-17T04:41:20.424139Z","end":"2026-04-17T04:41:20.558832Z","steps":["trace[1066811365] 'process raft request' (duration: 134.572102ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:41:21.066253Z","caller":"traceutil/trace.go:171","msg":"trace[628154746] transaction","detail":"{read_only:false; response_revision:2414; number_of_response:1; }","duration":"355.65176ms","start":"2026-04-17T04:41:20.710583Z","end":"2026-04-17T04:41:21.066235Z","steps":["trace[628154746] 'process raft request' (duration: 355.496777ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:41:21.06638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:41:20.71056Z","time spent":"355.760683ms","remote":"127.0.0.1:37306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T04:41:21.212159Z","caller":"traceutil/trace.go:171","msg":"trace[1972465124] transaction","detail":"{read_only:false; response_revision:2415; number_of_response:1; }","duration":"501.082ms","start":"2026-04-17T04:41:20.71103Z","end":"2026-04-17T04:41:21.212112Z","steps":["trace[1972465124] 'process raft request' (duration: 454.764391ms)","trace[1972465124] 'compare' (duration: 46.131456ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:41:21.212451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:41:20.711002Z","time spent":"501.292455ms","remote":"127.0.0.1:37306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T04:41:23.292561Z","caller":"traceutil/trace.go:171","msg":"trace[1839832146] transaction","detail":"{read_only:false; response_revision:2420; number_of_response:1; }","duration":"218.495467ms","start":"2026-04-17T04:41:23.074026Z","end":"2026-04-17T04:41:23.292522Z","steps":["trace[1839832146] 'process raft request' (duration: 218.189091ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:41:23.596012Z","caller":"traceutil/trace.go:171","msg":"trace[458085459] transaction","detail":"{read_only:false; response_revision:2423; number_of_response:1; }","duration":"100.508458ms","start":"2026-04-17T04:41:23.495466Z","end":"2026-04-17T04:41:23.595975Z","steps":["trace[458085459] 'process raft request' (duration: 100.369316ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:41:25.508756Z","caller":"traceutil/trace.go:171","msg":"trace[111491322] transaction","detail":"{read_only:false; response_revision:2430; number_of_response:1; }","duration":"156.777537ms","start":"2026-04-17T04:41:25.351957Z","end":"2026-04-17T04:41:25.508734Z","steps":["trace[111491322] 'process raft request' (duration: 155.701735ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:41:25.905238Z","caller":"traceutil/trace.go:171","msg":"trace[1204255028] transaction","detail":"{read_only:false; response_revision:2431; number_of_response:1; }","duration":"302.152445ms","start":"2026-04-17T04:41:25.60306Z","end":"2026-04-17T04:41:25.905213Z","steps":["trace[1204255028] 'process raft request' (duration: 301.977312ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:41:25.905395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:41:25.603032Z","time spent":"302.282608ms","remote":"127.0.0.1:37306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T04:41:27.63812Z","caller":"traceutil/trace.go:171","msg":"trace[825446006] linearizableReadLoop","detail":"{readStateIndex:2559; appliedIndex:2557; }","duration":"125.385867ms","start":"2026-04-17T04:41:27.512708Z","end":"2026-04-17T04:41:27.638094Z","steps":["trace[825446006] 'read index received' (duration: 37.448182ms)","trace[825446006] 'applied index is now lower than readState.Index' (duration: 87.936195ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:41:27.638282Z","caller":"traceutil/trace.go:171","msg":"trace[1396209893] transaction","detail":"{read_only:false; response_revision:2435; number_of_response:1; }","duration":"285.328709ms","start":"2026-04-17T04:41:27.352921Z","end":"2026-04-17T04:41:27.63825Z","steps":["trace[1396209893] 'process raft request' (duration: 197.296171ms)","trace[1396209893] 'compare' (duration: 87.605959ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:41:27.638359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.639902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-17T04:41:27.638395Z","caller":"traceutil/trace.go:171","msg":"trace[1985264614] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2436; }","duration":"125.727304ms","start":"2026-04-17T04:41:27.512656Z","end":"2026-04-17T04:41:27.638383Z","steps":["trace[1985264614] 'agreement among raft nodes before linearized reading' (duration: 125.52969ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:41:27.638411Z","caller":"traceutil/trace.go:171","msg":"trace[1521174634] transaction","detail":"{read_only:false; response_revision:2436; number_of_response:1; }","duration":"251.797306ms","start":"2026-04-17T04:41:27.386568Z","end":"2026-04-17T04:41:27.638366Z","steps":["trace[1521174634] 'process raft request' (duration: 251.398608ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:41:27.74465Z","caller":"traceutil/trace.go:171","msg":"trace[647204292] transaction","detail":"{read_only:false; response_revision:2437; number_of_response:1; }","duration":"102.14946ms","start":"2026-04-17T04:41:27.642472Z","end":"2026-04-17T04:41:27.744621Z","steps":["trace[647204292] 'process raft request' (duration: 101.868645ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:41:28.139344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.904846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-17T04:41:28.139411Z","caller":"traceutil/trace.go:171","msg":"trace[258363337] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2437; }","duration":"230.021069ms","start":"2026-04-17T04:41:27.909375Z","end":"2026-04-17T04:41:28.139396Z","steps":["trace[258363337] 'range keys from in-memory index tree' (duration: 229.738923ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:41:29.813716Z","caller":"traceutil/trace.go:171","msg":"trace[681659346] transaction","detail":"{read_only:false; response_revision:2442; number_of_response:1; }","duration":"171.087965ms","start":"2026-04-17T04:41:29.64259Z","end":"2026-04-17T04:41:29.813678Z","steps":["trace[681659346] 'process raft request' (duration: 170.837289ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:41:30.236113Z","caller":"traceutil/trace.go:171","msg":"trace[591915200] transaction","detail":"{read_only:false; response_revision:2443; number_of_response:1; }","duration":"417.925681ms","start":"2026-04-17T04:41:29.818164Z","end":"2026-04-17T04:41:30.236089Z","steps":["trace[591915200] 'process raft request' (duration: 417.777239ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:41:30.236254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:41:29.818143Z","time spent":"418.039403ms","remote":"127.0.0.1:37306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T04:41:30.28426Z","caller":"traceutil/trace.go:171","msg":"trace[159120461] linearizableReadLoop","detail":"{readStateIndex:2567; appliedIndex:2565; }","duration":"181.886632ms","start":"2026-04-17T04:41:30.102354Z","end":"2026-04-17T04:41:30.28424Z","steps":["trace[159120461] 'read index received' (duration: 133.571231ms)","trace[159120461] 'applied index is now lower than readState.Index' (duration: 48.314151ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:41:30.284413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.036485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:41:30.28448Z","caller":"traceutil/trace.go:171","msg":"trace[867969783] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2443; }","duration":"182.159237ms","start":"2026-04-17T04:41:30.102303Z","end":"2026-04-17T04:41:30.284463Z","steps":["trace[867969783] 'agreement among raft nodes before linearized reading' (duration: 182.044025ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:41:30.284473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.037512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-17T04:41:30.284522Z","caller":"traceutil/trace.go:171","msg":"trace[107017459] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2443; }","duration":"136.120583ms","start":"2026-04-17T04:41:30.148391Z","end":"2026-04-17T04:41:30.284512Z","steps":["trace[107017459] 'agreement among raft nodes before linearized reading' (duration: 135.984331ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:41:30.418936Z","caller":"traceutil/trace.go:171","msg":"trace[1763977422] transaction","detail":"{read_only:false; response_revision:2444; number_of_response:1; }","duration":"130.747106ms","start":"2026-04-17T04:41:30.288169Z","end":"2026-04-17T04:41:30.418917Z","steps":["trace[1763977422] 'process raft request' (duration: 130.598303ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:41:30.7111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.832449ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T04:41:30.711203Z","caller":"traceutil/trace.go:171","msg":"trace[592858538] transaction","detail":"{read_only:false; response_revision:2445; number_of_response:1; }","duration":"422.375541ms","start":"2026-04-17T04:41:30.288809Z","end":"2026-04-17T04:41:30.711184Z","steps":["trace[592858538] 'process raft request' (duration: 267.353878ms)","trace[592858538] 'compare' (duration: 154.645055ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:41:30.711317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:41:30.288791Z","time spent":"422.459172ms","remote":"127.0.0.1:37306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T04:41:31.063192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.010247ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T04:41:31.063308Z","caller":"traceutil/trace.go:171","msg":"trace[1507522235] transaction","detail":"{read_only:false; response_revision:2446; number_of_response:1; }","duration":"348.755523ms","start":"2026-04-17T04:41:30.714531Z","end":"2026-04-17T04:41:31.063286Z","steps":["trace[1507522235] 'process raft request' (duration: 133.571112ms)","trace[1507522235] 'compare' (duration: 214.749671ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:41:31.063368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:41:30.71451Z","time spent":"348.830395ms","remote":"127.0.0.1:37306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T04:41:32.543171Z","caller":"traceutil/trace.go:171","msg":"trace[369796996] linearizableReadLoop","detail":"{readStateIndex:2573; appliedIndex:2572; }","duration":"120.06187ms","start":"2026-04-17T04:41:32.423079Z","end":"2026-04-17T04:41:32.543141Z","steps":["trace[369796996] 'read index received' (duration: 119.880727ms)","trace[369796996] 'applied index is now lower than readState.Index' (duration: 179.833µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:41:32.543389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.291016ms","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-04-17T04:41:32.543435Z","caller":"traceutil/trace.go:171","msg":"trace[1176202231] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2449; }","duration":"120.395177ms","start":"2026-04-17T04:41:32.423026Z","end":"2026-04-17T04:41:32.543421Z","steps":["trace[1176202231] 'agreement among raft nodes before linearized reading' (duration: 120.227664ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:41:32.543342Z","caller":"traceutil/trace.go:171","msg":"trace[164110674] transaction","detail":"{read_only:false; response_revision:2449; number_of_response:1; }","duration":"298.384152ms","start":"2026-04-17T04:41:32.244916Z","end":"2026-04-17T04:41:32.5433Z","steps":["trace[164110674] 'process raft request' (duration: 298.045445ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:41:32.728167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.90915ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T04:41:32.728482Z","caller":"traceutil/trace.go:171","msg":"trace[1308088220] transaction","detail":"{read_only:false; response_revision:2450; number_of_response:1; }","duration":"180.546345ms","start":"2026-04-17T04:41:32.547887Z","end":"2026-04-17T04:41:32.728434Z","steps":["trace[1308088220] 'process raft request' (duration: 20.258347ms)","trace[1308088220] 'compare' (duration: 159.801648ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:41:33.260429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.589724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:88122"} {"level":"warn","ts":"2026-04-17T04:41:33.260475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.522235ms","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-04-17T04:41:33.260504Z","caller":"traceutil/trace.go:171","msg":"trace[1951092237] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:2451; }","duration":"144.708736ms","start":"2026-04-17T04:41:33.115779Z","end":"2026-04-17T04:41:33.260488Z","steps":["trace[1951092237] 'range keys from in-memory index tree' (duration: 144.365869ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:41:33.260826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.153184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-04-17T04:41:33.260906Z","caller":"traceutil/trace.go:171","msg":"trace[1645193070] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:2451; }","duration":"145.276447ms","start":"2026-04-17T04:41:33.115607Z","end":"2026-04-17T04:41:33.260883Z","steps":["trace[1645193070] 'range keys from in-memory index tree' (duration: 145.038302ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:41:33.260568Z","caller":"traceutil/trace.go:171","msg":"trace[2033283348] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2451; }","duration":"193.682678ms","start":"2026-04-17T04:41:33.066868Z","end":"2026-04-17T04:41:33.26055Z","steps":["trace[2033283348] 'range keys from in-memory index tree' (duration: 193.260451ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:41:33.553996Z","caller":"traceutil/trace.go:171","msg":"trace[1053548320] linearizableReadLoop","detail":"{readStateIndex:2576; appliedIndex:2575; }","duration":"288.500203ms","start":"2026-04-17T04:41:33.265465Z","end":"2026-04-17T04:41:33.553965Z","steps":["trace[1053548320] 'read index received' (duration: 288.249598ms)","trace[1053548320] 'applied index is now lower than readState.Index' (duration: 249.125µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:41:33.554172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.679966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-04-17T04:41:33.554229Z","caller":"traceutil/trace.go:171","msg":"trace[1016538800] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:2452; }","duration":"288.796659ms","start":"2026-04-17T04:41:33.265419Z","end":"2026-04-17T04:41:33.554216Z","steps":["trace[1016538800] 'agreement among raft nodes before linearized reading' (duration: 288.650466ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:41:33.554192Z","caller":"traceutil/trace.go:171","msg":"trace[1088647499] transaction","detail":"{read_only:false; response_revision:2452; number_of_response:1; }","duration":"288.641856ms","start":"2026-04-17T04:41:33.265413Z","end":"2026-04-17T04:41:33.554055Z","steps":["trace[1088647499] 'process raft request' (duration: 288.388431ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:41:33.554414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.89281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:88122"} {"level":"info","ts":"2026-04-17T04:41:33.554461Z","caller":"traceutil/trace.go:171","msg":"trace[1214326909] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:2452; }","duration":"288.963411ms","start":"2026-04-17T04:41:33.265486Z","end":"2026-04-17T04:41:33.554449Z","steps":["trace[1214326909] 'agreement among raft nodes before linearized reading' (duration: 288.625385ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:41:34.17513Z","caller":"traceutil/trace.go:171","msg":"trace[235667833] transaction","detail":"{read_only:false; response_revision:2454; number_of_response:1; }","duration":"301.530763ms","start":"2026-04-17T04:41:33.873563Z","end":"2026-04-17T04:41:34.175094Z","steps":["trace[235667833] 'process raft request' (duration: 301.251548ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:41:34.175322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:41:33.873538Z","time spent":"301.681967ms","remote":"127.0.0.1:37306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T04:41:34.788173Z","caller":"traceutil/trace.go:171","msg":"trace[1868461473] transaction","detail":"{read_only:false; response_revision:2455; number_of_response:1; }","duration":"235.805954ms","start":"2026-04-17T04:41:34.552344Z","end":"2026-04-17T04:41:34.78815Z","steps":["trace[1868461473] 'process raft request' (duration: 235.59186ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:41:35.185872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.825739ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T04:41:35.186024Z","caller":"traceutil/trace.go:171","msg":"trace[360044066] linearizableReadLoop","detail":"{readStateIndex:2581; appliedIndex:2579; }","duration":"367.781774ms","start":"2026-04-17T04:41:34.818218Z","end":"2026-04-17T04:41:35.186Z","steps":["trace[360044066] 'read index received' (duration: 22.211266ms)","trace[360044066] 'applied index is now lower than readState.Index' (duration: 345.568948ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:41:35.186102Z","caller":"traceutil/trace.go:171","msg":"trace[1423478390] transaction","detail":"{read_only:false; response_revision:2457; number_of_response:1; }","duration":"392.773965ms","start":"2026-04-17T04:41:34.793294Z","end":"2026-04-17T04:41:35.186068Z","steps":["trace[1423478390] 'process raft request' (duration: 223.61906ms)","trace[1423478390] 'compare' (duration: 168.637635ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:41:35.186252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:41:34.793268Z","time spent":"392.899018ms","remote":"127.0.0.1:37306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T04:41:35.186269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.038419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.250\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-17T04:41:35.186351Z","caller":"traceutil/trace.go:171","msg":"trace[1686527772] range","detail":"{range_begin:/registry/masterleases/199.204.45.250; range_end:; response_count:1; response_revision:2457; }","duration":"368.174392ms","start":"2026-04-17T04:41:34.818159Z","end":"2026-04-17T04:41:35.186334Z","steps":["trace[1686527772] 'agreement among raft nodes before linearized reading' (duration: 367.934597ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:41:35.186404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:41:34.818138Z","time spent":"368.256643ms","remote":"127.0.0.1:37086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.204.45.250\" "} {"level":"warn","ts":"2026-04-17T04:41:35.614339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.098851ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:41:35.61459Z","caller":"traceutil/trace.go:171","msg":"trace[1259404759] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2457; }","duration":"238.365706ms","start":"2026-04-17T04:41:35.376204Z","end":"2026-04-17T04:41:35.61457Z","steps":["trace[1259404759] 'range keys from in-memory index tree' (duration: 238.08232ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:41:35.614587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.125023ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-04-17T04:41:35.614895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:41:35.187686Z","time spent":"427.195827ms","remote":"127.0.0.1:37086","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-17T04:41:35.615042Z","caller":"traceutil/trace.go:171","msg":"trace[1005195865] transaction","detail":"{read_only:false; response_revision:2458; number_of_response:1; }","duration":"325.23583ms","start":"2026-04-17T04:41:35.289766Z","end":"2026-04-17T04:41:35.615002Z","steps":["trace[1005195865] 'process raft request' (duration: 324.984835ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:41:35.615242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:41:35.28973Z","time spent":"325.413124ms","remote":"127.0.0.1:37306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T04:41:35.875107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.636325ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T04:41:35.875266Z","caller":"traceutil/trace.go:171","msg":"trace[1025004975] transaction","detail":"{read_only:false; response_revision:2459; number_of_response:1; }","duration":"258.822246ms","start":"2026-04-17T04:41:35.616416Z","end":"2026-04-17T04:41:35.875238Z","steps":["trace[1025004975] 'process raft request' (duration: 98.944747ms)","trace[1025004975] 'compare' (duration: 159.42009ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:41:36.189963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.606336ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T04:41:36.190146Z","caller":"traceutil/trace.go:171","msg":"trace[37525304] linearizableReadLoop","detail":"{readStateIndex:2586; appliedIndex:2584; }","duration":"312.77189ms","start":"2026-04-17T04:41:35.877357Z","end":"2026-04-17T04:41:36.190129Z","steps":["trace[37525304] 'read index received' (duration: 148.983551ms)","trace[37525304] 'applied index is now lower than readState.Index' (duration: 163.787499ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:41:36.190158Z","caller":"traceutil/trace.go:171","msg":"trace[692342608] transaction","detail":"{read_only:false; response_revision:2460; number_of_response:1; }","duration":"570.595296ms","start":"2026-04-17T04:41:35.619532Z","end":"2026-04-17T04:41:36.190127Z","steps":["trace[692342608] 'process raft request' (duration: 406.716635ms)","trace[692342608] 'compare' (duration: 163.457863ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:41:36.190294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:41:35.619519Z","time spent":"570.698339ms","remote":"127.0.0.1:37306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T04:41:36.190328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.972484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-04-17T04:41:36.190419Z","caller":"traceutil/trace.go:171","msg":"trace[17606869] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:2461; }","duration":"313.103317ms","start":"2026-04-17T04:41:35.877303Z","end":"2026-04-17T04:41:36.190407Z","steps":["trace[17606869] 'agreement among raft nodes before linearized reading' (duration: 312.880163ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:41:36.190382Z","caller":"traceutil/trace.go:171","msg":"trace[977182622] transaction","detail":"{read_only:false; response_revision:2461; number_of_response:1; }","duration":"570.704309ms","start":"2026-04-17T04:41:35.619618Z","end":"2026-04-17T04:41:36.190322Z","steps":["trace[977182622] 'process raft request' (duration: 570.449594ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:41:36.190461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:41:35.877282Z","time spent":"313.169398ms","remote":"127.0.0.1:37218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":446,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-04-17T04:41:36.190723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:41:35.619589Z","time spent":"570.936763ms","remote":"127.0.0.1:37306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T04:42:03.168825Z","caller":"traceutil/trace.go:171","msg":"trace[619867514] transaction","detail":"{read_only:false; response_revision:2549; number_of_response:1; }","duration":"189.418702ms","start":"2026-04-17T04:42:02.979379Z","end":"2026-04-17T04:42:03.168798Z","steps":["trace[619867514] 'process raft request' (duration: 189.213428ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:42:03.752712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.261957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T04:42:03.752771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.769503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:42:03.752823Z","caller":"traceutil/trace.go:171","msg":"trace[1077008288] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:2551; }","duration":"141.43795ms","start":"2026-04-17T04:42:03.611358Z","end":"2026-04-17T04:42:03.752796Z","steps":["trace[1077008288] 'count revisions from in-memory index tree' (duration: 141.152005ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:42:03.752915Z","caller":"traceutil/trace.go:171","msg":"trace[1699651324] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2551; }","duration":"100.931556ms","start":"2026-04-17T04:42:03.651921Z","end":"2026-04-17T04:42:03.752852Z","steps":["trace[1699651324] 'range keys from in-memory index tree' (duration: 100.654081ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:42:04.458333Z","caller":"traceutil/trace.go:171","msg":"trace[708125300] linearizableReadLoop","detail":"{readStateIndex:2682; appliedIndex:2681; }","duration":"105.56661ms","start":"2026-04-17T04:42:04.352744Z","end":"2026-04-17T04:42:04.458311Z","steps":["trace[708125300] 'read index received' (duration: 105.349195ms)","trace[708125300] 'applied index is now lower than readState.Index' (duration: 216.535µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:42:04.458405Z","caller":"traceutil/trace.go:171","msg":"trace[1470297787] transaction","detail":"{read_only:false; response_revision:2552; number_of_response:1; }","duration":"114.347946ms","start":"2026-04-17T04:42:04.344021Z","end":"2026-04-17T04:42:04.458369Z","steps":["trace[1470297787] 'process raft request' (duration: 114.148412ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:42:04.458607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.847695ms","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-04-17T04:42:04.458607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.54677ms","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-04-17T04:42:04.45868Z","caller":"traceutil/trace.go:171","msg":"trace[828930807] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2552; }","duration":"105.941277ms","start":"2026-04-17T04:42:04.352709Z","end":"2026-04-17T04:42:04.45865Z","steps":["trace[828930807] 'agreement among raft nodes before linearized reading' (duration: 105.719053ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:42:04.459406Z","caller":"traceutil/trace.go:171","msg":"trace[1473858062] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2552; }","duration":"105.645682ms","start":"2026-04-17T04:42:04.353013Z","end":"2026-04-17T04:42:04.458659Z","steps":["trace[1473858062] 'agreement among raft nodes before linearized reading' (duration: 105.453498ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:42:04.730668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.677693ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T04:42:04.730768Z","caller":"traceutil/trace.go:171","msg":"trace[816054036] transaction","detail":"{read_only:false; response_revision:2553; number_of_response:1; }","duration":"267.823348ms","start":"2026-04-17T04:42:04.462925Z","end":"2026-04-17T04:42:04.730748Z","steps":["trace[816054036] 'process raft request' (duration: 141.991112ms)","trace[816054036] 'compare' (duration: 125.586031ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:42:04.734949Z","caller":"traceutil/trace.go:171","msg":"trace[260340571] transaction","detail":"{read_only:false; response_revision:2554; number_of_response:1; }","duration":"271.985091ms","start":"2026-04-17T04:42:04.46294Z","end":"2026-04-17T04:42:04.734925Z","steps":["trace[260340571] 'process raft request' (duration: 271.879859ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:42:05.230876Z","caller":"traceutil/trace.go:171","msg":"trace[6633585] transaction","detail":"{read_only:false; response_revision:2555; number_of_response:1; }","duration":"186.429073ms","start":"2026-04-17T04:42:05.04443Z","end":"2026-04-17T04:42:05.23086Z","steps":["trace[6633585] 'process raft request' (duration: 125.402378ms)","trace[6633585] 'compare' (duration: 60.829391ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:42:05.231308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.49473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:42:05.231372Z","caller":"traceutil/trace.go:171","msg":"trace[893845592] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2555; }","duration":"128.602122ms","start":"2026-04-17T04:42:05.102755Z","end":"2026-04-17T04:42:05.231358Z","steps":["trace[893845592] 'agreement among raft nodes before linearized reading' (duration: 128.468599ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:42:05.230819Z","caller":"traceutil/trace.go:171","msg":"trace[990077893] linearizableReadLoop","detail":"{readStateIndex:2686; appliedIndex:2685; }","duration":"127.99802ms","start":"2026-04-17T04:42:05.102798Z","end":"2026-04-17T04:42:05.230796Z","steps":["trace[990077893] 'read index received' (duration: 67.017676ms)","trace[990077893] 'applied index is now lower than readState.Index' (duration: 60.979314ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:42:05.496186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.928648ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:42:05.496356Z","caller":"traceutil/trace.go:171","msg":"trace[75201179] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2556; }","duration":"120.087252ms","start":"2026-04-17T04:42:05.376218Z","end":"2026-04-17T04:42:05.496305Z","steps":["trace[75201179] 'range keys from in-memory index tree' (duration: 119.914958ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:42:05.49639Z","caller":"traceutil/trace.go:171","msg":"trace[306991516] linearizableReadLoop","detail":"{readStateIndex:2688; appliedIndex:2687; }","duration":"131.660543ms","start":"2026-04-17T04:42:05.36471Z","end":"2026-04-17T04:42:05.496371Z","steps":["trace[306991516] 'read index received' (duration: 66.333072ms)","trace[306991516] 'applied index is now lower than readState.Index' (duration: 65.326321ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:42:05.496443Z","caller":"traceutil/trace.go:171","msg":"trace[623421401] transaction","detail":"{read_only:false; response_revision:2557; number_of_response:1; }","duration":"187.25694ms","start":"2026-04-17T04:42:05.30915Z","end":"2026-04-17T04:42:05.496407Z","steps":["trace[623421401] 'process raft request' (duration: 121.947709ms)","trace[623421401] 'compare' (duration: 65.133247ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:42:05.496534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.812607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T04:42:05.496583Z","caller":"traceutil/trace.go:171","msg":"trace[940918719] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:2557; }","duration":"131.892938ms","start":"2026-04-17T04:42:05.364673Z","end":"2026-04-17T04:42:05.496566Z","steps":["trace[940918719] 'agreement among raft nodes before linearized reading' (duration: 131.801876ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:42:06.594796Z","caller":"traceutil/trace.go:171","msg":"trace[152078781] transaction","detail":"{read_only:false; response_revision:2560; number_of_response:1; }","duration":"131.229725ms","start":"2026-04-17T04:42:06.463545Z","end":"2026-04-17T04:42:06.594775Z","steps":["trace[152078781] 'process raft request' (duration: 131.098922ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:42:07.622314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.685063ms","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-04-17T04:42:07.622397Z","caller":"traceutil/trace.go:171","msg":"trace[538147860] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2563; }","duration":"122.808786ms","start":"2026-04-17T04:42:07.49957Z","end":"2026-04-17T04:42:07.622379Z","steps":["trace[538147860] 'range keys from in-memory index tree' (duration: 122.51945ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:42:08.902316Z","caller":"traceutil/trace.go:171","msg":"trace[1263577700] linearizableReadLoop","detail":"{readStateIndex:2699; appliedIndex:2698; }","duration":"109.196962ms","start":"2026-04-17T04:42:08.793078Z","end":"2026-04-17T04:42:08.902275Z","steps":["trace[1263577700] 'read index received' (duration: 109.06819ms)","trace[1263577700] 'applied index is now lower than readState.Index' (duration: 127.552µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:42:08.902373Z","caller":"traceutil/trace.go:171","msg":"trace[2133016146] transaction","detail":"{read_only:false; response_revision:2568; number_of_response:1; }","duration":"303.093186ms","start":"2026-04-17T04:42:08.599244Z","end":"2026-04-17T04:42:08.902337Z","steps":["trace[2133016146] 'process raft request' (duration: 302.899982ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:42:08.902537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.442327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-17T04:42:08.902533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.698101ms","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-04-17T04:42:08.902547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:42:08.599219Z","time spent":"303.234498ms","remote":"127.0.0.1:37306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T04:42:08.902578Z","caller":"traceutil/trace.go:171","msg":"trace[1917269393] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2568; }","duration":"109.548629ms","start":"2026-04-17T04:42:08.793018Z","end":"2026-04-17T04:42:08.902567Z","steps":["trace[1917269393] 'agreement among raft nodes before linearized reading' (duration: 109.398476ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:42:08.902585Z","caller":"traceutil/trace.go:171","msg":"trace[1057375678] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2568; }","duration":"100.778373ms","start":"2026-04-17T04:42:08.801795Z","end":"2026-04-17T04:42:08.902573Z","steps":["trace[1057375678] 'agreement among raft nodes before linearized reading' (duration: 100.63287ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:42:09.192967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.957465ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T04:42:09.193293Z","caller":"traceutil/trace.go:171","msg":"trace[81174500] transaction","detail":"{read_only:false; response_revision:2569; number_of_response:1; }","duration":"287.007363ms","start":"2026-04-17T04:42:08.906254Z","end":"2026-04-17T04:42:09.193262Z","steps":["trace[81174500] 'process raft request' (duration: 103.668342ms)","trace[81174500] 'compare' (duration: 182.850052ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:42:09.193437Z","caller":"traceutil/trace.go:171","msg":"trace[1854401940] transaction","detail":"{read_only:false; response_revision:2570; number_of_response:1; }","duration":"287.112665ms","start":"2026-04-17T04:42:08.906291Z","end":"2026-04-17T04:42:09.193404Z","steps":["trace[1854401940] 'process raft request' (duration: 287.003623ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:42:09.193547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.872986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:42:09.193631Z","caller":"traceutil/trace.go:171","msg":"trace[2077571531] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:2570; }","duration":"107.960208ms","start":"2026-04-17T04:42:09.085651Z","end":"2026-04-17T04:42:09.193611Z","steps":["trace[2077571531] 'agreement among raft nodes before linearized reading' (duration: 107.835996ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:42:09.193643Z","caller":"traceutil/trace.go:171","msg":"trace[899644638] linearizableReadLoop","detail":"{readStateIndex:2701; appliedIndex:2699; }","duration":"107.697963ms","start":"2026-04-17T04:42:09.085664Z","end":"2026-04-17T04:42:09.193362Z","steps":["trace[899644638] 'read index received' (duration: 62.089618ms)","trace[899644638] 'applied index is now lower than readState.Index' (duration: 45.607015ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:42:09.944036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.215885ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T04:42:09.944146Z","caller":"traceutil/trace.go:171","msg":"trace[369552185] linearizableReadLoop","detail":"{readStateIndex:2704; appliedIndex:2703; }","duration":"202.344103ms","start":"2026-04-17T04:42:09.741784Z","end":"2026-04-17T04:42:09.944128Z","steps":["trace[369552185] 'read index received' (duration: 74.958585ms)","trace[369552185] 'applied index is now lower than readState.Index' (duration: 127.384258ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:42:09.944227Z","caller":"traceutil/trace.go:171","msg":"trace[6012791] transaction","detail":"{read_only:false; response_revision:2573; number_of_response:1; }","duration":"220.679991ms","start":"2026-04-17T04:42:09.723476Z","end":"2026-04-17T04:42:09.944156Z","steps":["trace[6012791] 'process raft request' (duration: 93.212011ms)","trace[6012791] 'compare' (duration: 127.024132ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:42:09.944246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.439214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:42:09.944354Z","caller":"traceutil/trace.go:171","msg":"trace[1704878641] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:2573; }","duration":"202.576407ms","start":"2026-04-17T04:42:09.741734Z","end":"2026-04-17T04:42:09.94431Z","steps":["trace[1704878641] 'agreement among raft nodes before linearized reading' (duration: 202.444455ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:42:10.239273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.117225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T04:42:10.239358Z","caller":"traceutil/trace.go:171","msg":"trace[1033409538] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:2573; }","duration":"172.263868ms","start":"2026-04-17T04:42:10.067076Z","end":"2026-04-17T04:42:10.23934Z","steps":["trace[1033409538] 'count revisions from in-memory index tree' (duration: 172.030854ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:42:10.562098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.975063ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-17T04:42:11.054803Z","caller":"traceutil/trace.go:171","msg":"trace[129971399] transaction","detail":"{read_only:false; response_revision:2574; number_of_response:1; }","duration":"148.169225ms","start":"2026-04-17T04:42:10.906607Z","end":"2026-04-17T04:42:11.054776Z","steps":["trace[129971399] 'process raft request' (duration: 147.958231ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:42:31.91638Z","caller":"traceutil/trace.go:171","msg":"trace[1198989966] linearizableReadLoop","detail":"{readStateIndex:2780; appliedIndex:2779; }","duration":"104.261586ms","start":"2026-04-17T04:42:31.81209Z","end":"2026-04-17T04:42:31.916352Z","steps":["trace[1198989966] 'read index received' (duration: 103.932809ms)","trace[1198989966] 'applied index is now lower than readState.Index' (duration: 327.437µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:42:31.916504Z","caller":"traceutil/trace.go:171","msg":"trace[332964588] transaction","detail":"{read_only:false; response_revision:2644; number_of_response:1; }","duration":"112.498012ms","start":"2026-04-17T04:42:31.80395Z","end":"2026-04-17T04:42:31.916448Z","steps":["trace[332964588] 'process raft request' (duration: 112.209606ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:42:31.916738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.622262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-17T04:42:31.917001Z","caller":"traceutil/trace.go:171","msg":"trace[1725150262] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2644; }","duration":"104.989561ms","start":"2026-04-17T04:42:31.811994Z","end":"2026-04-17T04:42:31.916983Z","steps":["trace[1725150262] 'agreement among raft nodes before linearized reading' (duration: 104.48412ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:42:32.119495Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":1663} {"level":"warn","ts":"2026-04-17T04:42:32.120688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.939993ms","expected-duration":"100ms","prefix":"","request":"header: compaction: ","response":"size:5"} {"level":"info","ts":"2026-04-17T04:42:32.121091Z","caller":"traceutil/trace.go:171","msg":"trace[1459763542] compact","detail":"{revision:1663; response_revision:2644; }","duration":"202.723995ms","start":"2026-04-17T04:42:31.918338Z","end":"2026-04-17T04:42:32.121062Z","steps":["trace[1459763542] 'process raft request' (duration: 60.925815ms)","trace[1459763542] 'check and update compact revision' (duration: 139.710099ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:42:32.320256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.127813ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T04:42:32.320412Z","caller":"traceutil/trace.go:171","msg":"trace[159223067] linearizableReadLoop","detail":"{readStateIndex:2782; appliedIndex:2780; }","duration":"280.694643ms","start":"2026-04-17T04:42:32.039692Z","end":"2026-04-17T04:42:32.320387Z","steps":["trace[159223067] 'read index received' (duration: 29.748578ms)","trace[159223067] 'applied index is now lower than readState.Index' (duration: 250.942935ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:42:32.320682Z","caller":"traceutil/trace.go:171","msg":"trace[1127706306] transaction","detail":"{read_only:false; response_revision:2645; number_of_response:1; }","duration":"398.858689ms","start":"2026-04-17T04:42:31.921775Z","end":"2026-04-17T04:42:32.320634Z","steps":["trace[1127706306] 'process raft request' (duration: 199.231255ms)","trace[1127706306] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/plndr-svcs-lock; req_size:420; } (duration: 198.928879ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:42:32.320969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:42:31.921745Z","time spent":"399.065434ms","remote":"127.0.0.1:37306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T04:42:32.321971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.279145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-17T04:42:32.322034Z","caller":"traceutil/trace.go:171","msg":"trace[1033780388] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2645; }","duration":"282.396467ms","start":"2026-04-17T04:42:32.039624Z","end":"2026-04-17T04:42:32.32202Z","steps":["trace[1033780388] 'agreement among raft nodes before linearized reading' (duration: 280.840287ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:42:32.334562Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":1663,"took":"214.155106ms","hash":914030063,"current-db-size-bytes":23904256,"current-db-size":"24 MB","current-db-size-in-use-bytes":5550080,"current-db-size-in-use":"5.6 MB"} {"level":"info","ts":"2026-04-17T04:42:32.334623Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":914030063,"revision":1663,"compact-revision":-1} {"level":"warn","ts":"2026-04-17T04:42:37.634497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.327982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-17T04:42:37.634591Z","caller":"traceutil/trace.go:171","msg":"trace[1275785475] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2663; }","duration":"151.475486ms","start":"2026-04-17T04:42:37.483091Z","end":"2026-04-17T04:42:37.634567Z","steps":["trace[1275785475] 'range keys from in-memory index tree' (duration: 151.116268ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:42:37.774878Z","caller":"traceutil/trace.go:171","msg":"trace[817400096] transaction","detail":"{read_only:false; response_revision:2664; number_of_response:1; }","duration":"135.517635ms","start":"2026-04-17T04:42:37.639325Z","end":"2026-04-17T04:42:37.774842Z","steps":["trace[817400096] 'process raft request' (duration: 135.324441ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:42:43.941747Z","caller":"traceutil/trace.go:171","msg":"trace[1109230613] transaction","detail":"{read_only:false; response_revision:2684; number_of_response:1; }","duration":"139.454794ms","start":"2026-04-17T04:42:43.802267Z","end":"2026-04-17T04:42:43.941722Z","steps":["trace[1109230613] 'process raft request' (duration: 139.302061ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:42:44.35532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.09667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T04:42:44.355413Z","caller":"traceutil/trace.go:171","msg":"trace[187178237] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:2684; }","duration":"270.236403ms","start":"2026-04-17T04:42:44.085153Z","end":"2026-04-17T04:42:44.355389Z","steps":["trace[187178237] 'count revisions from in-memory index tree' (duration: 269.963208ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:42:44.355424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.484005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:42:44.355533Z","caller":"traceutil/trace.go:171","msg":"trace[102156910] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2684; }","duration":"252.619539ms","start":"2026-04-17T04:42:44.102884Z","end":"2026-04-17T04:42:44.355503Z","steps":["trace[102156910] 'range keys from in-memory index tree' (duration: 252.417875ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:42:44.545342Z","caller":"traceutil/trace.go:171","msg":"trace[1144201859] transaction","detail":"{read_only:false; response_revision:2685; number_of_response:1; }","duration":"123.816759ms","start":"2026-04-17T04:42:44.421499Z","end":"2026-04-17T04:42:44.545315Z","steps":["trace[1144201859] 'process raft request' (duration: 123.405241ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:42:44.60112Z","caller":"traceutil/trace.go:171","msg":"trace[1250317492] transaction","detail":"{read_only:false; response_revision:2686; number_of_response:1; }","duration":"177.09992ms","start":"2026-04-17T04:42:44.423994Z","end":"2026-04-17T04:42:44.601094Z","steps":["trace[1250317492] 'process raft request' (duration: 176.921127ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:42:49.687279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.677248ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T04:42:49.687372Z","caller":"traceutil/trace.go:171","msg":"trace[2000829602] linearizableReadLoop","detail":"{readStateIndex:2939; appliedIndex:2938; }","duration":"327.102826ms","start":"2026-04-17T04:42:49.360256Z","end":"2026-04-17T04:42:49.687359Z","steps":["trace[2000829602] 'read index received' (duration: 193.211054ms)","trace[2000829602] 'applied index is now lower than readState.Index' (duration: 133.890872ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:42:49.687416Z","caller":"traceutil/trace.go:171","msg":"trace[1488220355] transaction","detail":"{read_only:false; response_revision:2797; number_of_response:1; }","duration":"345.862192ms","start":"2026-04-17T04:42:49.341518Z","end":"2026-04-17T04:42:49.68738Z","steps":["trace[1488220355] 'process raft request' (duration: 212.006231ms)","trace[1488220355] 'compare' (duration: 133.444793ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:42:49.687481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.87923ms","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-04-17T04:42:49.68747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.193478ms","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-04-17T04:42:49.687506Z","caller":"traceutil/trace.go:171","msg":"trace[130073418] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2797; }","duration":"313.919441ms","start":"2026-04-17T04:42:49.373579Z","end":"2026-04-17T04:42:49.687498Z","steps":["trace[130073418] 'agreement among raft nodes before linearized reading' (duration: 313.847769ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:42:49.68753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:42:49.373569Z","time spent":"313.955412ms","remote":"127.0.0.1:37306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-17T04:42:49.68753Z","caller":"traceutil/trace.go:171","msg":"trace[333252143] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2797; }","duration":"327.28399ms","start":"2026-04-17T04:42:49.360231Z","end":"2026-04-17T04:42:49.687515Z","steps":["trace[333252143] 'agreement among raft nodes before linearized reading' (duration: 327.185538ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:42:49.687549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:42:49.341503Z","time spent":"345.977504ms","remote":"127.0.0.1:37484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7129,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T04:42:49.68757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.536934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/memcached-memcached\" ","response":"range_response_count:1 size:543"} {"level":"warn","ts":"2026-04-17T04:42:49.687584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:42:49.360221Z","time spent":"327.332451ms","remote":"127.0.0.1:37306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-17T04:42:49.687625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.358665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:2 size:30784"} {"level":"info","ts":"2026-04-17T04:42:49.687653Z","caller":"traceutil/trace.go:171","msg":"trace[294718130] range","detail":"{range_begin:/registry/serviceaccounts/openstack/memcached-memcached; range_end:; response_count:1; response_revision:2797; }","duration":"219.654165ms","start":"2026-04-17T04:42:49.467978Z","end":"2026-04-17T04:42:49.687632Z","steps":["trace[294718130] 'agreement among raft nodes before linearized reading' (duration: 219.489812ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:42:49.687664Z","caller":"traceutil/trace.go:171","msg":"trace[908906203] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:2; response_revision:2797; }","duration":"280.428877ms","start":"2026-04-17T04:42:49.407224Z","end":"2026-04-17T04:42:49.687653Z","steps":["trace[908906203] 'agreement among raft nodes before linearized reading' (duration: 280.264144ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:42:49.687669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.137634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/goldpinger-pt9n6\" ","response":"range_response_count:1 size:4995"} {"level":"info","ts":"2026-04-17T04:42:49.687709Z","caller":"traceutil/trace.go:171","msg":"trace[578741998] range","detail":"{range_begin:/registry/pods/monitoring/goldpinger-pt9n6; range_end:; response_count:1; response_revision:2797; }","duration":"118.202046ms","start":"2026-04-17T04:42:49.569495Z","end":"2026-04-17T04:42:49.687697Z","steps":["trace[578741998] 'agreement among raft nodes before linearized reading' (duration: 118.064143ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:42:49.687735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.98401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:1 size:10243"} {"level":"info","ts":"2026-04-17T04:42:49.687834Z","caller":"traceutil/trace.go:171","msg":"trace[1743476514] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:1; response_revision:2797; }","duration":"298.125483ms","start":"2026-04-17T04:42:49.389684Z","end":"2026-04-17T04:42:49.687809Z","steps":["trace[1743476514] 'agreement among raft nodes before linearized reading' (duration: 297.895558ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:42:50.164299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.06708ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T04:42:50.164474Z","caller":"traceutil/trace.go:171","msg":"trace[1855406642] transaction","detail":"{read_only:false; response_revision:2798; number_of_response:1; }","duration":"473.206763ms","start":"2026-04-17T04:42:49.691215Z","end":"2026-04-17T04:42:50.164422Z","steps":["trace[1855406642] 'process raft request' (duration: 179.912428ms)","trace[1855406642] 'compare' (duration: 292.973358ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:42:50.164574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:42:49.691198Z","time spent":"473.331065ms","remote":"127.0.0.1:37306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T04:42:50.16847Z","caller":"traceutil/trace.go:171","msg":"trace[1374505470] transaction","detail":"{read_only:false; response_revision:2799; number_of_response:1; }","duration":"474.961289ms","start":"2026-04-17T04:42:49.693492Z","end":"2026-04-17T04:42:50.168454Z","steps":["trace[1374505470] 'process raft request' (duration: 474.759465ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:42:50.168565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:42:49.693475Z","time spent":"475.04646ms","remote":"127.0.0.1:37306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T04:42:50.168842Z","caller":"traceutil/trace.go:171","msg":"trace[2048050890] transaction","detail":"{read_only:false; response_revision:2800; number_of_response:1; }","duration":"471.199532ms","start":"2026-04-17T04:42:49.69761Z","end":"2026-04-17T04:42:50.16881Z","steps":["trace[2048050890] 'process raft request' (duration: 470.719502ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:42:50.168995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:42:49.6976Z","time spent":"471.317445ms","remote":"127.0.0.1:37230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5184,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T04:42:50.169064Z","caller":"traceutil/trace.go:171","msg":"trace[1973282339] linearizableReadLoop","detail":"{readStateIndex:2942; appliedIndex:2939; }","duration":"469.532179ms","start":"2026-04-17T04:42:49.698855Z","end":"2026-04-17T04:42:50.168387Z","steps":["trace[1973282339] 'read index received' (duration: 172.284535ms)","trace[1973282339] 'applied index is now lower than readState.Index' (duration: 297.246554ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:42:50.169263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.398966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:1 size:10243"} {"level":"info","ts":"2026-04-17T04:42:50.169319Z","caller":"traceutil/trace.go:171","msg":"trace[1370461256] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:1; response_revision:2800; }","duration":"470.476237ms","start":"2026-04-17T04:42:49.698829Z","end":"2026-04-17T04:42:50.169305Z","steps":["trace[1370461256] 'agreement among raft nodes before linearized reading' (duration: 470.356585ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:42:50.169363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:42:49.698821Z","time spent":"470.530709ms","remote":"127.0.0.1:37146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":10267,"request content":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" "} {"level":"warn","ts":"2026-04-17T04:42:50.16976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.39132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:2 size:30784"} {"level":"info","ts":"2026-04-17T04:42:50.169823Z","caller":"traceutil/trace.go:171","msg":"trace[286283470] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:2; response_revision:2800; }","duration":"408.483081ms","start":"2026-04-17T04:42:49.761323Z","end":"2026-04-17T04:42:50.169806Z","steps":["trace[286283470] 'agreement among raft nodes before linearized reading' (duration: 408.268947ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:42:50.169874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:42:49.761306Z","time spent":"408.554803ms","remote":"127.0.0.1:37146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":2,"response size":30808,"request content":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" "} {"level":"warn","ts":"2026-04-17T04:42:50.169887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.54419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/ingress-nginx/ingress-nginx\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:42:50.169938Z","caller":"traceutil/trace.go:171","msg":"trace[1176616634] range","detail":"{range_begin:/registry/serviceaccounts/ingress-nginx/ingress-nginx; range_end:; response_count:0; response_revision:2800; }","duration":"188.624782ms","start":"2026-04-17T04:42:49.981297Z","end":"2026-04-17T04:42:50.169922Z","steps":["trace[1176616634] 'agreement among raft nodes before linearized reading' (duration: 188.53785ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:42:50.169992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.176264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-17T04:42:50.170074Z","caller":"traceutil/trace.go:171","msg":"trace[1691492078] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2800; }","duration":"211.303307ms","start":"2026-04-17T04:42:49.958749Z","end":"2026-04-17T04:42:50.170052Z","steps":["trace[1691492078] 'agreement among raft nodes before linearized reading' (duration: 211.113953ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:42:50.512804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.139064ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T04:42:50.512924Z","caller":"traceutil/trace.go:171","msg":"trace[1560388252] linearizableReadLoop","detail":"{readStateIndex:2944; appliedIndex:2943; }","duration":"329.976783ms","start":"2026-04-17T04:42:50.18293Z","end":"2026-04-17T04:42:50.512907Z","steps":["trace[1560388252] 'read index received' (duration: 114.725357ms)","trace[1560388252] 'applied index is now lower than readState.Index' (duration: 215.250346ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:42:50.513009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.077835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/ingress-nginx/ingress-nginx-tcp\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:42:50.513042Z","caller":"traceutil/trace.go:171","msg":"trace[901358987] range","detail":"{range_begin:/registry/configmaps/ingress-nginx/ingress-nginx-tcp; range_end:; response_count:0; response_revision:2802; }","duration":"330.169896ms","start":"2026-04-17T04:42:50.182863Z","end":"2026-04-17T04:42:50.513033Z","steps":["trace[901358987] 'agreement among raft nodes before linearized reading' (duration: 330.114255ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:42:50.513031Z","caller":"traceutil/trace.go:171","msg":"trace[1630119747] transaction","detail":"{read_only:false; response_revision:2802; number_of_response:1; }","duration":"333.146656ms","start":"2026-04-17T04:42:50.179847Z","end":"2026-04-17T04:42:50.512994Z","steps":["trace[1630119747] 'process raft request' (duration: 117.751227ms)","trace[1630119747] 'compare' (duration: 214.9793ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:42:50.513077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:42:50.182838Z","time spent":"330.230828ms","remote":"127.0.0.1:37148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":29,"request content":"key:\"/registry/configmaps/ingress-nginx/ingress-nginx-tcp\" "} {"level":"warn","ts":"2026-04-17T04:42:50.513195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:42:50.179834Z","time spent":"333.269609ms","remote":"127.0.0.1:37230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4986,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T04:42:50.819988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.610589ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:5"} {"level":"info","ts":"2026-04-17T04:42:50.820238Z","caller":"traceutil/trace.go:171","msg":"trace[1404066979] transaction","detail":"{read_only:false; number_of_response:0; response_revision:2802; }","duration":"628.115867ms","start":"2026-04-17T04:42:50.192085Z","end":"2026-04-17T04:42:50.820201Z","steps":["trace[1404066979] 'process raft request' (duration: 473.211792ms)","trace[1404066979] 'compare' (duration: 154.543998ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:42:50.820309Z","caller":"traceutil/trace.go:171","msg":"trace[1261463058] transaction","detail":"{read_only:false; number_of_response:0; response_revision:2802; }","duration":"626.750329ms","start":"2026-04-17T04:42:50.193515Z","end":"2026-04-17T04:42:50.820266Z","steps":["trace[1261463058] 'process raft request' (duration: 626.580016ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:42:50.820426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:42:50.19206Z","time spent":"628.291061ms","remote":"127.0.0.1:37112","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T04:42:50.820456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:42:50.193504Z","time spent":"626.890702ms","remote":"127.0.0.1:37112","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T04:42:51.105187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.99019ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-17T04:42:51.105547Z","caller":"traceutil/trace.go:171","msg":"trace[648521953] linearizableReadLoop","detail":"{readStateIndex:2947; appliedIndex:2944; }","duration":"592.565553ms","start":"2026-04-17T04:42:50.51295Z","end":"2026-04-17T04:42:51.105515Z","steps":["trace[648521953] 'read index received' (duration: 152.361812ms)","trace[648521953] 'applied index is now lower than readState.Index' (duration: 440.200761ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:42:51.105735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.558932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/rook-ceph/rook-ceph-osd\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:42:51.105741Z","caller":"traceutil/trace.go:171","msg":"trace[1190007131] transaction","detail":"{read_only:false; response_revision:2804; number_of_response:1; }","duration":"588.099844ms","start":"2026-04-17T04:42:50.517616Z","end":"2026-04-17T04:42:51.105715Z","steps":["trace[1190007131] 'process raft request' (duration: 587.89893ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:42:51.105747Z","caller":"traceutil/trace.go:171","msg":"trace[2063884353] transaction","detail":"{read_only:false; response_revision:2803; number_of_response:1; }","duration":"588.176075ms","start":"2026-04-17T04:42:50.517545Z","end":"2026-04-17T04:42:51.105721Z","steps":["trace[2063884353] 'process raft request' (duration: 587.806328ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:42:51.105841Z","caller":"traceutil/trace.go:171","msg":"trace[1075540494] transaction","detail":"{read_only:false; response_revision:2805; number_of_response:1; }","duration":"586.583103ms","start":"2026-04-17T04:42:50.519248Z","end":"2026-04-17T04:42:51.105832Z","steps":["trace[1075540494] 'process raft request' (duration: 586.395859ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:42:51.105882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:42:50.517601Z","time spent":"588.190115ms","remote":"127.0.0.1:37218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":919,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T04:42:51.105895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:42:50.51753Z","time spent":"588.281068ms","remote":"127.0.0.1:37320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1434,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T04:42:51.10593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:42:50.519236Z","time spent":"586.635744ms","remote":"127.0.0.1:37508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3912,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T04:42:51.105771Z","caller":"traceutil/trace.go:171","msg":"trace[498221160] range","detail":"{range_begin:/registry/serviceaccounts/rook-ceph/rook-ceph-osd; range_end:; response_count:0; response_revision:2804; }","duration":"686.638024ms","start":"2026-04-17T04:42:50.419121Z","end":"2026-04-17T04:42:51.105759Z","steps":["trace[498221160] 'agreement among raft nodes before linearized reading' (duration: 686.510851ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:42:51.106038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:42:50.419105Z","time spent":"686.915669ms","remote":"127.0.0.1:37244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":0,"response size":29,"request content":"key:\"/registry/serviceaccounts/rook-ceph/rook-ceph-osd\" "} {"level":"warn","ts":"2026-04-17T04:42:51.106066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"722.711098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/cert-manager/cert-manager-cainjector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:42:51.106137Z","caller":"traceutil/trace.go:171","msg":"trace[569607881] range","detail":"{range_begin:/registry/serviceaccounts/cert-manager/cert-manager-cainjector; range_end:; response_count:0; response_revision:2805; }","duration":"722.7848ms","start":"2026-04-17T04:42:50.383332Z","end":"2026-04-17T04:42:51.106117Z","steps":["trace[569607881] 'agreement among raft nodes before linearized reading' (duration: 722.432813ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:42:51.106177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:42:50.383284Z","time spent":"722.882602ms","remote":"127.0.0.1:37244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":29,"request content":"key:\"/registry/serviceaccounts/cert-manager/cert-manager-cainjector\" "} {"level":"warn","ts":"2026-04-17T04:42:51.563397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.506723ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-17T04:42:51.56359Z","caller":"traceutil/trace.go:171","msg":"trace[376155662] transaction","detail":"{read_only:false; response_revision:2807; number_of_response:1; }","duration":"366.667842ms","start":"2026-04-17T04:42:51.196904Z","end":"2026-04-17T04:42:51.563572Z","steps":["trace[376155662] 'process raft request' (duration: 366.600211ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:42:51.563652Z","caller":"traceutil/trace.go:171","msg":"trace[1654985961] linearizableReadLoop","detail":"{readStateIndex:2951; appliedIndex:2950; }","duration":"458.008168ms","start":"2026-04-17T04:42:51.105606Z","end":"2026-04-17T04:42:51.563614Z","steps":["trace[1654985961] 'read index received' (duration: 141.236499ms)","trace[1654985961] 'applied index is now lower than readState.Index' (duration: 316.769069ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:42:51.563679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:42:51.196881Z","time spent":"366.752123ms","remote":"127.0.0.1:37306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T04:42:51.563774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.168098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T04:42:51.563805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.093589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/nodefeatures.nfd.k8s-sigs.io\" ","response":"range_response_count:1 size:4678"} {"level":"warn","ts":"2026-04-17T04:42:51.563823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.046490279s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/ingress-nginx/ingress-nginx-udp\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:42:51.563849Z","caller":"traceutil/trace.go:171","msg":"trace[185448317] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:2807; }","duration":"721.28145ms","start":"2026-04-17T04:42:50.842546Z","end":"2026-04-17T04:42:51.563828Z","steps":["trace[185448317] 'agreement among raft nodes before linearized reading' (duration: 721.137467ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:42:51.563855Z","caller":"traceutil/trace.go:171","msg":"trace[600085280] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/nodefeatures.nfd.k8s-sigs.io; range_end:; response_count:1; response_revision:2807; }","duration":"742.17662ms","start":"2026-04-17T04:42:50.821662Z","end":"2026-04-17T04:42:51.563839Z","steps":["trace[600085280] 'agreement among raft nodes before linearized reading' (duration: 741.989566ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:42:51.563861Z","caller":"traceutil/trace.go:171","msg":"trace[663812775] range","detail":"{range_begin:/registry/configmaps/ingress-nginx/ingress-nginx-udp; range_end:; response_count:0; response_revision:2807; }","duration":"1.046582071s","start":"2026-04-17T04:42:50.517269Z","end":"2026-04-17T04:42:51.563851Z","steps":["trace[663812775] 'agreement among raft nodes before linearized reading' (duration: 1.04649502s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:42:51.563857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.883346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T04:42:51.563892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:42:50.821644Z","time spent":"742.241272ms","remote":"127.0.0.1:37112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":1,"response size":4702,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/nodefeatures.nfd.k8s-sigs.io\" "} {"level":"warn","ts":"2026-04-17T04:42:51.563897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:42:50.842535Z","time spent":"721.351902ms","remote":"127.0.0.1:37444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-04-17T04:42:51.563898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:42:50.517243Z","time spent":"1.046648152s","remote":"127.0.0.1:37148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":29,"request content":"key:\"/registry/configmaps/ingress-nginx/ingress-nginx-udp\" "} {"level":"info","ts":"2026-04-17T04:42:51.563925Z","caller":"traceutil/trace.go:171","msg":"trace[2060115438] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2807; }","duration":"458.001838ms","start":"2026-04-17T04:42:51.105905Z","end":"2026-04-17T04:42:51.563907Z","steps":["trace[2060115438] 'agreement among raft nodes before linearized reading' (duration: 457.897145ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:42:51.56392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"997.930723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-17T04:42:51.563969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:42:51.105883Z","time spent":"458.0779ms","remote":"127.0.0.1:37054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-17T04:42:51.563999Z","caller":"traceutil/trace.go:171","msg":"trace[116606694] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2807; }","duration":"998.065226ms","start":"2026-04-17T04:42:50.565914Z","end":"2026-04-17T04:42:51.563979Z","steps":["trace[116606694] 'agreement among raft nodes before linearized reading' (duration: 997.798541ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:42:51.564046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:42:50.565893Z","time spent":"998.143417ms","remote":"127.0.0.1:37306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-17T04:42:51.564079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.734831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/memcached-metrics\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T04:42:51.564069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.285923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/nodefeaturerules.nfd.k8s-sigs.io\" ","response":"range_response_count:1 size:10381"} {"level":"info","ts":"2026-04-17T04:42:51.564077Z","caller":"traceutil/trace.go:171","msg":"trace[1082967655] transaction","detail":"{read_only:false; response_revision:2806; number_of_response:1; }","duration":"643.13079ms","start":"2026-04-17T04:42:50.920841Z","end":"2026-04-17T04:42:51.563971Z","steps":["trace[1082967655] 'process raft request' (duration: 325.983814ms)","trace[1082967655] 'compare' (duration: 316.376401ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:42:51.564173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.604469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/cert-manager/cert-manager\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T04:42:51.564177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.206351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/rook-ceph/rook-ceph-mgr\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T04:42:51.564194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.836818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T04:42:51.564195Z","caller":"traceutil/trace.go:171","msg":"trace[2036620097] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/nodefeaturerules.nfd.k8s-sigs.io; range_end:; response_count:1; response_revision:2807; }","duration":"742.443436ms","start":"2026-04-17T04:42:50.821716Z","end":"2026-04-17T04:42:51.56416Z","steps":["trace[2036620097] 'agreement among raft nodes before linearized reading' (duration: 742.1676ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:42:51.564204Z","caller":"traceutil/trace.go:171","msg":"trace[1201801450] range","detail":"{range_begin:/registry/serviceaccounts/cert-manager/cert-manager; range_end:; response_count:0; response_revision:2807; }","duration":"454.66688ms","start":"2026-04-17T04:42:51.109527Z","end":"2026-04-17T04:42:51.564193Z","steps":["trace[1201801450] 'agreement among raft nodes before linearized reading' (duration: 454.621469ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:42:51.564216Z","caller":"traceutil/trace.go:171","msg":"trace[1549768886] range","detail":"{range_begin:/registry/serviceaccounts/rook-ceph/rook-ceph-mgr; range_end:; response_count:0; response_revision:2807; }","duration":"454.253692ms","start":"2026-04-17T04:42:51.109949Z","end":"2026-04-17T04:42:51.564203Z","steps":["trace[1549768886] 'agreement among raft nodes before linearized reading' (duration: 454.196061ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:42:51.564224Z","caller":"traceutil/trace.go:171","msg":"trace[1071530973] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:2807; }","duration":"400.90948ms","start":"2026-04-17T04:42:51.163306Z","end":"2026-04-17T04:42:51.564216Z","steps":["trace[1071530973] 'agreement among raft nodes before linearized reading' (duration: 400.854289ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:42:51.564221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"944.609221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-04-17T04:42:51.564244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:42:51.109496Z","time spent":"454.742612ms","remote":"127.0.0.1:37244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/serviceaccounts/cert-manager/cert-manager\" "} {"level":"warn","ts":"2026-04-17T04:42:51.564252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:42:51.109944Z","time spent":"454.302193ms","remote":"127.0.0.1:37244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":0,"response size":29,"request content":"key:\"/registry/serviceaccounts/rook-ceph/rook-ceph-mgr\" "} {"level":"warn","ts":"2026-04-17T04:42:51.564262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.047418667s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-hl5ft\" ","response":"range_response_count:1 size:11628"} {"level":"warn","ts":"2026-04-17T04:42:51.564258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:42:51.163286Z","time spent":"400.96459ms","remote":"127.0.0.1:37448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":31,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-04-17T04:42:51.564262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:42:50.821711Z","time spent":"742.539537ms","remote":"127.0.0.1:37112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":91,"response count":1,"response size":10405,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/nodefeaturerules.nfd.k8s-sigs.io\" "} {"level":"warn","ts":"2026-04-17T04:42:51.56427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:42:50.920816Z","time spent":"643.374424ms","remote":"127.0.0.1:43056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1671,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T04:42:51.564291Z","caller":"traceutil/trace.go:171","msg":"trace[1763860000] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2807; }","duration":"944.742684ms","start":"2026-04-17T04:42:50.619529Z","end":"2026-04-17T04:42:51.564271Z","steps":["trace[1763860000] 'agreement among raft nodes before linearized reading' (duration: 944.458968ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:42:51.564297Z","caller":"traceutil/trace.go:171","msg":"trace[1746040210] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-hl5ft; range_end:; response_count:1; response_revision:2807; }","duration":"1.047487099s","start":"2026-04-17T04:42:50.5168Z","end":"2026-04-17T04:42:51.564287Z","steps":["trace[1746040210] 'agreement among raft nodes before linearized reading' (duration: 1.047342716s)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:42:51.564132Z","caller":"traceutil/trace.go:171","msg":"trace[1924366295] range","detail":"{range_begin:/registry/services/specs/openstack/memcached-metrics; range_end:; response_count:0; response_revision:2807; }","duration":"444.822813ms","start":"2026-04-17T04:42:51.119291Z","end":"2026-04-17T04:42:51.564114Z","steps":["trace[1924366295] 'agreement among raft nodes before linearized reading' (duration: 444.723601ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:42:51.564334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:42:50.619518Z","time spent":"944.808035ms","remote":"127.0.0.1:37306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-17T04:42:51.564337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:42:50.516784Z","time spent":"1.0475473s","remote":"127.0.0.1:37230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":11652,"request content":"key:\"/registry/pods/openstack/openvswitch-hl5ft\" "} {"level":"warn","ts":"2026-04-17T04:42:51.564379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:42:51.119269Z","time spent":"445.102999ms","remote":"127.0.0.1:37238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":29,"request content":"key:\"/registry/services/specs/openstack/memcached-metrics\" "} {"level":"info","ts":"2026-04-17T04:42:51.995436Z","caller":"traceutil/trace.go:171","msg":"trace[285408581] transaction","detail":"{read_only:false; response_revision:2809; number_of_response:1; }","duration":"424.928021ms","start":"2026-04-17T04:42:51.57048Z","end":"2026-04-17T04:42:51.995408Z","steps":["trace[285408581] 'process raft request' (duration: 424.649925ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:42:51.995596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:42:51.570464Z","time spent":"425.048173ms","remote":"127.0.0.1:37306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T04:42:51.995609Z","caller":"traceutil/trace.go:171","msg":"trace[1799201443] transaction","detail":"{read_only:false; response_revision:2810; number_of_response:1; }","duration":"424.091163ms","start":"2026-04-17T04:42:51.571483Z","end":"2026-04-17T04:42:51.995574Z","steps":["trace[1799201443] 'process raft request' (duration: 423.814008ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:42:51.995729Z","caller":"traceutil/trace.go:171","msg":"trace[357127457] linearizableReadLoop","detail":"{readStateIndex:2954; appliedIndex:2952; }","duration":"424.308657ms","start":"2026-04-17T04:42:51.571409Z","end":"2026-04-17T04:42:51.995718Z","steps":["trace[357127457] 'read index received' (duration: 88.075561ms)","trace[357127457] 'applied index is now lower than readState.Index' (duration: 336.232406ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:42:51.995732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:42:51.571472Z","time spent":"424.198805ms","remote":"127.0.0.1:43056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1565,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T04:42:51.995796Z","caller":"traceutil/trace.go:171","msg":"trace[1712748173] transaction","detail":"{read_only:false; response_revision:2811; number_of_response:1; }","duration":"421.482251ms","start":"2026-04-17T04:42:51.574279Z","end":"2026-04-17T04:42:51.995761Z","steps":["trace[1712748173] 'process raft request' (duration: 421.227616ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:42:51.995835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.41955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/rook-ceph/rook-ceph-purge-osd\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:42:51.995889Z","caller":"traceutil/trace.go:171","msg":"trace[1767761207] range","detail":"{range_begin:/registry/serviceaccounts/rook-ceph/rook-ceph-purge-osd; range_end:; response_count:0; response_revision:2812; }","duration":"424.473411ms","start":"2026-04-17T04:42:51.571405Z","end":"2026-04-17T04:42:51.995879Z","steps":["trace[1767761207] 'agreement among raft nodes before linearized reading' (duration: 424.40106ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:42:51.995913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:42:51.571397Z","time spent":"424.508552ms","remote":"127.0.0.1:37244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":0,"response size":29,"request content":"key:\"/registry/serviceaccounts/rook-ceph/rook-ceph-purge-osd\" "} {"level":"warn","ts":"2026-04-17T04:42:51.99592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:42:51.574269Z","time spent":"421.579243ms","remote":"127.0.0.1:43002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2493,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T04:42:51.995904Z","caller":"traceutil/trace.go:171","msg":"trace[606629297] transaction","detail":"{read_only:false; response_revision:2812; number_of_response:1; }","duration":"418.619803ms","start":"2026-04-17T04:42:51.57723Z","end":"2026-04-17T04:42:51.995849Z","steps":["trace[606629297] 'process raft request' (duration: 418.406998ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:42:51.995975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.796437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/cert-manager/cert-manager-webhook\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:42:51.996011Z","caller":"traceutil/trace.go:171","msg":"trace[1996607400] range","detail":"{range_begin:/registry/configmaps/cert-manager/cert-manager-webhook; range_end:; response_count:0; response_revision:2812; }","duration":"423.851289ms","start":"2026-04-17T04:42:51.57215Z","end":"2026-04-17T04:42:51.996001Z","steps":["trace[1996607400] 'agreement among raft nodes before linearized reading' (duration: 423.790557ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:42:51.996038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:42:51.572142Z","time spent":"423.889039ms","remote":"127.0.0.1:37148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/configmaps/cert-manager/cert-manager-webhook\" "} {"level":"warn","ts":"2026-04-17T04:42:51.996019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.476901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/ingress-nginx\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:42:51.996109Z","caller":"traceutil/trace.go:171","msg":"trace[265943086] range","detail":"{range_begin:/registry/clusterroles/ingress-nginx; range_end:; response_count:0; response_revision:2812; }","duration":"424.607323ms","start":"2026-04-17T04:42:51.571477Z","end":"2026-04-17T04:42:51.996084Z","steps":["trace[265943086] 'agreement among raft nodes before linearized reading' (duration: 424.46246ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:42:51.996153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:42:51.571462Z","time spent":"424.682884ms","remote":"127.0.0.1:37384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":29,"request content":"key:\"/registry/clusterroles/ingress-nginx\" "} {"level":"warn","ts":"2026-04-17T04:42:51.996151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:42:51.577217Z","time spent":"418.801827ms","remote":"127.0.0.1:37230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11815,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T04:42:51.996182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.630194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:105560"} {"level":"info","ts":"2026-04-17T04:42:51.996225Z","caller":"traceutil/trace.go:171","msg":"trace[1709216507] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:2812; }","duration":"424.688185ms","start":"2026-04-17T04:42:51.571525Z","end":"2026-04-17T04:42:51.996214Z","steps":["trace[1709216507] 'agreement among raft nodes before linearized reading' (duration: 424.507051ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:42:51.996261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:42:51.571519Z","time spent":"424.734246ms","remote":"127.0.0.1:37128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":105584,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"info","ts":"2026-04-17T04:47:32.126942Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2644} {"level":"info","ts":"2026-04-17T04:47:32.150029Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2644,"took":"21.922329ms","hash":3886806241,"current-db-size-bytes":24776704,"current-db-size":"25 MB","current-db-size-in-use-bytes":24367104,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-04-17T04:47:32.1501Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3886806241,"revision":2644,"compact-revision":1663} {"level":"info","ts":"2026-04-17T04:50:43.218306Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023ef.wal"} {"level":"info","ts":"2026-04-17T04:51:10.380525Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d98d5f2c752cc52d","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-17T04:51:10.386206Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-17T04:51:10.386295Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-17T04:51:42.466601Z","caller":"traceutil/trace.go:171","msg":"trace[1470891667] linearizableReadLoop","detail":"{readStateIndex:10486; appliedIndex:10484; }","duration":"172.900444ms","start":"2026-04-17T04:51:42.293681Z","end":"2026-04-17T04:51:42.466581Z","steps":["trace[1470891667] 'read index received' (duration: 90.241653ms)","trace[1470891667] 'applied index is now lower than readState.Index' (duration: 82.658181ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:51:42.466893Z","caller":"traceutil/trace.go:171","msg":"trace[668115424] transaction","detail":"{read_only:false; response_revision:10135; number_of_response:1; }","duration":"212.349577ms","start":"2026-04-17T04:51:42.254532Z","end":"2026-04-17T04:51:42.466881Z","steps":["trace[668115424] 'process raft request' (duration: 129.540303ms)","trace[668115424] 'compare' (duration: 82.312893ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:51:42.467083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.150623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/glance-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:51:42.467167Z","caller":"traceutil/trace.go:171","msg":"trace[1002302154] range","detail":"{range_begin:/registry/jobs/openstack/glance-ks-endpoints; range_end:; response_count:0; response_revision:10136; }","duration":"208.289546ms","start":"2026-04-17T04:51:42.258859Z","end":"2026-04-17T04:51:42.467149Z","steps":["trace[1002302154] 'agreement among raft nodes before linearized reading' (duration: 208.105383ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:51:42.467061Z","caller":"traceutil/trace.go:171","msg":"trace[525528239] transaction","detail":"{read_only:false; response_revision:10136; number_of_response:1; }","duration":"195.182943ms","start":"2026-04-17T04:51:42.271776Z","end":"2026-04-17T04:51:42.466959Z","steps":["trace[525528239] 'process raft request' (duration: 194.735494ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:51:42.474894Z","caller":"traceutil/trace.go:171","msg":"trace[1406710141] transaction","detail":"{read_only:false; number_of_response:1; response_revision:10136; }","duration":"162.613368ms","start":"2026-04-17T04:51:42.312263Z","end":"2026-04-17T04:51:42.474876Z","steps":["trace[1406710141] 'process raft request' (duration: 162.545877ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:51:42.475202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.018627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:16 size:111739"} {"level":"info","ts":"2026-04-17T04:51:42.475245Z","caller":"traceutil/trace.go:171","msg":"trace[1185059436] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:16; response_revision:10136; }","duration":"163.109388ms","start":"2026-04-17T04:51:42.312126Z","end":"2026-04-17T04:51:42.475235Z","steps":["trace[1185059436] 'agreement among raft nodes before linearized reading' (duration: 162.765941ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:51:42.475405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.399235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-04-17T04:51:42.475514Z","caller":"traceutil/trace.go:171","msg":"trace[1235981268] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:10136; }","duration":"170.510857ms","start":"2026-04-17T04:51:42.304932Z","end":"2026-04-17T04:51:42.475443Z","steps":["trace[1235981268] 'agreement among raft nodes before linearized reading' (duration: 169.962177ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:51:42.475871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.094398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:51:42.475913Z","caller":"traceutil/trace.go:171","msg":"trace[721923747] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:10136; }","duration":"159.166919ms","start":"2026-04-17T04:51:42.316736Z","end":"2026-04-17T04:51:42.475903Z","steps":["trace[721923747] 'agreement among raft nodes before linearized reading' (duration: 159.061337ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:52:32.133802Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6081} {"level":"info","ts":"2026-04-17T04:52:32.216497Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6081,"took":"80.666401ms","hash":2156606079,"current-db-size-bytes":60985344,"current-db-size":"61 MB","current-db-size-in-use-bytes":47325184,"current-db-size-in-use":"47 MB"} {"level":"info","ts":"2026-04-17T04:52:32.216558Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2156606079,"revision":6081,"compact-revision":2644} {"level":"info","ts":"2026-04-17T04:56:16.504017Z","caller":"traceutil/trace.go:171","msg":"trace[1951208158] transaction","detail":"{read_only:false; response_revision:12872; number_of_response:1; }","duration":"243.48985ms","start":"2026-04-17T04:56:16.2605Z","end":"2026-04-17T04:56:16.50399Z","steps":["trace[1951208158] 'process raft request' (duration: 243.319647ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:56:16.647406Z","caller":"traceutil/trace.go:171","msg":"trace[1440746170] linearizableReadLoop","detail":"{readStateIndex:13303; appliedIndex:13301; }","duration":"279.641847ms","start":"2026-04-17T04:56:16.367738Z","end":"2026-04-17T04:56:16.64738Z","steps":["trace[1440746170] 'read index received' (duration: 136.073263ms)","trace[1440746170] 'applied index is now lower than readState.Index' (duration: 143.567444ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:56:16.647481Z","caller":"traceutil/trace.go:171","msg":"trace[741412940] transaction","detail":"{read_only:false; response_revision:12873; number_of_response:1; }","duration":"323.349425ms","start":"2026-04-17T04:56:16.324102Z","end":"2026-04-17T04:56:16.647451Z","steps":["trace[741412940] 'process raft request' (duration: 316.632509ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:56:16.647548Z","caller":"traceutil/trace.go:171","msg":"trace[587746891] transaction","detail":"{read_only:false; response_revision:12874; number_of_response:1; }","duration":"241.46774ms","start":"2026-04-17T04:56:16.406067Z","end":"2026-04-17T04:56:16.647535Z","steps":["trace[587746891] 'process raft request' (duration: 241.245905ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:56:16.647633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:56:16.324078Z","time spent":"323.470348ms","remote":"127.0.0.1:37306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T04:56:16.64773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.975434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-17T04:56:16.647762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.249442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/rook-ceph/csi-rbdplugin-metrics\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:56:16.647794Z","caller":"traceutil/trace.go:171","msg":"trace[64025974] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:12874; }","duration":"280.082056ms","start":"2026-04-17T04:56:16.367695Z","end":"2026-04-17T04:56:16.647777Z","steps":["trace[64025974] 'agreement among raft nodes before linearized reading' (duration: 279.977584ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:56:16.647872Z","caller":"traceutil/trace.go:171","msg":"trace[806693589] range","detail":"{range_begin:/registry/services/specs/rook-ceph/csi-rbdplugin-metrics; range_end:; response_count:0; response_revision:12874; }","duration":"199.429937ms","start":"2026-04-17T04:56:16.448422Z","end":"2026-04-17T04:56:16.647851Z","steps":["trace[806693589] 'agreement among raft nodes before linearized reading' (duration: 199.198862ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:56:18.455303Z","caller":"traceutil/trace.go:171","msg":"trace[1521149470] transaction","detail":"{read_only:false; response_revision:12920; number_of_response:1; }","duration":"107.416428ms","start":"2026-04-17T04:56:18.347863Z","end":"2026-04-17T04:56:18.45528Z","steps":["trace[1521149470] 'process raft request' (duration: 101.464578ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:56:20.472673Z","caller":"traceutil/trace.go:171","msg":"trace[1187775361] linearizableReadLoop","detail":"{readStateIndex:13438; appliedIndex:13437; }","duration":"137.93878ms","start":"2026-04-17T04:56:20.334705Z","end":"2026-04-17T04:56:20.472644Z","steps":["trace[1187775361] 'read index received' (duration: 137.521662ms)","trace[1187775361] 'applied index is now lower than readState.Index' (duration: 415.998µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:56:20.472937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.187965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/nova-cell-setup-cron\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:56:20.473Z","caller":"traceutil/trace.go:171","msg":"trace[1121486250] range","detail":"{range_begin:/registry/serviceaccounts/openstack/nova-cell-setup-cron; range_end:; response_count:0; response_revision:13006; }","duration":"138.298877ms","start":"2026-04-17T04:56:20.334688Z","end":"2026-04-17T04:56:20.472986Z","steps":["trace[1121486250] 'agreement among raft nodes before linearized reading' (duration: 138.091123ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:56:20.473402Z","caller":"traceutil/trace.go:171","msg":"trace[1685533777] transaction","detail":"{read_only:false; response_revision:13006; number_of_response:1; }","duration":"140.261477ms","start":"2026-04-17T04:56:20.333096Z","end":"2026-04-17T04:56:20.473357Z","steps":["trace[1685533777] 'process raft request' (duration: 139.291208ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:56:20.988837Z","caller":"traceutil/trace.go:171","msg":"trace[1606656775] linearizableReadLoop","detail":"{readStateIndex:13444; appliedIndex:13443; }","duration":"129.690014ms","start":"2026-04-17T04:56:20.859117Z","end":"2026-04-17T04:56:20.988807Z","steps":["trace[1606656775] 'read index received' (duration: 91.022037ms)","trace[1606656775] 'applied index is now lower than readState.Index' (duration: 38.666767ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:56:20.988861Z","caller":"traceutil/trace.go:171","msg":"trace[712453489] transaction","detail":"{read_only:false; number_of_response:0; response_revision:13011; }","duration":"178.915474ms","start":"2026-04-17T04:56:20.809915Z","end":"2026-04-17T04:56:20.988831Z","steps":["trace[712453489] 'process raft request' (duration: 140.297248ms)","trace[712453489] 'compare' (duration: 38.538764ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:56:20.989024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.894289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/orc-system/f35396c5.k-orc.cloud\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:56:20.989131Z","caller":"traceutil/trace.go:171","msg":"trace[1286200218] range","detail":"{range_begin:/registry/leases/orc-system/f35396c5.k-orc.cloud; range_end:; response_count:0; response_revision:13011; }","duration":"130.045902ms","start":"2026-04-17T04:56:20.859041Z","end":"2026-04-17T04:56:20.989087Z","steps":["trace[1286200218] 'agreement among raft nodes before linearized reading' (duration: 129.876579ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:56:26.466337Z","caller":"traceutil/trace.go:171","msg":"trace[1785080075] linearizableReadLoop","detail":"{readStateIndex:13980; appliedIndex:13979; }","duration":"102.712623ms","start":"2026-04-17T04:56:26.363542Z","end":"2026-04-17T04:56:26.466254Z","steps":["trace[1785080075] 'read index received' (duration: 100.768744ms)","trace[1785080075] 'applied index is now lower than readState.Index' (duration: 1.942839ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:56:26.466693Z","caller":"traceutil/trace.go:171","msg":"trace[1570061566] transaction","detail":"{read_only:false; response_revision:13538; number_of_response:1; }","duration":"104.952248ms","start":"2026-04-17T04:56:26.361727Z","end":"2026-04-17T04:56:26.46668Z","steps":["trace[1570061566] 'process raft request' (duration: 102.56772ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:56:26.466812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.251624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-04-17T04:56:26.466871Z","caller":"traceutil/trace.go:171","msg":"trace[1014886400] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:13538; }","duration":"103.334296ms","start":"2026-04-17T04:56:26.363522Z","end":"2026-04-17T04:56:26.466856Z","steps":["trace[1014886400] 'agreement among raft nodes before linearized reading' (duration: 103.174703ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:56:26.470397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.701443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/capi-kubeadm-control-plane-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:56:26.470464Z","caller":"traceutil/trace.go:171","msg":"trace[30270685] range","detail":"{range_begin:/registry/namespaces/capi-kubeadm-control-plane-system; range_end:; response_count:0; response_revision:13540; }","duration":"106.797935ms","start":"2026-04-17T04:56:26.363647Z","end":"2026-04-17T04:56:26.470445Z","steps":["trace[30270685] 'agreement among raft nodes before linearized reading' (duration: 106.691653ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:56:26.470537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.500919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/capi-kubeadm-bootstrap-mutating-webhook-configuration\" ","response":"range_response_count:1 size:5186"} {"level":"info","ts":"2026-04-17T04:56:26.470586Z","caller":"traceutil/trace.go:171","msg":"trace[320240665] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/capi-kubeadm-bootstrap-mutating-webhook-configuration; range_end:; response_count:1; response_revision:13540; }","duration":"104.57561ms","start":"2026-04-17T04:56:26.366001Z","end":"2026-04-17T04:56:26.470577Z","steps":["trace[320240665] 'agreement among raft nodes before linearized reading' (duration: 104.410807ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:56:26.470621Z","caller":"traceutil/trace.go:171","msg":"trace[2057350079] transaction","detail":"{read_only:false; response_revision:13539; number_of_response:1; }","duration":"103.485219ms","start":"2026-04-17T04:56:26.367083Z","end":"2026-04-17T04:56:26.470568Z","steps":["trace[2057350079] 'process raft request' (duration: 102.935488ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:56:26.470762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.163343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/capi-kubeadm-bootstrap-validating-webhook-configuration\" ","response":"range_response_count:1 size:5150"} {"level":"info","ts":"2026-04-17T04:56:26.470818Z","caller":"traceutil/trace.go:171","msg":"trace[1513033398] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/capi-kubeadm-bootstrap-validating-webhook-configuration; range_end:; response_count:1; response_revision:13540; }","duration":"106.226614ms","start":"2026-04-17T04:56:26.364577Z","end":"2026-04-17T04:56:26.470803Z","steps":["trace[1513033398] 'agreement among raft nodes before linearized reading' (duration: 106.099821ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:57:32.163027Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10658} {"level":"info","ts":"2026-04-17T04:57:32.297193Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10658,"took":"130.158843ms","hash":1121615258,"current-db-size-bytes":70766592,"current-db-size":"71 MB","current-db-size-in-use-bytes":42905600,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-04-17T04:57:32.297269Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1121615258,"revision":10658,"compact-revision":6081} {"level":"info","ts":"2026-04-17T05:02:32.170024Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14573} {"level":"info","ts":"2026-04-17T05:02:32.299405Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14573,"took":"125.331281ms","hash":3895467925,"current-db-size-bytes":70766592,"current-db-size":"71 MB","current-db-size-in-use-bytes":37318656,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-04-17T05:02:32.299452Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3895467925,"revision":14573,"compact-revision":10658} {"level":"info","ts":"2026-04-17T05:04:18.120596Z","caller":"traceutil/trace.go:171","msg":"trace[1049840570] transaction","detail":"{read_only:false; response_revision:19318; number_of_response:1; }","duration":"135.969428ms","start":"2026-04-17T05:04:17.984605Z","end":"2026-04-17T05:04:18.120575Z","steps":["trace[1049840570] 'process raft request' (duration: 135.837245ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:04:18.200412Z","caller":"traceutil/trace.go:171","msg":"trace[2074917624] transaction","detail":"{read_only:false; response_revision:19319; number_of_response:1; }","duration":"215.509485ms","start":"2026-04-17T05:04:17.984878Z","end":"2026-04-17T05:04:18.200388Z","steps":["trace[2074917624] 'process raft request' (duration: 185.36598ms)","trace[2074917624] 'compare' (duration: 29.90502ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:04:18.200699Z","caller":"traceutil/trace.go:171","msg":"trace[854769466] transaction","detail":"{read_only:false; response_revision:19320; number_of_response:1; }","duration":"210.567315ms","start":"2026-04-17T05:04:17.990096Z","end":"2026-04-17T05:04:18.200663Z","steps":["trace[854769466] 'process raft request' (duration: 210.214938ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:04:18.390043Z","caller":"traceutil/trace.go:171","msg":"trace[996453273] transaction","detail":"{read_only:false; response_revision:19322; number_of_response:1; }","duration":"181.41576ms","start":"2026-04-17T05:04:18.208603Z","end":"2026-04-17T05:04:18.390019Z","steps":["trace[996453273] 'process raft request' (duration: 170.540192ms)","trace[996453273] 'compare' (duration: 10.694545ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:04:18.390152Z","caller":"traceutil/trace.go:171","msg":"trace[371382860] transaction","detail":"{read_only:false; response_revision:19323; number_of_response:1; }","duration":"174.661385ms","start":"2026-04-17T05:04:18.215464Z","end":"2026-04-17T05:04:18.390125Z","steps":["trace[371382860] 'process raft request' (duration: 174.508902ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:04:28.503658Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d98d5f2c752cc52d","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-17T05:04:28.508799Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-04-17T05:04:28.508897Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"warn","ts":"2026-04-17T05:04:53.259442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.832147ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-17T05:04:53.259631Z","caller":"traceutil/trace.go:171","msg":"trace[2013515952] linearizableReadLoop","detail":"{readStateIndex:20358; appliedIndex:20356; }","duration":"288.248204ms","start":"2026-04-17T05:04:52.971366Z","end":"2026-04-17T05:04:53.259614Z","steps":["trace[2013515952] 'read index received' (duration: 143.252334ms)","trace[2013515952] 'applied index is now lower than readState.Index' (duration: 144.99498ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T05:04:53.2598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.772327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5007"} {"level":"info","ts":"2026-04-17T05:04:53.259834Z","caller":"traceutil/trace.go:171","msg":"trace[1262676847] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:19762; }","duration":"335.8998ms","start":"2026-04-17T05:04:52.923926Z","end":"2026-04-17T05:04:53.259826Z","steps":["trace[1262676847] 'agreement among raft nodes before linearized reading' (duration: 335.762397ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:04:53.259865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:04:52.923915Z","time spent":"335.942811ms","remote":"127.0.0.1:37276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":5031,"request content":"key:\"/registry/jobs/openstack/manila-db-sync\" "} {"level":"warn","ts":"2026-04-17T05:04:53.259968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.538063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-17T05:04:53.259979Z","caller":"traceutil/trace.go:171","msg":"trace[500395788] transaction","detail":"{read_only:false; response_revision:19761; number_of_response:1; }","duration":"320.243095ms","start":"2026-04-17T05:04:52.9397Z","end":"2026-04-17T05:04:53.259943Z","steps":["trace[500395788] 'process raft request' (duration: 174.826817ms)","trace[500395788] 'compare' (duration: 144.728655ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:04:53.260024Z","caller":"traceutil/trace.go:171","msg":"trace[1436333072] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:19762; }","duration":"340.634755ms","start":"2026-04-17T05:04:52.919375Z","end":"2026-04-17T05:04:53.26001Z","steps":["trace[1436333072] 'agreement among raft nodes before linearized reading' (duration: 340.554263ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:04:53.260008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.330099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-17T05:04:53.260066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:04:52.919345Z","time spent":"340.714187ms","remote":"127.0.0.1:37276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":30,"request content":"key:\"/registry/jobs/openstack/manila-rabbit-init\" "} {"level":"info","ts":"2026-04-17T05:04:53.26008Z","caller":"traceutil/trace.go:171","msg":"trace[1108974801] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:19762; }","duration":"340.435271ms","start":"2026-04-17T05:04:52.91963Z","end":"2026-04-17T05:04:53.260065Z","steps":["trace[1108974801] 'agreement among raft nodes before linearized reading' (duration: 340.297478ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:04:53.260117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:04:52.919621Z","time spent":"340.488302ms","remote":"127.0.0.1:37276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":30,"request content":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" "} {"level":"warn","ts":"2026-04-17T05:04:53.260128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:04:52.939658Z","time spent":"320.390008ms","remote":"127.0.0.1:37306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T05:04:53.259709Z","caller":"traceutil/trace.go:171","msg":"trace[245535609] transaction","detail":"{read_only:false; response_revision:19762; number_of_response:1; }","duration":"311.279626ms","start":"2026-04-17T05:04:52.948381Z","end":"2026-04-17T05:04:53.259661Z","steps":["trace[245535609] 'process raft request' (duration: 311.159093ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:04:53.26036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:04:52.948372Z","time spent":"311.900148ms","remote":"127.0.0.1:37306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T05:04:53.273854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.053155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-29606700-nqzf6\" ","response":"range_response_count:1 size:6958"} {"level":"info","ts":"2026-04-17T05:04:53.273916Z","caller":"traceutil/trace.go:171","msg":"trace[103515989] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-29606700-nqzf6; range_end:; response_count:1; response_revision:19763; }","duration":"204.138816ms","start":"2026-04-17T05:04:53.069763Z","end":"2026-04-17T05:04:53.273902Z","steps":["trace[103515989] 'agreement among raft nodes before linearized reading' (duration: 203.974103ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:04:53.273658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.038408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-17T05:04:53.274176Z","caller":"traceutil/trace.go:171","msg":"trace[1731630640] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:19763; }","duration":"254.598409ms","start":"2026-04-17T05:04:53.01955Z","end":"2026-04-17T05:04:53.274149Z","steps":["trace[1731630640] 'agreement among raft nodes before linearized reading' (duration: 254.018788ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:04:53.274484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.407094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-17T05:04:53.274526Z","caller":"traceutil/trace.go:171","msg":"trace[862364446] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:19763; }","duration":"254.447044ms","start":"2026-04-17T05:04:53.020069Z","end":"2026-04-17T05:04:53.274516Z","steps":["trace[862364446] 'agreement among raft nodes before linearized reading' (duration: 254.390103ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:04:53.274541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.67657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5007"} {"level":"info","ts":"2026-04-17T05:04:53.274599Z","caller":"traceutil/trace.go:171","msg":"trace[1472456314] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:19763; }","duration":"250.754031ms","start":"2026-04-17T05:04:53.023831Z","end":"2026-04-17T05:04:53.274585Z","steps":["trace[1472456314] 'agreement among raft nodes before linearized reading' (duration: 250.553677ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:04:53.274611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.911485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-17T05:04:53.274217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.656011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-rts96\" ","response":"range_response_count:1 size:9713"} {"level":"info","ts":"2026-04-17T05:04:53.274702Z","caller":"traceutil/trace.go:171","msg":"trace[1026909464] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:19763; }","duration":"255.055248ms","start":"2026-04-17T05:04:53.019624Z","end":"2026-04-17T05:04:53.27468Z","steps":["trace[1026909464] 'agreement among raft nodes before linearized reading' (duration: 254.890274ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:04:53.274733Z","caller":"traceutil/trace.go:171","msg":"trace[751065928] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-rts96; range_end:; response_count:1; response_revision:19763; }","duration":"132.224293ms","start":"2026-04-17T05:04:53.1425Z","end":"2026-04-17T05:04:53.274724Z","steps":["trace[751065928] 'agreement among raft nodes before linearized reading' (duration: 131.647761ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:04:53.274257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.176165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-17T05:04:53.274873Z","caller":"traceutil/trace.go:171","msg":"trace[1451081251] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19763; }","duration":"170.827867ms","start":"2026-04-17T05:04:53.104033Z","end":"2026-04-17T05:04:53.27486Z","steps":["trace[1451081251] 'agreement among raft nodes before linearized reading' (duration: 170.204195ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:04:54.472815Z","caller":"traceutil/trace.go:171","msg":"trace[238801359] transaction","detail":"{read_only:false; response_revision:19765; number_of_response:1; }","duration":"141.967269ms","start":"2026-04-17T05:04:54.330819Z","end":"2026-04-17T05:04:54.472786Z","steps":["trace[238801359] 'process raft request' (duration: 141.475139ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:07:32.176632Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17980} {"level":"info","ts":"2026-04-17T05:07:32.306134Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17980,"took":"125.689807ms","hash":2135689687,"current-db-size-bytes":70766592,"current-db-size":"71 MB","current-db-size-in-use-bytes":40853504,"current-db-size-in-use":"41 MB"} {"level":"info","ts":"2026-04-17T05:07:32.306286Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2135689687,"revision":17980,"compact-revision":14573} {"level":"info","ts":"2026-04-17T05:12:32.183121Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21594} {"level":"info","ts":"2026-04-17T05:12:32.338261Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21594,"took":"151.431946ms","hash":548266657,"current-db-size-bytes":70766592,"current-db-size":"71 MB","current-db-size-in-use-bytes":34078720,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-17T05:12:32.338358Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":548266657,"revision":21594,"compact-revision":17980} {"level":"info","ts":"2026-04-17T05:17:32.188496Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23980} {"level":"info","ts":"2026-04-17T05:17:32.320167Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23980,"took":"128.193656ms","hash":3485867153,"current-db-size-bytes":70766592,"current-db-size":"71 MB","current-db-size-in-use-bytes":32268288,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-17T05:17:32.320247Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3485867153,"revision":23980,"compact-revision":21594} {"level":"info","ts":"2026-04-17T05:20:25.180874Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006ea9.wal"}