{"level":"warn","ts":"2026-05-26T07:20:01.776361Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-26T07:20:01.776456Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.57: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.57:2380","--initial-cluster=instance=https://199.204.45.57:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.57:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.57: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-05-26T07:20:01.776529Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-26T07:20:01.776544Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.57:2380"]} {"level":"info","ts":"2026-05-26T07:20:01.776565Z","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-05-26T07:20:01.777382Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"]} {"level":"info","ts":"2026-05-26T07:20:01.777481Z","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.57:2380"],"listen-peer-urls":["https://199.204.45.57:2380"],"advertise-client-urls":["https://199.204.45.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.57: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-05-26T07:20:01.78148Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.38309ms"} {"level":"info","ts":"2026-05-26T07:20:01.786382Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b6478f0ac5aeb8e1","cluster-id":"6c58cff80d4a5b62"} {"level":"info","ts":"2026-05-26T07:20:01.786466Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=()"} {"level":"info","ts":"2026-05-26T07:20:01.786497Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became follower at term 0"} {"level":"info","ts":"2026-05-26T07:20:01.786512Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b6478f0ac5aeb8e1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-26T07:20:01.786527Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became follower at term 1"} {"level":"info","ts":"2026-05-26T07:20:01.786583Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=(13134624114678348001)"} {"level":"warn","ts":"2026-05-26T07:20:01.79087Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-26T07:20:01.792539Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-26T07:20:01.793746Z","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-05-26T07:20:01.795862Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b6478f0ac5aeb8e1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-26T07:20:01.796347Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b6478f0ac5aeb8e1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-26T07:20:01.796493Z","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-05-26T07:20:01.796612Z","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-05-26T07:20:01.796648Z","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-05-26T07:20:01.797192Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=(13134624114678348001)"} {"level":"info","ts":"2026-05-26T07:20:01.798725Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6c58cff80d4a5b62","local-member-id":"b6478f0ac5aeb8e1","added-peer-id":"b6478f0ac5aeb8e1","added-peer-peer-urls":["https://199.204.45.57:2380"]} {"level":"info","ts":"2026-05-26T07:20:01.801809Z","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-05-26T07:20:01.802114Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.57:2380"} {"level":"info","ts":"2026-05-26T07:20:01.802164Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.57:2380"} {"level":"info","ts":"2026-05-26T07:20:01.802285Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-26T07:20:01.802017Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b6478f0ac5aeb8e1","initial-advertise-peer-urls":["https://199.204.45.57:2380"],"listen-peer-urls":["https://199.204.45.57:2380"],"advertise-client-urls":["https://199.204.45.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-26T07:20:02.687956Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 is starting a new election at term 1"} {"level":"info","ts":"2026-05-26T07:20:02.688167Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-26T07:20:02.688215Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 received MsgPreVoteResp from b6478f0ac5aeb8e1 at term 1"} {"level":"info","ts":"2026-05-26T07:20:02.688253Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became candidate at term 2"} {"level":"info","ts":"2026-05-26T07:20:02.68827Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 received MsgVoteResp from b6478f0ac5aeb8e1 at term 2"} {"level":"info","ts":"2026-05-26T07:20:02.688301Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became leader at term 2"} {"level":"info","ts":"2026-05-26T07:20:02.68831Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b6478f0ac5aeb8e1 elected leader b6478f0ac5aeb8e1 at term 2"} {"level":"info","ts":"2026-05-26T07:20:02.696102Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b6478f0ac5aeb8e1","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.57:2379]}","request-path":"/0/members/b6478f0ac5aeb8e1/attributes","cluster-id":"6c58cff80d4a5b62","publish-timeout":"7s"} {"level":"info","ts":"2026-05-26T07:20:02.696218Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-26T07:20:02.696618Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-26T07:20:02.69687Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-26T07:20:02.700445Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-26T07:20:02.700532Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-26T07:20:02.700733Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6c58cff80d4a5b62","local-member-id":"b6478f0ac5aeb8e1","cluster-version":"3.5"} {"level":"info","ts":"2026-05-26T07:20:02.700872Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-26T07:20:02.700906Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-26T07:20:02.7013Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.57:2379"} {"level":"info","ts":"2026-05-26T07:20:02.70234Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-26T07:20:23.807633Z","caller":"traceutil/trace.go:171","msg":"trace[1982421503] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"218.918259ms","start":"2026-05-26T07:20:23.588674Z","end":"2026-05-26T07:20:23.807592Z","steps":["trace[1982421503] 'process raft request' (duration: 218.432966ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:20:29.379739Z","caller":"traceutil/trace.go:171","msg":"trace[1518944720] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"285.688215ms","start":"2026-05-26T07:20:29.094028Z","end":"2026-05-26T07:20:29.379717Z","steps":["trace[1518944720] 'process raft request' (duration: 285.570872ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:20:29.385321Z","caller":"traceutil/trace.go:171","msg":"trace[1473421993] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"285.293854ms","start":"2026-05-26T07:20:29.099995Z","end":"2026-05-26T07:20:29.385289Z","steps":["trace[1473421993] 'process raft request' (duration: 285.1302ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:20:29.385403Z","caller":"traceutil/trace.go:171","msg":"trace[1676479427] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"285.030007ms","start":"2026-05-26T07:20:29.100351Z","end":"2026-05-26T07:20:29.385381Z","steps":["trace[1676479427] 'process raft request' (duration: 284.882503ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:20:29.386062Z","caller":"traceutil/trace.go:171","msg":"trace[319009341] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"277.7955ms","start":"2026-05-26T07:20:29.108249Z","end":"2026-05-26T07:20:29.386045Z","steps":["trace[319009341] 'process raft request' (duration: 277.071119ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:20:29.387037Z","caller":"traceutil/trace.go:171","msg":"trace[1843402848] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"277.483432ms","start":"2026-05-26T07:20:29.109531Z","end":"2026-05-26T07:20:29.387014Z","steps":["trace[1843402848] 'process raft request' (duration: 277.07362ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:20:29.387107Z","caller":"traceutil/trace.go:171","msg":"trace[1377069484] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"276.635717ms","start":"2026-05-26T07:20:29.110456Z","end":"2026-05-26T07:20:29.387092Z","steps":["trace[1377069484] 'process raft request' (duration: 276.232995ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:20:29.387105Z","caller":"traceutil/trace.go:171","msg":"trace[1987483844] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"277.343597ms","start":"2026-05-26T07:20:29.109728Z","end":"2026-05-26T07:20:29.387072Z","steps":["trace[1987483844] 'process raft request' (duration: 276.922145ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:20:29.387224Z","caller":"traceutil/trace.go:171","msg":"trace[950367203] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"278.250293ms","start":"2026-05-26T07:20:29.108962Z","end":"2026-05-26T07:20:29.387212Z","steps":["trace[950367203] 'process raft request' (duration: 277.021808ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:20:29.387395Z","caller":"traceutil/trace.go:171","msg":"trace[2084400407] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"254.564467ms","start":"2026-05-26T07:20:29.132804Z","end":"2026-05-26T07:20:29.387369Z","steps":["trace[2084400407] 'process raft request' (duration: 253.926969ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:20:29.388021Z","caller":"traceutil/trace.go:171","msg":"trace[1874696141] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"246.890028ms","start":"2026-05-26T07:20:29.141117Z","end":"2026-05-26T07:20:29.388007Z","steps":["trace[1874696141] 'process raft request' (duration: 245.865329ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:20:29.388291Z","caller":"traceutil/trace.go:171","msg":"trace[209968018] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"226.799915ms","start":"2026-05-26T07:20:29.16148Z","end":"2026-05-26T07:20:29.38828Z","steps":["trace[209968018] 'process raft request' (duration: 226.113305ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:20:29.389041Z","caller":"traceutil/trace.go:171","msg":"trace[1936971168] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"114.123388ms","start":"2026-05-26T07:20:29.274873Z","end":"2026-05-26T07:20:29.388996Z","steps":["trace[1936971168] 'process raft request' (duration: 113.345046ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T07:20:36.088362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.631836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.57\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-26T07:20:36.088549Z","caller":"traceutil/trace.go:171","msg":"trace[147837097] range","detail":"{range_begin:/registry/masterleases/199.204.45.57; range_end:; response_count:1; response_revision:610; }","duration":"435.864301ms","start":"2026-05-26T07:20:35.652662Z","end":"2026-05-26T07:20:36.088526Z","steps":["trace[147837097] 'range keys from in-memory index tree' (duration: 435.515062ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T07:20:36.088602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T07:20:35.652644Z","time spent":"435.945234ms","remote":"127.0.0.1:49426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.57\" "} {"level":"info","ts":"2026-05-26T07:20:36.089276Z","caller":"traceutil/trace.go:171","msg":"trace[1588461455] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:631; }","duration":"226.761813ms","start":"2026-05-26T07:20:35.862485Z","end":"2026-05-26T07:20:36.089246Z","steps":["trace[1588461455] 'read index received' (duration: 224.692794ms)","trace[1588461455] 'applied index is now lower than readState.Index' (duration: 2.068009ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T07:20:36.089329Z","caller":"traceutil/trace.go:171","msg":"trace[1442508275] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"420.267436ms","start":"2026-05-26T07:20:35.669031Z","end":"2026-05-26T07:20:36.089298Z","steps":["trace[1442508275] 'process raft request' (duration: 418.28172ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T07:20:36.089572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.066141ms","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-05-26T07:20:36.089614Z","caller":"traceutil/trace.go:171","msg":"trace[410786942] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:611; }","duration":"227.171134ms","start":"2026-05-26T07:20:35.86243Z","end":"2026-05-26T07:20:36.089601Z","steps":["trace[410786942] 'agreement among raft nodes before linearized reading' (duration: 226.984469ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T07:20:36.089776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.542141ms","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-05-26T07:20:36.089878Z","caller":"traceutil/trace.go:171","msg":"trace[154318733] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:611; }","duration":"223.711515ms","start":"2026-05-26T07:20:35.866143Z","end":"2026-05-26T07:20:36.089854Z","steps":["trace[154318733] 'agreement among raft nodes before linearized reading' (duration: 223.290443ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T07:20:36.09Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T07:20:35.668996Z","time spent":"420.38463ms","remote":"127.0.0.1:49476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036301,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T07:20:50.977451Z","caller":"traceutil/trace.go:171","msg":"trace[593467701] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"169.710814ms","start":"2026-05-26T07:20:50.807705Z","end":"2026-05-26T07:20:50.977416Z","steps":["trace[593467701] 'process raft request' (duration: 169.281852ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T07:21:03.583506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.313704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/cephbuckettopics.ceph.rook.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T07:21:03.583607Z","caller":"traceutil/trace.go:171","msg":"trace[1450486950] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/cephbuckettopics.ceph.rook.io; range_end:; response_count:0; response_revision:978; }","duration":"148.421707ms","start":"2026-05-26T07:21:03.435164Z","end":"2026-05-26T07:21:03.583586Z","steps":["trace[1450486950] 'range keys from in-memory index tree' (duration: 148.257863ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:21:03.5836Z","caller":"traceutil/trace.go:171","msg":"trace[1828222434] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"145.300378ms","start":"2026-05-26T07:21:03.438276Z","end":"2026-05-26T07:21:03.583577Z","steps":["trace[1828222434] 'process raft request' (duration: 51.398947ms)","trace[1828222434] 'compare' (duration: 93.781177ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T07:21:03.583596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.87768ms","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-05-26T07:21:03.583686Z","caller":"traceutil/trace.go:171","msg":"trace[621111935] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:978; }","duration":"141.972323ms","start":"2026-05-26T07:21:03.441701Z","end":"2026-05-26T07:21:03.583674Z","steps":["trace[621111935] 'range keys from in-memory index tree' (duration: 141.86232ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:21:08.916262Z","caller":"traceutil/trace.go:171","msg":"trace[1333529394] transaction","detail":"{read_only:false; response_revision:1399; number_of_response:1; }","duration":"156.320342ms","start":"2026-05-26T07:21:08.759922Z","end":"2026-05-26T07:21:08.916242Z","steps":["trace[1333529394] 'process raft request' (duration: 128.714304ms)","trace[1333529394] 'compare' (duration: 27.457534ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T07:21:08.916432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.895396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/keepalived\" ","response":"range_response_count:1 size:283"} {"level":"info","ts":"2026-05-26T07:21:08.916473Z","caller":"traceutil/trace.go:171","msg":"trace[1651823845] range","detail":"{range_begin:/registry/serviceaccounts/openstack/keepalived; range_end:; response_count:1; response_revision:1400; }","duration":"110.975988ms","start":"2026-05-26T07:21:08.805486Z","end":"2026-05-26T07:21:08.916462Z","steps":["trace[1651823845] 'agreement among raft nodes before linearized reading' (duration: 110.781342ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:21:08.916264Z","caller":"traceutil/trace.go:171","msg":"trace[769928353] linearizableReadLoop","detail":"{readStateIndex:1434; appliedIndex:1433; }","duration":"110.710901ms","start":"2026-05-26T07:21:08.805527Z","end":"2026-05-26T07:21:08.916238Z","steps":["trace[769928353] 'read index received' (duration: 83.105523ms)","trace[769928353] 'applied index is now lower than readState.Index' (duration: 27.603658ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T07:21:09.560605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.153963ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-26T07:21:09.560823Z","caller":"traceutil/trace.go:171","msg":"trace[1018289629] transaction","detail":"{read_only:false; response_revision:1401; number_of_response:1; }","duration":"638.341011ms","start":"2026-05-26T07:21:08.922424Z","end":"2026-05-26T07:21:09.560765Z","steps":["trace[1018289629] 'process raft request' (duration: 237.583741ms)","trace[1018289629] 'compare' (duration: 399.980838ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T07:21:09.56089Z","caller":"traceutil/trace.go:171","msg":"trace[1025668411] linearizableReadLoop","detail":"{readStateIndex:1439; appliedIndex:1435; }","duration":"472.916429ms","start":"2026-05-26T07:21:09.087958Z","end":"2026-05-26T07:21:09.560875Z","steps":["trace[1025668411] 'read index received' (duration: 72.178809ms)","trace[1025668411] 'applied index is now lower than readState.Index' (duration: 400.73646ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T07:21:09.560987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T07:21:08.922404Z","time spent":"638.500135ms","remote":"127.0.0.1:49546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":681,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T07:21:09.56107Z","caller":"traceutil/trace.go:171","msg":"trace[328263607] transaction","detail":"{read_only:false; response_revision:1404; number_of_response:1; }","duration":"637.116916ms","start":"2026-05-26T07:21:08.923904Z","end":"2026-05-26T07:21:09.561021Z","steps":["trace[328263607] 'process raft request' (duration: 636.92206ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:21:09.561191Z","caller":"traceutil/trace.go:171","msg":"trace[1706598905] transaction","detail":"{read_only:false; response_revision:1402; number_of_response:1; }","duration":"638.521996ms","start":"2026-05-26T07:21:08.922657Z","end":"2026-05-26T07:21:09.561179Z","steps":["trace[1706598905] 'process raft request' (duration: 638.045743ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T07:21:09.561244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T07:21:08.923893Z","time spent":"637.265121ms","remote":"127.0.0.1:49626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1067,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T07:21:09.561263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T07:21:08.922646Z","time spent":"638.582598ms","remote":"127.0.0.1:49626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1038,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T07:21:09.561282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.32932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-26T07:21:09.561288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.271678ms","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-05-26T07:21:09.561335Z","caller":"traceutil/trace.go:171","msg":"trace[1298262824] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1404; }","duration":"206.34301ms","start":"2026-05-26T07:21:09.354979Z","end":"2026-05-26T07:21:09.561322Z","steps":["trace[1298262824] 'agreement among raft nodes before linearized reading' (duration: 206.223797ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:21:09.561337Z","caller":"traceutil/trace.go:171","msg":"trace[599157837] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1404; }","duration":"473.411713ms","start":"2026-05-26T07:21:09.087906Z","end":"2026-05-26T07:21:09.561317Z","steps":["trace[599157837] 'agreement among raft nodes before linearized reading' (duration: 473.238188ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:21:09.561367Z","caller":"traceutil/trace.go:171","msg":"trace[1801922521] transaction","detail":"{read_only:false; response_revision:1403; number_of_response:1; }","duration":"638.176936ms","start":"2026-05-26T07:21:08.923182Z","end":"2026-05-26T07:21:09.561358Z","steps":["trace[1801922521] 'process raft request' (duration: 637.60485ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T07:21:09.561376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T07:21:09.087883Z","time spent":"473.482805ms","remote":"127.0.0.1:49624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-26T07:21:09.561447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T07:21:08.923171Z","time spent":"638.251748ms","remote":"127.0.0.1:49546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":529,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T07:21:09.561092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.019057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T07:21:09.561546Z","caller":"traceutil/trace.go:171","msg":"trace[1825432045] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1404; }","duration":"415.523381ms","start":"2026-05-26T07:21:09.146001Z","end":"2026-05-26T07:21:09.561525Z","steps":["trace[1825432045] 'agreement among raft nodes before linearized reading' (duration: 415.009586ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T07:21:09.561603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T07:21:09.145959Z","time spent":"415.629254ms","remote":"127.0.0.1:49392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-26T07:21:09.561651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.106884ms","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-05-26T07:21:09.56175Z","caller":"traceutil/trace.go:171","msg":"trace[2123625087] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1404; }","duration":"295.33938ms","start":"2026-05-26T07:21:09.266396Z","end":"2026-05-26T07:21:09.561735Z","steps":["trace[2123625087] 'agreement among raft nodes before linearized reading' (duration: 295.108244ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:21:27.757858Z","caller":"traceutil/trace.go:171","msg":"trace[1010586745] linearizableReadLoop","detail":"{readStateIndex:1792; appliedIndex:1791; }","duration":"102.775192ms","start":"2026-05-26T07:21:27.655059Z","end":"2026-05-26T07:21:27.757834Z","steps":["trace[1010586745] 'read index received' (duration: 102.580846ms)","trace[1010586745] 'applied index is now lower than readState.Index' (duration: 193.356µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T07:21:27.757942Z","caller":"traceutil/trace.go:171","msg":"trace[1215690414] transaction","detail":"{read_only:false; response_revision:1746; number_of_response:1; }","duration":"103.988926ms","start":"2026-05-26T07:21:27.653912Z","end":"2026-05-26T07:21:27.757901Z","steps":["trace[1215690414] 'process raft request' (duration: 103.78339ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T07:21:27.758052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.972757ms","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-05-26T07:21:27.758088Z","caller":"traceutil/trace.go:171","msg":"trace[618690231] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1746; }","duration":"103.05236ms","start":"2026-05-26T07:21:27.655024Z","end":"2026-05-26T07:21:27.758077Z","steps":["trace[618690231] 'agreement among raft nodes before linearized reading' (duration: 102.904995ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:21:27.878827Z","caller":"traceutil/trace.go:171","msg":"trace[338716304] transaction","detail":"{read_only:false; response_revision:1747; number_of_response:1; }","duration":"114.957424ms","start":"2026-05-26T07:21:27.763842Z","end":"2026-05-26T07:21:27.878799Z","steps":["trace[338716304] 'process raft request' (duration: 48.340246ms)","trace[338716304] 'compare' (duration: 66.468494ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T07:21:47.305815Z","caller":"traceutil/trace.go:171","msg":"trace[2076157966] linearizableReadLoop","detail":"{readStateIndex:2155; appliedIndex:2154; }","duration":"159.785251ms","start":"2026-05-26T07:21:47.146011Z","end":"2026-05-26T07:21:47.305796Z","steps":["trace[2076157966] 'read index received' (duration: 118.900594ms)","trace[2076157966] 'applied index is now lower than readState.Index' (duration: 40.883607ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T07:21:47.305992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.963885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T07:21:47.306034Z","caller":"traceutil/trace.go:171","msg":"trace[621340851] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2102; }","duration":"160.044018ms","start":"2026-05-26T07:21:47.145979Z","end":"2026-05-26T07:21:47.306023Z","steps":["trace[621340851] 'agreement among raft nodes before linearized reading' (duration: 159.896194ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:21:47.306087Z","caller":"traceutil/trace.go:171","msg":"trace[846481742] transaction","detail":"{read_only:false; response_revision:2102; number_of_response:1; }","duration":"171.755086ms","start":"2026-05-26T07:21:47.134297Z","end":"2026-05-26T07:21:47.306052Z","steps":["trace[846481742] 'process raft request' (duration: 130.563201ms)","trace[846481742] 'compare' (duration: 40.67532ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T07:22:00.739118Z","caller":"traceutil/trace.go:171","msg":"trace[1500103717] linearizableReadLoop","detail":"{readStateIndex:2693; appliedIndex:2691; }","duration":"190.222644ms","start":"2026-05-26T07:22:00.548877Z","end":"2026-05-26T07:22:00.739099Z","steps":["trace[1500103717] 'read index received' (duration: 187.697453ms)","trace[1500103717] 'applied index is now lower than readState.Index' (duration: 2.524181ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T07:22:00.739142Z","caller":"traceutil/trace.go:171","msg":"trace[925843895] transaction","detail":"{read_only:false; response_revision:2607; number_of_response:1; }","duration":"213.064765ms","start":"2026-05-26T07:22:00.526037Z","end":"2026-05-26T07:22:00.739102Z","steps":["trace[925843895] 'process raft request' (duration: 210.567834ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:22:00.739384Z","caller":"traceutil/trace.go:171","msg":"trace[1348534103] transaction","detail":"{read_only:false; response_revision:2608; number_of_response:1; }","duration":"207.078667ms","start":"2026-05-26T07:22:00.532286Z","end":"2026-05-26T07:22:00.739365Z","steps":["trace[1348534103] 'process raft request' (duration: 206.731627ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T07:22:00.739869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.188816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/libvirt-api-ca\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-26T07:22:00.739881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.992326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/openstack/valkey\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T07:22:00.739943Z","caller":"traceutil/trace.go:171","msg":"trace[2079462169] range","detail":"{range_begin:/registry/secrets/openstack/libvirt-api-ca; range_end:; response_count:0; response_revision:2608; }","duration":"158.276459ms","start":"2026-05-26T07:22:00.581647Z","end":"2026-05-26T07:22:00.739924Z","steps":["trace[2079462169] 'agreement among raft nodes before linearized reading' (duration: 158.092494ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:22:00.73999Z","caller":"traceutil/trace.go:171","msg":"trace[1708982482] range","detail":"{range_begin:/registry/networkpolicies/openstack/valkey; range_end:; response_count:0; response_revision:2608; }","duration":"191.103149ms","start":"2026-05-26T07:22:00.548832Z","end":"2026-05-26T07:22:00.739935Z","steps":["trace[1708982482] 'agreement among raft nodes before linearized reading' (duration: 190.882803ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T07:22:00.740248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.632616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-05-26T07:22:00.740313Z","caller":"traceutil/trace.go:171","msg":"trace[6908685] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2608; }","duration":"108.713399ms","start":"2026-05-26T07:22:00.631584Z","end":"2026-05-26T07:22:00.740297Z","steps":["trace[6908685] 'agreement among raft nodes before linearized reading' (duration: 108.564515ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T07:22:00.740455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.420211ms","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-05-26T07:22:00.740544Z","caller":"traceutil/trace.go:171","msg":"trace[1653358728] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2608; }","duration":"128.541645ms","start":"2026-05-26T07:22:00.611982Z","end":"2026-05-26T07:22:00.740524Z","steps":["trace[1653358728] 'agreement among raft nodes before linearized reading' (duration: 128.214365ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:22:00.845196Z","caller":"traceutil/trace.go:171","msg":"trace[1703557566] transaction","detail":"{read_only:false; response_revision:2610; number_of_response:1; }","duration":"100.795136ms","start":"2026-05-26T07:22:00.744379Z","end":"2026-05-26T07:22:00.845174Z","steps":["trace[1703557566] 'process raft request' (duration: 94.832209ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:22:08.326103Z","caller":"traceutil/trace.go:171","msg":"trace[1084105562] linearizableReadLoop","detail":"{readStateIndex:2909; appliedIndex:2908; }","duration":"139.997756ms","start":"2026-05-26T07:22:08.18608Z","end":"2026-05-26T07:22:08.326078Z","steps":["trace[1084105562] 'read index received' (duration: 139.825121ms)","trace[1084105562] 'applied index is now lower than readState.Index' (duration: 171.294µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T07:22:08.326241Z","caller":"traceutil/trace.go:171","msg":"trace[1859113117] transaction","detail":"{read_only:false; response_revision:2815; number_of_response:1; }","duration":"142.855336ms","start":"2026-05-26T07:22:08.183352Z","end":"2026-05-26T07:22:08.326208Z","steps":["trace[1859113117] 'process raft request' (duration: 142.581218ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T07:22:08.326433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.326265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:14 size:141492"} {"level":"info","ts":"2026-05-26T07:22:08.326481Z","caller":"traceutil/trace.go:171","msg":"trace[1588537439] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:14; response_revision:2815; }","duration":"140.408598ms","start":"2026-05-26T07:22:08.186062Z","end":"2026-05-26T07:22:08.32647Z","steps":["trace[1588537439] 'agreement among raft nodes before linearized reading' (duration: 140.118259ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:22:12.579413Z","caller":"traceutil/trace.go:171","msg":"trace[1275952037] transaction","detail":"{read_only:false; response_revision:2843; number_of_response:1; }","duration":"235.582377ms","start":"2026-05-26T07:22:12.343812Z","end":"2026-05-26T07:22:12.579395Z","steps":["trace[1275952037] 'process raft request' (duration: 235.470883ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:22:12.579434Z","caller":"traceutil/trace.go:171","msg":"trace[2084726848] linearizableReadLoop","detail":"{readStateIndex:2938; appliedIndex:2938; }","duration":"229.31315ms","start":"2026-05-26T07:22:12.350092Z","end":"2026-05-26T07:22:12.579405Z","steps":["trace[2084726848] 'read index received' (duration: 229.298859ms)","trace[2084726848] 'applied index is now lower than readState.Index' (duration: 11.241µs)"],"step_count":2} {"level":"warn","ts":"2026-05-26T07:22:12.579607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.508586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-jkwmz\" ","response":"range_response_count:1 size:6441"} {"level":"info","ts":"2026-05-26T07:22:12.579633Z","caller":"traceutil/trace.go:171","msg":"trace[1141356833] range","detail":"{range_begin:/registry/pods/openstack/keepalived-jkwmz; range_end:; response_count:1; response_revision:2843; }","duration":"229.572248ms","start":"2026-05-26T07:22:12.350052Z","end":"2026-05-26T07:22:12.579625Z","steps":["trace[1141356833] 'agreement among raft nodes before linearized reading' (duration: 229.473715ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:22:16.057016Z","caller":"traceutil/trace.go:171","msg":"trace[659425201] transaction","detail":"{read_only:false; response_revision:2862; number_of_response:1; }","duration":"101.352033ms","start":"2026-05-26T07:22:15.955632Z","end":"2026-05-26T07:22:16.056984Z","steps":["trace[659425201] 'process raft request' (duration: 29.988851ms)","trace[659425201] 'compare' (duration: 70.835717ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T07:22:47.766385Z","caller":"traceutil/trace.go:171","msg":"trace[774637539] transaction","detail":"{read_only:false; response_revision:3082; number_of_response:1; }","duration":"116.377803ms","start":"2026-05-26T07:22:47.649977Z","end":"2026-05-26T07:22:47.766355Z","steps":["trace[774637539] 'process raft request' (duration: 116.230079ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:22:53.268443Z","caller":"traceutil/trace.go:171","msg":"trace[470834177] transaction","detail":"{read_only:false; response_revision:3115; number_of_response:1; }","duration":"156.384973ms","start":"2026-05-26T07:22:53.112017Z","end":"2026-05-26T07:22:53.268401Z","steps":["trace[470834177] 'process raft request' (duration: 156.141567ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:22:53.378112Z","caller":"traceutil/trace.go:171","msg":"trace[1260127958] transaction","detail":"{read_only:false; response_revision:3116; number_of_response:1; }","duration":"266.007518ms","start":"2026-05-26T07:22:53.112063Z","end":"2026-05-26T07:22:53.378071Z","steps":["trace[1260127958] 'process raft request' (duration: 264.61595ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:22:53.37842Z","caller":"traceutil/trace.go:171","msg":"trace[1340415518] linearizableReadLoop","detail":"{readStateIndex:3221; appliedIndex:3217; }","duration":"232.21867ms","start":"2026-05-26T07:22:53.146185Z","end":"2026-05-26T07:22:53.378404Z","steps":["trace[1340415518] 'read index received' (duration: 122.0495ms)","trace[1340415518] 'applied index is now lower than readState.Index' (duration: 110.16807ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T07:22:53.378557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.363024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T07:22:53.378521Z","caller":"traceutil/trace.go:171","msg":"trace[2043550684] transaction","detail":"{read_only:false; response_revision:3118; number_of_response:1; }","duration":"246.25421ms","start":"2026-05-26T07:22:53.132216Z","end":"2026-05-26T07:22:53.37847Z","steps":["trace[2043550684] 'process raft request' (duration: 246.081565ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:22:53.378602Z","caller":"traceutil/trace.go:171","msg":"trace[140797118] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3118; }","duration":"232.495518ms","start":"2026-05-26T07:22:53.146094Z","end":"2026-05-26T07:22:53.37859Z","steps":["trace[140797118] 'agreement among raft nodes before linearized reading' (duration: 232.410395ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:22:53.378572Z","caller":"traceutil/trace.go:171","msg":"trace[1095297047] transaction","detail":"{read_only:false; response_revision:3117; number_of_response:1; }","duration":"266.353378ms","start":"2026-05-26T07:22:53.11211Z","end":"2026-05-26T07:22:53.378463Z","steps":["trace[1095297047] 'process raft request' (duration: 265.890035ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:24:22.013729Z","caller":"traceutil/trace.go:171","msg":"trace[1522598965] transaction","detail":"{read_only:false; response_revision:3664; number_of_response:1; }","duration":"146.02554ms","start":"2026-05-26T07:24:21.867682Z","end":"2026-05-26T07:24:22.013708Z","steps":["trace[1522598965] 'process raft request' (duration: 145.891936ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:24:22.122359Z","caller":"traceutil/trace.go:171","msg":"trace[581415548] transaction","detail":"{read_only:false; response_revision:3665; number_of_response:1; }","duration":"251.688475ms","start":"2026-05-26T07:24:21.870639Z","end":"2026-05-26T07:24:22.122328Z","steps":["trace[581415548] 'process raft request' (duration: 239.157361ms)","trace[581415548] 'compare' (duration: 12.38837ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T07:26:24.836616Z","caller":"traceutil/trace.go:171","msg":"trace[1465477805] transaction","detail":"{read_only:false; response_revision:4458; number_of_response:1; }","duration":"108.566138ms","start":"2026-05-26T07:26:24.728014Z","end":"2026-05-26T07:26:24.836581Z","steps":["trace[1465477805] 'process raft request' (duration: 50.762153ms)","trace[1465477805] 'compare' (duration: 57.567809ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T07:28:23.853908Z","caller":"traceutil/trace.go:171","msg":"trace[979195124] linearizableReadLoop","detail":"{readStateIndex:6256; appliedIndex:6255; }","duration":"129.759149ms","start":"2026-05-26T07:28:23.724125Z","end":"2026-05-26T07:28:23.853884Z","steps":["trace[979195124] 'read index received' (duration: 129.533323ms)","trace[979195124] 'applied index is now lower than readState.Index' (duration: 224.886µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T07:28:23.85399Z","caller":"traceutil/trace.go:171","msg":"trace[1392812423] transaction","detail":"{read_only:false; response_revision:6055; number_of_response:1; }","duration":"139.370353ms","start":"2026-05-26T07:28:23.714574Z","end":"2026-05-26T07:28:23.853944Z","steps":["trace[1392812423] 'process raft request' (duration: 139.149037ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T07:28:23.854165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.196283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-05-26T07:28:23.854215Z","caller":"traceutil/trace.go:171","msg":"trace[1275845528] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:6055; }","duration":"105.288626ms","start":"2026-05-26T07:28:23.748912Z","end":"2026-05-26T07:28:23.8542Z","steps":["trace[1275845528] 'agreement among raft nodes before linearized reading' (duration: 105.107201ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T07:28:23.854329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.15288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16084"} {"level":"info","ts":"2026-05-26T07:28:23.854467Z","caller":"traceutil/trace.go:171","msg":"trace[1397043263] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:6055; }","duration":"130.343525ms","start":"2026-05-26T07:28:23.724091Z","end":"2026-05-26T07:28:23.854435Z","steps":["trace[1397043263] 'agreement among raft nodes before linearized reading' (duration: 129.913863ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:28:24.21588Z","caller":"traceutil/trace.go:171","msg":"trace[326635743] transaction","detail":"{read_only:false; response_revision:6066; number_of_response:1; }","duration":"184.231877ms","start":"2026-05-26T07:28:24.031608Z","end":"2026-05-26T07:28:24.215839Z","steps":["trace[326635743] 'process raft request' (duration: 171.528808ms)","trace[326635743] 'compare' (duration: 12.477593ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T07:28:24.216105Z","caller":"traceutil/trace.go:171","msg":"trace[1436962107] transaction","detail":"{read_only:false; response_revision:6067; number_of_response:1; }","duration":"184.363901ms","start":"2026-05-26T07:28:24.031715Z","end":"2026-05-26T07:28:24.216079Z","steps":["trace[1436962107] 'process raft request' (duration: 184.026641ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:28:24.216317Z","caller":"traceutil/trace.go:171","msg":"trace[335640162] transaction","detail":"{read_only:false; response_revision:6068; number_of_response:1; }","duration":"184.212687ms","start":"2026-05-26T07:28:24.032078Z","end":"2026-05-26T07:28:24.216291Z","steps":["trace[335640162] 'process raft request' (duration: 183.915758ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:28:24.37241Z","caller":"traceutil/trace.go:171","msg":"trace[1464695713] transaction","detail":"{read_only:false; response_revision:6069; number_of_response:1; }","duration":"102.107788ms","start":"2026-05-26T07:28:24.270234Z","end":"2026-05-26T07:28:24.372342Z","steps":["trace[1464695713] 'process raft request' (duration: 65.371318ms)","trace[1464695713] 'compare' (duration: 36.613397ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T07:29:00.806779Z","caller":"traceutil/trace.go:171","msg":"trace[1740920202] transaction","detail":"{read_only:false; response_revision:6356; number_of_response:1; }","duration":"127.834857ms","start":"2026-05-26T07:29:00.678907Z","end":"2026-05-26T07:29:00.806742Z","steps":["trace[1740920202] 'process raft request' (duration: 127.60425ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:30:02.724328Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3880} {"level":"info","ts":"2026-05-26T07:30:02.825713Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3880,"took":"99.87403ms","hash":3483230303,"current-db-size-bytes":64684032,"current-db-size":"65 MB","current-db-size-in-use-bytes":35635200,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-05-26T07:30:02.825776Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3483230303,"revision":3880,"compact-revision":-1} {"level":"info","ts":"2026-05-26T07:30:37.298793Z","caller":"traceutil/trace.go:171","msg":"trace[2141546002] linearizableReadLoop","detail":"{readStateIndex:7769; appliedIndex:7768; }","duration":"155.399332ms","start":"2026-05-26T07:30:37.143371Z","end":"2026-05-26T07:30:37.29877Z","steps":["trace[2141546002] 'read index received' (duration: 155.198987ms)","trace[2141546002] 'applied index is now lower than readState.Index' (duration: 199.415µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T07:30:37.298841Z","caller":"traceutil/trace.go:171","msg":"trace[1946552713] transaction","detail":"{read_only:false; response_revision:7526; number_of_response:1; }","duration":"162.082076ms","start":"2026-05-26T07:30:37.136745Z","end":"2026-05-26T07:30:37.298827Z","steps":["trace[1946552713] 'process raft request' (duration: 161.87739ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T07:30:37.298922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.542837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T07:30:37.299205Z","caller":"traceutil/trace.go:171","msg":"trace[1515487305] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:7526; }","duration":"155.875306ms","start":"2026-05-26T07:30:37.143319Z","end":"2026-05-26T07:30:37.299194Z","steps":["trace[1515487305] 'agreement among raft nodes before linearized reading' (duration: 155.554607ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:30:39.127435Z","caller":"traceutil/trace.go:171","msg":"trace[1736787212] transaction","detail":"{read_only:false; response_revision:7538; number_of_response:1; }","duration":"117.175324ms","start":"2026-05-26T07:30:39.010227Z","end":"2026-05-26T07:30:39.127403Z","steps":["trace[1736787212] 'process raft request' (duration: 59.389619ms)","trace[1736787212] 'compare' (duration: 57.582449ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T07:30:40.156144Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000001e74.wal"} {"level":"info","ts":"2026-05-26T07:30:57.449312Z","caller":"traceutil/trace.go:171","msg":"trace[1711148171] transaction","detail":"{read_only:false; response_revision:8280; number_of_response:1; }","duration":"128.398812ms","start":"2026-05-26T07:30:57.320894Z","end":"2026-05-26T07:30:57.449293Z","steps":["trace[1711148171] 'process raft request' (duration: 127.572339ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:31:11.695164Z","caller":"traceutil/trace.go:171","msg":"trace[494236120] transaction","detail":"{read_only:false; response_revision:8439; number_of_response:1; }","duration":"147.35289ms","start":"2026-05-26T07:31:11.547783Z","end":"2026-05-26T07:31:11.695136Z","steps":["trace[494236120] 'process raft request' (duration: 147.020791ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:31:11.695154Z","caller":"traceutil/trace.go:171","msg":"trace[725894799] linearizableReadLoop","detail":"{readStateIndex:8697; appliedIndex:8696; }","duration":"118.013985ms","start":"2026-05-26T07:31:11.5771Z","end":"2026-05-26T07:31:11.695114Z","steps":["trace[725894799] 'read index received' (duration: 117.776579ms)","trace[725894799] 'applied index is now lower than readState.Index' (duration: 235.866µs)"],"step_count":2} {"level":"warn","ts":"2026-05-26T07:31:11.695493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.372835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/heat-rabbit-init-9hjdd\" ","response":"range_response_count:1 size:6549"} {"level":"info","ts":"2026-05-26T07:31:11.695591Z","caller":"traceutil/trace.go:171","msg":"trace[1576460397] range","detail":"{range_begin:/registry/pods/openstack/heat-rabbit-init-9hjdd; range_end:; response_count:1; response_revision:8439; }","duration":"118.509349ms","start":"2026-05-26T07:31:11.577064Z","end":"2026-05-26T07:31:11.695573Z","steps":["trace[1576460397] 'agreement among raft nodes before linearized reading' (duration: 118.19555ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T07:31:11.728017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.242953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T07:31:11.728101Z","caller":"traceutil/trace.go:171","msg":"trace[364842809] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:8440; }","duration":"145.371415ms","start":"2026-05-26T07:31:11.582711Z","end":"2026-05-26T07:31:11.728082Z","steps":["trace[364842809] 'agreement among raft nodes before linearized reading' (duration: 145.230111ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T07:31:11.728348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.355365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T07:31:11.728384Z","caller":"traceutil/trace.go:171","msg":"trace[1396638611] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:8440; }","duration":"145.420077ms","start":"2026-05-26T07:31:11.582954Z","end":"2026-05-26T07:31:11.728374Z","steps":["trace[1396638611] 'agreement among raft nodes before linearized reading' (duration: 145.369706ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T07:31:11.728425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.395186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/cinder-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-05-26T07:31:11.728492Z","caller":"traceutil/trace.go:171","msg":"trace[1272590089] range","detail":"{range_begin:/registry/services/endpoints/openstack/cinder-api; range_end:; response_count:1; response_revision:8440; }","duration":"145.487569ms","start":"2026-05-26T07:31:11.58299Z","end":"2026-05-26T07:31:11.728478Z","steps":["trace[1272590089] 'agreement among raft nodes before linearized reading' (duration: 145.283213ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T07:31:11.728619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.198882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-26T07:31:11.728754Z","caller":"traceutil/trace.go:171","msg":"trace[873608445] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:8440; }","duration":"102.420328ms","start":"2026-05-26T07:31:11.62631Z","end":"2026-05-26T07:31:11.728731Z","steps":["trace[873608445] 'agreement among raft nodes before linearized reading' (duration: 101.945445ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T07:31:11.72893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.8872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5004"} {"level":"info","ts":"2026-05-26T07:31:11.728988Z","caller":"traceutil/trace.go:171","msg":"trace[1113920029] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:8440; }","duration":"145.961452ms","start":"2026-05-26T07:31:11.583014Z","end":"2026-05-26T07:31:11.728975Z","steps":["trace[1113920029] 'agreement among raft nodes before linearized reading' (duration: 145.712645ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T07:31:11.728971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.705105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T07:31:11.729093Z","caller":"traceutil/trace.go:171","msg":"trace[1230135279] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:8440; }","duration":"145.823618ms","start":"2026-05-26T07:31:11.583223Z","end":"2026-05-26T07:31:11.729046Z","steps":["trace[1230135279] 'agreement among raft nodes before linearized reading' (duration: 145.659253ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:31:11.729378Z","caller":"traceutil/trace.go:171","msg":"trace[195048779] transaction","detail":"{read_only:false; response_revision:8440; number_of_response:1; }","duration":"129.005657ms","start":"2026-05-26T07:31:11.600361Z","end":"2026-05-26T07:31:11.729366Z","steps":["trace[195048779] 'process raft request' (duration: 127.408114ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:31:12.141784Z","caller":"traceutil/trace.go:171","msg":"trace[1632823927] transaction","detail":"{read_only:false; response_revision:8444; number_of_response:1; }","duration":"121.592144ms","start":"2026-05-26T07:31:12.020169Z","end":"2026-05-26T07:31:12.141761Z","steps":["trace[1632823927] 'process raft request' (duration: 121.46545ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:31:12.15298Z","caller":"traceutil/trace.go:171","msg":"trace[1043394218] transaction","detail":"{read_only:false; response_revision:8445; number_of_response:1; }","duration":"116.945447ms","start":"2026-05-26T07:31:12.036017Z","end":"2026-05-26T07:31:12.152962Z","steps":["trace[1043394218] 'process raft request' (duration: 116.590477ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T07:31:19.329358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.765541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T07:31:19.329483Z","caller":"traceutil/trace.go:171","msg":"trace[1437017740] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8513; }","duration":"182.941746ms","start":"2026-05-26T07:31:19.146512Z","end":"2026-05-26T07:31:19.329454Z","steps":["trace[1437017740] 'range keys from in-memory index tree' (duration: 182.590326ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:31:19.900679Z","caller":"traceutil/trace.go:171","msg":"trace[1666199089] linearizableReadLoop","detail":"{readStateIndex:8774; appliedIndex:8773; }","duration":"124.558735ms","start":"2026-05-26T07:31:19.776099Z","end":"2026-05-26T07:31:19.900658Z","steps":["trace[1666199089] 'read index received' (duration: 124.413221ms)","trace[1666199089] 'applied index is now lower than readState.Index' (duration: 144.484µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T07:31:19.900764Z","caller":"traceutil/trace.go:171","msg":"trace[1139743844] transaction","detail":"{read_only:false; response_revision:8515; number_of_response:1; }","duration":"125.538851ms","start":"2026-05-26T07:31:19.775165Z","end":"2026-05-26T07:31:19.900704Z","steps":["trace[1139743844] 'process raft request' (duration: 125.356726ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T07:31:19.900886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.77568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5004"} {"level":"info","ts":"2026-05-26T07:31:19.90093Z","caller":"traceutil/trace.go:171","msg":"trace[781340379] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:8515; }","duration":"124.852703ms","start":"2026-05-26T07:31:19.776064Z","end":"2026-05-26T07:31:19.900917Z","steps":["trace[781340379] 'agreement among raft nodes before linearized reading' (duration: 124.722089ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:31:23.194786Z","caller":"traceutil/trace.go:171","msg":"trace[1194779183] transaction","detail":"{read_only:false; response_revision:8550; number_of_response:1; }","duration":"135.574027ms","start":"2026-05-26T07:31:23.059156Z","end":"2026-05-26T07:31:23.19473Z","steps":["trace[1194779183] 'process raft request' (duration: 135.069933ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:31:26.409782Z","caller":"traceutil/trace.go:171","msg":"trace[292542390] transaction","detail":"{read_only:false; response_revision:8569; number_of_response:1; }","duration":"103.056266ms","start":"2026-05-26T07:31:26.306699Z","end":"2026-05-26T07:31:26.409756Z","steps":["trace[292542390] 'process raft request' (duration: 24.306846ms)","trace[292542390] 'compare' (duration: 78.617296ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T07:32:59.275872Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b6478f0ac5aeb8e1","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-26T07:32:59.283587Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-26T07:32:59.283677Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-26T07:33:56.86207Z","caller":"traceutil/trace.go:171","msg":"trace[788379895] transaction","detail":"{read_only:false; response_revision:10311; number_of_response:1; }","duration":"356.048165ms","start":"2026-05-26T07:33:56.506002Z","end":"2026-05-26T07:33:56.862051Z","steps":["trace[788379895] 'process raft request' (duration: 355.945622ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T07:33:56.862267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T07:33:56.505978Z","time spent":"356.22288ms","remote":"127.0.0.1:49624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T07:33:56.863441Z","caller":"traceutil/trace.go:171","msg":"trace[1347793830] transaction","detail":"{read_only:false; response_revision:10312; number_of_response:1; }","duration":"273.208288ms","start":"2026-05-26T07:33:56.590156Z","end":"2026-05-26T07:33:56.863364Z","steps":["trace[1347793830] 'process raft request' (duration: 272.612932ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:34:08.921755Z","caller":"traceutil/trace.go:171","msg":"trace[1497735208] transaction","detail":"{read_only:false; response_revision:10420; number_of_response:1; }","duration":"288.122843ms","start":"2026-05-26T07:34:08.633601Z","end":"2026-05-26T07:34:08.921724Z","steps":["trace[1497735208] 'process raft request' (duration: 240.650868ms)","trace[1497735208] 'compare' (duration: 46.744076ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T07:34:09.616632Z","caller":"traceutil/trace.go:171","msg":"trace[1980528340] linearizableReadLoop","detail":"{readStateIndex:10737; appliedIndex:10736; }","duration":"125.701542ms","start":"2026-05-26T07:34:09.490905Z","end":"2026-05-26T07:34:09.616607Z","steps":["trace[1980528340] 'read index received' (duration: 125.545478ms)","trace[1980528340] 'applied index is now lower than readState.Index' (duration: 155.064µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T07:34:09.616669Z","caller":"traceutil/trace.go:171","msg":"trace[1779484945] transaction","detail":"{read_only:false; response_revision:10429; number_of_response:1; }","duration":"241.177861ms","start":"2026-05-26T07:34:09.375462Z","end":"2026-05-26T07:34:09.61664Z","steps":["trace[1779484945] 'process raft request' (duration: 240.996356ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T07:34:09.616822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.896658ms","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-05-26T07:34:09.616929Z","caller":"traceutil/trace.go:171","msg":"trace[1306276845] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:10429; }","duration":"126.052972ms","start":"2026-05-26T07:34:09.490857Z","end":"2026-05-26T07:34:09.61691Z","steps":["trace[1306276845] 'agreement among raft nodes before linearized reading' (duration: 125.845447ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:34:09.734203Z","caller":"traceutil/trace.go:171","msg":"trace[106005905] transaction","detail":"{read_only:false; response_revision:10431; number_of_response:1; }","duration":"112.450861ms","start":"2026-05-26T07:34:09.621729Z","end":"2026-05-26T07:34:09.73418Z","steps":["trace[106005905] 'process raft request' (duration: 112.40205ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:34:09.734228Z","caller":"traceutil/trace.go:171","msg":"trace[344817725] transaction","detail":"{read_only:false; response_revision:10430; number_of_response:1; }","duration":"129.659024ms","start":"2026-05-26T07:34:09.60453Z","end":"2026-05-26T07:34:09.734189Z","steps":["trace[344817725] 'process raft request' (duration: 111.400923ms)","trace[344817725] 'compare' (duration: 18.008545ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T07:34:10.575059Z","caller":"traceutil/trace.go:171","msg":"trace[144157552] transaction","detail":"{read_only:false; response_revision:10434; number_of_response:1; }","duration":"132.759655ms","start":"2026-05-26T07:34:10.442274Z","end":"2026-05-26T07:34:10.575033Z","steps":["trace[144157552] 'process raft request' (duration: 132.56799ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T07:34:10.899933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.349223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/monitoring/\" range_end:\"/registry/resourcequotas/monitoring0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T07:34:10.900025Z","caller":"traceutil/trace.go:171","msg":"trace[1121434078] range","detail":"{range_begin:/registry/resourcequotas/monitoring/; range_end:/registry/resourcequotas/monitoring0; response_count:0; response_revision:10435; }","duration":"232.451217ms","start":"2026-05-26T07:34:10.667556Z","end":"2026-05-26T07:34:10.900007Z","steps":["trace[1121434078] 'range keys from in-memory index tree' (duration: 232.264882ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:35:02.731728Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6753} {"level":"info","ts":"2026-05-26T07:35:02.828126Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6753,"took":"93.893522ms","hash":3230244634,"current-db-size-bytes":64684032,"current-db-size":"65 MB","current-db-size-in-use-bytes":37769216,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-05-26T07:35:02.828203Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3230244634,"revision":6753,"compact-revision":3880} {"level":"info","ts":"2026-05-26T07:35:22.00454Z","caller":"traceutil/trace.go:171","msg":"trace[796198071] transaction","detail":"{read_only:false; response_revision:11166; number_of_response:1; }","duration":"135.197918ms","start":"2026-05-26T07:35:21.86931Z","end":"2026-05-26T07:35:22.004508Z","steps":["trace[796198071] 'process raft request' (duration: 133.124004ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T07:35:22.004976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.462022ms","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-05-26T07:35:22.005078Z","caller":"traceutil/trace.go:171","msg":"trace[228627006] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:11165; }","duration":"402.607556ms","start":"2026-05-26T07:35:21.60245Z","end":"2026-05-26T07:35:22.005058Z","steps":["trace[228627006] 'range keys from in-memory index tree' (duration: 401.768954ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T07:35:22.005284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T07:35:21.602432Z","time spent":"402.839152ms","remote":"127.0.0.1:49624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-26T07:35:44.817128Z","caller":"traceutil/trace.go:171","msg":"trace[2022453641] transaction","detail":"{read_only:false; response_revision:11507; number_of_response:1; }","duration":"152.591492ms","start":"2026-05-26T07:35:44.664519Z","end":"2026-05-26T07:35:44.81711Z","steps":["trace[2022453641] 'process raft request' (duration: 142.633196ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:35:44.862302Z","caller":"traceutil/trace.go:171","msg":"trace[1639396268] transaction","detail":"{read_only:false; response_revision:11508; number_of_response:1; }","duration":"135.431129ms","start":"2026-05-26T07:35:44.72684Z","end":"2026-05-26T07:35:44.862271Z","steps":["trace[1639396268] 'process raft request' (duration: 135.248474ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:36:20.527256Z","caller":"traceutil/trace.go:171","msg":"trace[794550854] transaction","detail":"{read_only:false; response_revision:12083; number_of_response:1; }","duration":"116.302ms","start":"2026-05-26T07:36:20.410922Z","end":"2026-05-26T07:36:20.527224Z","steps":["trace[794550854] 'process raft request' (duration: 116.066563ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T07:36:20.540442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.451542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T07:36:20.540523Z","caller":"traceutil/trace.go:171","msg":"trace[949115581] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:12083; }","duration":"110.582956ms","start":"2026-05-26T07:36:20.42992Z","end":"2026-05-26T07:36:20.540503Z","steps":["trace[949115581] 'agreement among raft nodes before linearized reading' (duration: 97.310615ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T07:36:20.540528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.526544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T07:36:20.540586Z","caller":"traceutil/trace.go:171","msg":"trace[583945675] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:12083; }","duration":"110.630147ms","start":"2026-05-26T07:36:20.429942Z","end":"2026-05-26T07:36:20.540572Z","steps":["trace[583945675] 'agreement among raft nodes before linearized reading' (duration: 97.311145ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T07:36:20.540637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.352689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T07:36:20.540737Z","caller":"traceutil/trace.go:171","msg":"trace[444724347] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:12083; }","duration":"110.485253ms","start":"2026-05-26T07:36:20.430229Z","end":"2026-05-26T07:36:20.540714Z","steps":["trace[444724347] 'agreement among raft nodes before linearized reading' (duration: 97.049047ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T07:36:20.540753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.063545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"info","ts":"2026-05-26T07:36:20.540789Z","caller":"traceutil/trace.go:171","msg":"trace[652301391] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:12083; }","duration":"107.116146ms","start":"2026-05-26T07:36:20.433664Z","end":"2026-05-26T07:36:20.54078Z","steps":["trace[652301391] 'agreement among raft nodes before linearized reading' (duration: 93.607788ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:36:38.730986Z","caller":"traceutil/trace.go:171","msg":"trace[939245556] transaction","detail":"{read_only:false; response_revision:12180; number_of_response:1; }","duration":"109.067834ms","start":"2026-05-26T07:36:38.621889Z","end":"2026-05-26T07:36:38.730957Z","steps":["trace[939245556] 'process raft request' (duration: 108.874139ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:40:02.737862Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10958} {"level":"info","ts":"2026-05-26T07:40:02.854104Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10958,"took":"112.904844ms","hash":1656880011,"current-db-size-bytes":64684032,"current-db-size":"65 MB","current-db-size-in-use-bytes":34201600,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-26T07:40:02.854178Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1656880011,"revision":10958,"compact-revision":6753} {"level":"info","ts":"2026-05-26T07:42:08.332937Z","caller":"traceutil/trace.go:171","msg":"trace[920474958] linearizableReadLoop","detail":"{readStateIndex:15021; appliedIndex:15020; }","duration":"463.672459ms","start":"2026-05-26T07:42:07.869243Z","end":"2026-05-26T07:42:08.332916Z","steps":["trace[920474958] 'read index received' (duration: 463.217676ms)","trace[920474958] 'applied index is now lower than readState.Index' (duration: 453.823µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T07:42:08.33302Z","caller":"traceutil/trace.go:171","msg":"trace[150389101] transaction","detail":"{read_only:false; response_revision:14585; number_of_response:1; }","duration":"486.84858ms","start":"2026-05-26T07:42:07.84615Z","end":"2026-05-26T07:42:08.332998Z","steps":["trace[150389101] 'process raft request' (duration: 486.424918ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T07:42:08.333168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T07:42:07.846125Z","time spent":"486.956713ms","remote":"127.0.0.1:49624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T07:42:08.333247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.978687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-6tv5m\" ","response":"range_response_count:1 size:20664"} {"level":"warn","ts":"2026-05-26T07:42:08.333305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.912386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-ovn-vpn-agent-default-vnnmr\" ","response":"range_response_count:1 size:15347"} {"level":"info","ts":"2026-05-26T07:42:08.333339Z","caller":"traceutil/trace.go:171","msg":"trace[1967377861] range","detail":"{range_begin:/registry/pods/openstack/neutron-ovn-vpn-agent-default-vnnmr; range_end:; response_count:1; response_revision:14585; }","duration":"407.991388ms","start":"2026-05-26T07:42:07.925335Z","end":"2026-05-26T07:42:08.333326Z","steps":["trace[1967377861] 'agreement among raft nodes before linearized reading' (duration: 407.69983ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:42:08.333348Z","caller":"traceutil/trace.go:171","msg":"trace[10235639] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-6tv5m; range_end:; response_count:1; response_revision:14585; }","duration":"464.119481ms","start":"2026-05-26T07:42:07.869206Z","end":"2026-05-26T07:42:08.333325Z","steps":["trace[10235639] 'agreement among raft nodes before linearized reading' (duration: 463.840783ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T07:42:08.333345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.850952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-m5djf\" ","response":"range_response_count:1 size:22010"} {"level":"warn","ts":"2026-05-26T07:42:08.333277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.777836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-26T07:42:08.333405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T07:42:07.869189Z","time spent":"464.203194ms","remote":"127.0.0.1:49552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":20687,"request content":"key:\"/registry/pods/openstack/libvirt-libvirt-default-6tv5m\" "} {"level":"info","ts":"2026-05-26T07:42:08.333413Z","caller":"traceutil/trace.go:171","msg":"trace[63211227] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-m5djf; range_end:; response_count:1; response_revision:14585; }","duration":"322.958646ms","start":"2026-05-26T07:42:08.010435Z","end":"2026-05-26T07:42:08.333394Z","steps":["trace[63211227] 'agreement among raft nodes before linearized reading' (duration: 322.783331ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T07:42:08.333276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.41761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-ovn-metadata-agent-default-xl8nt\" ","response":"range_response_count:1 size:15339"} {"level":"info","ts":"2026-05-26T07:42:08.333463Z","caller":"traceutil/trace.go:171","msg":"trace[1181529241] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14585; }","duration":"238.998713ms","start":"2026-05-26T07:42:08.094434Z","end":"2026-05-26T07:42:08.333432Z","steps":["trace[1181529241] 'agreement among raft nodes before linearized reading' (duration: 238.678314ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T07:42:08.333459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T07:42:08.010417Z","time spent":"323.030127ms","remote":"127.0.0.1:49552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22033,"request content":"key:\"/registry/pods/openstack/nova-compute-default-m5djf\" "} {"level":"warn","ts":"2026-05-26T07:42:08.333444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T07:42:07.925313Z","time spent":"408.122212ms","remote":"127.0.0.1:49552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":15370,"request content":"key:\"/registry/pods/openstack/neutron-ovn-vpn-agent-default-vnnmr\" "} {"level":"info","ts":"2026-05-26T07:42:08.333516Z","caller":"traceutil/trace.go:171","msg":"trace[1499416762] range","detail":"{range_begin:/registry/pods/openstack/neutron-ovn-metadata-agent-default-xl8nt; range_end:; response_count:1; response_revision:14585; }","duration":"335.691928ms","start":"2026-05-26T07:42:07.997797Z","end":"2026-05-26T07:42:08.333489Z","steps":["trace[1499416762] 'agreement among raft nodes before linearized reading' (duration: 335.352968ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T07:42:08.333573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T07:42:07.997776Z","time spent":"335.782251ms","remote":"127.0.0.1:49552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":15362,"request content":"key:\"/registry/pods/openstack/neutron-ovn-metadata-agent-default-xl8nt\" "} {"level":"warn","ts":"2026-05-26T07:42:08.333708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.11646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-m5djf\" ","response":"range_response_count:1 size:22010"} {"level":"info","ts":"2026-05-26T07:42:08.333846Z","caller":"traceutil/trace.go:171","msg":"trace[1114148586] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-m5djf; range_end:; response_count:1; response_revision:14585; }","duration":"400.270265ms","start":"2026-05-26T07:42:07.933554Z","end":"2026-05-26T07:42:08.333825Z","steps":["trace[1114148586] 'agreement among raft nodes before linearized reading' (duration: 399.705669ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T07:42:08.333941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T07:42:07.933544Z","time spent":"400.376128ms","remote":"127.0.0.1:49552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22033,"request content":"key:\"/registry/pods/openstack/nova-compute-default-m5djf\" "} {"level":"info","ts":"2026-05-26T07:43:01.063743Z","caller":"traceutil/trace.go:171","msg":"trace[1242232163] transaction","detail":"{read_only:false; response_revision:15192; number_of_response:1; }","duration":"464.411959ms","start":"2026-05-26T07:43:00.599289Z","end":"2026-05-26T07:43:01.063701Z","steps":["trace[1242232163] 'process raft request' (duration: 464.194543ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T07:43:01.064685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T07:43:00.599273Z","time spent":"465.306984ms","remote":"127.0.0.1:49552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20884,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T07:43:01.0641Z","caller":"traceutil/trace.go:171","msg":"trace[701241275] linearizableReadLoop","detail":"{readStateIndex:15642; appliedIndex:15642; }","duration":"363.201579ms","start":"2026-05-26T07:43:00.700863Z","end":"2026-05-26T07:43:01.064064Z","steps":["trace[701241275] 'read index received' (duration: 363.188128ms)","trace[701241275] 'applied index is now lower than readState.Index' (duration: 11.311µs)"],"step_count":2} {"level":"warn","ts":"2026-05-26T07:43:01.064365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.492377ms","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-05-26T07:43:01.065338Z","caller":"traceutil/trace.go:171","msg":"trace[1180912503] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15192; }","duration":"364.522505ms","start":"2026-05-26T07:43:00.700792Z","end":"2026-05-26T07:43:01.065314Z","steps":["trace[1180912503] 'agreement among raft nodes before linearized reading' (duration: 363.372363ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T07:43:01.065457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T07:43:00.70077Z","time spent":"364.664578ms","remote":"127.0.0.1:49624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-26T07:43:01.142799Z","caller":"traceutil/trace.go:171","msg":"trace[373643634] transaction","detail":"{read_only:false; response_revision:15193; number_of_response:1; }","duration":"231.783122ms","start":"2026-05-26T07:43:00.910956Z","end":"2026-05-26T07:43:01.142739Z","steps":["trace[373643634] 'process raft request' (duration: 230.372093ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:43:01.164129Z","caller":"traceutil/trace.go:171","msg":"trace[2122407351] transaction","detail":"{read_only:false; response_revision:15194; number_of_response:1; }","duration":"231.114614ms","start":"2026-05-26T07:43:00.932976Z","end":"2026-05-26T07:43:01.16409Z","steps":["trace[2122407351] 'process raft request' (duration: 209.742393ms)","trace[2122407351] 'compare' (duration: 19.942582ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T07:43:01.164773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.773522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-26T07:43:01.164879Z","caller":"traceutil/trace.go:171","msg":"trace[111377214] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:15196; }","duration":"300.949366ms","start":"2026-05-26T07:43:00.863903Z","end":"2026-05-26T07:43:01.164852Z","steps":["trace[111377214] 'agreement among raft nodes before linearized reading' (duration: 300.72512ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:43:01.164597Z","caller":"traceutil/trace.go:171","msg":"trace[905753508] transaction","detail":"{read_only:false; response_revision:15195; number_of_response:1; }","duration":"229.834639ms","start":"2026-05-26T07:43:00.934716Z","end":"2026-05-26T07:43:01.16455Z","steps":["trace[905753508] 'process raft request' (duration: 228.957005ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T07:43:01.164713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.370429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-05-26T07:43:01.164921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T07:43:00.863881Z","time spent":"301.031619ms","remote":"127.0.0.1:48724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":4,"response size":30,"request content":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true "} {"level":"info","ts":"2026-05-26T07:43:01.16574Z","caller":"traceutil/trace.go:171","msg":"trace[1605725159] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15196; }","duration":"460.332916ms","start":"2026-05-26T07:43:00.705224Z","end":"2026-05-26T07:43:01.165557Z","steps":["trace[1605725159] 'agreement among raft nodes before linearized reading' (duration: 459.316378ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T07:43:01.165047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.49622ms","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-05-26T07:43:01.165925Z","caller":"traceutil/trace.go:171","msg":"trace[1278670849] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15196; }","duration":"457.491168ms","start":"2026-05-26T07:43:00.708417Z","end":"2026-05-26T07:43:01.165908Z","steps":["trace[1278670849] 'agreement among raft nodes before linearized reading' (duration: 456.258173ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T07:43:01.166022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T07:43:00.708379Z","time spent":"457.613451ms","remote":"127.0.0.1:49624","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-05-26T07:43:01.16611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T07:43:00.705206Z","time spent":"460.643585ms","remote":"127.0.0.1:49624","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":"info","ts":"2026-05-26T07:43:24.028157Z","caller":"traceutil/trace.go:171","msg":"trace[431736455] transaction","detail":"{read_only:false; response_revision:15409; number_of_response:1; }","duration":"323.801819ms","start":"2026-05-26T07:43:23.704325Z","end":"2026-05-26T07:43:24.028126Z","steps":["trace[431736455] 'process raft request' (duration: 323.642684ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T07:43:24.028335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T07:43:23.70431Z","time spent":"323.939852ms","remote":"127.0.0.1:49624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T07:43:24.405281Z","caller":"traceutil/trace.go:171","msg":"trace[1099856503] transaction","detail":"{read_only:false; response_revision:15410; number_of_response:1; }","duration":"673.210905ms","start":"2026-05-26T07:43:23.732046Z","end":"2026-05-26T07:43:24.405257Z","steps":["trace[1099856503] 'process raft request' (duration: 666.081408ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T07:43:24.405425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T07:43:23.732007Z","time spent":"673.343189ms","remote":"127.0.0.1:49624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T07:43:24.405506Z","caller":"traceutil/trace.go:171","msg":"trace[980792261] linearizableReadLoop","detail":"{readStateIndex:15871; appliedIndex:15869; }","duration":"606.929521ms","start":"2026-05-26T07:43:23.798546Z","end":"2026-05-26T07:43:24.405476Z","steps":["trace[980792261] 'read index received' (duration: 229.416707ms)","trace[980792261] 'applied index is now lower than readState.Index' (duration: 377.511634ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T07:43:24.405758Z","caller":"traceutil/trace.go:171","msg":"trace[976386248] transaction","detail":"{read_only:false; response_revision:15411; number_of_response:1; }","duration":"493.723529ms","start":"2026-05-26T07:43:23.912004Z","end":"2026-05-26T07:43:24.405728Z","steps":["trace[976386248] 'process raft request' (duration: 493.192424ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T07:43:24.405858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T07:43:23.911986Z","time spent":"493.802712ms","remote":"127.0.0.1:49624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T07:43:24.406025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.327178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T07:43:24.406086Z","caller":"traceutil/trace.go:171","msg":"trace[1229596624] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:15411; }","duration":"283.41555ms","start":"2026-05-26T07:43:24.122654Z","end":"2026-05-26T07:43:24.406069Z","steps":["trace[1229596624] 'agreement among raft nodes before linearized reading' (duration: 283.311308ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T07:43:24.406121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.552528ms","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":"warn","ts":"2026-05-26T07:43:24.406093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.141202ms","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-05-26T07:43:24.406205Z","caller":"traceutil/trace.go:171","msg":"trace[1683153234] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15411; }","duration":"607.690972ms","start":"2026-05-26T07:43:23.798494Z","end":"2026-05-26T07:43:24.406185Z","steps":["trace[1683153234] 'agreement among raft nodes before linearized reading' (duration: 607.457285ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T07:43:24.406243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T07:43:23.798472Z","time spent":"607.765544ms","remote":"127.0.0.1:49624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-26T07:43:24.406231Z","caller":"traceutil/trace.go:171","msg":"trace[679573195] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:15411; }","duration":"151.332637ms","start":"2026-05-26T07:43:24.254867Z","end":"2026-05-26T07:43:24.4062Z","steps":["trace[679573195] 'agreement among raft nodes before linearized reading' (duration: 151.101361ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T07:43:24.887143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.484306ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-26T07:43:24.88725Z","caller":"traceutil/trace.go:171","msg":"trace[860371702] linearizableReadLoop","detail":"{readStateIndex:15873; appliedIndex:15872; }","duration":"464.354027ms","start":"2026-05-26T07:43:24.42288Z","end":"2026-05-26T07:43:24.887234Z","steps":["trace[860371702] 'read index received' (duration: 134.738178ms)","trace[860371702] 'applied index is now lower than readState.Index' (duration: 329.614939ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T07:43:24.887275Z","caller":"traceutil/trace.go:171","msg":"trace[1437381045] transaction","detail":"{read_only:false; response_revision:15412; number_of_response:1; }","duration":"477.707627ms","start":"2026-05-26T07:43:24.409539Z","end":"2026-05-26T07:43:24.887247Z","steps":["trace[1437381045] 'process raft request' (duration: 148.036556ms)","trace[1437381045] 'compare' (duration: 329.379663ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T07:43:24.887384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T07:43:24.409523Z","time spent":"477.81313ms","remote":"127.0.0.1:49624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T07:43:24.887445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.10311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-26T07:43:24.887507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.627695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7588"} {"level":"info","ts":"2026-05-26T07:43:24.887518Z","caller":"traceutil/trace.go:171","msg":"trace[1604235636] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:15412; }","duration":"164.225703ms","start":"2026-05-26T07:43:24.723275Z","end":"2026-05-26T07:43:24.887501Z","steps":["trace[1604235636] 'agreement among raft nodes before linearized reading' (duration: 164.061759ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:43:24.887579Z","caller":"traceutil/trace.go:171","msg":"trace[1520376431] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:15412; }","duration":"464.733708ms","start":"2026-05-26T07:43:24.422832Z","end":"2026-05-26T07:43:24.887566Z","steps":["trace[1520376431] 'agreement among raft nodes before linearized reading' (duration: 464.477821ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T07:43:24.887623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T07:43:24.422815Z","time spent":"464.79785ms","remote":"127.0.0.1:48690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7611,"request content":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" "} {"level":"warn","ts":"2026-05-26T07:43:25.180165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.497021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-05-26T07:43:25.180264Z","caller":"traceutil/trace.go:171","msg":"trace[110210026] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:15412; }","duration":"177.647745ms","start":"2026-05-26T07:43:25.002595Z","end":"2026-05-26T07:43:25.180243Z","steps":["trace[110210026] 'range keys from in-memory index tree' (duration: 177.315456ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:44:03.980319Z","caller":"traceutil/trace.go:171","msg":"trace[195911778] transaction","detail":"{read_only:false; response_revision:15787; number_of_response:1; }","duration":"214.31625ms","start":"2026-05-26T07:44:03.765968Z","end":"2026-05-26T07:44:03.980284Z","steps":["trace[195911778] 'process raft request' (duration: 162.654051ms)","trace[195911778] 'compare' (duration: 51.528005ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T07:44:11.664378Z","caller":"traceutil/trace.go:171","msg":"trace[1555274292] transaction","detail":"{read_only:false; response_revision:15845; number_of_response:1; }","duration":"115.463287ms","start":"2026-05-26T07:44:11.548899Z","end":"2026-05-26T07:44:11.664362Z","steps":["trace[1555274292] 'process raft request' (duration: 115.326673ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:44:11.685957Z","caller":"traceutil/trace.go:171","msg":"trace[817966857] transaction","detail":"{read_only:false; response_revision:15846; number_of_response:1; }","duration":"122.056498ms","start":"2026-05-26T07:44:11.563874Z","end":"2026-05-26T07:44:11.68593Z","steps":["trace[817966857] 'process raft request' (duration: 121.743109ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:44:11.686081Z","caller":"traceutil/trace.go:171","msg":"trace[1366688669] transaction","detail":"{read_only:false; response_revision:15847; number_of_response:1; }","duration":"120.386593ms","start":"2026-05-26T07:44:11.565675Z","end":"2026-05-26T07:44:11.686062Z","steps":["trace[1366688669] 'process raft request' (duration: 120.128046ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:44:11.818831Z","caller":"traceutil/trace.go:171","msg":"trace[1605778326] transaction","detail":"{read_only:false; response_revision:15848; number_of_response:1; }","duration":"126.054139ms","start":"2026-05-26T07:44:11.692567Z","end":"2026-05-26T07:44:11.818621Z","steps":["trace[1605778326] 'process raft request' (duration: 77.187771ms)","trace[1605778326] 'compare' (duration: 48.711824ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T07:44:11.849153Z","caller":"traceutil/trace.go:171","msg":"trace[800655461] transaction","detail":"{read_only:false; response_revision:15850; number_of_response:1; }","duration":"154.473853ms","start":"2026-05-26T07:44:11.694657Z","end":"2026-05-26T07:44:11.84913Z","steps":["trace[800655461] 'process raft request' (duration: 154.402671ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:44:11.849238Z","caller":"traceutil/trace.go:171","msg":"trace[2067025579] transaction","detail":"{read_only:false; response_revision:15849; number_of_response:1; }","duration":"155.403309ms","start":"2026-05-26T07:44:11.693793Z","end":"2026-05-26T07:44:11.849196Z","steps":["trace[2067025579] 'process raft request' (duration: 155.147092ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:44:12.19139Z","caller":"traceutil/trace.go:171","msg":"trace[1315992923] linearizableReadLoop","detail":"{readStateIndex:16325; appliedIndex:16324; }","duration":"181.457688ms","start":"2026-05-26T07:44:12.009905Z","end":"2026-05-26T07:44:12.191363Z","steps":["trace[1315992923] 'read index received' (duration: 141.188227ms)","trace[1315992923] 'applied index is now lower than readState.Index' (duration: 40.268191ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T07:44:12.191595Z","caller":"traceutil/trace.go:171","msg":"trace[2073534226] transaction","detail":"{read_only:false; response_revision:15851; number_of_response:1; }","duration":"417.04325ms","start":"2026-05-26T07:44:11.774538Z","end":"2026-05-26T07:44:12.191581Z","steps":["trace[2073534226] 'process raft request' (duration: 376.635205ms)","trace[2073534226] 'compare' (duration: 39.897021ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T07:44:12.19172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T07:44:11.774514Z","time spent":"417.134592ms","remote":"127.0.0.1:49610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4989,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T07:44:12.191956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.035114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T07:44:12.192019Z","caller":"traceutil/trace.go:171","msg":"trace[1539694862] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:15851; }","duration":"182.150617ms","start":"2026-05-26T07:44:12.009853Z","end":"2026-05-26T07:44:12.192003Z","steps":["trace[1539694862] 'agreement among raft nodes before linearized reading' (duration: 182.019073ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:44:13.244521Z","caller":"traceutil/trace.go:171","msg":"trace[858473683] transaction","detail":"{read_only:false; response_revision:15860; number_of_response:1; }","duration":"120.780073ms","start":"2026-05-26T07:44:13.123707Z","end":"2026-05-26T07:44:13.244487Z","steps":["trace[858473683] 'process raft request' (duration: 120.468734ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T07:44:13.556078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.898664ms","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-05-26T07:44:13.556192Z","caller":"traceutil/trace.go:171","msg":"trace[1469122869] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:15860; }","duration":"114.021647ms","start":"2026-05-26T07:44:13.442153Z","end":"2026-05-26T07:44:13.556174Z","steps":["trace[1469122869] 'range keys from in-memory index tree' (duration: 113.886293ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:44:13.798245Z","caller":"traceutil/trace.go:171","msg":"trace[1024272925] transaction","detail":"{read_only:false; response_revision:15863; number_of_response:1; }","duration":"106.076207ms","start":"2026-05-26T07:44:13.692146Z","end":"2026-05-26T07:44:13.798223Z","steps":["trace[1024272925] 'process raft request' (duration: 106.028906ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:44:13.798453Z","caller":"traceutil/trace.go:171","msg":"trace[117276397] transaction","detail":"{read_only:false; response_revision:15862; number_of_response:1; }","duration":"107.529648ms","start":"2026-05-26T07:44:13.690901Z","end":"2026-05-26T07:44:13.798431Z","steps":["trace[117276397] 'process raft request' (duration: 104.455663ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:45:02.776688Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13767} {"level":"info","ts":"2026-05-26T07:45:02.954617Z","caller":"traceutil/trace.go:171","msg":"trace[312654473] linearizableReadLoop","detail":"{readStateIndex:16939; appliedIndex:16938; }","duration":"139.657694ms","start":"2026-05-26T07:45:02.814926Z","end":"2026-05-26T07:45:02.954584Z","steps":["trace[312654473] 'read index received' (duration: 137.144815ms)","trace[312654473] 'applied index is now lower than readState.Index' (duration: 2.511699ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T07:45:02.954709Z","caller":"traceutil/trace.go:171","msg":"trace[559349510] transaction","detail":"{read_only:false; response_revision:16443; number_of_response:1; }","duration":"152.217471ms","start":"2026-05-26T07:45:02.802485Z","end":"2026-05-26T07:45:02.954703Z","steps":["trace[559349510] 'process raft request' (duration: 149.569937ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T07:45:02.954883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.939512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-26T07:45:02.954919Z","caller":"traceutil/trace.go:171","msg":"trace[332486621] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:16443; }","duration":"139.986923ms","start":"2026-05-26T07:45:02.814919Z","end":"2026-05-26T07:45:02.954906Z","steps":["trace[332486621] 'agreement among raft nodes before linearized reading' (duration: 139.913401ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T07:45:02.956439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.361552ms","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-05-26T07:45:02.956514Z","caller":"traceutil/trace.go:171","msg":"trace[1337406788] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:16444; }","duration":"141.461964ms","start":"2026-05-26T07:45:02.815035Z","end":"2026-05-26T07:45:02.956497Z","steps":["trace[1337406788] 'agreement among raft nodes before linearized reading' (duration: 141.32302ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:45:02.956871Z","caller":"traceutil/trace.go:171","msg":"trace[533650394] transaction","detail":"{read_only:false; response_revision:16444; number_of_response:1; }","duration":"135.988413ms","start":"2026-05-26T07:45:02.820873Z","end":"2026-05-26T07:45:02.956861Z","steps":["trace[533650394] 'process raft request' (duration: 135.285314ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T07:45:02.957636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.58592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-26T07:45:02.957665Z","caller":"traceutil/trace.go:171","msg":"trace[229392908] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:16444; }","duration":"123.642482ms","start":"2026-05-26T07:45:02.834016Z","end":"2026-05-26T07:45:02.957659Z","steps":["trace[229392908] 'agreement among raft nodes before linearized reading' (duration: 123.589201ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:45:03.089604Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13767,"took":"306.945731ms","hash":3180675538,"current-db-size-bytes":64684032,"current-db-size":"65 MB","current-db-size-in-use-bytes":35352576,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-05-26T07:45:03.089692Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3180675538,"revision":13767,"compact-revision":10958} {"level":"info","ts":"2026-05-26T07:45:43.905119Z","caller":"traceutil/trace.go:171","msg":"trace[1196815984] transaction","detail":"{read_only:false; response_revision:16808; number_of_response:1; }","duration":"169.183657ms","start":"2026-05-26T07:45:43.73591Z","end":"2026-05-26T07:45:43.905093Z","steps":["trace[1196815984] 'process raft request' (duration: 169.039634ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:45:52.570698Z","caller":"traceutil/trace.go:171","msg":"trace[794181392] linearizableReadLoop","detail":"{readStateIndex:17390; appliedIndex:17389; }","duration":"109.365029ms","start":"2026-05-26T07:45:52.461308Z","end":"2026-05-26T07:45:52.570673Z","steps":["trace[794181392] 'read index received' (duration: 44.384926ms)","trace[794181392] 'applied index is now lower than readState.Index' (duration: 64.979213ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T07:45:52.570854Z","caller":"traceutil/trace.go:171","msg":"trace[1946296629] transaction","detail":"{read_only:false; response_revision:16884; number_of_response:1; }","duration":"114.983083ms","start":"2026-05-26T07:45:52.455839Z","end":"2026-05-26T07:45:52.570822Z","steps":["trace[1946296629] 'process raft request' (duration: 49.891737ms)","trace[1946296629] 'compare' (duration: 64.831959ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T07:45:52.570889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.558264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-26T07:45:52.570924Z","caller":"traceutil/trace.go:171","msg":"trace[1121003296] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:16884; }","duration":"109.622626ms","start":"2026-05-26T07:45:52.461293Z","end":"2026-05-26T07:45:52.570916Z","steps":["trace[1121003296] 'agreement among raft nodes before linearized reading' (duration: 109.476912ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T07:48:31.820638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.548222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-26T07:48:31.820748Z","caller":"traceutil/trace.go:171","msg":"trace[1065652429] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:17933; }","duration":"216.694487ms","start":"2026-05-26T07:48:31.604036Z","end":"2026-05-26T07:48:31.82073Z","steps":["trace[1065652429] 'range keys from in-memory index tree' (duration: 216.44455ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T07:48:31.820853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.474356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/staffeln-api-59fbbfd4f9-dmtpt\" ","response":"range_response_count:1 size:7543"} {"level":"warn","ts":"2026-05-26T07:48:31.820893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.98173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/openstack/horizon-horizon\" ","response":"range_response_count:1 size:713"} {"level":"info","ts":"2026-05-26T07:48:31.820934Z","caller":"traceutil/trace.go:171","msg":"trace[219638769] range","detail":"{range_begin:/registry/pods/openstack/staffeln-api-59fbbfd4f9-dmtpt; range_end:; response_count:1; response_revision:17933; }","duration":"226.58053ms","start":"2026-05-26T07:48:31.594332Z","end":"2026-05-26T07:48:31.820913Z","steps":["trace[219638769] 'range keys from in-memory index tree' (duration: 226.267881ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:48:31.820995Z","caller":"traceutil/trace.go:171","msg":"trace[343732139] range","detail":"{range_begin:/registry/rolebindings/openstack/horizon-horizon; range_end:; response_count:1; response_revision:17933; }","duration":"223.126174ms","start":"2026-05-26T07:48:31.597845Z","end":"2026-05-26T07:48:31.820971Z","steps":["trace[343732139] 'range keys from in-memory index tree' (duration: 222.754314ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T07:49:19.414908Z","caller":"traceutil/trace.go:171","msg":"trace[1332757522] linearizableReadLoop","detail":"{readStateIndex:18732; appliedIndex:18731; }","duration":"312.244843ms","start":"2026-05-26T07:49:19.102638Z","end":"2026-05-26T07:49:19.414883Z","steps":["trace[1332757522] 'read index received' (duration: 312.081828ms)","trace[1332757522] 'applied index is now lower than readState.Index' (duration: 162.145µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T07:49:19.415015Z","caller":"traceutil/trace.go:171","msg":"trace[1954212289] transaction","detail":"{read_only:false; response_revision:18178; number_of_response:1; }","duration":"349.616343ms","start":"2026-05-26T07:49:19.065326Z","end":"2026-05-26T07:49:19.414943Z","steps":["trace[1954212289] 'process raft request' (duration: 349.437158ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T07:49:19.415094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.446118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/nova-db-api-user\" ","response":"range_response_count:1 size:714"} {"level":"info","ts":"2026-05-26T07:49:19.415126Z","caller":"traceutil/trace.go:171","msg":"trace[1849333157] range","detail":"{range_begin:/registry/secrets/openstack/nova-db-api-user; range_end:; response_count:1; response_revision:18178; }","duration":"312.5351ms","start":"2026-05-26T07:49:19.10258Z","end":"2026-05-26T07:49:19.415116Z","steps":["trace[1849333157] 'agreement among raft nodes before linearized reading' (duration: 312.409947ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T07:49:19.415159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T07:49:19.10255Z","time spent":"312.602052ms","remote":"127.0.0.1:49476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":737,"request content":"key:\"/registry/secrets/openstack/nova-db-api-user\" "} {"level":"warn","ts":"2026-05-26T07:49:19.415282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T07:49:19.065304Z","time spent":"349.810508ms","remote":"127.0.0.1:49624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T07:49:19.415318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.313503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-26T07:49:19.415385Z","caller":"traceutil/trace.go:171","msg":"trace[1353987369] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18178; }","duration":"271.427646ms","start":"2026-05-26T07:49:19.14394Z","end":"2026-05-26T07:49:19.415367Z","steps":["trace[1353987369] 'agreement among raft nodes before linearized reading' (duration: 271.274312ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T07:49:19.415334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.88032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/nova-db-cell0-admin\" ","response":"range_response_count:1 size:722"} {"level":"info","ts":"2026-05-26T07:49:19.415534Z","caller":"traceutil/trace.go:171","msg":"trace[457911943] range","detail":"{range_begin:/registry/secrets/openstack/nova-db-cell0-admin; range_end:; response_count:1; response_revision:18178; }","duration":"288.116196ms","start":"2026-05-26T07:49:19.1274Z","end":"2026-05-26T07:49:19.415517Z","steps":["trace[457911943] 'agreement among raft nodes before linearized reading' (duration: 287.651953ms)"],"step_count":1}