{"level":"warn","ts":"2026-09-07T03:22:06.009603Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-07T03:22:06.009709Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.22:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.22:2380","--initial-cluster=instance=https://162.253.55.22:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.22:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.22:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-09-07T03:22:06.0098Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-07T03:22:06.00982Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.22:2380"]} {"level":"info","ts":"2026-09-07T03:22:06.009841Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-07T03:22:06.010609Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.22:2379"]} {"level":"info","ts":"2026-09-07T03:22:06.010709Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.22:2380"],"listen-peer-urls":["https://162.253.55.22:2380"],"advertise-client-urls":["https://162.253.55.22:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.22:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.22:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-09-07T03:22:06.016228Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.286569ms"} {"level":"info","ts":"2026-09-07T03:22:06.026862Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"8608f4af3693a53b","cluster-id":"c541111188849f0e"} {"level":"info","ts":"2026-09-07T03:22:06.027083Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b switched to configuration voters=()"} {"level":"info","ts":"2026-09-07T03:22:06.027254Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b became follower at term 0"} {"level":"info","ts":"2026-09-07T03:22:06.027324Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8608f4af3693a53b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-07T03:22:06.027334Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b became follower at term 1"} {"level":"info","ts":"2026-09-07T03:22:06.027537Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b switched to configuration voters=(9658238434268128571)"} {"level":"warn","ts":"2026-09-07T03:22:06.035175Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-07T03:22:06.036771Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-07T03:22:06.037987Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-09-07T03:22:06.040202Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"8608f4af3693a53b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-07T03:22:06.041794Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-07T03:22:06.043065Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-07T03:22:06.043081Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-07T03:22:06.044332Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"8608f4af3693a53b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-07T03:22:06.044807Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b switched to configuration voters=(9658238434268128571)"} {"level":"info","ts":"2026-09-07T03:22:06.044958Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c541111188849f0e","local-member-id":"8608f4af3693a53b","added-peer-id":"8608f4af3693a53b","added-peer-peer-urls":["https://162.253.55.22:2380"]} {"level":"info","ts":"2026-09-07T03:22:06.047148Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-07T03:22:06.047439Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"8608f4af3693a53b","initial-advertise-peer-urls":["https://162.253.55.22:2380"],"listen-peer-urls":["https://162.253.55.22:2380"],"advertise-client-urls":["https://162.253.55.22:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.22:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-07T03:22:06.047475Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-07T03:22:06.048025Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.22:2380"} {"level":"info","ts":"2026-09-07T03:22:06.048085Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.22:2380"} {"level":"info","ts":"2026-09-07T03:22:06.127995Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b is starting a new election at term 1"} {"level":"info","ts":"2026-09-07T03:22:06.12807Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b became pre-candidate at term 1"} {"level":"info","ts":"2026-09-07T03:22:06.128136Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b received MsgPreVoteResp from 8608f4af3693a53b at term 1"} {"level":"info","ts":"2026-09-07T03:22:06.128191Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b became candidate at term 2"} {"level":"info","ts":"2026-09-07T03:22:06.128212Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b received MsgVoteResp from 8608f4af3693a53b at term 2"} {"level":"info","ts":"2026-09-07T03:22:06.128234Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8608f4af3693a53b became leader at term 2"} {"level":"info","ts":"2026-09-07T03:22:06.128254Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8608f4af3693a53b elected leader 8608f4af3693a53b at term 2"} {"level":"info","ts":"2026-09-07T03:22:06.129218Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-07T03:22:06.13012Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-07T03:22:06.13012Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"8608f4af3693a53b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.22:2379]}","request-path":"/0/members/8608f4af3693a53b/attributes","cluster-id":"c541111188849f0e","publish-timeout":"7s"} {"level":"info","ts":"2026-09-07T03:22:06.130222Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-07T03:22:06.130668Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-07T03:22:06.130688Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-07T03:22:06.131665Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c541111188849f0e","local-member-id":"8608f4af3693a53b","cluster-version":"3.5"} {"level":"info","ts":"2026-09-07T03:22:06.131784Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-07T03:22:06.131974Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-07T03:22:06.133649Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-07T03:22:06.134704Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.22:2379"} {"level":"info","ts":"2026-09-07T03:22:43.647625Z","caller":"traceutil/trace.go:171","msg":"trace[553137802] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"136.206601ms","start":"2026-09-07T03:22:43.511389Z","end":"2026-09-07T03:22:43.647595Z","steps":["trace[553137802] 'process raft request' (duration: 136.082099ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:22:43.655347Z","caller":"traceutil/trace.go:171","msg":"trace[348056706] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"124.887426ms","start":"2026-09-07T03:22:43.530442Z","end":"2026-09-07T03:22:43.65533Z","steps":["trace[348056706] 'process raft request' (duration: 124.645322ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:22:43.655489Z","caller":"traceutil/trace.go:171","msg":"trace[1800357975] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"135.985898ms","start":"2026-09-07T03:22:43.519495Z","end":"2026-09-07T03:22:43.655481Z","steps":["trace[1800357975] 'process raft request' (duration: 135.44128ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:22:43.655607Z","caller":"traceutil/trace.go:171","msg":"trace[1016378678] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"130.953395ms","start":"2026-09-07T03:22:43.52459Z","end":"2026-09-07T03:22:43.655543Z","steps":["trace[1016378678] 'process raft request' (duration: 130.463158ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:22:43.655655Z","caller":"traceutil/trace.go:171","msg":"trace[1699823402] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"118.330558ms","start":"2026-09-07T03:22:43.537304Z","end":"2026-09-07T03:22:43.655635Z","steps":["trace[1699823402] 'process raft request' (duration: 117.881652ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:22:43.655762Z","caller":"traceutil/trace.go:171","msg":"trace[120281996] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"111.482148ms","start":"2026-09-07T03:22:43.544249Z","end":"2026-09-07T03:22:43.655732Z","steps":["trace[120281996] 'process raft request' (duration: 111.066403ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:22:43.655755Z","caller":"traceutil/trace.go:171","msg":"trace[1588061634] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"118.365969ms","start":"2026-09-07T03:22:43.537354Z","end":"2026-09-07T03:22:43.65572Z","steps":["trace[1588061634] 'process raft request' (duration: 117.869402ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:22:43.655851Z","caller":"traceutil/trace.go:171","msg":"trace[710785246] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"118.601902ms","start":"2026-09-07T03:22:43.53719Z","end":"2026-09-07T03:22:43.655792Z","steps":["trace[710785246] 'process raft request' (duration: 117.958543ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:22:43.656325Z","caller":"traceutil/trace.go:171","msg":"trace[542223467] linearizableReadLoop","detail":"{readStateIndex:522; appliedIndex:514; }","duration":"105.49089ms","start":"2026-09-07T03:22:43.55082Z","end":"2026-09-07T03:22:43.656311Z","steps":["trace[542223467] 'read index received' (duration: 96.713711ms)","trace[542223467] 'applied index is now lower than readState.Index' (duration: 8.776449ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T03:22:43.65655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.714843ms","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-09-07T03:22:43.656598Z","caller":"traceutil/trace.go:171","msg":"trace[237583255] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:507; }","duration":"105.803594ms","start":"2026-09-07T03:22:43.550784Z","end":"2026-09-07T03:22:43.656588Z","steps":["trace[237583255] 'agreement among raft nodes before linearized reading' (duration: 105.681882ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:22:43.680268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.506231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-07T03:22:43.680328Z","caller":"traceutil/trace.go:171","msg":"trace[1848285785] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:514; }","duration":"120.614272ms","start":"2026-09-07T03:22:43.559697Z","end":"2026-09-07T03:22:43.680312Z","steps":["trace[1848285785] 'agreement among raft nodes before linearized reading' (duration: 120.526151ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:22:49.984129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.602805ms","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-09-07T03:22:49.984236Z","caller":"traceutil/trace.go:171","msg":"trace[372040146] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:601; }","duration":"273.759557ms","start":"2026-09-07T03:22:49.710452Z","end":"2026-09-07T03:22:49.984211Z","steps":["trace[372040146] 'range keys from in-memory index tree' (duration: 273.411712ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:22:50.02044Z","caller":"traceutil/trace.go:171","msg":"trace[65177291] linearizableReadLoop","detail":"{readStateIndex:626; appliedIndex:625; }","duration":"262.214282ms","start":"2026-09-07T03:22:49.758197Z","end":"2026-09-07T03:22:50.020411Z","steps":["trace[65177291] 'read index received' (duration: 261.510372ms)","trace[65177291] 'applied index is now lower than readState.Index' (duration: 701.599µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T03:22:50.0205Z","caller":"traceutil/trace.go:171","msg":"trace[1263764598] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"307.841932ms","start":"2026-09-07T03:22:49.712607Z","end":"2026-09-07T03:22:50.020449Z","steps":["trace[1263764598] 'process raft request' (duration: 307.208933ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:22:50.0207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.483845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-07T03:22:50.020764Z","caller":"traceutil/trace.go:171","msg":"trace[1840861754] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:602; }","duration":"262.583347ms","start":"2026-09-07T03:22:49.758163Z","end":"2026-09-07T03:22:50.020746Z","steps":["trace[1840861754] 'agreement among raft nodes before linearized reading' (duration: 262.374834ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:22:50.02277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T03:22:49.712591Z","time spent":"307.956863ms","remote":"127.0.0.1:36040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T03:22:50.254647Z","caller":"traceutil/trace.go:171","msg":"trace[1351409791] linearizableReadLoop","detail":"{readStateIndex:627; appliedIndex:626; }","duration":"234.106407ms","start":"2026-09-07T03:22:50.020514Z","end":"2026-09-07T03:22:50.25462Z","steps":["trace[1351409791] 'read index received' (duration: 234.043846ms)","trace[1351409791] 'applied index is now lower than readState.Index' (duration: 61.041µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T03:22:50.25476Z","caller":"traceutil/trace.go:171","msg":"trace[1872618732] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"266.574598ms","start":"2026-09-07T03:22:49.988174Z","end":"2026-09-07T03:22:50.254749Z","steps":["trace[1872618732] 'process raft request' (duration: 266.207793ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:22:50.255007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.914673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-07T03:22:50.255039Z","caller":"traceutil/trace.go:171","msg":"trace[1069707681] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:603; }","duration":"495.966694ms","start":"2026-09-07T03:22:49.759061Z","end":"2026-09-07T03:22:50.255028Z","steps":["trace[1069707681] 'agreement among raft nodes before linearized reading' (duration: 495.806871ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:22:50.255064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T03:22:49.759056Z","time spent":"496.000544ms","remote":"127.0.0.1:36194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-07T03:22:50.516489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.956028ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T03:22:50.51702Z","caller":"traceutil/trace.go:171","msg":"trace[966702722] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:627; }","duration":"262.286756ms","start":"2026-09-07T03:22:50.254716Z","end":"2026-09-07T03:22:50.517003Z","steps":["trace[966702722] 'read index received' (duration: 86.521786ms)","trace[966702722] 'applied index is now lower than readState.Index' (duration: 175.7632ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T03:22:50.517296Z","caller":"traceutil/trace.go:171","msg":"trace[223303754] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"257.541809ms","start":"2026-09-07T03:22:50.259668Z","end":"2026-09-07T03:22:50.51721Z","steps":["trace[223303754] 'process raft request' (duration: 256.97023ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:22:50.517645Z","caller":"traceutil/trace.go:171","msg":"trace[1781223233] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"421.450021ms","start":"2026-09-07T03:22:50.096157Z","end":"2026-09-07T03:22:50.517607Z","steps":["trace[1781223233] 'process raft request' (duration: 420.446976ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:22:50.517723Z","caller":"traceutil/trace.go:171","msg":"trace[2142047926] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"492.841903ms","start":"2026-09-07T03:22:50.024821Z","end":"2026-09-07T03:22:50.517663Z","steps":["trace[2142047926] 'process raft request' (duration: 316.336262ms)","trace[2142047926] 'compare' (duration: 174.712375ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T03:22:50.517783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.027834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.22\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-09-07T03:22:50.517936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T03:22:50.0248Z","time spent":"493.016725ms","remote":"127.0.0.1:36194","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-09-07T03:22:50.517776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.98282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T03:22:50.518032Z","caller":"traceutil/trace.go:171","msg":"trace[1164886921] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:606; }","duration":"343.244894ms","start":"2026-09-07T03:22:50.174748Z","end":"2026-09-07T03:22:50.517993Z","steps":["trace[1164886921] 'agreement among raft nodes before linearized reading' (duration: 342.98037ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:22:50.517981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.265122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-5kr6g\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-09-07T03:22:50.517998Z","caller":"traceutil/trace.go:171","msg":"trace[781127336] range","detail":"{range_begin:/registry/masterleases/162.253.55.22; range_end:; response_count:1; response_revision:606; }","duration":"344.276598ms","start":"2026-09-07T03:22:50.173699Z","end":"2026-09-07T03:22:50.517975Z","steps":["trace[781127336] 'agreement among raft nodes before linearized reading' (duration: 343.964254ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:22:50.517775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T03:22:50.096129Z","time spent":"421.606694ms","remote":"127.0.0.1:36450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-07T03:22:50.518308Z","caller":"traceutil/trace.go:171","msg":"trace[974839427] range","detail":"{range_begin:/registry/pods/kube-system/cilium-5kr6g; range_end:; response_count:1; response_revision:606; }","duration":"309.560236ms","start":"2026-09-07T03:22:50.208646Z","end":"2026-09-07T03:22:50.518206Z","steps":["trace[974839427] 'agreement among raft nodes before linearized reading' (duration: 308.977668ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:22:50.518238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T03:22:50.173679Z","time spent":"344.544972ms","remote":"127.0.0.1:35970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/162.253.55.22\" "} {"level":"warn","ts":"2026-09-07T03:22:50.51848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T03:22:50.208613Z","time spent":"309.792689ms","remote":"127.0.0.1:36126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-5kr6g\" "} {"level":"warn","ts":"2026-09-07T03:22:50.518124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T03:22:50.174723Z","time spent":"343.357706ms","remote":"127.0.0.1:35944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-07T03:22:50.988562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T03:22:50.519313Z","time spent":"469.242328ms","remote":"127.0.0.1:35970","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-09-07T03:22:50.988675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.156792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T03:22:50.988575Z","caller":"traceutil/trace.go:171","msg":"trace[1657836584] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:630; }","duration":"468.047871ms","start":"2026-09-07T03:22:50.520497Z","end":"2026-09-07T03:22:50.988544Z","steps":["trace[1657836584] 'read index received' (duration: 375.459069ms)","trace[1657836584] 'applied index is now lower than readState.Index' (duration: 92.586902ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T03:22:50.988743Z","caller":"traceutil/trace.go:171","msg":"trace[73118401] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:606; }","duration":"468.239834ms","start":"2026-09-07T03:22:50.520469Z","end":"2026-09-07T03:22:50.988709Z","steps":["trace[73118401] 'agreement among raft nodes before linearized reading' (duration: 468.142903ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:22:50.988796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T03:22:50.520452Z","time spent":"468.332225ms","remote":"127.0.0.1:35952","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-09-07T03:22:51.098588Z","caller":"traceutil/trace.go:171","msg":"trace[498942562] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:631; }","duration":"109.83216ms","start":"2026-09-07T03:22:50.988725Z","end":"2026-09-07T03:22:51.09854Z","steps":["trace[498942562] 'read index received' (duration: 107.843072ms)","trace[498942562] 'applied index is now lower than readState.Index' (duration: 1.971178ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T03:22:51.098758Z","caller":"traceutil/trace.go:171","msg":"trace[1531204920] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"561.971604ms","start":"2026-09-07T03:22:50.536759Z","end":"2026-09-07T03:22:51.098731Z","steps":["trace[1531204920] 'process raft request' (duration: 559.831054ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:22:51.098837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.156177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-07T03:22:51.098921Z","caller":"traceutil/trace.go:171","msg":"trace[800253793] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:607; }","duration":"576.277408ms","start":"2026-09-07T03:22:50.522601Z","end":"2026-09-07T03:22:51.098878Z","steps":["trace[800253793] 'agreement among raft nodes before linearized reading' (duration: 576.062765ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:22:51.098959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T03:22:50.522589Z","time spent":"576.358399ms","remote":"127.0.0.1:36194","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-09-07T03:22:51.09897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.173618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T03:22:51.099025Z","caller":"traceutil/trace.go:171","msg":"trace[2147208015] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:607; }","duration":"567.25743ms","start":"2026-09-07T03:22:50.531749Z","end":"2026-09-07T03:22:51.099006Z","steps":["trace[2147208015] 'agreement among raft nodes before linearized reading' (duration: 567.167729ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:22:51.099014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T03:22:50.536744Z","time spent":"562.117198ms","remote":"127.0.0.1:36126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T03:22:51.099055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T03:22:50.531734Z","time spent":"567.313281ms","remote":"127.0.0.1:36158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"info","ts":"2026-09-07T03:22:51.100259Z","caller":"traceutil/trace.go:171","msg":"trace[1781564074] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"110.583851ms","start":"2026-09-07T03:22:50.989656Z","end":"2026-09-07T03:22:51.10024Z","steps":["trace[1781564074] 'process raft request' (duration: 110.461519ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:22:52.544756Z","caller":"traceutil/trace.go:171","msg":"trace[139447390] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"190.649525ms","start":"2026-09-07T03:22:52.354081Z","end":"2026-09-07T03:22:52.544731Z","steps":["trace[139447390] 'process raft request' (duration: 190.514253ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:22:52.553009Z","caller":"traceutil/trace.go:171","msg":"trace[2042000880] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"197.720243ms","start":"2026-09-07T03:22:52.355271Z","end":"2026-09-07T03:22:52.552991Z","steps":["trace[2042000880] 'process raft request' (duration: 197.546461ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:23:05.44349Z","caller":"traceutil/trace.go:171","msg":"trace[1418520739] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"134.208575ms","start":"2026-09-07T03:23:05.309256Z","end":"2026-09-07T03:23:05.443465Z","steps":["trace[1418520739] 'process raft request' (duration: 134.058223ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:24:06.787073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.331269ms","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-09-07T03:24:06.78723Z","caller":"traceutil/trace.go:171","msg":"trace[1819268867] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1461; }","duration":"102.48701ms","start":"2026-09-07T03:24:06.684678Z","end":"2026-09-07T03:24:06.787165Z","steps":["trace[1819268867] 'range keys from in-memory index tree' (duration: 102.205297ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:24:06.982209Z","caller":"traceutil/trace.go:171","msg":"trace[434386455] transaction","detail":"{read_only:false; response_revision:1462; number_of_response:1; }","duration":"190.528911ms","start":"2026-09-07T03:24:06.791654Z","end":"2026-09-07T03:24:06.982183Z","steps":["trace[434386455] 'process raft request' (duration: 190.340059ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:24:07.006954Z","caller":"traceutil/trace.go:171","msg":"trace[1004045087] transaction","detail":"{read_only:false; response_revision:1463; number_of_response:1; }","duration":"214.915959ms","start":"2026-09-07T03:24:06.791999Z","end":"2026-09-07T03:24:07.006915Z","steps":["trace[1004045087] 'process raft request' (duration: 199.391279ms)","trace[1004045087] 'compare' (duration: 15.270568ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T03:24:32.974392Z","caller":"traceutil/trace.go:171","msg":"trace[2137305051] transaction","detail":"{read_only:false; response_revision:1883; number_of_response:1; }","duration":"257.928195ms","start":"2026-09-07T03:24:32.716443Z","end":"2026-09-07T03:24:32.974372Z","steps":["trace[2137305051] 'process raft request' (duration: 257.806944ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:24:33.026487Z","caller":"traceutil/trace.go:171","msg":"trace[1514386594] linearizableReadLoop","detail":"{readStateIndex:1960; appliedIndex:1959; }","duration":"277.659573ms","start":"2026-09-07T03:24:32.748762Z","end":"2026-09-07T03:24:33.026421Z","steps":["trace[1514386594] 'read index received' (duration: 225.936887ms)","trace[1514386594] 'applied index is now lower than readState.Index' (duration: 51.721136ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T03:24:33.026664Z","caller":"traceutil/trace.go:171","msg":"trace[1112807846] transaction","detail":"{read_only:false; response_revision:1884; number_of_response:1; }","duration":"310.111306ms","start":"2026-09-07T03:24:32.716519Z","end":"2026-09-07T03:24:33.02663Z","steps":["trace[1112807846] 'process raft request' (duration: 309.685042ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:24:33.026899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.13676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ac6735a4-6d87-4022-979c-8cb4f6ba1544\" ","response":"range_response_count:1 size:3626"} {"level":"warn","ts":"2026-09-07T03:24:33.026877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T03:24:32.716446Z","time spent":"310.286338ms","remote":"127.0.0.1:36194","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-09-07T03:24:33.026963Z","caller":"traceutil/trace.go:171","msg":"trace[903344350] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ac6735a4-6d87-4022-979c-8cb4f6ba1544; range_end:; response_count:1; response_revision:1884; }","duration":"278.249911ms","start":"2026-09-07T03:24:32.748693Z","end":"2026-09-07T03:24:33.026943Z","steps":["trace[903344350] 'agreement among raft nodes before linearized reading' (duration: 277.927306ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:24:33.406905Z","caller":"traceutil/trace.go:171","msg":"trace[209964958] linearizableReadLoop","detail":"{readStateIndex:1963; appliedIndex:1961; }","duration":"274.523527ms","start":"2026-09-07T03:24:33.132359Z","end":"2026-09-07T03:24:33.406882Z","steps":["trace[209964958] 'read index received' (duration: 11.300169ms)","trace[209964958] 'applied index is now lower than readState.Index' (duration: 263.222458ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T03:24:33.407347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.969312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-07T03:24:33.40734Z","caller":"traceutil/trace.go:171","msg":"trace[260326065] transaction","detail":"{read_only:false; response_revision:1887; number_of_response:1; }","duration":"275.022852ms","start":"2026-09-07T03:24:33.132269Z","end":"2026-09-07T03:24:33.407292Z","steps":["trace[260326065] 'process raft request' (duration: 274.475677ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:24:33.407414Z","caller":"traceutil/trace.go:171","msg":"trace[1607211922] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1887; }","duration":"275.087942ms","start":"2026-09-07T03:24:33.132313Z","end":"2026-09-07T03:24:33.407401Z","steps":["trace[1607211922] 'agreement among raft nodes before linearized reading' (duration: 274.88484ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:24:33.407795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.346971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T03:24:33.407865Z","caller":"traceutil/trace.go:171","msg":"trace[1424942054] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1887; }","duration":"232.435143ms","start":"2026-09-07T03:24:33.17541Z","end":"2026-09-07T03:24:33.407846Z","steps":["trace[1424942054] 'agreement among raft nodes before linearized reading' (duration: 232.321622ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:24:33.407945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.290486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-07T03:24:33.408023Z","caller":"traceutil/trace.go:171","msg":"trace[735148653] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1887; }","duration":"240.416228ms","start":"2026-09-07T03:24:33.167593Z","end":"2026-09-07T03:24:33.408009Z","steps":["trace[735148653] 'agreement among raft nodes before linearized reading' (duration: 240.172295ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:24:33.408453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.433988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-07T03:24:33.408509Z","caller":"traceutil/trace.go:171","msg":"trace[2105150759] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1887; }","duration":"241.545469ms","start":"2026-09-07T03:24:33.166952Z","end":"2026-09-07T03:24:33.408498Z","steps":["trace[2105150759] 'agreement among raft nodes before linearized reading' (duration: 240.71777ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:24:45.746906Z","caller":"traceutil/trace.go:171","msg":"trace[1709503583] linearizableReadLoop","detail":"{readStateIndex:2074; appliedIndex:2073; }","duration":"194.854106ms","start":"2026-09-07T03:24:45.55203Z","end":"2026-09-07T03:24:45.746884Z","steps":["trace[1709503583] 'read index received' (duration: 162.297158ms)","trace[1709503583] 'applied index is now lower than readState.Index' (duration: 32.556108ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T03:24:45.747127Z","caller":"traceutil/trace.go:171","msg":"trace[53043678] transaction","detail":"{read_only:false; response_revision:1995; number_of_response:1; }","duration":"252.027491ms","start":"2026-09-07T03:24:45.495079Z","end":"2026-09-07T03:24:45.747106Z","steps":["trace[53043678] 'process raft request' (duration: 219.303541ms)","trace[53043678] 'compare' (duration: 32.416447ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T03:24:45.747446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.408412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-07T03:24:45.747525Z","caller":"traceutil/trace.go:171","msg":"trace[880322385] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1995; }","duration":"195.541463ms","start":"2026-09-07T03:24:45.551961Z","end":"2026-09-07T03:24:45.747502Z","steps":["trace[880322385] 'agreement among raft nodes before linearized reading' (duration: 195.180749ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:24:45.979567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.388324ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T03:24:45.979719Z","caller":"traceutil/trace.go:171","msg":"trace[851907543] transaction","detail":"{read_only:false; response_revision:1998; number_of_response:1; }","duration":"170.595234ms","start":"2026-09-07T03:24:45.809095Z","end":"2026-09-07T03:24:45.97969Z","steps":["trace[851907543] 'process raft request' (duration: 42.993018ms)","trace[851907543] 'compare' (duration: 127.263433ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T03:24:49.342599Z","caller":"traceutil/trace.go:171","msg":"trace[1245407984] linearizableReadLoop","detail":"{readStateIndex:2103; appliedIndex:2102; }","duration":"171.090822ms","start":"2026-09-07T03:24:49.171486Z","end":"2026-09-07T03:24:49.342577Z","steps":["trace[1245407984] 'read index received' (duration: 170.876599ms)","trace[1245407984] 'applied index is now lower than readState.Index' (duration: 213.153µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T03:24:49.342722Z","caller":"traceutil/trace.go:171","msg":"trace[1949268451] transaction","detail":"{read_only:false; response_revision:2024; number_of_response:1; }","duration":"194.851538ms","start":"2026-09-07T03:24:49.147861Z","end":"2026-09-07T03:24:49.342712Z","steps":["trace[1949268451] 'process raft request' (duration: 194.579205ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:24:49.342894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.392944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T03:24:49.342959Z","caller":"traceutil/trace.go:171","msg":"trace[1805407015] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2024; }","duration":"171.465435ms","start":"2026-09-07T03:24:49.171474Z","end":"2026-09-07T03:24:49.342939Z","steps":["trace[1805407015] 'agreement among raft nodes before linearized reading' (duration: 171.365894ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:24:49.343193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.212256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-09-07T03:24:49.343292Z","caller":"traceutil/trace.go:171","msg":"trace[465647599] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2024; }","duration":"123.333348ms","start":"2026-09-07T03:24:49.219941Z","end":"2026-09-07T03:24:49.343275Z","steps":["trace[465647599] 'agreement among raft nodes before linearized reading' (duration: 123.130095ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:24:49.697865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.459941ms","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-09-07T03:24:49.697932Z","caller":"traceutil/trace.go:171","msg":"trace[1489167242] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2025; }","duration":"187.557492ms","start":"2026-09-07T03:24:49.510358Z","end":"2026-09-07T03:24:49.697916Z","steps":["trace[1489167242] 'range keys from in-memory index tree' (duration: 187.32597ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:24:49.697985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.102704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-07T03:24:49.698025Z","caller":"traceutil/trace.go:171","msg":"trace[992131079] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2025; }","duration":"177.177245ms","start":"2026-09-07T03:24:49.520836Z","end":"2026-09-07T03:24:49.698013Z","steps":["trace[992131079] 'range keys from in-memory index tree' (duration: 176.991494ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:25:13.272989Z","caller":"traceutil/trace.go:171","msg":"trace[977207044] transaction","detail":"{read_only:false; response_revision:2156; number_of_response:1; }","duration":"127.211172ms","start":"2026-09-07T03:25:13.145754Z","end":"2026-09-07T03:25:13.272965Z","steps":["trace[977207044] 'process raft request' (duration: 127.086008ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:25:13.817455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.498485ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T03:25:13.817608Z","caller":"traceutil/trace.go:171","msg":"trace[836573569] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2156; }","duration":"144.663201ms","start":"2026-09-07T03:25:13.672921Z","end":"2026-09-07T03:25:13.817584Z","steps":["trace[836573569] 'range keys from in-memory index tree' (duration: 144.477754ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:25:13.818215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.790635ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T03:25:13.818333Z","caller":"traceutil/trace.go:171","msg":"trace[550613967] linearizableReadLoop","detail":"{readStateIndex:2241; appliedIndex:2240; }","duration":"343.594532ms","start":"2026-09-07T03:25:13.474722Z","end":"2026-09-07T03:25:13.818316Z","steps":["trace[550613967] 'read index received' (duration: 56.932µs)","trace[550613967] 'applied index is now lower than readState.Index' (duration: 343.53573ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T03:25:13.818414Z","caller":"traceutil/trace.go:171","msg":"trace[1844376883] transaction","detail":"{read_only:false; response_revision:2157; number_of_response:1; }","duration":"642.267547ms","start":"2026-09-07T03:25:13.17612Z","end":"2026-09-07T03:25:13.818388Z","steps":["trace[1844376883] 'process raft request' (duration: 238.058007ms)","trace[1844376883] 'compare' (duration: 403.537497ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T03:25:13.818568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T03:25:13.17611Z","time spent":"642.365132ms","remote":"127.0.0.1:36194","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-09-07T03:25:13.818619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.898193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-07T03:25:13.818696Z","caller":"traceutil/trace.go:171","msg":"trace[1796493967] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2157; }","duration":"344.022527ms","start":"2026-09-07T03:25:13.474662Z","end":"2026-09-07T03:25:13.818685Z","steps":["trace[1796493967] 'agreement among raft nodes before linearized reading' (duration: 343.752317ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:25:13.818771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T03:25:13.474635Z","time spent":"344.124611ms","remote":"127.0.0.1:36194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-09-07T03:25:14.030198Z","caller":"traceutil/trace.go:171","msg":"trace[2110045088] transaction","detail":"{read_only:false; response_revision:2161; number_of_response:1; }","duration":"120.855221ms","start":"2026-09-07T03:25:13.909319Z","end":"2026-09-07T03:25:14.030174Z","steps":["trace[2110045088] 'process raft request' (duration: 120.624702ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:26:36.685615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.219297ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T03:26:36.685853Z","caller":"traceutil/trace.go:171","msg":"trace[2110295995] linearizableReadLoop","detail":"{readStateIndex:2823; appliedIndex:2822; }","duration":"145.062068ms","start":"2026-09-07T03:26:36.540769Z","end":"2026-09-07T03:26:36.685831Z","steps":["trace[2110295995] 'read index received' (duration: 44.041µs)","trace[2110295995] 'applied index is now lower than readState.Index' (duration: 145.016547ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T03:26:36.685984Z","caller":"traceutil/trace.go:171","msg":"trace[701604540] transaction","detail":"{read_only:false; response_revision:2719; number_of_response:1; }","duration":"161.683451ms","start":"2026-09-07T03:26:36.524284Z","end":"2026-09-07T03:26:36.685968Z","steps":["trace[701604540] 'compare' (duration: 151.9763ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:26:36.686222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.452317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-07T03:26:36.686348Z","caller":"traceutil/trace.go:171","msg":"trace[1650032045] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2720; }","duration":"145.54224ms","start":"2026-09-07T03:26:36.540719Z","end":"2026-09-07T03:26:36.686261Z","steps":["trace[1650032045] 'agreement among raft nodes before linearized reading' (duration: 145.359465ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:26:37.036747Z","caller":"traceutil/trace.go:171","msg":"trace[1940691280] transaction","detail":"{read_only:false; response_revision:2722; number_of_response:1; }","duration":"204.415301ms","start":"2026-09-07T03:26:36.832306Z","end":"2026-09-07T03:26:37.036721Z","steps":["trace[1940691280] 'process raft request' (duration: 203.618961ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:26:37.147129Z","caller":"traceutil/trace.go:171","msg":"trace[1733909714] transaction","detail":"{read_only:false; response_revision:2723; number_of_response:1; }","duration":"107.762269ms","start":"2026-09-07T03:26:37.039303Z","end":"2026-09-07T03:26:37.147065Z","steps":["trace[1733909714] 'process raft request' (duration: 93.694511ms)","trace[1733909714] 'compare' (duration: 13.794331ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T03:31:01.65827Z","caller":"traceutil/trace.go:171","msg":"trace[300599672] transaction","detail":"{read_only:false; response_revision:4580; number_of_response:1; }","duration":"123.671439ms","start":"2026-09-07T03:31:01.534561Z","end":"2026-09-07T03:31:01.658233Z","steps":["trace[300599672] 'process raft request' (duration: 123.409875ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:31:01.658378Z","caller":"traceutil/trace.go:171","msg":"trace[149607925] transaction","detail":"{read_only:false; response_revision:4581; number_of_response:1; }","duration":"120.704527ms","start":"2026-09-07T03:31:01.537643Z","end":"2026-09-07T03:31:01.658347Z","steps":["trace[149607925] 'process raft request' (duration: 120.433143ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:31:01.658907Z","caller":"traceutil/trace.go:171","msg":"trace[1632484106] transaction","detail":"{read_only:false; response_revision:4582; number_of_response:1; }","duration":"104.740438ms","start":"2026-09-07T03:31:01.554147Z","end":"2026-09-07T03:31:01.658888Z","steps":["trace[1632484106] 'process raft request' (duration: 104.026918ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:31:04.814431Z","caller":"traceutil/trace.go:171","msg":"trace[1834535731] transaction","detail":"{read_only:false; response_revision:4656; number_of_response:1; }","duration":"146.302828ms","start":"2026-09-07T03:31:04.667643Z","end":"2026-09-07T03:31:04.813946Z","steps":["trace[1834535731] 'process raft request' (duration: 130.653135ms)","trace[1834535731] 'compare' (duration: 15.520671ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T03:31:09.962265Z","caller":"traceutil/trace.go:171","msg":"trace[271854501] linearizableReadLoop","detail":"{readStateIndex:5016; appliedIndex:5015; }","duration":"109.08702ms","start":"2026-09-07T03:31:09.853159Z","end":"2026-09-07T03:31:09.962246Z","steps":["trace[271854501] 'read index received' (duration: 108.915797ms)","trace[271854501] 'applied index is now lower than readState.Index' (duration: 170.303µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T03:31:09.962356Z","caller":"traceutil/trace.go:171","msg":"trace[1894720992] transaction","detail":"{read_only:false; response_revision:4830; number_of_response:1; }","duration":"110.081774ms","start":"2026-09-07T03:31:09.852245Z","end":"2026-09-07T03:31:09.962327Z","steps":["trace[1894720992] 'process raft request' (duration: 109.885581ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:31:09.962805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.727035ms","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-09-07T03:31:09.962828Z","caller":"traceutil/trace.go:171","msg":"trace[1785649347] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4830; }","duration":"108.763746ms","start":"2026-09-07T03:31:09.854056Z","end":"2026-09-07T03:31:09.96282Z","steps":["trace[1785649347] 'agreement among raft nodes before linearized reading' (duration: 108.706135ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:31:09.962512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.333094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-07T03:31:09.962919Z","caller":"traceutil/trace.go:171","msg":"trace[2015764371] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4830; }","duration":"109.78411ms","start":"2026-09-07T03:31:09.85313Z","end":"2026-09-07T03:31:09.962914Z","steps":["trace[2015764371] 'agreement among raft nodes before linearized reading' (duration: 109.262573ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:31:16.962552Z","caller":"traceutil/trace.go:171","msg":"trace[582496708] transaction","detail":"{read_only:false; response_revision:4960; number_of_response:1; }","duration":"227.744759ms","start":"2026-09-07T03:31:16.734776Z","end":"2026-09-07T03:31:16.962521Z","steps":["trace[582496708] 'process raft request' (duration: 227.546996ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:31:16.977932Z","caller":"traceutil/trace.go:171","msg":"trace[1442128614] transaction","detail":"{read_only:false; response_revision:4961; number_of_response:1; }","duration":"133.094351ms","start":"2026-09-07T03:31:16.844816Z","end":"2026-09-07T03:31:16.97791Z","steps":["trace[1442128614] 'process raft request' (duration: 132.974709ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:31:17.480993Z","caller":"traceutil/trace.go:171","msg":"trace[1877895162] transaction","detail":"{read_only:false; response_revision:4963; number_of_response:1; }","duration":"149.483582ms","start":"2026-09-07T03:31:17.331461Z","end":"2026-09-07T03:31:17.480945Z","steps":["trace[1877895162] 'process raft request' (duration: 149.186128ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:31:25.633498Z","caller":"traceutil/trace.go:171","msg":"trace[2077326911] transaction","detail":"{read_only:false; response_revision:5092; number_of_response:1; }","duration":"123.312992ms","start":"2026-09-07T03:31:25.510163Z","end":"2026-09-07T03:31:25.633476Z","steps":["trace[2077326911] 'process raft request' (duration: 123.1713ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:31:31.118427Z","caller":"traceutil/trace.go:171","msg":"trace[1796266236] linearizableReadLoop","detail":"{readStateIndex:5324; appliedIndex:5323; }","duration":"433.149781ms","start":"2026-09-07T03:31:30.685255Z","end":"2026-09-07T03:31:31.118405Z","steps":["trace[1796266236] 'read index received' (duration: 432.970198ms)","trace[1796266236] 'applied index is now lower than readState.Index' (duration: 178.503µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T03:31:31.118537Z","caller":"traceutil/trace.go:171","msg":"trace[640809093] transaction","detail":"{read_only:false; response_revision:5129; number_of_response:1; }","duration":"433.361214ms","start":"2026-09-07T03:31:30.685164Z","end":"2026-09-07T03:31:31.118525Z","steps":["trace[640809093] 'process raft request' (duration: 433.086001ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:31:31.118649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T03:31:30.685146Z","time spent":"433.420965ms","remote":"127.0.0.1:36020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":731,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T03:31:31.118913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.635017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:1451"} {"level":"info","ts":"2026-09-07T03:31:31.119013Z","caller":"traceutil/trace.go:171","msg":"trace[1110713875] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5129; }","duration":"433.75995ms","start":"2026-09-07T03:31:30.685229Z","end":"2026-09-07T03:31:31.118989Z","steps":["trace[1110713875] 'agreement among raft nodes before linearized reading' (duration: 433.524876ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:31:31.119068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T03:31:30.685218Z","time spent":"433.837941ms","remote":"127.0.0.1:36128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":1475,"request content":"key:\"/registry/services/specs/openstack/rabbitmq-keystone\" "} {"level":"warn","ts":"2026-09-07T03:31:31.13352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.115986ms","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":"info","ts":"2026-09-07T03:31:31.133617Z","caller":"traceutil/trace.go:171","msg":"trace[1268386965] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:5130; }","duration":"359.252718ms","start":"2026-09-07T03:31:30.774339Z","end":"2026-09-07T03:31:31.133592Z","steps":["trace[1268386965] 'agreement among raft nodes before linearized reading' (duration: 359.102986ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:31:31.133617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.057039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"warn","ts":"2026-09-07T03:31:31.133679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T03:31:30.774323Z","time spent":"359.33959ms","remote":"127.0.0.1:36380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-09-07T03:31:31.133692Z","caller":"traceutil/trace.go:171","msg":"trace[1264404841] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5130; }","duration":"210.198781ms","start":"2026-09-07T03:31:30.923472Z","end":"2026-09-07T03:31:31.133671Z","steps":["trace[1264404841] 'agreement among raft nodes before linearized reading' (duration: 209.976638ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:31:31.133819Z","caller":"traceutil/trace.go:171","msg":"trace[993864252] transaction","detail":"{read_only:false; response_revision:5130; number_of_response:1; }","duration":"106.632082ms","start":"2026-09-07T03:31:31.027164Z","end":"2026-09-07T03:31:31.133796Z","steps":["trace[993864252] 'process raft request' (duration: 106.061264ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:31:31.474122Z","caller":"traceutil/trace.go:171","msg":"trace[582979071] transaction","detail":"{read_only:false; response_revision:5135; number_of_response:1; }","duration":"142.464423ms","start":"2026-09-07T03:31:31.331603Z","end":"2026-09-07T03:31:31.474067Z","steps":["trace[582979071] 'process raft request' (duration: 133.627259ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:31:31.474393Z","caller":"traceutil/trace.go:171","msg":"trace[241599759] transaction","detail":"{read_only:false; response_revision:5136; number_of_response:1; }","duration":"135.504985ms","start":"2026-09-07T03:31:31.33886Z","end":"2026-09-07T03:31:31.474365Z","steps":["trace[241599759] 'process raft request' (duration: 135.094169ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:31:31.700168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.901801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T03:31:31.70029Z","caller":"traceutil/trace.go:171","msg":"trace[1264466669] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5137; }","duration":"121.076193ms","start":"2026-09-07T03:31:31.579194Z","end":"2026-09-07T03:31:31.70027Z","steps":["trace[1264466669] 'range keys from in-memory index tree' (duration: 120.82325ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:31:31.885311Z","caller":"traceutil/trace.go:171","msg":"trace[2029728775] transaction","detail":"{read_only:false; response_revision:5139; number_of_response:1; }","duration":"100.852911ms","start":"2026-09-07T03:31:31.78442Z","end":"2026-09-07T03:31:31.885273Z","steps":["trace[2029728775] 'process raft request' (duration: 26.332438ms)","trace[2029728775] 'compare' (duration: 73.798893ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T03:32:06.493059Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2876} {"level":"info","ts":"2026-09-07T03:32:06.577363Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2876,"took":"83.352322ms","hash":1217426599,"current-db-size-bytes":53850112,"current-db-size":"54 MB","current-db-size-in-use-bytes":28229632,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-07T03:32:06.577418Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1217426599,"revision":2876,"compact-revision":-1} {"level":"info","ts":"2026-09-07T03:33:05.594685Z","caller":"traceutil/trace.go:171","msg":"trace[1723468801] transaction","detail":"{read_only:false; response_revision:5792; number_of_response:1; }","duration":"105.361959ms","start":"2026-09-07T03:33:05.489298Z","end":"2026-09-07T03:33:05.59466Z","steps":["trace[1723468801] 'process raft request' (duration: 105.191596ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:33:05.647252Z","caller":"traceutil/trace.go:171","msg":"trace[1647913896] linearizableReadLoop","detail":"{readStateIndex:6010; appliedIndex:6008; }","duration":"131.271115ms","start":"2026-09-07T03:33:05.515954Z","end":"2026-09-07T03:33:05.647225Z","steps":["trace[1647913896] 'read index received' (duration: 78.637851ms)","trace[1647913896] 'applied index is now lower than readState.Index' (duration: 52.631974ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T03:33:05.647377Z","caller":"traceutil/trace.go:171","msg":"trace[1706812997] transaction","detail":"{read_only:false; response_revision:5793; number_of_response:1; }","duration":"156.850916ms","start":"2026-09-07T03:33:05.490484Z","end":"2026-09-07T03:33:05.647335Z","steps":["trace[1706812997] 'process raft request' (duration: 156.485432ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:33:05.647473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.126612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T03:33:05.647518Z","caller":"traceutil/trace.go:171","msg":"trace[204976955] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5793; }","duration":"131.204463ms","start":"2026-09-07T03:33:05.516298Z","end":"2026-09-07T03:33:05.647503Z","steps":["trace[204976955] 'agreement among raft nodes before linearized reading' (duration: 131.122441ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:33:05.647564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.451917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T03:33:05.647654Z","caller":"traceutil/trace.go:171","msg":"trace[128583278] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5793; }","duration":"131.72442ms","start":"2026-09-07T03:33:05.515916Z","end":"2026-09-07T03:33:05.64764Z","steps":["trace[128583278] 'agreement among raft nodes before linearized reading' (duration: 131.452617ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:35:35.293168Z","caller":"traceutil/trace.go:171","msg":"trace[1849393323] linearizableReadLoop","detail":"{readStateIndex:7116; appliedIndex:7115; }","duration":"119.405561ms","start":"2026-09-07T03:35:35.173733Z","end":"2026-09-07T03:35:35.293138Z","steps":["trace[1849393323] 'read index received' (duration: 119.152783ms)","trace[1849393323] 'applied index is now lower than readState.Index' (duration: 234.058µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T03:35:35.293286Z","caller":"traceutil/trace.go:171","msg":"trace[98873847] transaction","detail":"{read_only:false; response_revision:6864; number_of_response:1; }","duration":"136.821078ms","start":"2026-09-07T03:35:35.156395Z","end":"2026-09-07T03:35:35.293217Z","steps":["trace[98873847] 'process raft request' (duration: 136.56611ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:35:35.293385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.625238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-07T03:35:35.293407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.663486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T03:35:35.29345Z","caller":"traceutil/trace.go:171","msg":"trace[957254446] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:6864; }","duration":"119.730602ms","start":"2026-09-07T03:35:35.173704Z","end":"2026-09-07T03:35:35.293435Z","steps":["trace[957254446] 'agreement among raft nodes before linearized reading' (duration: 119.610448ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:35:35.293473Z","caller":"traceutil/trace.go:171","msg":"trace[1915686217] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6864; }","duration":"117.767929ms","start":"2026-09-07T03:35:35.175688Z","end":"2026-09-07T03:35:35.293456Z","steps":["trace[1915686217] 'agreement among raft nodes before linearized reading' (duration: 117.656716ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T03:35:35.293535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.680497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T03:35:35.293598Z","caller":"traceutil/trace.go:171","msg":"trace[240909319] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:6864; }","duration":"110.7732ms","start":"2026-09-07T03:35:35.18281Z","end":"2026-09-07T03:35:35.293583Z","steps":["trace[240909319] 'agreement among raft nodes before linearized reading' (duration: 110.640186ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T03:37:06.504835Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5389} {"level":"info","ts":"2026-09-07T03:37:06.690316Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5389,"took":"184.050365ms","hash":1885504561,"current-db-size-bytes":53850112,"current-db-size":"54 MB","current-db-size-in-use-bytes":18718720,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-07T03:37:06.690419Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1885504561,"revision":5389,"compact-revision":2876}