{"level":"warn","ts":"2026-08-31T07:28:01.805321Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-31T07:28:01.805436Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.24:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.24:2380","--initial-cluster=instance=https://162.253.55.24:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.24:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.24: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-08-31T07:28:01.805518Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-31T07:28:01.80553Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.24:2380"]} {"level":"info","ts":"2026-08-31T07:28:01.805554Z","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-08-31T07:28:01.80642Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.24:2379"]} {"level":"info","ts":"2026-08-31T07:28:01.806515Z","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://162.253.55.24:2380"],"listen-peer-urls":["https://162.253.55.24:2380"],"advertise-client-urls":["https://162.253.55.24:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.24:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.24: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-08-31T07:28:01.810846Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.996861ms"} {"level":"info","ts":"2026-08-31T07:28:01.821756Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"7a47c02f9bc0b904","cluster-id":"318a7363fda604da"} {"level":"info","ts":"2026-08-31T07:28:01.821852Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7a47c02f9bc0b904 switched to configuration voters=()"} {"level":"info","ts":"2026-08-31T07:28:01.821875Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7a47c02f9bc0b904 became follower at term 0"} {"level":"info","ts":"2026-08-31T07:28:01.821894Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 7a47c02f9bc0b904 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-31T07:28:01.821904Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7a47c02f9bc0b904 became follower at term 1"} {"level":"info","ts":"2026-08-31T07:28:01.821954Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7a47c02f9bc0b904 switched to configuration voters=(8811222506682759428)"} {"level":"warn","ts":"2026-08-31T07:28:01.836715Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-31T07:28:01.840069Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-31T07:28:01.842639Z","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-08-31T07:28:01.845302Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"7a47c02f9bc0b904","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-31T07:28:01.848041Z","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-08-31T07:28:01.848351Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"7a47c02f9bc0b904","initial-advertise-peer-urls":["https://162.253.55.24:2380"],"listen-peer-urls":["https://162.253.55.24:2380"],"advertise-client-urls":["https://162.253.55.24:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.24:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-31T07:28:01.848387Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-31T07:28:01.848619Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"7a47c02f9bc0b904","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-31T07:28:01.848832Z","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-08-31T07:28:01.848972Z","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-08-31T07:28:01.84891Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.24:2380"} {"level":"info","ts":"2026-08-31T07:28:01.848993Z","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-08-31T07:28:01.849021Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.24:2380"} {"level":"info","ts":"2026-08-31T07:28:01.849719Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7a47c02f9bc0b904 switched to configuration voters=(8811222506682759428)"} {"level":"info","ts":"2026-08-31T07:28:01.849893Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"318a7363fda604da","local-member-id":"7a47c02f9bc0b904","added-peer-id":"7a47c02f9bc0b904","added-peer-peer-urls":["https://162.253.55.24:2380"]} {"level":"info","ts":"2026-08-31T07:28:02.122645Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7a47c02f9bc0b904 is starting a new election at term 1"} {"level":"info","ts":"2026-08-31T07:28:02.122899Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7a47c02f9bc0b904 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-31T07:28:02.123049Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7a47c02f9bc0b904 received MsgPreVoteResp from 7a47c02f9bc0b904 at term 1"} {"level":"info","ts":"2026-08-31T07:28:02.123098Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7a47c02f9bc0b904 became candidate at term 2"} {"level":"info","ts":"2026-08-31T07:28:02.123147Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7a47c02f9bc0b904 received MsgVoteResp from 7a47c02f9bc0b904 at term 2"} {"level":"info","ts":"2026-08-31T07:28:02.123189Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7a47c02f9bc0b904 became leader at term 2"} {"level":"info","ts":"2026-08-31T07:28:02.123208Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 7a47c02f9bc0b904 elected leader 7a47c02f9bc0b904 at term 2"} {"level":"info","ts":"2026-08-31T07:28:02.124402Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"7a47c02f9bc0b904","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.24:2379]}","request-path":"/0/members/7a47c02f9bc0b904/attributes","cluster-id":"318a7363fda604da","publish-timeout":"7s"} {"level":"info","ts":"2026-08-31T07:28:02.124421Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-31T07:28:02.124496Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-31T07:28:02.124468Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-31T07:28:02.124963Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-31T07:28:02.125004Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-31T07:28:02.125729Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"318a7363fda604da","local-member-id":"7a47c02f9bc0b904","cluster-version":"3.5"} {"level":"info","ts":"2026-08-31T07:28:02.125881Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-31T07:28:02.125931Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-31T07:28:02.128029Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.24:2379"} {"level":"info","ts":"2026-08-31T07:28:02.128081Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-08-31T07:28:25.599169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.689956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.24\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-08-31T07:28:25.599288Z","caller":"traceutil/trace.go:171","msg":"trace[1099592214] range","detail":"{range_begin:/registry/masterleases/162.253.55.24; range_end:; response_count:1; response_revision:459; }","duration":"120.86529ms","start":"2026-08-31T07:28:25.478401Z","end":"2026-08-31T07:28:25.599266Z","steps":["trace[1099592214] 'range keys from in-memory index tree' (duration: 120.526412ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:28:44.591082Z","caller":"traceutil/trace.go:171","msg":"trace[1320910288] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:649; }","duration":"350.369542ms","start":"2026-08-31T07:28:44.240684Z","end":"2026-08-31T07:28:44.591053Z","steps":["trace[1320910288] 'read index received' (duration: 350.301121ms)","trace[1320910288] 'applied index is now lower than readState.Index' (duration: 67.211µs)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:28:44.591152Z","caller":"traceutil/trace.go:171","msg":"trace[21532405] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"377.04503ms","start":"2026-08-31T07:28:44.214059Z","end":"2026-08-31T07:28:44.591104Z","steps":["trace[21532405] 'process raft request' (duration: 376.848045ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:28:44.591344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.645869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-08-31T07:28:44.591408Z","caller":"traceutil/trace.go:171","msg":"trace[312094416] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:627; }","duration":"350.753802ms","start":"2026-08-31T07:28:44.240637Z","end":"2026-08-31T07:28:44.591391Z","steps":["trace[312094416] 'agreement among raft nodes before linearized reading' (duration: 350.562657ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:28:44.591454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:28:44.240617Z","time spent":"350.823553ms","remote":"127.0.0.1:41158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-31T07:28:44.592089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:28:44.21403Z","time spent":"377.266275ms","remote":"127.0.0.1:41064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-31T07:28:44.860279Z","caller":"traceutil/trace.go:171","msg":"trace[834783348] linearizableReadLoop","detail":"{readStateIndex:652; appliedIndex:650; }","duration":"269.088856ms","start":"2026-08-31T07:28:44.591162Z","end":"2026-08-31T07:28:44.860251Z","steps":["trace[834783348] 'read index received' (duration: 268.221523ms)","trace[834783348] 'applied index is now lower than readState.Index' (duration: 866.213µs)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:28:44.860431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.377804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T07:28:44.860428Z","caller":"traceutil/trace.go:171","msg":"trace[1056316246] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"595.929079ms","start":"2026-08-31T07:28:44.26446Z","end":"2026-08-31T07:28:44.860389Z","steps":["trace[1056316246] 'process raft request' (duration: 595.061677ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:28:44.860477Z","caller":"traceutil/trace.go:171","msg":"trace[432575658] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:629; }","duration":"417.472976ms","start":"2026-08-31T07:28:44.442987Z","end":"2026-08-31T07:28:44.86046Z","steps":["trace[432575658] 'agreement among raft nodes before linearized reading' (duration: 417.384934ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:28:44.860688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:28:44.264432Z","time spent":"596.126895ms","remote":"127.0.0.1:40974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036257,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-31T07:28:44.860705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:28:44.442967Z","time spent":"417.701091ms","remote":"127.0.0.1:40898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-31T07:28:44.860428Z","caller":"traceutil/trace.go:171","msg":"trace[2055999952] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"390.711286ms","start":"2026-08-31T07:28:44.469664Z","end":"2026-08-31T07:28:44.860375Z","steps":["trace[2055999952] 'process raft request' (duration: 390.505841ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:28:44.861065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:28:44.469624Z","time spent":"391.380343ms","remote":"127.0.0.1:41158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-31T07:28:44.97497Z","caller":"traceutil/trace.go:171","msg":"trace[1092475233] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"378.567888ms","start":"2026-08-31T07:28:44.59636Z","end":"2026-08-31T07:28:44.974928Z","steps":["trace[1092475233] 'process raft request' (duration: 325.364927ms)","trace[1092475233] 'compare' (duration: 52.974536ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:28:44.975148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:28:44.596328Z","time spent":"378.722122ms","remote":"127.0.0.1:41158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-31T07:28:44.974969Z","caller":"traceutil/trace.go:171","msg":"trace[144261850] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:652; }","duration":"113.059533ms","start":"2026-08-31T07:28:44.861881Z","end":"2026-08-31T07:28:44.974941Z","steps":["trace[144261850] 'read index received' (duration: 59.723688ms)","trace[144261850] 'applied index is now lower than readState.Index' (duration: 53.334715ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:28:44.97525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.349139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T07:28:44.975335Z","caller":"traceutil/trace.go:171","msg":"trace[424098198] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:631; }","duration":"113.450892ms","start":"2026-08-31T07:28:44.861862Z","end":"2026-08-31T07:28:44.975313Z","steps":["trace[424098198] 'agreement among raft nodes before linearized reading' (duration: 113.157695ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:28:45.568904Z","caller":"traceutil/trace.go:171","msg":"trace[2125011695] linearizableReadLoop","detail":"{readStateIndex:655; appliedIndex:654; }","duration":"125.532699ms","start":"2026-08-31T07:28:45.4433Z","end":"2026-08-31T07:28:45.568833Z","steps":["trace[2125011695] 'read index received' (duration: 124.408221ms)","trace[2125011695] 'applied index is now lower than readState.Index' (duration: 1.123068ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:28:45.569153Z","caller":"traceutil/trace.go:171","msg":"trace[2064229443] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"185.731698ms","start":"2026-08-31T07:28:45.38336Z","end":"2026-08-31T07:28:45.569092Z","steps":["trace[2064229443] 'process raft request' (duration: 184.618209ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:28:45.569426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.102403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T07:28:45.569504Z","caller":"traceutil/trace.go:171","msg":"trace[381162043] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:632; }","duration":"126.220326ms","start":"2026-08-31T07:28:45.44325Z","end":"2026-08-31T07:28:45.56947Z","steps":["trace[381162043] 'agreement among raft nodes before linearized reading' (duration: 125.901578ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:28:45.570136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.698934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-31T07:28:45.570217Z","caller":"traceutil/trace.go:171","msg":"trace[137202745] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:632; }","duration":"103.802257ms","start":"2026-08-31T07:28:45.466374Z","end":"2026-08-31T07:28:45.570177Z","steps":["trace[137202745] 'agreement among raft nodes before linearized reading' (duration: 103.611672ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:30:17.642294Z","caller":"traceutil/trace.go:171","msg":"trace[1190837811] transaction","detail":"{read_only:false; response_revision:1878; number_of_response:1; }","duration":"211.944242ms","start":"2026-08-31T07:30:17.430308Z","end":"2026-08-31T07:30:17.642252Z","steps":["trace[1190837811] 'process raft request' (duration: 211.576583ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:30:17.871775Z","caller":"traceutil/trace.go:171","msg":"trace[212920981] transaction","detail":"{read_only:false; response_revision:1879; number_of_response:1; }","duration":"100.276582ms","start":"2026-08-31T07:30:17.771471Z","end":"2026-08-31T07:30:17.871747Z","steps":["trace[212920981] 'process raft request' (duration: 100.094058ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:30:18.186602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.631378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-95c6fb8f-73e3-46e3-830f-9e3384237779\" ","response":"range_response_count:1 size:3746"} {"level":"info","ts":"2026-08-31T07:30:18.186765Z","caller":"traceutil/trace.go:171","msg":"trace[1599510866] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-95c6fb8f-73e3-46e3-830f-9e3384237779; range_end:; response_count:1; response_revision:1879; }","duration":"191.823072ms","start":"2026-08-31T07:30:17.994909Z","end":"2026-08-31T07:30:18.186732Z","steps":["trace[1599510866] 'range keys from in-memory index tree' (duration: 191.426513ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:30:30.561001Z","caller":"traceutil/trace.go:171","msg":"trace[1286996488] transaction","detail":"{read_only:false; response_revision:1982; number_of_response:1; }","duration":"264.591654ms","start":"2026-08-31T07:30:30.296347Z","end":"2026-08-31T07:30:30.560939Z","steps":["trace[1286996488] 'process raft request' (duration: 264.441141ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:30:30.562493Z","caller":"traceutil/trace.go:171","msg":"trace[1304707612] transaction","detail":"{read_only:false; response_revision:1983; number_of_response:1; }","duration":"210.328744ms","start":"2026-08-31T07:30:30.352129Z","end":"2026-08-31T07:30:30.562458Z","steps":["trace[1304707612] 'process raft request' (duration: 210.144379ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:30:30.566428Z","caller":"traceutil/trace.go:171","msg":"trace[1921621103] linearizableReadLoop","detail":"{readStateIndex:2058; appliedIndex:2056; }","duration":"139.642817ms","start":"2026-08-31T07:30:30.426759Z","end":"2026-08-31T07:30:30.566402Z","steps":["trace[1921621103] 'read index received' (duration: 134.075864ms)","trace[1921621103] 'applied index is now lower than readState.Index' (duration: 5.565293ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:30:30.566746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.985516ms","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-08-31T07:30:30.566727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.364827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T07:30:30.566812Z","caller":"traceutil/trace.go:171","msg":"trace[1612620094] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1983; }","duration":"140.051217ms","start":"2026-08-31T07:30:30.426744Z","end":"2026-08-31T07:30:30.566795Z","steps":["trace[1612620094] 'agreement among raft nodes before linearized reading' (duration: 139.811011ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:30:30.566819Z","caller":"traceutil/trace.go:171","msg":"trace[234128216] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1983; }","duration":"123.54433ms","start":"2026-08-31T07:30:30.443258Z","end":"2026-08-31T07:30:30.566803Z","steps":["trace[234128216] 'agreement among raft nodes before linearized reading' (duration: 123.354996ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:30:30.566737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.766793ms","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-08-31T07:30:30.566913Z","caller":"traceutil/trace.go:171","msg":"trace[1452569003] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1983; }","duration":"136.944248ms","start":"2026-08-31T07:30:30.429952Z","end":"2026-08-31T07:30:30.566896Z","steps":["trace[1452569003] 'agreement among raft nodes before linearized reading' (duration: 136.63688ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:30:31.01225Z","caller":"traceutil/trace.go:171","msg":"trace[2073987252] transaction","detail":"{read_only:false; response_revision:1990; number_of_response:1; }","duration":"175.511573ms","start":"2026-08-31T07:30:30.836716Z","end":"2026-08-31T07:30:31.012228Z","steps":["trace[2073987252] 'process raft request' (duration: 175.36729ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:31:02.207554Z","caller":"traceutil/trace.go:171","msg":"trace[614520657] linearizableReadLoop","detail":"{readStateIndex:2250; appliedIndex:2249; }","duration":"133.236042ms","start":"2026-08-31T07:31:02.074293Z","end":"2026-08-31T07:31:02.207529Z","steps":["trace[614520657] 'read index received' (duration: 132.993556ms)","trace[614520657] 'applied index is now lower than readState.Index' (duration: 241.426µs)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:31:02.207716Z","caller":"traceutil/trace.go:171","msg":"trace[959076636] transaction","detail":"{read_only:false; response_revision:2168; number_of_response:1; }","duration":"196.881305ms","start":"2026-08-31T07:31:02.010755Z","end":"2026-08-31T07:31:02.207636Z","steps":["trace[959076636] 'process raft request' (duration: 196.596958ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:31:02.207846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.525719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-31T07:31:02.207926Z","caller":"traceutil/trace.go:171","msg":"trace[1961614020] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2168; }","duration":"133.647851ms","start":"2026-08-31T07:31:02.074261Z","end":"2026-08-31T07:31:02.207909Z","steps":["trace[1961614020] 'agreement among raft nodes before linearized reading' (duration: 133.409495ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:31:08.522389Z","caller":"traceutil/trace.go:171","msg":"trace[1646375158] linearizableReadLoop","detail":"{readStateIndex:2285; appliedIndex:2284; }","duration":"273.821283ms","start":"2026-08-31T07:31:08.248543Z","end":"2026-08-31T07:31:08.522364Z","steps":["trace[1646375158] 'read index received' (duration: 273.69507ms)","trace[1646375158] 'applied index is now lower than readState.Index' (duration: 125.043µs)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:31:08.522596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.031078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-31T07:31:08.522602Z","caller":"traceutil/trace.go:171","msg":"trace[302664921] transaction","detail":"{read_only:false; response_revision:2201; number_of_response:1; }","duration":"284.061447ms","start":"2026-08-31T07:31:08.238517Z","end":"2026-08-31T07:31:08.522578Z","steps":["trace[302664921] 'process raft request' (duration: 283.663897ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:31:08.522629Z","caller":"traceutil/trace.go:171","msg":"trace[479314881] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2201; }","duration":"274.11017ms","start":"2026-08-31T07:31:08.248507Z","end":"2026-08-31T07:31:08.522617Z","steps":["trace[479314881] 'agreement among raft nodes before linearized reading' (duration: 273.947686ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:36:34.089759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.916027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:7 size:62894"} {"level":"info","ts":"2026-08-31T07:36:34.089867Z","caller":"traceutil/trace.go:171","msg":"trace[615132462] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:7; response_revision:4382; }","duration":"103.047391ms","start":"2026-08-31T07:36:33.986796Z","end":"2026-08-31T07:36:34.089844Z","steps":["trace[615132462] 'agreement among raft nodes before linearized reading' (duration: 102.722801ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:37:25.404291Z","caller":"traceutil/trace.go:171","msg":"trace[538848599] transaction","detail":"{read_only:false; response_revision:5188; number_of_response:1; }","duration":"161.197484ms","start":"2026-08-31T07:37:25.243067Z","end":"2026-08-31T07:37:25.404265Z","steps":["trace[538848599] 'process raft request' (duration: 161.002709ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:37:25.407823Z","caller":"traceutil/trace.go:171","msg":"trace[1826323270] transaction","detail":"{read_only:false; response_revision:5189; number_of_response:1; }","duration":"161.431331ms","start":"2026-08-31T07:37:25.246371Z","end":"2026-08-31T07:37:25.407802Z","steps":["trace[1826323270] 'process raft request' (duration: 161.340639ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:37:25.597521Z","caller":"traceutil/trace.go:171","msg":"trace[826385913] transaction","detail":"{read_only:false; response_revision:5194; number_of_response:1; }","duration":"105.354121ms","start":"2026-08-31T07:37:25.492146Z","end":"2026-08-31T07:37:25.5975Z","steps":["trace[826385913] 'process raft request' (duration: 98.608454ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:37:28.600442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.799025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-31T07:37:28.600481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.441122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:7 size:62894"} {"level":"info","ts":"2026-08-31T07:37:28.600526Z","caller":"traceutil/trace.go:171","msg":"trace[215556862] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5324; }","duration":"157.924568ms","start":"2026-08-31T07:37:28.442583Z","end":"2026-08-31T07:37:28.600508Z","steps":["trace[215556862] 'range keys from in-memory index tree' (duration: 157.324013ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:37:28.600514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.753751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9c2e19c7-1208-4733-90f4-2c5f7f18601b\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-08-31T07:37:28.600566Z","caller":"traceutil/trace.go:171","msg":"trace[1914816128] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:7; response_revision:5324; }","duration":"237.568295ms","start":"2026-08-31T07:37:28.36297Z","end":"2026-08-31T07:37:28.600538Z","steps":["trace[1914816128] 'range keys from in-memory index tree' (duration: 237.168856ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:37:28.600583Z","caller":"traceutil/trace.go:171","msg":"trace[825226405] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9c2e19c7-1208-4733-90f4-2c5f7f18601b; range_end:; response_count:1; response_revision:5324; }","duration":"163.857404ms","start":"2026-08-31T07:37:28.436709Z","end":"2026-08-31T07:37:28.600567Z","steps":["trace[825226405] 'range keys from in-memory index tree' (duration: 163.588007ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:37:28.601168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.128461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T07:37:28.601233Z","caller":"traceutil/trace.go:171","msg":"trace[2077548467] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:0; response_revision:5324; }","duration":"214.223634ms","start":"2026-08-31T07:37:28.386997Z","end":"2026-08-31T07:37:28.601221Z","steps":["trace[2077548467] 'range keys from in-memory index tree' (duration: 214.052409ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:37:28.775776Z","caller":"traceutil/trace.go:171","msg":"trace[1685852325] transaction","detail":"{read_only:false; response_revision:5326; number_of_response:1; }","duration":"111.702867ms","start":"2026-08-31T07:37:28.664025Z","end":"2026-08-31T07:37:28.775728Z","steps":["trace[1685852325] 'process raft request' (duration: 88.968672ms)","trace[1685852325] 'compare' (duration: 22.503079ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:37:28.78351Z","caller":"traceutil/trace.go:171","msg":"trace[2128464174] linearizableReadLoop","detail":"{readStateIndex:5533; appliedIndex:5531; }","duration":"117.922841ms","start":"2026-08-31T07:37:28.665563Z","end":"2026-08-31T07:37:28.783486Z","steps":["trace[2128464174] 'read index received' (duration: 87.468883ms)","trace[2128464174] 'applied index is now lower than readState.Index' (duration: 30.453078ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:37:28.783578Z","caller":"traceutil/trace.go:171","msg":"trace[608583479] transaction","detail":"{read_only:false; response_revision:5327; number_of_response:1; }","duration":"119.260016ms","start":"2026-08-31T07:37:28.664296Z","end":"2026-08-31T07:37:28.783556Z","steps":["trace[608583479] 'process raft request' (duration: 119.032759ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:37:28.783669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.045944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/vector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T07:37:28.78372Z","caller":"traceutil/trace.go:171","msg":"trace[221436517] range","detail":"{range_begin:/registry/clusterrolebindings/vector; range_end:; response_count:0; response_revision:5327; }","duration":"118.145497ms","start":"2026-08-31T07:37:28.665558Z","end":"2026-08-31T07:37:28.783704Z","steps":["trace[221436517] 'agreement among raft nodes before linearized reading' (duration: 118.018763ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:37:43.219826Z","caller":"traceutil/trace.go:171","msg":"trace[165534286] transaction","detail":"{read_only:false; response_revision:5593; number_of_response:1; }","duration":"102.458844ms","start":"2026-08-31T07:37:43.117333Z","end":"2026-08-31T07:37:43.219792Z","steps":["trace[165534286] 'process raft request' (duration: 102.190608ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:37:43.232601Z","caller":"traceutil/trace.go:171","msg":"trace[169407902] transaction","detail":"{read_only:false; response_revision:5594; number_of_response:1; }","duration":"114.802659ms","start":"2026-08-31T07:37:43.117753Z","end":"2026-08-31T07:37:43.232556Z","steps":["trace[169407902] 'process raft request' (duration: 114.484321ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:37:43.232633Z","caller":"traceutil/trace.go:171","msg":"trace[1755955690] transaction","detail":"{read_only:false; response_revision:5595; number_of_response:1; }","duration":"108.725879ms","start":"2026-08-31T07:37:43.123872Z","end":"2026-08-31T07:37:43.232598Z","steps":["trace[1755955690] 'process raft request' (duration: 108.500953ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:37:43.232704Z","caller":"traceutil/trace.go:171","msg":"trace[459209605] transaction","detail":"{read_only:false; response_revision:5597; number_of_response:1; }","duration":"103.302166ms","start":"2026-08-31T07:37:43.129374Z","end":"2026-08-31T07:37:43.232677Z","steps":["trace[459209605] 'process raft request' (duration: 103.189413ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:37:43.232967Z","caller":"traceutil/trace.go:171","msg":"trace[111809738] transaction","detail":"{read_only:false; response_revision:5596; number_of_response:1; }","duration":"105.745032ms","start":"2026-08-31T07:37:43.127204Z","end":"2026-08-31T07:37:43.232949Z","steps":["trace[111809738] 'process raft request' (duration: 105.29898ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:37:43.706174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.928067ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-31T07:37:43.706468Z","caller":"traceutil/trace.go:171","msg":"trace[2061442272] transaction","detail":"{read_only:false; response_revision:5598; number_of_response:1; }","duration":"467.260405ms","start":"2026-08-31T07:37:43.239182Z","end":"2026-08-31T07:37:43.706442Z","steps":["trace[2061442272] 'process raft request' (duration: 255.763522ms)","trace[2061442272] 'compare' (duration: 210.482386ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:37:43.7065Z","caller":"traceutil/trace.go:171","msg":"trace[1169705538] transaction","detail":"{read_only:false; response_revision:5599; number_of_response:1; }","duration":"467.210094ms","start":"2026-08-31T07:37:43.239271Z","end":"2026-08-31T07:37:43.706481Z","steps":["trace[1169705538] 'process raft request' (duration: 467.024759ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:37:43.706608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:37:43.239255Z","time spent":"467.304117ms","remote":"127.0.0.1:41168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1065,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-31T07:37:43.706663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:37:43.239151Z","time spent":"467.371278ms","remote":"127.0.0.1:41050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":679,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-31T07:37:43.706884Z","caller":"traceutil/trace.go:171","msg":"trace[809693339] transaction","detail":"{read_only:false; response_revision:5600; number_of_response:1; }","duration":"467.196214ms","start":"2026-08-31T07:37:43.239664Z","end":"2026-08-31T07:37:43.70686Z","steps":["trace[809693339] 'process raft request' (duration: 466.755982ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:37:43.706917Z","caller":"traceutil/trace.go:171","msg":"trace[1517458216] linearizableReadLoop","detail":"{readStateIndex:5817; appliedIndex:5813; }","duration":"263.883315ms","start":"2026-08-31T07:37:43.442981Z","end":"2026-08-31T07:37:43.706864Z","steps":["trace[1517458216] 'read index received' (duration: 51.967181ms)","trace[1517458216] 'applied index is now lower than readState.Index' (duration: 211.914694ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:37:43.707016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.021798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T07:37:43.707029Z","caller":"traceutil/trace.go:171","msg":"trace[1272793700] transaction","detail":"{read_only:false; response_revision:5601; number_of_response:1; }","duration":"466.759391ms","start":"2026-08-31T07:37:43.240257Z","end":"2026-08-31T07:37:43.707016Z","steps":["trace[1272793700] 'process raft request' (duration: 466.512035ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:37:43.707026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:37:43.239646Z","time spent":"467.305337ms","remote":"127.0.0.1:41050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":527,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-31T07:37:43.707057Z","caller":"traceutil/trace.go:171","msg":"trace[847502993] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5601; }","duration":"264.10403ms","start":"2026-08-31T07:37:43.442941Z","end":"2026-08-31T07:37:43.707045Z","steps":["trace[847502993] 'agreement among raft nodes before linearized reading' (duration: 264.025568ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:37:43.707077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:37:43.240232Z","time spent":"466.822243ms","remote":"127.0.0.1:41168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-31T07:38:00.631544Z","caller":"traceutil/trace.go:171","msg":"trace[1819761730] linearizableReadLoop","detail":"{readStateIndex:6112; appliedIndex:6111; }","duration":"187.463252ms","start":"2026-08-31T07:38:00.444046Z","end":"2026-08-31T07:38:00.63151Z","steps":["trace[1819761730] 'read index received' (duration: 143.982404ms)","trace[1819761730] 'applied index is now lower than readState.Index' (duration: 43.479708ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:38:00.631723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.66324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T07:38:00.631709Z","caller":"traceutil/trace.go:171","msg":"trace[1394024680] transaction","detail":"{read_only:false; response_revision:5890; number_of_response:1; }","duration":"293.764048ms","start":"2026-08-31T07:38:00.3379Z","end":"2026-08-31T07:38:00.631664Z","steps":["trace[1394024680] 'process raft request' (duration: 250.151575ms)","trace[1394024680] 'compare' (duration: 43.242719ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:38:00.631765Z","caller":"traceutil/trace.go:171","msg":"trace[1728179893] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5890; }","duration":"187.765935ms","start":"2026-08-31T07:38:00.443985Z","end":"2026-08-31T07:38:00.631751Z","steps":["trace[1728179893] 'agreement among raft nodes before linearized reading' (duration: 187.669101ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:38:00.768273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.785198ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-08-31T07:38:02.343525Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3014} {"level":"info","ts":"2026-08-31T07:38:02.441192Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3014,"took":"96.065178ms","hash":3628093267,"current-db-size-bytes":57217024,"current-db-size":"57 MB","current-db-size-in-use-bytes":30158848,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-31T07:38:02.44126Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3628093267,"revision":3014,"compact-revision":-1} {"level":"info","ts":"2026-08-31T07:40:47.062544Z","caller":"traceutil/trace.go:171","msg":"trace[1755877714] transaction","detail":"{read_only:false; response_revision:7042; number_of_response:1; }","duration":"116.166224ms","start":"2026-08-31T07:40:46.94634Z","end":"2026-08-31T07:40:47.062506Z","steps":["trace[1755877714] 'process raft request' (duration: 116.062321ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:40:47.063457Z","caller":"traceutil/trace.go:171","msg":"trace[583723622] transaction","detail":"{read_only:false; response_revision:7040; number_of_response:1; }","duration":"123.85976ms","start":"2026-08-31T07:40:46.939402Z","end":"2026-08-31T07:40:47.063262Z","steps":["trace[583723622] 'process raft request' (duration: 122.672728ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:40:47.063738Z","caller":"traceutil/trace.go:171","msg":"trace[96585947] transaction","detail":"{read_only:false; response_revision:7041; number_of_response:1; }","duration":"124.284751ms","start":"2026-08-31T07:40:46.939438Z","end":"2026-08-31T07:40:47.063723Z","steps":["trace[96585947] 'process raft request' (duration: 122.871953ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:43:02.350499Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5903} {"level":"info","ts":"2026-08-31T07:43:02.441102Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5903,"took":"88.498729ms","hash":2951168699,"current-db-size-bytes":57217024,"current-db-size":"57 MB","current-db-size-in-use-bytes":25878528,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-31T07:43:02.441166Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2951168699,"revision":5903,"compact-revision":3014} {"level":"info","ts":"2026-08-31T07:44:15.595139Z","caller":"traceutil/trace.go:171","msg":"trace[683433417] transaction","detail":"{read_only:false; response_revision:8555; number_of_response:1; }","duration":"102.281379ms","start":"2026-08-31T07:44:15.492824Z","end":"2026-08-31T07:44:15.595105Z","steps":["trace[683433417] 'process raft request' (duration: 42.022312ms)","trace[683433417] 'compare' (duration: 59.855787ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:44:15.595219Z","caller":"traceutil/trace.go:171","msg":"trace[749714845] transaction","detail":"{read_only:false; response_revision:8556; number_of_response:1; }","duration":"100.777589ms","start":"2026-08-31T07:44:15.494413Z","end":"2026-08-31T07:44:15.595191Z","steps":["trace[749714845] 'process raft request' (duration: 100.45153ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:44:19.860171Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002338.wal"} {"level":"info","ts":"2026-08-31T07:44:35.509554Z","caller":"traceutil/trace.go:171","msg":"trace[423717139] transaction","detail":"{read_only:false; response_revision:8788; number_of_response:1; }","duration":"131.636163ms","start":"2026-08-31T07:44:35.377883Z","end":"2026-08-31T07:44:35.509519Z","steps":["trace[423717139] 'process raft request' (duration: 131.442128ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:44:35.53955Z","caller":"traceutil/trace.go:171","msg":"trace[1700881779] transaction","detail":"{read_only:false; response_revision:8789; number_of_response:1; }","duration":"104.286032ms","start":"2026-08-31T07:44:35.435241Z","end":"2026-08-31T07:44:35.539527Z","steps":["trace[1700881779] 'process raft request' (duration: 104.169689ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:44:36.022861Z","caller":"traceutil/trace.go:171","msg":"trace[1573742441] linearizableReadLoop","detail":"{readStateIndex:9110; appliedIndex:9109; }","duration":"280.5292ms","start":"2026-08-31T07:44:35.742302Z","end":"2026-08-31T07:44:36.022831Z","steps":["trace[1573742441] 'read index received' (duration: 267.319225ms)","trace[1573742441] 'applied index is now lower than readState.Index' (duration: 13.208324ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:44:36.022981Z","caller":"traceutil/trace.go:171","msg":"trace[1848791866] transaction","detail":"{read_only:false; response_revision:8792; number_of_response:1; }","duration":"305.703592ms","start":"2026-08-31T07:44:35.717232Z","end":"2026-08-31T07:44:36.022936Z","steps":["trace[1848791866] 'process raft request' (duration: 292.373405ms)","trace[1848791866] 'compare' (duration: 12.761591ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:44:36.023138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.974296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-31T07:44:36.023184Z","caller":"traceutil/trace.go:171","msg":"trace[1326095506] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8792; }","duration":"301.060348ms","start":"2026-08-31T07:44:35.722115Z","end":"2026-08-31T07:44:36.023175Z","steps":["trace[1326095506] 'agreement among raft nodes before linearized reading' (duration: 300.847313ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:44:36.02317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:44:35.71721Z","time spent":"305.854127ms","remote":"127.0.0.1:41158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-31T07:44:36.023208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:44:35.722094Z","time spent":"301.108229ms","remote":"127.0.0.1:41158","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":"warn","ts":"2026-08-31T07:44:36.023206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.693741ms","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-08-31T07:44:36.023247Z","caller":"traceutil/trace.go:171","msg":"trace[1048407437] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8792; }","duration":"321.798374ms","start":"2026-08-31T07:44:35.701437Z","end":"2026-08-31T07:44:36.023236Z","steps":["trace[1048407437] 'agreement among raft nodes before linearized reading' (duration: 321.575308ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:44:36.023292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:44:35.70142Z","time spent":"321.863425ms","remote":"127.0.0.1:41158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-31T07:44:36.07341Z","caller":"traceutil/trace.go:171","msg":"trace[424183441] transaction","detail":"{read_only:false; response_revision:8793; number_of_response:1; }","duration":"299.64968ms","start":"2026-08-31T07:44:35.773736Z","end":"2026-08-31T07:44:36.073386Z","steps":["trace[424183441] 'process raft request' (duration: 299.450175ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:44:36.073662Z","caller":"traceutil/trace.go:171","msg":"trace[40322272] transaction","detail":"{read_only:false; response_revision:8794; number_of_response:1; }","duration":"102.40673ms","start":"2026-08-31T07:44:35.971242Z","end":"2026-08-31T07:44:36.073648Z","steps":["trace[40322272] 'process raft request' (duration: 102.100542ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:44:36.073726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.260824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-08-31T07:44:36.073794Z","caller":"traceutil/trace.go:171","msg":"trace[436687059] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:8794; }","duration":"329.342026ms","start":"2026-08-31T07:44:35.744435Z","end":"2026-08-31T07:44:36.073777Z","steps":["trace[436687059] 'agreement among raft nodes before linearized reading' (duration: 329.081119ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:44:36.073841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:44:35.744404Z","time spent":"329.423167ms","remote":"127.0.0.1:40904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"info","ts":"2026-08-31T07:44:36.586976Z","caller":"traceutil/trace.go:171","msg":"trace[756486530] linearizableReadLoop","detail":"{readStateIndex:9115; appliedIndex:9114; }","duration":"121.922053ms","start":"2026-08-31T07:44:36.465026Z","end":"2026-08-31T07:44:36.586948Z","steps":["trace[756486530] 'read index received' (duration: 121.549442ms)","trace[756486530] 'applied index is now lower than readState.Index' (duration: 371.041µs)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:44:36.587043Z","caller":"traceutil/trace.go:171","msg":"trace[415750541] transaction","detail":"{read_only:false; response_revision:8797; number_of_response:1; }","duration":"130.985857ms","start":"2026-08-31T07:44:36.45603Z","end":"2026-08-31T07:44:36.587016Z","steps":["trace[415750541] 'process raft request' (duration: 130.699619ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:44:36.587628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.581251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-08-31T07:44:36.587702Z","caller":"traceutil/trace.go:171","msg":"trace[1515108165] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:8797; }","duration":"122.681993ms","start":"2026-08-31T07:44:36.464999Z","end":"2026-08-31T07:44:36.587681Z","steps":["trace[1515108165] 'agreement among raft nodes before linearized reading' (duration: 122.063377ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:44:36.587764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.308782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-31T07:44:36.587838Z","caller":"traceutil/trace.go:171","msg":"trace[1107596714] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:8797; }","duration":"101.402044ms","start":"2026-08-31T07:44:36.486402Z","end":"2026-08-31T07:44:36.587804Z","steps":["trace[1107596714] 'agreement among raft nodes before linearized reading' (duration: 100.726307ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:44:36.786247Z","caller":"traceutil/trace.go:171","msg":"trace[744775197] transaction","detail":"{read_only:false; response_revision:8798; number_of_response:1; }","duration":"145.007112ms","start":"2026-08-31T07:44:36.641212Z","end":"2026-08-31T07:44:36.786219Z","steps":["trace[744775197] 'process raft request' (duration: 77.810593ms)","trace[744775197] 'compare' (duration: 67.036194ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:44:38.201749Z","caller":"traceutil/trace.go:171","msg":"trace[1404325274] linearizableReadLoop","detail":"{readStateIndex:9123; appliedIndex:9122; }","duration":"119.335804ms","start":"2026-08-31T07:44:38.08239Z","end":"2026-08-31T07:44:38.201726Z","steps":["trace[1404325274] 'read index received' (duration: 1.783097ms)","trace[1404325274] 'applied index is now lower than readState.Index' (duration: 117.551887ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:44:38.201815Z","caller":"traceutil/trace.go:171","msg":"trace[2069465255] transaction","detail":"{read_only:false; response_revision:8805; number_of_response:1; }","duration":"123.116685ms","start":"2026-08-31T07:44:38.078664Z","end":"2026-08-31T07:44:38.20178Z","steps":["trace[2069465255] 'process raft request' (duration: 25.440541ms)","trace[2069465255] 'compare' (duration: 97.5181ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:44:38.201991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.848591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-31T07:44:38.202033Z","caller":"traceutil/trace.go:171","msg":"trace[379605183] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8805; }","duration":"115.943894ms","start":"2026-08-31T07:44:38.086078Z","end":"2026-08-31T07:44:38.202022Z","steps":["trace[379605183] 'agreement among raft nodes before linearized reading' (duration: 115.776359ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:44:38.202103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.687183ms","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-08-31T07:44:38.202199Z","caller":"traceutil/trace.go:171","msg":"trace[1562010832] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8805; }","duration":"119.818497ms","start":"2026-08-31T07:44:38.082358Z","end":"2026-08-31T07:44:38.202177Z","steps":["trace[1562010832] 'agreement among raft nodes before linearized reading' (duration: 119.500018ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:44:39.906954Z","caller":"traceutil/trace.go:171","msg":"trace[1362748205] transaction","detail":"{read_only:false; response_revision:8812; number_of_response:1; }","duration":"138.341963ms","start":"2026-08-31T07:44:39.768528Z","end":"2026-08-31T07:44:39.90687Z","steps":["trace[1362748205] 'process raft request' (duration: 138.003724ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:45:07.630551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.203582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-31T07:45:07.630627Z","caller":"traceutil/trace.go:171","msg":"trace[602197560] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:9013; }","duration":"706.321775ms","start":"2026-08-31T07:45:06.924289Z","end":"2026-08-31T07:45:07.630611Z","steps":["trace[602197560] 'count revisions from in-memory index tree' (duration: 706.153221ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:45:07.630655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:45:06.924274Z","time spent":"706.374387ms","remote":"127.0.0.1:45594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"info","ts":"2026-08-31T07:45:07.630983Z","caller":"traceutil/trace.go:171","msg":"trace[1356162867] transaction","detail":"{read_only:false; response_revision:9015; number_of_response:1; }","duration":"405.629797ms","start":"2026-08-31T07:45:07.225335Z","end":"2026-08-31T07:45:07.630965Z","steps":["trace[1356162867] 'process raft request' (duration: 405.513204ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:45:07.631092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:45:07.225311Z","time spent":"405.72272ms","remote":"127.0.0.1:41158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-31T07:45:07.631252Z","caller":"traceutil/trace.go:171","msg":"trace[1227564712] transaction","detail":"{read_only:false; response_revision:9014; number_of_response:1; }","duration":"541.272639ms","start":"2026-08-31T07:45:07.089968Z","end":"2026-08-31T07:45:07.631241Z","steps":["trace[1227564712] 'process raft request' (duration: 534.175929ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:45:07.631301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:45:07.089951Z","time spent":"541.322249ms","remote":"127.0.0.1:41158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-31T07:45:07.631347Z","caller":"traceutil/trace.go:171","msg":"trace[1446987800] linearizableReadLoop","detail":"{readStateIndex:9341; appliedIndex:9340; }","duration":"499.848109ms","start":"2026-08-31T07:45:07.131461Z","end":"2026-08-31T07:45:07.63131Z","steps":["trace[1446987800] 'read index received' (duration: 492.695068ms)","trace[1446987800] 'applied index is now lower than readState.Index' (duration: 7.149721ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:45:07.631499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.615362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T07:45:07.631532Z","caller":"traceutil/trace.go:171","msg":"trace[873818786] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:9015; }","duration":"391.678134ms","start":"2026-08-31T07:45:07.239844Z","end":"2026-08-31T07:45:07.631523Z","steps":["trace[873818786] 'agreement among raft nodes before linearized reading' (duration: 391.624983ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:45:07.631531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.28137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-31T07:45:07.631561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:45:07.239831Z","time spent":"391.718476ms","remote":"127.0.0.1:41284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":27,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"info","ts":"2026-08-31T07:45:07.631565Z","caller":"traceutil/trace.go:171","msg":"trace[1202620167] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9015; }","duration":"188.364132ms","start":"2026-08-31T07:45:07.44319Z","end":"2026-08-31T07:45:07.631554Z","steps":["trace[1202620167] 'agreement among raft nodes before linearized reading' (duration: 188.28928ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:45:07.631589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.639835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-b2wcm\" ","response":"range_response_count:1 size:6447"} {"level":"info","ts":"2026-08-31T07:45:07.631617Z","caller":"traceutil/trace.go:171","msg":"trace[1625914175] range","detail":"{range_begin:/registry/pods/openstack/keepalived-b2wcm; range_end:; response_count:1; response_revision:9015; }","duration":"316.735888ms","start":"2026-08-31T07:45:07.314874Z","end":"2026-08-31T07:45:07.631609Z","steps":["trace[1625914175] 'agreement among raft nodes before linearized reading' (duration: 316.621925ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:45:07.631637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:45:07.31485Z","time spent":"316.782989ms","remote":"127.0.0.1:41064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6469,"request content":"key:\"/registry/pods/openstack/keepalived-b2wcm\" "} {"level":"warn","ts":"2026-08-31T07:45:07.631665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.200488ms","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-08-31T07:45:07.63174Z","caller":"traceutil/trace.go:171","msg":"trace[1561911319] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:9015; }","duration":"500.302761ms","start":"2026-08-31T07:45:07.131428Z","end":"2026-08-31T07:45:07.631731Z","steps":["trace[1561911319] 'agreement among raft nodes before linearized reading' (duration: 500.055715ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:45:07.63176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:45:07.131412Z","time spent":"500.343992ms","remote":"127.0.0.1:41064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-08-31T07:45:07.631669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.035264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-31T07:45:07.631936Z","caller":"traceutil/trace.go:171","msg":"trace[1681845339] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9015; }","duration":"200.347842ms","start":"2026-08-31T07:45:07.431573Z","end":"2026-08-31T07:45:07.63192Z","steps":["trace[1681845339] 'agreement among raft nodes before linearized reading' (duration: 199.923261ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:45:17.879032Z","caller":"traceutil/trace.go:171","msg":"trace[1968770480] linearizableReadLoop","detail":"{readStateIndex:9397; appliedIndex:9396; }","duration":"143.388507ms","start":"2026-08-31T07:45:17.735624Z","end":"2026-08-31T07:45:17.879012Z","steps":["trace[1968770480] 'read index received' (duration: 143.246394ms)","trace[1968770480] 'applied index is now lower than readState.Index' (duration: 141.463µs)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:45:17.8791Z","caller":"traceutil/trace.go:171","msg":"trace[11508453] transaction","detail":"{read_only:false; response_revision:9068; number_of_response:1; }","duration":"146.692217ms","start":"2026-08-31T07:45:17.732381Z","end":"2026-08-31T07:45:17.879073Z","steps":["trace[11508453] 'process raft request' (duration: 146.494052ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:45:17.879205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.562953ms","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-08-31T07:45:17.879226Z","caller":"traceutil/trace.go:171","msg":"trace[1733951689] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9068; }","duration":"143.607654ms","start":"2026-08-31T07:45:17.735612Z","end":"2026-08-31T07:45:17.879219Z","steps":["trace[1733951689] 'agreement among raft nodes before linearized reading' (duration: 143.504281ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:45:17.879371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.148061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-b2wcm\" ","response":"range_response_count:1 size:6447"} {"level":"info","ts":"2026-08-31T07:45:17.879464Z","caller":"traceutil/trace.go:171","msg":"trace[1509036349] range","detail":"{range_begin:/registry/pods/openstack/keepalived-b2wcm; range_end:; response_count:1; response_revision:9068; }","duration":"143.290815ms","start":"2026-08-31T07:45:17.736151Z","end":"2026-08-31T07:45:17.879442Z","steps":["trace[1509036349] 'agreement among raft nodes before linearized reading' (duration: 143.052179ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:45:17.999124Z","caller":"traceutil/trace.go:171","msg":"trace[1169598269] linearizableReadLoop","detail":"{readStateIndex:9398; appliedIndex:9397; }","duration":"115.861521ms","start":"2026-08-31T07:45:17.883244Z","end":"2026-08-31T07:45:17.999105Z","steps":["trace[1169598269] 'read index received' (duration: 48.152938ms)","trace[1169598269] 'applied index is now lower than readState.Index' (duration: 67.707953ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:45:17.999182Z","caller":"traceutil/trace.go:171","msg":"trace[823241992] transaction","detail":"{read_only:false; response_revision:9069; number_of_response:1; }","duration":"116.554129ms","start":"2026-08-31T07:45:17.882611Z","end":"2026-08-31T07:45:17.999165Z","steps":["trace[823241992] 'process raft request' (duration: 48.776575ms)","trace[823241992] 'compare' (duration: 67.62345ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:45:17.999318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.81378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:20288"} {"level":"info","ts":"2026-08-31T07:45:17.999348Z","caller":"traceutil/trace.go:171","msg":"trace[1769359754] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:9069; }","duration":"115.870261ms","start":"2026-08-31T07:45:17.883471Z","end":"2026-08-31T07:45:17.999341Z","steps":["trace[1769359754] 'agreement among raft nodes before linearized reading' (duration: 115.751658ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:45:17.999471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.228651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:227484"} {"level":"info","ts":"2026-08-31T07:45:17.999511Z","caller":"traceutil/trace.go:171","msg":"trace[1777989696] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:9069; }","duration":"116.312383ms","start":"2026-08-31T07:45:17.883191Z","end":"2026-08-31T07:45:17.999504Z","steps":["trace[1777989696] 'agreement among raft nodes before linearized reading' (duration: 115.997435ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:45:20.626955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.097538ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-31T07:45:20.627107Z","caller":"traceutil/trace.go:171","msg":"trace[1021215605] linearizableReadLoop","detail":"{readStateIndex:9420; appliedIndex:9418; }","duration":"260.774331ms","start":"2026-08-31T07:45:20.366316Z","end":"2026-08-31T07:45:20.62709Z","steps":["trace[1021215605] 'read index received' (duration: 7.413219ms)","trace[1021215605] 'applied index is now lower than readState.Index' (duration: 253.359612ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:45:20.62721Z","caller":"traceutil/trace.go:171","msg":"trace[533132616] transaction","detail":"{read_only:false; response_revision:9091; number_of_response:1; }","duration":"263.40635ms","start":"2026-08-31T07:45:20.363791Z","end":"2026-08-31T07:45:20.627198Z","steps":["trace[533132616] 'process raft request' (duration: 87.973454ms)","trace[533132616] 'compare' (duration: 174.656465ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:45:20.627535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.203852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-config-override\" ","response":"range_response_count:1 size:626"} {"level":"info","ts":"2026-08-31T07:45:20.627602Z","caller":"traceutil/trace.go:171","msg":"trace[1052393308] range","detail":"{range_begin:/registry/configmaps/openstack/rook-config-override; range_end:; response_count:1; response_revision:9091; }","duration":"261.284194ms","start":"2026-08-31T07:45:20.366302Z","end":"2026-08-31T07:45:20.627586Z","steps":["trace[1052393308] 'agreement among raft nodes before linearized reading' (duration: 261.086699ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:45:20.634059Z","caller":"traceutil/trace.go:171","msg":"trace[82613677] transaction","detail":"{read_only:false; response_revision:9092; number_of_response:1; }","duration":"258.12792ms","start":"2026-08-31T07:45:20.375906Z","end":"2026-08-31T07:45:20.634034Z","steps":["trace[82613677] 'process raft request' (duration: 257.944115ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:45:20.657535Z","caller":"traceutil/trace.go:171","msg":"trace[2027848904] transaction","detail":"{read_only:false; response_revision:9093; number_of_response:1; }","duration":"109.033607ms","start":"2026-08-31T07:45:20.548465Z","end":"2026-08-31T07:45:20.657499Z","steps":["trace[2027848904] 'process raft request' (duration: 108.809101ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:45:20.709393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.176774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-31T07:45:20.70949Z","caller":"traceutil/trace.go:171","msg":"trace[1096376144] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9094; }","duration":"198.308118ms","start":"2026-08-31T07:45:20.511159Z","end":"2026-08-31T07:45:20.709467Z","steps":["trace[1096376144] 'agreement among raft nodes before linearized reading' (duration: 198.101822ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:45:20.709482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.682816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-31T07:45:20.70939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.486364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-31T07:45:20.709552Z","caller":"traceutil/trace.go:171","msg":"trace[1268064644] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9094; }","duration":"264.789668ms","start":"2026-08-31T07:45:20.444745Z","end":"2026-08-31T07:45:20.709535Z","steps":["trace[1268064644] 'agreement among raft nodes before linearized reading' (duration: 264.650844ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:45:20.709561Z","caller":"traceutil/trace.go:171","msg":"trace[191022831] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:9094; }","duration":"151.694191ms","start":"2026-08-31T07:45:20.557854Z","end":"2026-08-31T07:45:20.709548Z","steps":["trace[191022831] 'agreement among raft nodes before linearized reading' (duration: 151.412742ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:45:20.70964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.860638ms","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-08-31T07:45:20.709726Z","caller":"traceutil/trace.go:171","msg":"trace[1668978427] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9094; }","duration":"249.961391ms","start":"2026-08-31T07:45:20.459736Z","end":"2026-08-31T07:45:20.709698Z","steps":["trace[1668978427] 'agreement among raft nodes before linearized reading' (duration: 249.698025ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:46:19.479225Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"7a47c02f9bc0b904","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-31T07:46:19.513035Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-31T07:46:19.513136Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-31T07:46:45.425597Z","caller":"traceutil/trace.go:171","msg":"trace[1212669106] linearizableReadLoop","detail":"{readStateIndex:10275; appliedIndex:10274; }","duration":"199.115095ms","start":"2026-08-31T07:46:45.226451Z","end":"2026-08-31T07:46:45.425566Z","steps":["trace[1212669106] 'read index received' (duration: 198.8322ms)","trace[1212669106] 'applied index is now lower than readState.Index' (duration: 260.755µs)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:46:45.425796Z","caller":"traceutil/trace.go:171","msg":"trace[1713912177] transaction","detail":"{read_only:false; response_revision:9920; number_of_response:1; }","duration":"252.298401ms","start":"2026-08-31T07:46:45.173478Z","end":"2026-08-31T07:46:45.425777Z","steps":["trace[1713912177] 'process raft request' (duration: 251.898014ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:46:45.426057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.595064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-31T07:46:45.426453Z","caller":"traceutil/trace.go:171","msg":"trace[1254820467] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9920; }","duration":"200.063193ms","start":"2026-08-31T07:46:45.226374Z","end":"2026-08-31T07:46:45.426438Z","steps":["trace[1254820467] 'agreement among raft nodes before linearized reading' (duration: 199.505962ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:46:45.572721Z","caller":"traceutil/trace.go:171","msg":"trace[828089033] linearizableReadLoop","detail":"{readStateIndex:10276; appliedIndex:10275; }","duration":"131.086269ms","start":"2026-08-31T07:46:45.441583Z","end":"2026-08-31T07:46:45.572669Z","steps":["trace[828089033] 'read index received' (duration: 121.952196ms)","trace[828089033] 'applied index is now lower than readState.Index' (duration: 9.132423ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:46:45.572882Z","caller":"traceutil/trace.go:171","msg":"trace[1174080118] transaction","detail":"{read_only:false; response_revision:9921; number_of_response:1; }","duration":"142.812641ms","start":"2026-08-31T07:46:45.430048Z","end":"2026-08-31T07:46:45.572861Z","steps":["trace[1174080118] 'process raft request' (duration: 133.431893ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:46:45.572933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.329634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T07:46:45.57298Z","caller":"traceutil/trace.go:171","msg":"trace[430381792] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9921; }","duration":"131.429896ms","start":"2026-08-31T07:46:45.441535Z","end":"2026-08-31T07:46:45.572965Z","steps":["trace[430381792] 'agreement among raft nodes before linearized reading' (duration: 131.329594ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:46:45.573307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.831194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-31T07:46:45.573424Z","caller":"traceutil/trace.go:171","msg":"trace[624437418] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:9921; }","duration":"102.971348ms","start":"2026-08-31T07:46:45.470428Z","end":"2026-08-31T07:46:45.573399Z","steps":["trace[624437418] 'agreement among raft nodes before linearized reading' (duration: 102.758713ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:46:45.747761Z","caller":"traceutil/trace.go:171","msg":"trace[572607598] transaction","detail":"{read_only:false; response_revision:9922; number_of_response:1; }","duration":"160.010756ms","start":"2026-08-31T07:46:45.587719Z","end":"2026-08-31T07:46:45.747729Z","steps":["trace[572607598] 'process raft request' (duration: 104.774411ms)","trace[572607598] 'compare' (duration: 55.053961ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:48:02.35708Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8067} {"level":"info","ts":"2026-08-31T07:48:02.425055Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8067,"took":"66.278265ms","hash":711387930,"current-db-size-bytes":57217024,"current-db-size":"57 MB","current-db-size-in-use-bytes":28078080,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-31T07:48:02.425123Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":711387930,"revision":8067,"compact-revision":5903} {"level":"info","ts":"2026-08-31T07:52:28.356548Z","caller":"traceutil/trace.go:171","msg":"trace[331628890] transaction","detail":"{read_only:false; response_revision:12370; number_of_response:1; }","duration":"243.422822ms","start":"2026-08-31T07:52:28.1131Z","end":"2026-08-31T07:52:28.356523Z","steps":["trace[331628890] 'process raft request' (duration: 243.259598ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:52:28.380014Z","caller":"traceutil/trace.go:171","msg":"trace[1932077865] linearizableReadLoop","detail":"{readStateIndex:12805; appliedIndex:12803; }","duration":"197.70791ms","start":"2026-08-31T07:52:28.182281Z","end":"2026-08-31T07:52:28.379989Z","steps":["trace[1932077865] 'read index received' (duration: 174.16646ms)","trace[1932077865] 'applied index is now lower than readState.Index' (duration: 23.54056ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:52:28.380132Z","caller":"traceutil/trace.go:171","msg":"trace[736931160] transaction","detail":"{read_only:false; response_revision:12371; number_of_response:1; }","duration":"226.696335ms","start":"2026-08-31T07:52:28.153425Z","end":"2026-08-31T07:52:28.380122Z","steps":["trace[736931160] 'process raft request' (duration: 226.436208ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:52:28.380282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.939316ms","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-08-31T07:52:28.380365Z","caller":"traceutil/trace.go:171","msg":"trace[620801470] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:12371; }","duration":"198.074359ms","start":"2026-08-31T07:52:28.182272Z","end":"2026-08-31T07:52:28.380347Z","steps":["trace[620801470] 'agreement among raft nodes before linearized reading' (duration: 197.921585ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:52:28.381341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.800378ms","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-08-31T07:52:28.381457Z","caller":"traceutil/trace.go:171","msg":"trace[32572262] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:12371; }","duration":"198.926411ms","start":"2026-08-31T07:52:28.182485Z","end":"2026-08-31T07:52:28.381411Z","steps":["trace[32572262] 'agreement among raft nodes before linearized reading' (duration: 197.970325ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:52:28.381593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.650437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5004"} {"level":"warn","ts":"2026-08-31T07:52:28.380538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.118889ms","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-08-31T07:52:28.381703Z","caller":"traceutil/trace.go:171","msg":"trace[1285736620] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:12371; }","duration":"197.73723ms","start":"2026-08-31T07:52:28.183921Z","end":"2026-08-31T07:52:28.381658Z","steps":["trace[1285736620] 'agreement among raft nodes before linearized reading' (duration: 196.411294ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:52:28.381804Z","caller":"traceutil/trace.go:171","msg":"trace[1575501631] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:12371; }","duration":"199.338222ms","start":"2026-08-31T07:52:28.182393Z","end":"2026-08-31T07:52:28.381732Z","steps":["trace[1575501631] 'agreement among raft nodes before linearized reading' (duration: 198.11544ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:52:28.38255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.745342ms","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-08-31T07:52:28.382646Z","caller":"traceutil/trace.go:171","msg":"trace[719362304] range","detail":"{range_begin:/registry/services/endpoints/openstack/cinder-api; range_end:; response_count:1; response_revision:12371; }","duration":"199.825645ms","start":"2026-08-31T07:52:28.182792Z","end":"2026-08-31T07:52:28.382618Z","steps":["trace[719362304] 'agreement among raft nodes before linearized reading' (duration: 197.675538ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:52:28.382711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.374981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T07:52:28.382854Z","caller":"traceutil/trace.go:171","msg":"trace[1975972108] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:12371; }","duration":"200.554986ms","start":"2026-08-31T07:52:28.182242Z","end":"2026-08-31T07:52:28.382797Z","steps":["trace[1975972108] 'agreement among raft nodes before linearized reading' (duration: 198.269095ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:53:02.364739Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10434} {"level":"info","ts":"2026-08-31T07:53:02.463486Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10434,"took":"96.462977ms","hash":1861810312,"current-db-size-bytes":57217024,"current-db-size":"57 MB","current-db-size-in-use-bytes":25788416,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-31T07:53:02.463553Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1861810312,"revision":10434,"compact-revision":8067} {"level":"info","ts":"2026-08-31T07:58:02.379425Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12600} {"level":"info","ts":"2026-08-31T07:58:02.471975Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12600,"took":"89.995395ms","hash":1255521976,"current-db-size-bytes":57217024,"current-db-size":"57 MB","current-db-size-in-use-bytes":27598848,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-31T07:58:02.472045Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1255521976,"revision":12600,"compact-revision":10434} {"level":"info","ts":"2026-08-31T07:59:32.384738Z","caller":"traceutil/trace.go:171","msg":"trace[452481323] linearizableReadLoop","detail":"{readStateIndex:15692; appliedIndex:15691; }","duration":"206.196656ms","start":"2026-08-31T07:59:32.178517Z","end":"2026-08-31T07:59:32.384714Z","steps":["trace[452481323] 'read index received' (duration: 206.055453ms)","trace[452481323] 'applied index is now lower than readState.Index' (duration: 140.423µs)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:59:32.384847Z","caller":"traceutil/trace.go:171","msg":"trace[2074489934] transaction","detail":"{read_only:false; response_revision:15158; number_of_response:1; }","duration":"307.597126ms","start":"2026-08-31T07:59:32.07724Z","end":"2026-08-31T07:59:32.384837Z","steps":["trace[2074489934] 'process raft request' (duration: 307.32633ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:59:32.38494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:59:32.077212Z","time spent":"307.658538ms","remote":"127.0.0.1:41158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-31T07:59:32.385061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.580376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T07:59:32.385134Z","caller":"traceutil/trace.go:171","msg":"trace[562205808] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:15158; }","duration":"206.71988ms","start":"2026-08-31T07:59:32.178398Z","end":"2026-08-31T07:59:32.385118Z","steps":["trace[562205808] 'agreement among raft nodes before linearized reading' (duration: 206.604247ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:59:32.386444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.843299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T07:59:32.386482Z","caller":"traceutil/trace.go:171","msg":"trace[1100629640] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:15159; }","duration":"145.918481ms","start":"2026-08-31T07:59:32.240555Z","end":"2026-08-31T07:59:32.386473Z","steps":["trace[1100629640] 'agreement among raft nodes before linearized reading' (duration: 145.798838ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:59:32.386499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.535277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-31T07:59:32.386558Z","caller":"traceutil/trace.go:171","msg":"trace[1902372952] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:15159; }","duration":"156.668361ms","start":"2026-08-31T07:59:32.229873Z","end":"2026-08-31T07:59:32.386541Z","steps":["trace[1902372952] 'agreement among raft nodes before linearized reading' (duration: 156.534828ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:59:32.386736Z","caller":"traceutil/trace.go:171","msg":"trace[1763647291] transaction","detail":"{read_only:false; response_revision:15159; number_of_response:1; }","duration":"200.130314ms","start":"2026-08-31T07:59:32.18659Z","end":"2026-08-31T07:59:32.386721Z","steps":["trace[1763647291] 'process raft request' (duration: 199.59827ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:59:32.386831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.423936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-31T07:59:32.386934Z","caller":"traceutil/trace.go:171","msg":"trace[1184762970] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15159; }","duration":"162.861836ms","start":"2026-08-31T07:59:32.22405Z","end":"2026-08-31T07:59:32.386911Z","steps":["trace[1184762970] 'agreement among raft nodes before linearized reading' (duration: 162.285532ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:59:56.804032Z","caller":"traceutil/trace.go:171","msg":"trace[43198646] transaction","detail":"{read_only:false; response_revision:15479; number_of_response:1; }","duration":"320.156737ms","start":"2026-08-31T07:59:56.483848Z","end":"2026-08-31T07:59:56.804005Z","steps":["trace[43198646] 'process raft request' (duration: 319.959882ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:59:56.804191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:59:56.483819Z","time spent":"320.291431ms","remote":"127.0.0.1:41158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-31T07:59:56.822838Z","caller":"traceutil/trace.go:171","msg":"trace[1156234075] transaction","detail":"{read_only:false; response_revision:15480; number_of_response:1; }","duration":"326.815845ms","start":"2026-08-31T07:59:56.495995Z","end":"2026-08-31T07:59:56.822811Z","steps":["trace[1156234075] 'process raft request' (duration: 326.589959ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:59:56.822998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:59:56.495967Z","time spent":"326.952929ms","remote":"127.0.0.1:41158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-31T07:59:56.82316Z","caller":"traceutil/trace.go:171","msg":"trace[524758587] linearizableReadLoop","detail":"{readStateIndex:16027; appliedIndex:16025; }","duration":"307.187669ms","start":"2026-08-31T07:59:56.515955Z","end":"2026-08-31T07:59:56.823142Z","steps":["trace[524758587] 'read index received' (duration: 287.86908ms)","trace[524758587] 'applied index is now lower than readState.Index' (duration: 19.317419ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:59:56.82333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.376484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-08-31T07:59:56.823501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.645537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T07:59:56.823539Z","caller":"traceutil/trace.go:171","msg":"trace[1468864632] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:15480; }","duration":"267.700749ms","start":"2026-08-31T07:59:56.555825Z","end":"2026-08-31T07:59:56.823526Z","steps":["trace[1468864632] 'agreement among raft nodes before linearized reading' (duration: 267.646177ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:59:56.823815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.395825ms","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-08-31T07:59:56.823876Z","caller":"traceutil/trace.go:171","msg":"trace[2094506235] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15480; }","duration":"170.507328ms","start":"2026-08-31T07:59:56.653352Z","end":"2026-08-31T07:59:56.82386Z","steps":["trace[2094506235] 'agreement among raft nodes before linearized reading' (duration: 170.359534ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:59:56.824322Z","caller":"traceutil/trace.go:171","msg":"trace[874414673] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15480; }","duration":"308.43942ms","start":"2026-08-31T07:59:56.515869Z","end":"2026-08-31T07:59:56.823434Z","steps":["trace[874414673] 'agreement among raft nodes before linearized reading' (duration: 307.353033ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:59:56.824402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:59:56.515838Z","time spent":"308.551154ms","remote":"127.0.0.1:41158","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":"info","ts":"2026-08-31T08:01:29.396682Z","caller":"traceutil/trace.go:171","msg":"trace[1129979145] transaction","detail":"{read_only:false; response_revision:16324; number_of_response:1; }","duration":"197.059576ms","start":"2026-08-31T08:01:29.199589Z","end":"2026-08-31T08:01:29.396649Z","steps":["trace[1129979145] 'process raft request' (duration: 162.552146ms)","trace[1129979145] 'compare' (duration: 34.042919ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T08:01:30.368948Z","caller":"traceutil/trace.go:171","msg":"trace[1461792147] transaction","detail":"{read_only:false; response_revision:16331; number_of_response:1; }","duration":"270.92741ms","start":"2026-08-31T08:01:30.097642Z","end":"2026-08-31T08:01:30.368569Z","steps":["trace[1461792147] 'process raft request' (duration: 205.686476ms)","trace[1461792147] 'compare' (duration: 64.588408ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T08:03:02.391276Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14605} {"level":"info","ts":"2026-08-31T08:03:02.470077Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14605,"took":"76.817528ms","hash":2849928387,"current-db-size-bytes":57217024,"current-db-size":"57 MB","current-db-size-in-use-bytes":31133696,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-31T08:03:02.470134Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2849928387,"revision":14605,"compact-revision":12600} {"level":"info","ts":"2026-08-31T08:03:51.446488Z","caller":"traceutil/trace.go:171","msg":"trace[355871627] linearizableReadLoop","detail":"{readStateIndex:17897; appliedIndex:17896; }","duration":"152.378012ms","start":"2026-08-31T08:03:51.294078Z","end":"2026-08-31T08:03:51.446456Z","steps":["trace[355871627] 'read index received' (duration: 99.444811ms)","trace[355871627] 'applied index is now lower than readState.Index' (duration: 52.931841ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T08:03:51.446889Z","caller":"traceutil/trace.go:171","msg":"trace[1763333618] transaction","detail":"{read_only:false; response_revision:17291; number_of_response:1; }","duration":"175.851717ms","start":"2026-08-31T08:03:51.271015Z","end":"2026-08-31T08:03:51.446867Z","steps":["trace[1763333618] 'process raft request' (duration: 122.540958ms)","trace[1763333618] 'compare' (duration: 52.738366ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T08:03:51.447164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.081609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-31T08:03:51.447221Z","caller":"traceutil/trace.go:171","msg":"trace[302771629] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:17291; }","duration":"153.320914ms","start":"2026-08-31T08:03:51.293882Z","end":"2026-08-31T08:03:51.447203Z","steps":["trace[302771629] 'agreement among raft nodes before linearized reading' (duration: 153.222882ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:04:50.272339Z","caller":"traceutil/trace.go:171","msg":"trace[910468478] transaction","detail":"{read_only:false; response_revision:17918; number_of_response:1; }","duration":"134.527921ms","start":"2026-08-31T08:04:50.137779Z","end":"2026-08-31T08:04:50.272307Z","steps":["trace[910468478] 'process raft request' (duration: 91.887135ms)","trace[910468478] 'compare' (duration: 42.526524ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T08:04:50.79393Z","caller":"traceutil/trace.go:171","msg":"trace[1476278022] transaction","detail":"{read_only:false; response_revision:17920; number_of_response:1; }","duration":"112.141962ms","start":"2026-08-31T08:04:50.68175Z","end":"2026-08-31T08:04:50.793892Z","steps":["trace[1476278022] 'process raft request' (duration: 35.713117ms)","trace[1476278022] 'compare' (duration: 76.007685ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T08:04:51.207073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.479156ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-08-31T08:04:51.690508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.119395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-31T08:04:51.690605Z","caller":"traceutil/trace.go:171","msg":"trace[599813303] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17925; }","duration":"247.226237ms","start":"2026-08-31T08:04:51.44336Z","end":"2026-08-31T08:04:51.690586Z","steps":["trace[599813303] 'range keys from in-memory index tree' (duration: 246.968621ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:04:51.691225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.78587ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-31T08:04:51.691376Z","caller":"traceutil/trace.go:171","msg":"trace[991491408] transaction","detail":"{read_only:false; response_revision:17926; number_of_response:1; }","duration":"214.476224ms","start":"2026-08-31T08:04:51.476875Z","end":"2026-08-31T08:04:51.691351Z","steps":["trace[991491408] 'process raft request' (duration: 19.492209ms)","trace[991491408] 'compare' (duration: 194.088273ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T08:04:52.209235Z","caller":"traceutil/trace.go:171","msg":"trace[388483556] transaction","detail":"{read_only:false; response_revision:17928; number_of_response:1; }","duration":"103.258073ms","start":"2026-08-31T08:04:52.105949Z","end":"2026-08-31T08:04:52.209207Z","steps":["trace[388483556] 'process raft request' (duration: 103.050808ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:04:52.237546Z","caller":"traceutil/trace.go:171","msg":"trace[2042482957] transaction","detail":"{read_only:false; response_revision:17929; number_of_response:1; }","duration":"105.939909ms","start":"2026-08-31T08:04:52.131542Z","end":"2026-08-31T08:04:52.237482Z","steps":["trace[2042482957] 'process raft request' (duration: 105.705373ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:04:52.985673Z","caller":"traceutil/trace.go:171","msg":"trace[2075665234] transaction","detail":"{read_only:false; response_revision:17934; number_of_response:1; }","duration":"120.877817ms","start":"2026-08-31T08:04:52.864763Z","end":"2026-08-31T08:04:52.985641Z","steps":["trace[2075665234] 'process raft request' (duration: 110.386379ms)","trace[2075665234] 'compare' (duration: 10.292193ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T08:04:53.85225Z","caller":"traceutil/trace.go:171","msg":"trace[717444719] transaction","detail":"{read_only:false; response_revision:17939; number_of_response:1; }","duration":"101.375618ms","start":"2026-08-31T08:04:53.750847Z","end":"2026-08-31T08:04:53.852223Z","steps":["trace[717444719] 'process raft request' (duration: 101.208964ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:04:54.030582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.078035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-b2wcm\" ","response":"range_response_count:1 size:6448"} {"level":"info","ts":"2026-08-31T08:04:54.030657Z","caller":"traceutil/trace.go:171","msg":"trace[2075502726] range","detail":"{range_begin:/registry/pods/openstack/keepalived-b2wcm; range_end:; response_count:1; response_revision:17939; }","duration":"102.199108ms","start":"2026-08-31T08:04:53.92844Z","end":"2026-08-31T08:04:54.030639Z","steps":["trace[2075502726] 'range keys from in-memory index tree' (duration: 101.85307ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:04:54.030791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.398191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-b9lb7\" ","response":"range_response_count:1 size:22018"} {"level":"info","ts":"2026-08-31T08:04:54.030869Z","caller":"traceutil/trace.go:171","msg":"trace[1887868383] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-b9lb7; range_end:; response_count:1; response_revision:17939; }","duration":"104.624037ms","start":"2026-08-31T08:04:53.926226Z","end":"2026-08-31T08:04:54.03085Z","steps":["trace[1887868383] 'range keys from in-memory index tree' (duration: 104.103925ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:04:54.538072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.542809ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-31T08:04:54.538165Z","caller":"traceutil/trace.go:171","msg":"trace[1390823743] linearizableReadLoop","detail":"{readStateIndex:18563; appliedIndex:18561; }","duration":"256.853044ms","start":"2026-08-31T08:04:54.281297Z","end":"2026-08-31T08:04:54.53815Z","steps":["trace[1390823743] 'read index received' (duration: 37.567902ms)","trace[1390823743] 'applied index is now lower than readState.Index' (duration: 219.284132ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T08:04:54.5383Z","caller":"traceutil/trace.go:171","msg":"trace[864292575] transaction","detail":"{read_only:false; response_revision:17941; number_of_response:1; }","duration":"296.081486ms","start":"2026-08-31T08:04:54.242206Z","end":"2026-08-31T08:04:54.538287Z","steps":["trace[864292575] 'process raft request' (duration: 111.24142ms)","trace[864292575] 'compare' (duration: 184.247091ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T08:04:54.538438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.12436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-31T08:04:54.538512Z","caller":"traceutil/trace.go:171","msg":"trace[660948753] range","detail":"{range_begin:/registry/jobs/openstack/neutron-rabbit-init; range_end:; response_count:0; response_revision:17941; }","duration":"257.236263ms","start":"2026-08-31T08:04:54.28126Z","end":"2026-08-31T08:04:54.538496Z","steps":["trace[660948753] 'agreement among raft nodes before linearized reading' (duration: 257.11679ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:04:54.538515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.925235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"info","ts":"2026-08-31T08:04:54.538602Z","caller":"traceutil/trace.go:171","msg":"trace[1910981513] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:17941; }","duration":"257.076989ms","start":"2026-08-31T08:04:54.281509Z","end":"2026-08-31T08:04:54.538586Z","steps":["trace[1910981513] 'agreement among raft nodes before linearized reading' (duration: 256.881875ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:04:54.538844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.859209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-31T08:04:54.538894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.170996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"info","ts":"2026-08-31T08:04:54.538921Z","caller":"traceutil/trace.go:171","msg":"trace[1997781402] range","detail":"{range_begin:/registry/jobs/openstack/neutron-rabbit-init; range_end:; response_count:0; response_revision:17941; }","duration":"255.953401ms","start":"2026-08-31T08:04:54.282956Z","end":"2026-08-31T08:04:54.538909Z","steps":["trace[1997781402] 'agreement among raft nodes before linearized reading' (duration: 255.849939ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:04:54.538927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.767097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"info","ts":"2026-08-31T08:04:54.53894Z","caller":"traceutil/trace.go:171","msg":"trace[1082616006] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:17941; }","duration":"256.261398ms","start":"2026-08-31T08:04:54.282663Z","end":"2026-08-31T08:04:54.538924Z","steps":["trace[1082616006] 'agreement among raft nodes before linearized reading' (duration: 256.142076ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:04:54.53893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.832263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-q6kpp\" ","response":"range_response_count:1 size:20562"} {"level":"info","ts":"2026-08-31T08:04:54.538973Z","caller":"traceutil/trace.go:171","msg":"trace[1945537412] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:17941; }","duration":"134.832088ms","start":"2026-08-31T08:04:54.404127Z","end":"2026-08-31T08:04:54.538959Z","steps":["trace[1945537412] 'agreement among raft nodes before linearized reading' (duration: 134.730576ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:04:54.539003Z","caller":"traceutil/trace.go:171","msg":"trace[1460218002] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-q6kpp; range_end:; response_count:1; response_revision:17941; }","duration":"135.942186ms","start":"2026-08-31T08:04:54.40304Z","end":"2026-08-31T08:04:54.538983Z","steps":["trace[1460218002] 'agreement among raft nodes before linearized reading' (duration: 135.722971ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:04:54.539069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.586528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-b9lb7\" ","response":"range_response_count:1 size:22018"} {"level":"info","ts":"2026-08-31T08:04:54.539115Z","caller":"traceutil/trace.go:171","msg":"trace[1788392976] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-b9lb7; range_end:; response_count:1; response_revision:17941; }","duration":"135.647579ms","start":"2026-08-31T08:04:54.40345Z","end":"2026-08-31T08:04:54.539098Z","steps":["trace[1788392976] 'agreement among raft nodes before linearized reading' (duration: 135.507665ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:04:54.53911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.034319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"info","ts":"2026-08-31T08:04:54.539151Z","caller":"traceutil/trace.go:171","msg":"trace[660922933] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:17941; }","duration":"156.108691ms","start":"2026-08-31T08:04:54.38303Z","end":"2026-08-31T08:04:54.539139Z","steps":["trace[660922933] 'agreement among raft nodes before linearized reading' (duration: 156.004738ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:04:54.539223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.779907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-31T08:04:54.539217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.08839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-31T08:04:54.5393Z","caller":"traceutil/trace.go:171","msg":"trace[652403886] range","detail":"{range_begin:/registry/jobs/openstack/neutron-rabbit-init; range_end:; response_count:0; response_revision:17941; }","duration":"134.8983ms","start":"2026-08-31T08:04:54.404381Z","end":"2026-08-31T08:04:54.53928Z","steps":["trace[652403886] 'agreement among raft nodes before linearized reading' (duration: 134.795287ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:04:54.539313Z","caller":"traceutil/trace.go:171","msg":"trace[684321883] range","detail":"{range_begin:/registry/jobs/openstack/neutron-rabbit-init; range_end:; response_count:0; response_revision:17941; }","duration":"156.213073ms","start":"2026-08-31T08:04:54.383083Z","end":"2026-08-31T08:04:54.539296Z","steps":["trace[684321883] 'agreement among raft nodes before linearized reading' (duration: 156.101851ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:04:54.867777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.923378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-31T08:04:54.867876Z","caller":"traceutil/trace.go:171","msg":"trace[1559654054] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:17941; }","duration":"174.03897ms","start":"2026-08-31T08:04:54.693812Z","end":"2026-08-31T08:04:54.867851Z","steps":["trace[1559654054] 'count revisions from in-memory index tree' (duration: 173.849206ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:04:54.867909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.595318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-db-sync\" ","response":"range_response_count:1 size:5236"} {"level":"warn","ts":"2026-08-31T08:04:54.867788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.480459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-31T08:04:54.867981Z","caller":"traceutil/trace.go:171","msg":"trace[1101510078] range","detail":"{range_begin:/registry/jobs/openstack/neutron-db-sync; range_end:; response_count:1; response_revision:17941; }","duration":"197.688541ms","start":"2026-08-31T08:04:54.670273Z","end":"2026-08-31T08:04:54.867962Z","steps":["trace[1101510078] 'range keys from in-memory index tree' (duration: 197.435764ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:04:54.867973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.804565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-08-31T08:04:54.868021Z","caller":"traceutil/trace.go:171","msg":"trace[628699515] range","detail":"{range_begin:/registry/jobs/openstack/neutron-ks-endpoints; range_end:; response_count:0; response_revision:17941; }","duration":"200.745516ms","start":"2026-08-31T08:04:54.667248Z","end":"2026-08-31T08:04:54.867994Z","steps":["trace[628699515] 'range keys from in-memory index tree' (duration: 200.396268ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:04:54.868073Z","caller":"traceutil/trace.go:171","msg":"trace[1890543524] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17941; }","duration":"182.952339ms","start":"2026-08-31T08:04:54.685098Z","end":"2026-08-31T08:04:54.86805Z","steps":["trace[1890543524] 'range keys from in-memory index tree' (duration: 182.625421ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:04:54.868283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.97254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-31T08:04:54.868334Z","caller":"traceutil/trace.go:171","msg":"trace[129147763] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:17941; }","duration":"170.030771ms","start":"2026-08-31T08:04:54.69829Z","end":"2026-08-31T08:04:54.868321Z","steps":["trace[129147763] 'count revisions from in-memory index tree' (duration: 169.910399ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:04:55.168346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.624444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-31T08:04:55.168386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.11917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-08-31T08:04:55.168433Z","caller":"traceutil/trace.go:171","msg":"trace[1606205787] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:17943; }","duration":"198.771408ms","start":"2026-08-31T08:04:54.969641Z","end":"2026-08-31T08:04:55.168412Z","steps":["trace[1606205787] 'count revisions from in-memory index tree' (duration: 198.528672ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:04:55.168455Z","caller":"traceutil/trace.go:171","msg":"trace[1578168342] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:17943; }","duration":"147.200722ms","start":"2026-08-31T08:04:55.021238Z","end":"2026-08-31T08:04:55.168439Z","steps":["trace[1578168342] 'range keys from in-memory index tree' (duration: 146.929926ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:04:55.168807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.031469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-31T08:04:55.168911Z","caller":"traceutil/trace.go:171","msg":"trace[1794323449] linearizableReadLoop","detail":"{readStateIndex:18566; appliedIndex:18565; }","duration":"126.287439ms","start":"2026-08-31T08:04:55.04259Z","end":"2026-08-31T08:04:55.168877Z","steps":["trace[1794323449] 'read index received' (duration: 38.671µs)","trace[1794323449] 'applied index is now lower than readState.Index' (duration: 126.247108ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T08:04:55.169079Z","caller":"traceutil/trace.go:171","msg":"trace[2133759347] transaction","detail":"{read_only:false; response_revision:17944; number_of_response:1; }","duration":"126.502124ms","start":"2026-08-31T08:04:55.042553Z","end":"2026-08-31T08:04:55.169055Z","steps":["trace[2133759347] 'process raft request' (duration: 75.566874ms)","trace[2133759347] 'compare' (duration: 50.225633ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T08:04:55.169124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.531495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-31T08:04:55.169201Z","caller":"traceutil/trace.go:171","msg":"trace[1855377337] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:17944; }","duration":"126.654788ms","start":"2026-08-31T08:04:55.042511Z","end":"2026-08-31T08:04:55.169166Z","steps":["trace[1855377337] 'agreement among raft nodes before linearized reading' (duration: 126.543815ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:04:55.169358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.612206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-wnpfg\" ","response":"range_response_count:1 size:9709"} {"level":"info","ts":"2026-08-31T08:04:55.169426Z","caller":"traceutil/trace.go:171","msg":"trace[1109750368] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17943; }","duration":"178.185183ms","start":"2026-08-31T08:04:54.990699Z","end":"2026-08-31T08:04:55.168884Z","steps":["trace[1109750368] 'range keys from in-memory index tree' (duration: 177.754883ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:04:55.169476Z","caller":"traceutil/trace.go:171","msg":"trace[1635996174] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-wnpfg; range_end:; response_count:1; response_revision:17944; }","duration":"106.788091ms","start":"2026-08-31T08:04:55.06266Z","end":"2026-08-31T08:04:55.169448Z","steps":["trace[1635996174] 'agreement among raft nodes before linearized reading' (duration: 106.474303ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:04:55.330892Z","caller":"traceutil/trace.go:171","msg":"trace[2040322251] linearizableReadLoop","detail":"{readStateIndex:18568; appliedIndex:18566; }","duration":"154.647735ms","start":"2026-08-31T08:04:55.176219Z","end":"2026-08-31T08:04:55.330867Z","steps":["trace[2040322251] 'read index received' (duration: 92.148231ms)","trace[2040322251] 'applied index is now lower than readState.Index' (duration: 62.498384ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T08:04:55.330932Z","caller":"traceutil/trace.go:171","msg":"trace[771307144] transaction","detail":"{read_only:false; response_revision:17945; number_of_response:1; }","duration":"158.638073ms","start":"2026-08-31T08:04:55.172251Z","end":"2026-08-31T08:04:55.330889Z","steps":["trace[771307144] 'process raft request' (duration: 96.085948ms)","trace[771307144] 'compare' (duration: 62.234307ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T08:04:55.331122Z","caller":"traceutil/trace.go:171","msg":"trace[1867893176] transaction","detail":"{read_only:false; response_revision:17946; number_of_response:1; }","duration":"158.11327ms","start":"2026-08-31T08:04:55.172966Z","end":"2026-08-31T08:04:55.331079Z","steps":["trace[1867893176] 'process raft request' (duration: 157.807093ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:04:55.331835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.591718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:90 size:899204"} {"level":"info","ts":"2026-08-31T08:04:55.331893Z","caller":"traceutil/trace.go:171","msg":"trace[468140922] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:90; response_revision:17946; }","duration":"155.711812ms","start":"2026-08-31T08:04:55.176167Z","end":"2026-08-31T08:04:55.331879Z","steps":["trace[468140922] 'agreement among raft nodes before linearized reading' (duration: 154.79703ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:04:55.578401Z","caller":"traceutil/trace.go:171","msg":"trace[1638110093] transaction","detail":"{read_only:false; response_revision:17947; number_of_response:1; }","duration":"110.168234ms","start":"2026-08-31T08:04:55.468197Z","end":"2026-08-31T08:04:55.578366Z","steps":["trace[1638110093] 'process raft request' (duration: 18.666328ms)","trace[1638110093] 'compare' (duration: 91.097176ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T08:04:55.753479Z","caller":"traceutil/trace.go:171","msg":"trace[994918814] transaction","detail":"{read_only:false; response_revision:17948; number_of_response:1; }","duration":"107.57802ms","start":"2026-08-31T08:04:55.645865Z","end":"2026-08-31T08:04:55.753443Z","steps":["trace[994918814] 'process raft request' (duration: 68.968451ms)","trace[994918814] 'compare' (duration: 38.473195ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T08:04:56.709967Z","caller":"traceutil/trace.go:171","msg":"trace[20946664] linearizableReadLoop","detail":"{readStateIndex:18574; appliedIndex:18573; }","duration":"165.696967ms","start":"2026-08-31T08:04:56.544246Z","end":"2026-08-31T08:04:56.709943Z","steps":["trace[20946664] 'read index received' (duration: 165.475522ms)","trace[20946664] 'applied index is now lower than readState.Index' (duration: 220.385µs)"],"step_count":2} {"level":"info","ts":"2026-08-31T08:04:56.710108Z","caller":"traceutil/trace.go:171","msg":"trace[550281302] transaction","detail":"{read_only:false; response_revision:17951; number_of_response:1; }","duration":"165.994754ms","start":"2026-08-31T08:04:56.5441Z","end":"2026-08-31T08:04:56.710094Z","steps":["trace[550281302] 'process raft request' (duration: 165.649816ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:04:56.710414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.147838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-31T08:04:56.710463Z","caller":"traceutil/trace.go:171","msg":"trace[1534310586] range","detail":"{range_begin:/registry/jobs/openstack/neutron-ks-user; range_end:; response_count:0; response_revision:17951; }","duration":"166.250151ms","start":"2026-08-31T08:04:56.544199Z","end":"2026-08-31T08:04:56.710449Z","steps":["trace[1534310586] 'agreement among raft nodes before linearized reading' (duration: 166.157178ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:04:56.71056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.122498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-31T08:04:56.710894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.892691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-31T08:04:56.710913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.206158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"warn","ts":"2026-08-31T08:04:56.710928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.10502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-l3-agent-default-rx4zt\" ","response":"range_response_count:1 size:18589"} {"level":"info","ts":"2026-08-31T08:04:56.710956Z","caller":"traceutil/trace.go:171","msg":"trace[1026751964] range","detail":"{range_begin:/registry/jobs/openstack/neutron-rabbit-init; range_end:; response_count:0; response_revision:17951; }","duration":"165.981283ms","start":"2026-08-31T08:04:56.544958Z","end":"2026-08-31T08:04:56.710939Z","steps":["trace[1026751964] 'agreement among raft nodes before linearized reading' (duration: 165.888271ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:04:56.710978Z","caller":"traceutil/trace.go:171","msg":"trace[804490456] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:17951; }","duration":"166.300231ms","start":"2026-08-31T08:04:56.544661Z","end":"2026-08-31T08:04:56.710961Z","steps":["trace[804490456] 'agreement among raft nodes before linearized reading' (duration: 166.174218ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:04:56.710977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.335731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"info","ts":"2026-08-31T08:04:56.710992Z","caller":"traceutil/trace.go:171","msg":"trace[1911131275] range","detail":"{range_begin:/registry/pods/openstack/neutron-l3-agent-default-rx4zt; range_end:; response_count:1; response_revision:17951; }","duration":"136.187422ms","start":"2026-08-31T08:04:56.574787Z","end":"2026-08-31T08:04:56.710975Z","steps":["trace[1911131275] 'agreement among raft nodes before linearized reading' (duration: 136.019878ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:04:56.711035Z","caller":"traceutil/trace.go:171","msg":"trace[1332426398] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:17951; }","duration":"166.410944ms","start":"2026-08-31T08:04:56.544608Z","end":"2026-08-31T08:04:56.711019Z","steps":["trace[1332426398] 'agreement among raft nodes before linearized reading' (duration: 166.27475ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:04:56.710895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.79189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-b9lb7\" ","response":"range_response_count:1 size:22018"} {"level":"info","ts":"2026-08-31T08:04:56.711246Z","caller":"traceutil/trace.go:171","msg":"trace[855553897] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-b9lb7; range_end:; response_count:1; response_revision:17951; }","duration":"140.17361ms","start":"2026-08-31T08:04:56.571058Z","end":"2026-08-31T08:04:56.711231Z","steps":["trace[855553897] 'agreement among raft nodes before linearized reading' (duration: 139.767129ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:04:56.710762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.525257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-db-sync\" ","response":"range_response_count:1 size:5236"} {"level":"info","ts":"2026-08-31T08:04:56.711445Z","caller":"traceutil/trace.go:171","msg":"trace[1276407380] range","detail":"{range_begin:/registry/jobs/openstack/neutron-db-sync; range_end:; response_count:1; response_revision:17951; }","duration":"165.217944ms","start":"2026-08-31T08:04:56.546193Z","end":"2026-08-31T08:04:56.711411Z","steps":["trace[1276407380] 'agreement among raft nodes before linearized reading' (duration: 164.23183ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:04:56.710632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.788788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-31T08:04:56.711521Z","caller":"traceutil/trace.go:171","msg":"trace[1659936033] range","detail":"{range_begin:/registry/jobs/openstack/neutron-rabbit-init; range_end:; response_count:0; response_revision:17951; }","duration":"166.736952ms","start":"2026-08-31T08:04:56.544767Z","end":"2026-08-31T08:04:56.711504Z","steps":["trace[1659936033] 'agreement among raft nodes before linearized reading' (duration: 165.735357ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:04:56.710733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.957793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-31T08:04:56.711618Z","caller":"traceutil/trace.go:171","msg":"trace[1417374828] range","detail":"{range_begin:/registry/jobs/openstack/neutron-ks-endpoints; range_end:; response_count:0; response_revision:17951; }","duration":"166.880445ms","start":"2026-08-31T08:04:56.544716Z","end":"2026-08-31T08:04:56.711596Z","steps":["trace[1417374828] 'agreement among raft nodes before linearized reading' (duration: 165.916971ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:04:56.710781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.526608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-metadata-agent-default-h2d4j\" ","response":"range_response_count:1 size:14195"} {"level":"info","ts":"2026-08-31T08:04:56.711Z","caller":"traceutil/trace.go:171","msg":"trace[1374256612] range","detail":"{range_begin:/registry/jobs/openstack/neutron-rabbit-init; range_end:; response_count:0; response_revision:17951; }","duration":"166.619779ms","start":"2026-08-31T08:04:56.544314Z","end":"2026-08-31T08:04:56.710934Z","steps":["trace[1374256612] 'agreement among raft nodes before linearized reading' (duration: 166.083736ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:04:56.711707Z","caller":"traceutil/trace.go:171","msg":"trace[110516452] range","detail":"{range_begin:/registry/pods/openstack/neutron-metadata-agent-default-h2d4j; range_end:; response_count:1; response_revision:17951; }","duration":"132.501192ms","start":"2026-08-31T08:04:56.579188Z","end":"2026-08-31T08:04:56.711689Z","steps":["trace[110516452] 'agreement among raft nodes before linearized reading' (duration: 131.434546ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:04:56.710837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.069121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-31T08:04:56.712046Z","caller":"traceutil/trace.go:171","msg":"trace[1438276821] range","detail":"{range_begin:/registry/jobs/openstack/neutron-rabbit-init; range_end:; response_count:0; response_revision:17951; }","duration":"166.277081ms","start":"2026-08-31T08:04:56.545734Z","end":"2026-08-31T08:04:56.712011Z","steps":["trace[1438276821] 'agreement among raft nodes before linearized reading' (duration: 165.03419ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:04:56.710868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.098765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"info","ts":"2026-08-31T08:04:56.712193Z","caller":"traceutil/trace.go:171","msg":"trace[1316219193] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:17951; }","duration":"167.432939ms","start":"2026-08-31T08:04:56.544747Z","end":"2026-08-31T08:04:56.71218Z","steps":["trace[1316219193] 'agreement among raft nodes before linearized reading' (duration: 166.035814ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:04:56.71089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.846615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"info","ts":"2026-08-31T08:04:56.712273Z","caller":"traceutil/trace.go:171","msg":"trace[2001158125] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:17951; }","duration":"166.236498ms","start":"2026-08-31T08:04:56.546012Z","end":"2026-08-31T08:04:56.712249Z","steps":["trace[2001158125] 'agreement among raft nodes before linearized reading' (duration: 164.722031ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:04:56.710826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.681467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-q6kpp\" ","response":"range_response_count:1 size:20562"} {"level":"info","ts":"2026-08-31T08:04:56.712519Z","caller":"traceutil/trace.go:171","msg":"trace[1402974646] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-q6kpp; range_end:; response_count:1; response_revision:17951; }","duration":"132.40914ms","start":"2026-08-31T08:04:56.58009Z","end":"2026-08-31T08:04:56.712499Z","steps":["trace[1402974646] 'agreement among raft nodes before linearized reading' (duration: 130.545274ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:04:57.019451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.323187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-31T08:04:57.01955Z","caller":"traceutil/trace.go:171","msg":"trace[178134508] range","detail":"{range_begin:/registry/jobs/openstack/neutron-ks-endpoints; range_end:; response_count:0; response_revision:17951; }","duration":"145.46742ms","start":"2026-08-31T08:04:56.874047Z","end":"2026-08-31T08:04:57.019515Z","steps":["trace[178134508] 'range keys from in-memory index tree' (duration: 145.218394ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:04:57.019716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.479159ms","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-08-31T08:04:57.019804Z","caller":"traceutil/trace.go:171","msg":"trace[568321873] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:17951; }","duration":"125.609853ms","start":"2026-08-31T08:04:56.894173Z","end":"2026-08-31T08:04:57.019783Z","steps":["trace[568321873] 'range keys from in-memory index tree' (duration: 125.345886ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:04:57.020006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.673285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-db-sync\" ","response":"range_response_count:1 size:5236"} {"level":"info","ts":"2026-08-31T08:04:57.020104Z","caller":"traceutil/trace.go:171","msg":"trace[1560187421] range","detail":"{range_begin:/registry/jobs/openstack/neutron-db-sync; range_end:; response_count:1; response_revision:17951; }","duration":"145.86752ms","start":"2026-08-31T08:04:56.874213Z","end":"2026-08-31T08:04:57.020081Z","steps":["trace[1560187421] 'range keys from in-memory index tree' (duration: 145.108972ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:04:57.584395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.49492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-wnpfg\" ","response":"range_response_count:1 size:9709"} {"level":"info","ts":"2026-08-31T08:04:57.584466Z","caller":"traceutil/trace.go:171","msg":"trace[979156053] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-wnpfg; range_end:; response_count:1; response_revision:17954; }","duration":"233.572961ms","start":"2026-08-31T08:04:57.350877Z","end":"2026-08-31T08:04:57.58445Z","steps":["trace[979156053] 'range keys from in-memory index tree' (duration: 233.335236ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:04:57.584448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.786209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-31T08:04:57.584561Z","caller":"traceutil/trace.go:171","msg":"trace[213152972] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17954; }","duration":"249.926513ms","start":"2026-08-31T08:04:57.334592Z","end":"2026-08-31T08:04:57.584518Z","steps":["trace[213152972] 'range keys from in-memory index tree' (duration: 249.547054ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:04:57.584395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.065903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-08-31T08:04:57.584663Z","caller":"traceutil/trace.go:171","msg":"trace[196928561] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:17954; }","duration":"249.369609ms","start":"2026-08-31T08:04:57.335283Z","end":"2026-08-31T08:04:57.584652Z","steps":["trace[196928561] 'range keys from in-memory index tree' (duration: 248.923089ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:04:57.839643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.748794ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-31T08:04:57.839834Z","caller":"traceutil/trace.go:171","msg":"trace[908490489] linearizableReadLoop","detail":"{readStateIndex:18580; appliedIndex:18577; }","duration":"250.894177ms","start":"2026-08-31T08:04:57.588922Z","end":"2026-08-31T08:04:57.839816Z","steps":["trace[908490489] 'read index received' (duration: 25.109015ms)","trace[908490489] 'applied index is now lower than readState.Index' (duration: 225.784102ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T08:04:57.839951Z","caller":"traceutil/trace.go:171","msg":"trace[639649073] transaction","detail":"{read_only:false; response_revision:17956; number_of_response:1; }","duration":"251.737418ms","start":"2026-08-31T08:04:57.588196Z","end":"2026-08-31T08:04:57.839933Z","steps":["trace[639649073] 'process raft request' (duration: 119.561953ms)","trace[639649073] 'compare' (duration: 131.629841ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T08:04:57.840101Z","caller":"traceutil/trace.go:171","msg":"trace[1990010580] transaction","detail":"{read_only:false; response_revision:17957; number_of_response:1; }","duration":"251.871861ms","start":"2026-08-31T08:04:57.588202Z","end":"2026-08-31T08:04:57.840074Z","steps":["trace[1990010580] 'process raft request' (duration: 251.543643ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:04:57.840204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.086338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-31T08:04:57.841013Z","caller":"traceutil/trace.go:171","msg":"trace[1263947312] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:17957; }","duration":"129.929029ms","start":"2026-08-31T08:04:57.711061Z","end":"2026-08-31T08:04:57.84099Z","steps":["trace[1263947312] 'agreement among raft nodes before linearized reading' (duration: 129.027436ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:04:57.842044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.08808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:90 size:899204"} {"level":"info","ts":"2026-08-31T08:04:57.842115Z","caller":"traceutil/trace.go:171","msg":"trace[918226153] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:90; response_revision:17957; }","duration":"253.192002ms","start":"2026-08-31T08:04:57.588908Z","end":"2026-08-31T08:04:57.8421Z","steps":["trace[918226153] 'agreement among raft nodes before linearized reading' (duration: 251.141242ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:04:58.100258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.291444ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-31T08:04:58.100454Z","caller":"traceutil/trace.go:171","msg":"trace[677667143] transaction","detail":"{read_only:false; response_revision:17958; number_of_response:1; }","duration":"172.161545ms","start":"2026-08-31T08:04:57.928265Z","end":"2026-08-31T08:04:58.100426Z","steps":["trace[677667143] 'process raft request' (duration: 33.619195ms)","trace[677667143] 'compare' (duration: 138.179192ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T08:04:58.463976Z","caller":"traceutil/trace.go:171","msg":"trace[1230886316] transaction","detail":"{read_only:false; response_revision:17959; number_of_response:1; }","duration":"104.065213ms","start":"2026-08-31T08:04:58.359878Z","end":"2026-08-31T08:04:58.463944Z","steps":["trace[1230886316] 'process raft request' (duration: 103.620073ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:04:58.850153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.792742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-metadata-agent-default-h2d4j\" ","response":"range_response_count:1 size:14195"} {"level":"info","ts":"2026-08-31T08:04:58.850255Z","caller":"traceutil/trace.go:171","msg":"trace[37259373] range","detail":"{range_begin:/registry/pods/openstack/neutron-metadata-agent-default-h2d4j; range_end:; response_count:1; response_revision:17960; }","duration":"102.937136ms","start":"2026-08-31T08:04:58.747291Z","end":"2026-08-31T08:04:58.850228Z","steps":["trace[37259373] 'range keys from in-memory index tree' (duration: 102.581857ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:04:59.182501Z","caller":"traceutil/trace.go:171","msg":"trace[114137861] linearizableReadLoop","detail":"{readStateIndex:18584; appliedIndex:18583; }","duration":"100.696761ms","start":"2026-08-31T08:04:59.081781Z","end":"2026-08-31T08:04:59.182478Z","steps":["trace[114137861] 'read index received' (duration: 100.448014ms)","trace[114137861] 'applied index is now lower than readState.Index' (duration: 247.867µs)"],"step_count":2} {"level":"info","ts":"2026-08-31T08:04:59.18273Z","caller":"traceutil/trace.go:171","msg":"trace[252429393] transaction","detail":"{read_only:false; response_revision:17961; number_of_response:1; }","duration":"112.929362ms","start":"2026-08-31T08:04:59.069768Z","end":"2026-08-31T08:04:59.182697Z","steps":["trace[252429393] 'process raft request' (duration: 112.528972ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:04:59.182758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.956877ms","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-08-31T08:04:59.182811Z","caller":"traceutil/trace.go:171","msg":"trace[360144728] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:17961; }","duration":"101.04647ms","start":"2026-08-31T08:04:59.081747Z","end":"2026-08-31T08:04:59.182794Z","steps":["trace[360144728] 'agreement among raft nodes before linearized reading' (duration: 100.857975ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:04:59.366998Z","caller":"traceutil/trace.go:171","msg":"trace[1255022533] transaction","detail":"{read_only:false; response_revision:17962; number_of_response:1; }","duration":"178.466559ms","start":"2026-08-31T08:04:59.188497Z","end":"2026-08-31T08:04:59.366963Z","steps":["trace[1255022533] 'process raft request' (duration: 178.213243ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:04:59.445047Z","caller":"traceutil/trace.go:171","msg":"trace[981922803] transaction","detail":"{read_only:false; response_revision:17963; number_of_response:1; }","duration":"171.584351ms","start":"2026-08-31T08:04:59.273434Z","end":"2026-08-31T08:04:59.445018Z","steps":["trace[981922803] 'process raft request' (duration: 171.403607ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:05:00.420772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"717.375844ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-31T08:05:00.421072Z","caller":"traceutil/trace.go:171","msg":"trace[1221301261] linearizableReadLoop","detail":"{readStateIndex:18589; appliedIndex:18587; }","duration":"576.273092ms","start":"2026-08-31T08:04:59.844777Z","end":"2026-08-31T08:05:00.42105Z","steps":["trace[1221301261] 'read index received' (duration: 60.302µs)","trace[1221301261] 'applied index is now lower than readState.Index' (duration: 576.21155ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T08:05:00.421149Z","caller":"traceutil/trace.go:171","msg":"trace[995260958] transaction","detail":"{read_only:false; response_revision:17966; number_of_response:1; }","duration":"706.112438ms","start":"2026-08-31T08:04:59.714999Z","end":"2026-08-31T08:05:00.421111Z","steps":["trace[995260958] 'process raft request' (duration: 705.927394ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:05:00.421159Z","caller":"traceutil/trace.go:171","msg":"trace[1077396532] transaction","detail":"{read_only:false; response_revision:17965; number_of_response:1; }","duration":"801.501178ms","start":"2026-08-31T08:04:59.619615Z","end":"2026-08-31T08:05:00.421116Z","steps":["trace[1077396532] 'process raft request' (duration: 83.660393ms)","trace[1077396532] 'compare' (duration: 716.76033ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T08:05:00.421299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:04:59.714974Z","time spent":"706.241831ms","remote":"127.0.0.1:41158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-31T08:05:00.421325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:04:59.619592Z","time spent":"801.634032ms","remote":"127.0.0.1:41158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-31T08:05:00.421311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.508627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-08-31T08:05:00.421365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.83268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-31T08:05:00.421417Z","caller":"traceutil/trace.go:171","msg":"trace[1115935138] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17966; }","duration":"522.935154ms","start":"2026-08-31T08:04:59.898464Z","end":"2026-08-31T08:05:00.421399Z","steps":["trace[1115935138] 'agreement among raft nodes before linearized reading' (duration: 522.851941ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:05:00.421407Z","caller":"traceutil/trace.go:171","msg":"trace[1429801808] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17966; }","duration":"576.658192ms","start":"2026-08-31T08:04:59.844721Z","end":"2026-08-31T08:05:00.421379Z","steps":["trace[1429801808] 'agreement among raft nodes before linearized reading' (duration: 576.434785ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:05:00.421324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.484587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"warn","ts":"2026-08-31T08:05:00.421436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.780774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cronjob-controller\" ","response":"range_response_count:1 size:202"} {"level":"warn","ts":"2026-08-31T08:05:00.421423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.016428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-wnpfg\" ","response":"range_response_count:1 size:9709"} {"level":"warn","ts":"2026-08-31T08:05:00.421443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.861717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-08-31T08:05:00.421446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.595457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-b9lb7\" ","response":"range_response_count:1 size:22018"} {"level":"info","ts":"2026-08-31T08:05:00.42148Z","caller":"traceutil/trace.go:171","msg":"trace[1031440982] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cronjob-controller; range_end:; response_count:1; response_revision:17966; }","duration":"318.877866ms","start":"2026-08-31T08:05:00.102589Z","end":"2026-08-31T08:05:00.421467Z","steps":["trace[1031440982] 'agreement among raft nodes before linearized reading' (duration: 318.707181ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:05:00.421473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:04:59.844697Z","time spent":"576.760525ms","remote":"127.0.0.1:41158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-31T08:05:00.421485Z","caller":"traceutil/trace.go:171","msg":"trace[1925029297] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:17966; }","duration":"576.687343ms","start":"2026-08-31T08:04:59.844779Z","end":"2026-08-31T08:05:00.421466Z","steps":["trace[1925029297] 'agreement among raft nodes before linearized reading' (duration: 576.401205ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:05:00.421511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:05:00.102561Z","time spent":"318.945938ms","remote":"127.0.0.1:41074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":224,"request content":"key:\"/registry/serviceaccounts/kube-system/cronjob-controller\" "} {"level":"info","ts":"2026-08-31T08:05:00.421504Z","caller":"traceutil/trace.go:171","msg":"trace[950124704] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-wnpfg; range_end:; response_count:1; response_revision:17966; }","duration":"561.13055ms","start":"2026-08-31T08:04:59.860352Z","end":"2026-08-31T08:05:00.421483Z","steps":["trace[950124704] 'agreement among raft nodes before linearized reading' (duration: 560.882763ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:05:00.421517Z","caller":"traceutil/trace.go:171","msg":"trace[722638779] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:17966; }","duration":"317.00644ms","start":"2026-08-31T08:05:00.104488Z","end":"2026-08-31T08:05:00.421495Z","steps":["trace[722638779] 'agreement among raft nodes before linearized reading' (duration: 316.712812ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:05:00.421524Z","caller":"traceutil/trace.go:171","msg":"trace[1349352749] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-b9lb7; range_end:; response_count:1; response_revision:17966; }","duration":"291.71922ms","start":"2026-08-31T08:05:00.129781Z","end":"2026-08-31T08:05:00.421501Z","steps":["trace[1349352749] 'agreement among raft nodes before linearized reading' (duration: 291.505134ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:05:00.421573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:04:59.844754Z","time spent":"576.768524ms","remote":"127.0.0.1:41050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":725,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"warn","ts":"2026-08-31T08:05:00.421581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:04:59.860335Z","time spent":"561.232773ms","remote":"127.0.0.1:41064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9731,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-wnpfg\" "} {"level":"warn","ts":"2026-08-31T08:05:00.421588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:05:00.104463Z","time spent":"317.110472ms","remote":"127.0.0.1:41158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-31T08:05:00.4216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.221822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-08-31T08:05:00.421459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:04:59.898446Z","time spent":"523.004485ms","remote":"127.0.0.1:40898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-31T08:05:00.421654Z","caller":"traceutil/trace.go:171","msg":"trace[267942018] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:17966; }","duration":"576.288803ms","start":"2026-08-31T08:04:59.845352Z","end":"2026-08-31T08:05:00.42164Z","steps":["trace[267942018] 'agreement among raft nodes before linearized reading' (duration: 576.126979ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:05:00.421452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.647464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-08-31T08:05:00.421702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:04:59.845345Z","time spent":"576.346744ms","remote":"127.0.0.1:41158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-31T08:05:00.421717Z","caller":"traceutil/trace.go:171","msg":"trace[2008576506] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:17966; }","duration":"561.947131ms","start":"2026-08-31T08:04:59.859751Z","end":"2026-08-31T08:05:00.421698Z","steps":["trace[2008576506] 'agreement among raft nodes before linearized reading' (duration: 561.604512ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:05:00.421745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.160454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-b2wcm\" ","response":"range_response_count:1 size:6448"} {"level":"info","ts":"2026-08-31T08:05:00.42178Z","caller":"traceutil/trace.go:171","msg":"trace[1553516562] range","detail":"{range_begin:/registry/pods/openstack/keepalived-b2wcm; range_end:; response_count:1; response_revision:17966; }","duration":"295.258156ms","start":"2026-08-31T08:05:00.12651Z","end":"2026-08-31T08:05:00.421768Z","steps":["trace[1553516562] 'agreement among raft nodes before linearized reading' (duration: 295.144653ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:05:00.421786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:04:59.85973Z","time spent":"562.019253ms","remote":"127.0.0.1:41050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":74,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-08-31T08:05:00.928574Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13331957091234995044,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-31T08:05:01.021737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.964893ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-31T08:05:01.021907Z","caller":"traceutil/trace.go:171","msg":"trace[2045056668] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:17966; }","duration":"197.164127ms","start":"2026-08-31T08:05:00.824721Z","end":"2026-08-31T08:05:01.021885Z","steps":["trace[2045056668] 'range keys from in-memory index tree' (duration: 196.946022ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:05:01.121183Z","caller":"traceutil/trace.go:171","msg":"trace[1820734634] transaction","detail":"{read_only:false; response_revision:17967; number_of_response:1; }","duration":"695.577359ms","start":"2026-08-31T08:05:00.425583Z","end":"2026-08-31T08:05:01.12116Z","steps":["trace[1820734634] 'process raft request' (duration: 695.425555ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:05:01.121325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:05:00.42552Z","time spent":"695.732094ms","remote":"127.0.0.1:41158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-31T08:05:01.636818Z","caller":"traceutil/trace.go:171","msg":"trace[2118376410] transaction","detail":"{read_only:false; response_revision:17968; number_of_response:1; }","duration":"1.209701536s","start":"2026-08-31T08:05:00.427089Z","end":"2026-08-31T08:05:01.636791Z","steps":["trace[2118376410] 'process raft request' (duration: 1.209418089s)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:05:01.636973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:05:00.427076Z","time spent":"1.209815489s","remote":"127.0.0.1:41158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-31T08:05:01.637194Z","caller":"traceutil/trace.go:171","msg":"trace[1711368861] transaction","detail":"{read_only:false; response_revision:17969; number_of_response:1; }","duration":"1.210071525s","start":"2026-08-31T08:05:00.427085Z","end":"2026-08-31T08:05:01.637156Z","steps":["trace[1711368861] 'process raft request' (duration: 1.209663375s)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:05:01.637251Z","caller":"traceutil/trace.go:171","msg":"trace[1390400722] transaction","detail":"{read_only:false; response_revision:17970; number_of_response:1; }","duration":"1.203209667s","start":"2026-08-31T08:05:00.434006Z","end":"2026-08-31T08:05:01.637216Z","steps":["trace[1390400722] 'process raft request' (duration: 1.203029003s)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:05:01.637376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:05:00.427058Z","time spent":"1.210218259s","remote":"127.0.0.1:41158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-31T08:05:01.637407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:05:00.433992Z","time spent":"1.20334522s","remote":"127.0.0.1:41126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5088,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-31T08:05:01.637285Z","caller":"traceutil/trace.go:171","msg":"trace[1926438770] transaction","detail":"{read_only:false; response_revision:17972; number_of_response:1; }","duration":"910.283039ms","start":"2026-08-31T08:05:00.726982Z","end":"2026-08-31T08:05:01.637265Z","steps":["trace[1926438770] 'process raft request' (duration: 910.215887ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:05:01.637631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:05:00.726966Z","time spent":"910.595446ms","remote":"127.0.0.1:41158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-31T08:05:01.637364Z","caller":"traceutil/trace.go:171","msg":"trace[1055123372] transaction","detail":"{read_only:false; response_revision:17971; number_of_response:1; }","duration":"1.167744507s","start":"2026-08-31T08:05:00.469588Z","end":"2026-08-31T08:05:01.637333Z","steps":["trace[1055123372] 'process raft request' (duration: 1.167561443s)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:05:01.637775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:05:00.469528Z","time spent":"1.168171457s","remote":"127.0.0.1:41158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-31T08:05:01.726394Z","caller":"traceutil/trace.go:171","msg":"trace[101580561] linearizableReadLoop","detail":"{readStateIndex:18595; appliedIndex:18590; }","duration":"1.29944931s","start":"2026-08-31T08:05:00.42692Z","end":"2026-08-31T08:05:01.726369Z","steps":["trace[101580561] 'read index received' (duration: 694.631577ms)","trace[101580561] 'applied index is now lower than readState.Index' (duration: 604.815462ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T08:05:01.726395Z","caller":"traceutil/trace.go:171","msg":"trace[1235891157] transaction","detail":"{read_only:false; response_revision:17973; number_of_response:1; }","duration":"275.04507ms","start":"2026-08-31T08:05:01.451322Z","end":"2026-08-31T08:05:01.726367Z","steps":["trace[1235891157] 'process raft request' (duration: 274.884436ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:05:01.811443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.384500816s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:90 size:899204"} {"level":"info","ts":"2026-08-31T08:05:01.811527Z","caller":"traceutil/trace.go:171","msg":"trace[995816941] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:90; response_revision:17973; }","duration":"1.384625089s","start":"2026-08-31T08:05:00.426883Z","end":"2026-08-31T08:05:01.811508Z","steps":["trace[995816941] 'agreement among raft nodes before linearized reading' (duration: 1.299590733s)","trace[995816941] 'range keys from in-memory index tree' (duration: 84.096684ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T08:05:01.811592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:05:00.426869Z","time spent":"1.384710631s","remote":"127.0.0.1:41064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":90,"response size":899226,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-08-31T08:05:01.81259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.085679852s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-31T08:05:01.812744Z","caller":"traceutil/trace.go:171","msg":"trace[2075075799] range","detail":"{range_begin:/registry/jobs/openstack/neutron-rabbit-init; range_end:; response_count:0; response_revision:17975; }","duration":"1.085816506s","start":"2026-08-31T08:05:00.726866Z","end":"2026-08-31T08:05:01.812682Z","steps":["trace[2075075799] 'agreement among raft nodes before linearized reading' (duration: 1.085596761s)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:05:01.812849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:05:00.726854Z","time spent":"1.085941109s","remote":"127.0.0.1:41126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/neutron-rabbit-init\" "} {"level":"warn","ts":"2026-08-31T08:05:01.813069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.418432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-31T08:05:01.813131Z","caller":"traceutil/trace.go:171","msg":"trace[1919148668] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17975; }","duration":"626.531496ms","start":"2026-08-31T08:05:01.186585Z","end":"2026-08-31T08:05:01.813116Z","steps":["trace[1919148668] 'agreement among raft nodes before linearized reading' (duration: 626.371961ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:05:01.813179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:05:01.186561Z","time spent":"626.605317ms","remote":"127.0.0.1:41158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-31T08:05:01.813286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.38263332s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:90 size:899204"} {"level":"info","ts":"2026-08-31T08:05:01.813367Z","caller":"traceutil/trace.go:171","msg":"trace[1207466265] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:90; response_revision:17975; }","duration":"1.382754774s","start":"2026-08-31T08:05:00.430592Z","end":"2026-08-31T08:05:01.813347Z","steps":["trace[1207466265] 'agreement among raft nodes before linearized reading' (duration: 1.381728418s)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:05:01.813377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.091997938s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"warn","ts":"2026-08-31T08:05:01.813417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.092842669s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-31T08:05:01.813427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:05:00.430575Z","time spent":"1.382835655s","remote":"127.0.0.1:41064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":90,"response size":899226,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-08-31T08:05:01.813454Z","caller":"traceutil/trace.go:171","msg":"trace[1656874831] range","detail":"{range_begin:/registry/jobs/openstack/neutron-ks-user; range_end:; response_count:0; response_revision:17975; }","duration":"1.092929851s","start":"2026-08-31T08:05:00.720509Z","end":"2026-08-31T08:05:01.813439Z","steps":["trace[1656874831] 'agreement among raft nodes before linearized reading' (duration: 1.09289007s)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:05:01.813484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:05:00.720493Z","time spent":"1.092984073s","remote":"127.0.0.1:41126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/neutron-ks-user\" "} {"level":"info","ts":"2026-08-31T08:05:01.813513Z","caller":"traceutil/trace.go:171","msg":"trace[1430515027] transaction","detail":"{read_only:false; response_revision:17974; number_of_response:1; }","duration":"170.150165ms","start":"2026-08-31T08:05:01.643345Z","end":"2026-08-31T08:05:01.813495Z","steps":["trace[1430515027] 'process raft request' (duration: 168.781512ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:05:01.813465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.092561623s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-31T08:05:01.813511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"782.904832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-db-sync\" ","response":"range_response_count:1 size:5236"} {"level":"warn","ts":"2026-08-31T08:05:01.813639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.087910307s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-31T08:05:01.813682Z","caller":"traceutil/trace.go:171","msg":"trace[1833331628] range","detail":"{range_begin:/registry/jobs/openstack/neutron-rabbit-init; range_end:; response_count:0; response_revision:17975; }","duration":"1.087973049s","start":"2026-08-31T08:05:00.725696Z","end":"2026-08-31T08:05:01.813669Z","steps":["trace[1833331628] 'agreement among raft nodes before linearized reading' (duration: 1.087912198s)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:05:01.813682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.1125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/openstack/\" range_end:\"/registry/resourcequotas/openstack0\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-31T08:05:01.813732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.092656524s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-31T08:05:01.813805Z","caller":"traceutil/trace.go:171","msg":"trace[1074897337] range","detail":"{range_begin:/registry/jobs/openstack/neutron-ks-user; range_end:; response_count:0; response_revision:17975; }","duration":"1.092760326s","start":"2026-08-31T08:05:00.721035Z","end":"2026-08-31T08:05:01.813795Z","steps":["trace[1074897337] 'agreement among raft nodes before linearized reading' (duration: 1.092644014s)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:05:01.813579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.086921103s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"warn","ts":"2026-08-31T08:05:01.813839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.110133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-31T08:05:01.813873Z","caller":"traceutil/trace.go:171","msg":"trace[1963616034] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:17975; }","duration":"1.087236511s","start":"2026-08-31T08:05:00.726626Z","end":"2026-08-31T08:05:01.813862Z","steps":["trace[1963616034] 'agreement among raft nodes before linearized reading' (duration: 1.086842892s)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:05:01.813876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.369858276s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-31T08:05:01.813709Z","caller":"traceutil/trace.go:171","msg":"trace[931973640] range","detail":"{range_begin:/registry/jobs/openstack/neutron-db-sync; range_end:; response_count:1; response_revision:17975; }","duration":"783.073297ms","start":"2026-08-31T08:05:01.030574Z","end":"2026-08-31T08:05:01.813647Z","steps":["trace[931973640] 'agreement among raft nodes before linearized reading' (duration: 782.740039ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:05:01.813925Z","caller":"traceutil/trace.go:171","msg":"trace[386314753] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17975; }","duration":"1.37001102s","start":"2026-08-31T08:05:00.443898Z","end":"2026-08-31T08:05:01.813909Z","steps":["trace[386314753] 'agreement among raft nodes before linearized reading' (duration: 1.369951609s)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:05:01.813973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:05:00.44388Z","time spent":"1.370082801s","remote":"127.0.0.1:40894","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-08-31T08:05:01.814062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.089155268s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"info","ts":"2026-08-31T08:05:01.814126Z","caller":"traceutil/trace.go:171","msg":"trace[557248199] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:17975; }","duration":"1.08924177s","start":"2026-08-31T08:05:00.72487Z","end":"2026-08-31T08:05:01.814112Z","steps":["trace[557248199] 'agreement among raft nodes before linearized reading' (duration: 1.089085896s)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:05:01.814108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"895.367963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-l3-agent-default-rx4zt\" ","response":"range_response_count:1 size:18589"} {"level":"warn","ts":"2026-08-31T08:05:01.814193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"907.035789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-dhcp-agent-default-q26rg\" ","response":"range_response_count:1 size:18564"} {"level":"warn","ts":"2026-08-31T08:05:01.814233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.089434105s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-31T08:05:01.814251Z","caller":"traceutil/trace.go:171","msg":"trace[519999823] range","detail":"{range_begin:/registry/pods/openstack/neutron-dhcp-agent-default-q26rg; range_end:; response_count:1; response_revision:17975; }","duration":"907.172203ms","start":"2026-08-31T08:05:00.907063Z","end":"2026-08-31T08:05:01.814235Z","steps":["trace[519999823] 'agreement among raft nodes before linearized reading' (duration: 906.952598ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:05:01.813442Z","caller":"traceutil/trace.go:171","msg":"trace[1784385090] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:17975; }","duration":"1.092081941s","start":"2026-08-31T08:05:00.721344Z","end":"2026-08-31T08:05:01.813426Z","steps":["trace[1784385090] 'agreement among raft nodes before linearized reading' (duration: 1.091921587s)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:05:01.814274Z","caller":"traceutil/trace.go:171","msg":"trace[2010985093] range","detail":"{range_begin:/registry/jobs/openstack/neutron-rabbit-init; range_end:; response_count:0; response_revision:17975; }","duration":"1.089502877s","start":"2026-08-31T08:05:00.72476Z","end":"2026-08-31T08:05:01.814262Z","steps":["trace[2010985093] 'agreement among raft nodes before linearized reading' (duration: 1.089444015s)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:05:01.814266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"895.816582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-q6kpp\" ","response":"range_response_count:1 size:20562"} {"level":"warn","ts":"2026-08-31T08:05:01.814294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:05:00.90704Z","time spent":"907.242364ms","remote":"127.0.0.1:41064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":18586,"request content":"key:\"/registry/pods/openstack/neutron-dhcp-agent-default-q26rg\" "} {"level":"warn","ts":"2026-08-31T08:05:01.814315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:05:00.724747Z","time spent":"1.089553447s","remote":"127.0.0.1:41126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/neutron-rabbit-init\" "} {"level":"info","ts":"2026-08-31T08:05:01.814318Z","caller":"traceutil/trace.go:171","msg":"trace[32170427] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-q6kpp; range_end:; response_count:1; response_revision:17975; }","duration":"895.869344ms","start":"2026-08-31T08:05:00.918435Z","end":"2026-08-31T08:05:01.814305Z","steps":["trace[32170427] 'agreement among raft nodes before linearized reading' (duration: 895.72883ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:05:01.814367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:05:00.918415Z","time spent":"895.940266ms","remote":"127.0.0.1:41064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":20584,"request content":"key:\"/registry/pods/openstack/libvirt-libvirt-default-q6kpp\" "} {"level":"info","ts":"2026-08-31T08:05:01.814394Z","caller":"traceutil/trace.go:171","msg":"trace[602040251] transaction","detail":"{read_only:false; response_revision:17975; number_of_response:1; }","duration":"168.495994ms","start":"2026-08-31T08:05:01.645886Z","end":"2026-08-31T08:05:01.814382Z","steps":["trace[602040251] 'process raft request' (duration: 166.366863ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:05:01.81441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"905.46785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-metadata-agent-default-h2d4j\" ","response":"range_response_count:1 size:14195"} {"level":"warn","ts":"2026-08-31T08:05:01.81418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:05:00.72486Z","time spent":"1.089307592s","remote":"127.0.0.1:41050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":725,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"info","ts":"2026-08-31T08:05:01.814465Z","caller":"traceutil/trace.go:171","msg":"trace[741963384] range","detail":"{range_begin:/registry/pods/openstack/neutron-metadata-agent-default-h2d4j; range_end:; response_count:1; response_revision:17975; }","duration":"905.555482ms","start":"2026-08-31T08:05:00.908897Z","end":"2026-08-31T08:05:01.814452Z","steps":["trace[741963384] 'agreement among raft nodes before linearized reading' (duration: 905.332497ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:05:01.813875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:05:00.721021Z","time spent":"1.092843828s","remote":"127.0.0.1:41126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/neutron-ks-user\" "} {"level":"warn","ts":"2026-08-31T08:05:01.814504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:05:00.908878Z","time spent":"905.618933ms","remote":"127.0.0.1:41064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":14217,"request content":"key:\"/registry/pods/openstack/neutron-metadata-agent-default-h2d4j\" "} {"level":"info","ts":"2026-08-31T08:05:01.814271Z","caller":"traceutil/trace.go:171","msg":"trace[1139868800] range","detail":"{range_begin:/registry/pods/openstack/neutron-l3-agent-default-rx4zt; range_end:; response_count:1; response_revision:17975; }","duration":"895.576077ms","start":"2026-08-31T08:05:00.918647Z","end":"2026-08-31T08:05:01.814223Z","steps":["trace[1139868800] 'agreement among raft nodes before linearized reading' (duration: 895.262409ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:05:01.814638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.092622824s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"warn","ts":"2026-08-31T08:05:01.814647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"913.231742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-b9lb7\" ","response":"range_response_count:1 size:22018"} {"level":"info","ts":"2026-08-31T08:05:01.814735Z","caller":"traceutil/trace.go:171","msg":"trace[1943754510] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:17975; }","duration":"1.092740076s","start":"2026-08-31T08:05:00.721979Z","end":"2026-08-31T08:05:01.814719Z","steps":["trace[1943754510] 'agreement among raft nodes before linearized reading' (duration: 1.092528311s)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:05:01.814774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:05:00.918625Z","time spent":"896.09071ms","remote":"127.0.0.1:41064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":18611,"request content":"key:\"/registry/pods/openstack/neutron-l3-agent-default-rx4zt\" "} {"level":"info","ts":"2026-08-31T08:05:01.813808Z","caller":"traceutil/trace.go:171","msg":"trace[2130834151] range","detail":"{range_begin:/registry/resourcequotas/openstack/; range_end:/registry/resourcequotas/openstack0; response_count:0; response_revision:17975; }","duration":"169.288524ms","start":"2026-08-31T08:05:01.644495Z","end":"2026-08-31T08:05:01.813783Z","steps":["trace[2130834151] 'agreement among raft nodes before linearized reading' (duration: 169.044298ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:05:01.813819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.186297122s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-31T08:05:01.813733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:05:00.725686Z","time spent":"1.088036551s","remote":"127.0.0.1:41126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/neutron-rabbit-init\" "} {"level":"warn","ts":"2026-08-31T08:05:01.814908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.09329071s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-db-sync\" ","response":"range_response_count:1 size:5236"} {"level":"warn","ts":"2026-08-31T08:05:01.813926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:05:00.726616Z","time spent":"1.087295872s","remote":"127.0.0.1:41050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":725,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"info","ts":"2026-08-31T08:05:01.814936Z","caller":"traceutil/trace.go:171","msg":"trace[1316312380] range","detail":"{range_begin:/registry/jobs/openstack/neutron-rabbit-init; range_end:; response_count:0; response_revision:17975; }","duration":"1.1874452s","start":"2026-08-31T08:05:00.627474Z","end":"2026-08-31T08:05:01.814919Z","steps":["trace[1316312380] 'agreement among raft nodes before linearized reading' (duration: 1.186308522s)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:05:01.814952Z","caller":"traceutil/trace.go:171","msg":"trace[661764015] range","detail":"{range_begin:/registry/jobs/openstack/neutron-db-sync; range_end:; response_count:1; response_revision:17975; }","duration":"1.093351192s","start":"2026-08-31T08:05:00.721588Z","end":"2026-08-31T08:05:01.814939Z","steps":["trace[661764015] 'agreement among raft nodes before linearized reading' (duration: 1.093225509s)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:05:01.814992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:05:00.721579Z","time spent":"1.093401813s","remote":"127.0.0.1:41126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":5258,"request content":"key:\"/registry/jobs/openstack/neutron-db-sync\" "} {"level":"info","ts":"2026-08-31T08:05:01.814774Z","caller":"traceutil/trace.go:171","msg":"trace[1329537281] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-b9lb7; range_end:; response_count:1; response_revision:17975; }","duration":"913.391835ms","start":"2026-08-31T08:05:00.901364Z","end":"2026-08-31T08:05:01.814756Z","steps":["trace[1329537281] 'agreement among raft nodes before linearized reading' (duration: 913.033816ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:05:01.815132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.093650288s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-31T08:05:01.813888Z","caller":"traceutil/trace.go:171","msg":"trace[2091938339] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:17975; }","duration":"443.219097ms","start":"2026-08-31T08:05:01.370653Z","end":"2026-08-31T08:05:01.813872Z","steps":["trace[2091938339] 'agreement among raft nodes before linearized reading' (duration: 443.089714ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:05:01.815146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.383765298s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:90 size:899204"} {"level":"info","ts":"2026-08-31T08:05:01.813607Z","caller":"traceutil/trace.go:171","msg":"trace[551042079] range","detail":"{range_begin:/registry/jobs/openstack/neutron-rabbit-init; range_end:; response_count:0; response_revision:17975; }","duration":"1.092760458s","start":"2026-08-31T08:05:00.720827Z","end":"2026-08-31T08:05:01.813588Z","steps":["trace[551042079] 'agreement among raft nodes before linearized reading' (duration: 1.092558623s)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:05:01.815223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:05:00.901344Z","time spent":"913.809646ms","remote":"127.0.0.1:41064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22040,"request content":"key:\"/registry/pods/openstack/nova-compute-default-b9lb7\" "} {"level":"info","ts":"2026-08-31T08:05:01.815173Z","caller":"traceutil/trace.go:171","msg":"trace[1471758758] range","detail":"{range_begin:/registry/jobs/openstack/neutron-rabbit-init; range_end:; response_count:0; response_revision:17975; }","duration":"1.09370482s","start":"2026-08-31T08:05:00.721456Z","end":"2026-08-31T08:05:01.815161Z","steps":["trace[1471758758] 'agreement among raft nodes before linearized reading' (duration: 1.093643489s)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:05:01.81526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:05:00.720798Z","time spent":"1.094444089s","remote":"127.0.0.1:41126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/neutron-rabbit-init\" "} {"level":"warn","ts":"2026-08-31T08:05:01.815295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:05:00.72145Z","time spent":"1.093832503s","remote":"127.0.0.1:41126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/neutron-rabbit-init\" "} {"level":"info","ts":"2026-08-31T08:05:01.815309Z","caller":"traceutil/trace.go:171","msg":"trace[1647011898] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:90; response_revision:17975; }","duration":"1.383947202s","start":"2026-08-31T08:05:00.431345Z","end":"2026-08-31T08:05:01.815292Z","steps":["trace[1647011898] 'agreement among raft nodes before linearized reading' (duration: 1.382834725s)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:05:01.815267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:05:01.370625Z","time spent":"444.625812ms","remote":"127.0.0.1:41158","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-08-31T08:05:01.814992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:05:00.627448Z","time spent":"1.187528783s","remote":"127.0.0.1:41126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/neutron-rabbit-init\" "} {"level":"warn","ts":"2026-08-31T08:05:01.81441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:05:00.721333Z","time spent":"1.092966261s","remote":"127.0.0.1:41050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":725,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"warn","ts":"2026-08-31T08:05:01.815381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:05:00.431337Z","time spent":"1.384020574s","remote":"127.0.0.1:41064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":90,"response size":899226,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-08-31T08:05:01.813841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"786.10826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-31T08:05:01.814787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:05:00.721965Z","time spent":"1.092807769s","remote":"127.0.0.1:41050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":725,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"info","ts":"2026-08-31T08:05:01.815442Z","caller":"traceutil/trace.go:171","msg":"trace[1023786410] range","detail":"{range_begin:/registry/jobs/openstack/neutron-ks-endpoints; range_end:; response_count:0; response_revision:17975; }","duration":"787.73322ms","start":"2026-08-31T08:05:01.027692Z","end":"2026-08-31T08:05:01.815425Z","steps":["trace[1023786410] 'agreement among raft nodes before linearized reading' (duration: 786.08323ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:05:01.813926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:05:01.030565Z","time spent":"783.352573ms","remote":"127.0.0.1:41126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":5258,"request content":"key:\"/registry/jobs/openstack/neutron-db-sync\" "} {"level":"warn","ts":"2026-08-31T08:05:01.815493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:05:01.027677Z","time spent":"787.800862ms","remote":"127.0.0.1:41126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/neutron-ks-endpoints\" "} {"level":"warn","ts":"2026-08-31T08:05:01.813365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.093387513s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-31T08:05:01.815813Z","caller":"traceutil/trace.go:171","msg":"trace[1867061758] range","detail":"{range_begin:/registry/jobs/openstack/neutron-ks-endpoints; range_end:; response_count:0; response_revision:17975; }","duration":"1.095896123s","start":"2026-08-31T08:05:00.7199Z","end":"2026-08-31T08:05:01.815796Z","steps":["trace[1867061758] 'agreement among raft nodes before linearized reading' (duration: 1.093407913s)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:05:01.81587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:05:00.719844Z","time spent":"1.096011056s","remote":"127.0.0.1:41126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/neutron-ks-endpoints\" "} {"level":"info","ts":"2026-08-31T08:05:02.237725Z","caller":"traceutil/trace.go:171","msg":"trace[1072429908] transaction","detail":"{read_only:false; response_revision:17976; number_of_response:1; }","duration":"418.88691ms","start":"2026-08-31T08:05:01.818783Z","end":"2026-08-31T08:05:02.23767Z","steps":["trace[1072429908] 'process raft request' (duration: 400.990831ms)","trace[1072429908] 'compare' (duration: 17.087219ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T08:05:02.237776Z","caller":"traceutil/trace.go:171","msg":"trace[55937148] linearizableReadLoop","detail":"{readStateIndex:18602; appliedIndex:18599; }","duration":"412.686768ms","start":"2026-08-31T08:05:01.825059Z","end":"2026-08-31T08:05:02.237746Z","steps":["trace[55937148] 'read index received' (duration: 394.733797ms)","trace[55937148] 'applied index is now lower than readState.Index' (duration: 17.951511ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T08:05:02.237925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:05:01.818756Z","time spent":"419.074104ms","remote":"127.0.0.1:41064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5283,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-31T08:05:02.238106Z","caller":"traceutil/trace.go:171","msg":"trace[515440630] transaction","detail":"{read_only:false; response_revision:17977; number_of_response:1; }","duration":"419.116435ms","start":"2026-08-31T08:05:01.818977Z","end":"2026-08-31T08:05:02.238093Z","steps":["trace[515440630] 'process raft request' (duration: 418.552612ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:05:02.238242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:05:01.818945Z","time spent":"419.204007ms","remote":"127.0.0.1:41158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-31T08:05:02.238406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.921799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-31T08:05:02.238456Z","caller":"traceutil/trace.go:171","msg":"trace[1843741787] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:17978; }","duration":"302.017911ms","start":"2026-08-31T08:05:01.936424Z","end":"2026-08-31T08:05:02.238441Z","steps":["trace[1843741787] 'agreement among raft nodes before linearized reading' (duration: 301.901779ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:05:02.238489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:05:01.936402Z","time spent":"302.078392ms","remote":"127.0.0.1:53588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-08-31T08:05:02.239377Z","caller":"traceutil/trace.go:171","msg":"trace[1426628372] transaction","detail":"{read_only:false; response_revision:17978; number_of_response:1; }","duration":"414.466251ms","start":"2026-08-31T08:05:01.824892Z","end":"2026-08-31T08:05:02.239358Z","steps":["trace[1426628372] 'process raft request' (duration: 412.805571ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:05:02.239472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:05:01.824867Z","time spent":"414.549464ms","remote":"127.0.0.1:41158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-31T08:05:02.239842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.759168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:91 size:904497"} {"level":"info","ts":"2026-08-31T08:05:02.23993Z","caller":"traceutil/trace.go:171","msg":"trace[1201795338] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:91; response_revision:17978; }","duration":"414.885781ms","start":"2026-08-31T08:05:01.825026Z","end":"2026-08-31T08:05:02.239912Z","steps":["trace[1201795338] 'agreement among raft nodes before linearized reading' (duration: 413.585209ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:05:02.239974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:05:01.825013Z","time spent":"414.948693ms","remote":"127.0.0.1:41064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":91,"response size":904519,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-08-31T08:05:02.241079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.392756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-31T08:05:02.241171Z","caller":"traceutil/trace.go:171","msg":"trace[1424212998] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17978; }","duration":"411.54435ms","start":"2026-08-31T08:05:01.829603Z","end":"2026-08-31T08:05:02.241148Z","steps":["trace[1424212998] 'agreement among raft nodes before linearized reading' (duration: 411.358455ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:05:02.24122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:05:01.829583Z","time spent":"411.628401ms","remote":"127.0.0.1:40898","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-08-31T08:05:02.241355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.420726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:91 size:904497"} {"level":"warn","ts":"2026-08-31T08:05:02.241412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.77338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:91 size:904497"} {"level":"info","ts":"2026-08-31T08:05:02.241442Z","caller":"traceutil/trace.go:171","msg":"trace[1394604431] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:91; response_revision:17978; }","duration":"411.817306ms","start":"2026-08-31T08:05:01.829604Z","end":"2026-08-31T08:05:02.241421Z","steps":["trace[1394604431] 'agreement among raft nodes before linearized reading' (duration: 410.249128ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:05:02.24146Z","caller":"traceutil/trace.go:171","msg":"trace[1920327585] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:91; response_revision:17978; }","duration":"410.842192ms","start":"2026-08-31T08:05:01.830605Z","end":"2026-08-31T08:05:02.241447Z","steps":["trace[1920327585] 'agreement among raft nodes before linearized reading' (duration: 408.870644ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:05:02.241497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:05:01.830595Z","time spent":"410.891563ms","remote":"127.0.0.1:41064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":91,"response size":904519,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-08-31T08:05:02.241501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:05:01.829332Z","time spent":"412.152264ms","remote":"127.0.0.1:41064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":91,"response size":904519,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-08-31T08:05:02.241485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.314717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:91 size:904497"} {"level":"info","ts":"2026-08-31T08:05:02.241626Z","caller":"traceutil/trace.go:171","msg":"trace[805829495] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:91; response_revision:17978; }","duration":"416.252316ms","start":"2026-08-31T08:05:01.82535Z","end":"2026-08-31T08:05:02.241602Z","steps":["trace[805829495] 'agreement among raft nodes before linearized reading' (duration: 412.932024ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:05:02.241701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:05:01.825338Z","time spent":"416.340198ms","remote":"127.0.0.1:41064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":91,"response size":904519,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-08-31T08:05:02.243137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.756315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:91 size:904497"} {"level":"info","ts":"2026-08-31T08:05:02.243199Z","caller":"traceutil/trace.go:171","msg":"trace[21932913] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:91; response_revision:17978; }","duration":"411.851506ms","start":"2026-08-31T08:05:01.831334Z","end":"2026-08-31T08:05:02.243185Z","steps":["trace[21932913] 'agreement among raft nodes before linearized reading' (duration: 408.35677ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:05:02.243238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:05:01.831319Z","time spent":"411.906438ms","remote":"127.0.0.1:41064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":91,"response size":904519,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-08-31T08:08:02.397755Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16933} {"level":"info","ts":"2026-08-31T08:08:02.5049Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16933,"took":"104.172669ms","hash":1067772579,"current-db-size-bytes":57217024,"current-db-size":"57 MB","current-db-size-in-use-bytes":31793152,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-31T08:08:02.504981Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1067772579,"revision":16933,"compact-revision":14605} {"level":"info","ts":"2026-08-31T08:08:18.526071Z","caller":"traceutil/trace.go:171","msg":"trace[1227398213] linearizableReadLoop","detail":"{readStateIndex:19907; appliedIndex:19906; }","duration":"194.460406ms","start":"2026-08-31T08:08:18.331589Z","end":"2026-08-31T08:08:18.52605Z","steps":["trace[1227398213] 'read index received' (duration: 194.400624ms)","trace[1227398213] 'applied index is now lower than readState.Index' (duration: 58.822µs)"],"step_count":2} {"level":"info","ts":"2026-08-31T08:08:18.526158Z","caller":"traceutil/trace.go:171","msg":"trace[8810431] transaction","detail":"{read_only:false; response_revision:19238; number_of_response:1; }","duration":"223.989991ms","start":"2026-08-31T08:08:18.302137Z","end":"2026-08-31T08:08:18.526127Z","steps":["trace[8810431] 'process raft request' (duration: 223.752975ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:08:18.526296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.697063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-wnpfg\" ","response":"range_response_count:1 size:9709"} {"level":"info","ts":"2026-08-31T08:08:18.526366Z","caller":"traceutil/trace.go:171","msg":"trace[298684682] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-wnpfg; range_end:; response_count:1; response_revision:19238; }","duration":"194.778485ms","start":"2026-08-31T08:08:18.331572Z","end":"2026-08-31T08:08:18.526351Z","steps":["trace[298684682] 'agreement among raft nodes before linearized reading' (duration: 194.5752ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:08:18.544215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.206542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-q6kpp\" ","response":"range_response_count:1 size:20664"} {"level":"info","ts":"2026-08-31T08:08:18.544284Z","caller":"traceutil/trace.go:171","msg":"trace[358161074] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-q6kpp; range_end:; response_count:1; response_revision:19241; }","duration":"181.318656ms","start":"2026-08-31T08:08:18.362947Z","end":"2026-08-31T08:08:18.544266Z","steps":["trace[358161074] 'agreement among raft nodes before linearized reading' (duration: 181.13678ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:08:19.20409Z","caller":"traceutil/trace.go:171","msg":"trace[592665601] linearizableReadLoop","detail":"{readStateIndex:19911; appliedIndex:19910; }","duration":"458.566611ms","start":"2026-08-31T08:08:18.745493Z","end":"2026-08-31T08:08:19.20406Z","steps":["trace[592665601] 'read index received' (duration: 458.239893ms)","trace[592665601] 'applied index is now lower than readState.Index' (duration: 324.858µs)"],"step_count":2} {"level":"info","ts":"2026-08-31T08:08:19.204205Z","caller":"traceutil/trace.go:171","msg":"trace[1039615065] transaction","detail":"{read_only:false; response_revision:19242; number_of_response:1; }","duration":"633.508926ms","start":"2026-08-31T08:08:18.570664Z","end":"2026-08-31T08:08:19.204173Z","steps":["trace[1039615065] 'process raft request' (duration: 633.164487ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:08:19.204335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.838932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-08-31T08:08:19.204357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:08:18.570632Z","time spent":"633.635639ms","remote":"127.0.0.1:41064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20781,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-31T08:08:19.204382Z","caller":"traceutil/trace.go:171","msg":"trace[1289077728] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:19242; }","duration":"455.934444ms","start":"2026-08-31T08:08:18.748432Z","end":"2026-08-31T08:08:19.204367Z","steps":["trace[1289077728] 'agreement among raft nodes before linearized reading' (duration: 455.77167ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:08:19.204421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:08:18.748407Z","time spent":"456.003516ms","remote":"127.0.0.1:41158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-31T08:08:19.204409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.89398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-31T08:08:19.2045Z","caller":"traceutil/trace.go:171","msg":"trace[872468529] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:19242; }","duration":"459.034843ms","start":"2026-08-31T08:08:18.745446Z","end":"2026-08-31T08:08:19.20448Z","steps":["trace[872468529] 'agreement among raft nodes before linearized reading' (duration: 458.757756ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:08:19.204543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:08:18.745425Z","time spent":"459.109774ms","remote":"127.0.0.1:41158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-31T08:08:19.204648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.933567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-b9lb7\" ","response":"range_response_count:1 size:22018"} {"level":"info","ts":"2026-08-31T08:08:19.204707Z","caller":"traceutil/trace.go:171","msg":"trace[2146034407] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-b9lb7; range_end:; response_count:1; response_revision:19242; }","duration":"145.031908ms","start":"2026-08-31T08:08:19.059659Z","end":"2026-08-31T08:08:19.20469Z","steps":["trace[2146034407] 'agreement among raft nodes before linearized reading' (duration: 144.863804ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:08:24.964316Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"7a47c02f9bc0b904","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-31T08:08:24.979119Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-31T08:08:24.979218Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-31T08:13:02.404909Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19121} {"level":"info","ts":"2026-08-31T08:13:02.514591Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19121,"took":"106.363534ms","hash":2563297470,"current-db-size-bytes":57217024,"current-db-size":"57 MB","current-db-size-in-use-bytes":33583104,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-31T08:13:02.514662Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2563297470,"revision":19121,"compact-revision":16933} {"level":"warn","ts":"2026-08-31T08:14:41.101926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.10304ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-31T08:14:41.102084Z","caller":"traceutil/trace.go:171","msg":"trace[643666606] linearizableReadLoop","detail":"{readStateIndex:22902; appliedIndex:22901; }","duration":"307.573463ms","start":"2026-08-31T08:14:40.794486Z","end":"2026-08-31T08:14:41.102059Z","steps":["trace[643666606] 'read index received' (duration: 51.304419ms)","trace[643666606] 'applied index is now lower than readState.Index' (duration: 256.267114ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T08:14:41.10236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.847691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-08-31T08:14:41.102413Z","caller":"traceutil/trace.go:171","msg":"trace[1203863954] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:22136; }","duration":"307.958863ms","start":"2026-08-31T08:14:40.794436Z","end":"2026-08-31T08:14:41.102395Z","steps":["trace[1203863954] 'agreement among raft nodes before linearized reading' (duration: 307.681256ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:14:41.102468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:14:40.794411Z","time spent":"308.043846ms","remote":"127.0.0.1:41158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-31T08:14:41.192812Z","caller":"traceutil/trace.go:171","msg":"trace[1935641778] transaction","detail":"{read_only:false; response_revision:22137; number_of_response:1; }","duration":"224.936198ms","start":"2026-08-31T08:14:40.967831Z","end":"2026-08-31T08:14:41.192767Z","steps":["trace[1935641778] 'process raft request' (duration: 224.686242ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:14:41.382969Z","caller":"traceutil/trace.go:171","msg":"trace[559930443] transaction","detail":"{read_only:false; response_revision:22138; number_of_response:1; }","duration":"275.402557ms","start":"2026-08-31T08:14:41.107499Z","end":"2026-08-31T08:14:41.382902Z","steps":["trace[559930443] 'process raft request' (duration: 275.11353ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:14:41.699966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.457652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-08-31T08:14:41.699994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.543308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-08-31T08:14:41.700026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.498614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-31T08:14:41.700066Z","caller":"traceutil/trace.go:171","msg":"trace[887318997] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:22138; }","duration":"234.626387ms","start":"2026-08-31T08:14:41.465418Z","end":"2026-08-31T08:14:41.700044Z","steps":["trace[887318997] 'range keys from in-memory index tree' (duration: 234.199416ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:14:41.700088Z","caller":"traceutil/trace.go:171","msg":"trace[1391852900] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:22138; }","duration":"231.709902ms","start":"2026-08-31T08:14:41.468357Z","end":"2026-08-31T08:14:41.700067Z","steps":["trace[1391852900] 'range keys from in-memory index tree' (duration: 231.325362ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:14:41.700108Z","caller":"traceutil/trace.go:171","msg":"trace[191447662] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:22138; }","duration":"234.630797ms","start":"2026-08-31T08:14:41.465459Z","end":"2026-08-31T08:14:41.70009Z","steps":["trace[191447662] 'range keys from in-memory index tree' (duration: 234.302469ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:14:41.700369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.437789ms","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-08-31T08:14:41.700464Z","caller":"traceutil/trace.go:171","msg":"trace[1824847497] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:22138; }","duration":"132.584982ms","start":"2026-08-31T08:14:41.567855Z","end":"2026-08-31T08:14:41.70044Z","steps":["trace[1824847497] 'range keys from in-memory index tree' (duration: 132.312765ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:14:41.700347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.220976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-31T08:14:41.70082Z","caller":"traceutil/trace.go:171","msg":"trace[526736640] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:22138; }","duration":"171.78131ms","start":"2026-08-31T08:14:41.529023Z","end":"2026-08-31T08:14:41.700804Z","steps":["trace[526736640] 'count revisions from in-memory index tree' (duration: 171.070233ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:14:42.02494Z","caller":"traceutil/trace.go:171","msg":"trace[391296712] transaction","detail":"{read_only:false; response_revision:22140; number_of_response:1; }","duration":"319.001727ms","start":"2026-08-31T08:14:41.705896Z","end":"2026-08-31T08:14:42.024897Z","steps":["trace[391296712] 'process raft request' (duration: 240.438766ms)","trace[391296712] 'compare' (duration: 78.264144ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T08:14:42.025197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:14:41.705871Z","time spent":"319.191593ms","remote":"127.0.0.1:41158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-31T08:14:42.025499Z","caller":"traceutil/trace.go:171","msg":"trace[103131007] linearizableReadLoop","detail":"{readStateIndex:22908; appliedIndex:22904; }","duration":"317.560871ms","start":"2026-08-31T08:14:41.707912Z","end":"2026-08-31T08:14:42.025473Z","steps":["trace[103131007] 'read index received' (duration: 37.527006ms)","trace[103131007] 'applied index is now lower than readState.Index' (duration: 280.032965ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T08:14:42.02559Z","caller":"traceutil/trace.go:171","msg":"trace[774315781] transaction","detail":"{read_only:false; response_revision:22142; number_of_response:1; }","duration":"319.450649ms","start":"2026-08-31T08:14:41.706103Z","end":"2026-08-31T08:14:42.025554Z","steps":["trace[774315781] 'process raft request' (duration: 319.260745ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:14:42.025772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.824458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-08-31T08:14:42.025833Z","caller":"traceutil/trace.go:171","msg":"trace[1255330689] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:22142; }","duration":"317.947951ms","start":"2026-08-31T08:14:41.707868Z","end":"2026-08-31T08:14:42.025816Z","steps":["trace[1255330689] 'agreement among raft nodes before linearized reading' (duration: 317.712245ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:14:42.025775Z","caller":"traceutil/trace.go:171","msg":"trace[366340949] transaction","detail":"{read_only:false; response_revision:22141; number_of_response:1; }","duration":"319.627744ms","start":"2026-08-31T08:14:41.706062Z","end":"2026-08-31T08:14:42.02569Z","steps":["trace[366340949] 'process raft request' (duration: 318.762061ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:14:42.025879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:14:41.707854Z","time spent":"318.016352ms","remote":"127.0.0.1:41064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-08-31T08:14:42.025931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:14:41.706053Z","time spent":"319.621524ms","remote":"127.0.0.1:41158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-31T08:14:42.0262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:14:41.706026Z","time spent":"319.88765ms","remote":"127.0.0.1:41158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-31T08:14:42.325043Z","caller":"traceutil/trace.go:171","msg":"trace[54110156] transaction","detail":"{read_only:false; response_revision:22143; number_of_response:1; }","duration":"103.661568ms","start":"2026-08-31T08:14:42.221345Z","end":"2026-08-31T08:14:42.325007Z","steps":["trace[54110156] 'process raft request' (duration: 103.284289ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:14:42.594939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.722807ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-31T08:14:42.595287Z","caller":"traceutil/trace.go:171","msg":"trace[715714319] transaction","detail":"{read_only:false; response_revision:22145; number_of_response:1; }","duration":"329.584142ms","start":"2026-08-31T08:14:42.265656Z","end":"2026-08-31T08:14:42.59524Z","steps":["trace[715714319] 'process raft request' (duration: 329.470149ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:14:42.59534Z","caller":"traceutil/trace.go:171","msg":"trace[939472192] transaction","detail":"{read_only:false; response_revision:22144; number_of_response:1; }","duration":"332.829064ms","start":"2026-08-31T08:14:42.262472Z","end":"2026-08-31T08:14:42.595302Z","steps":["trace[939472192] 'process raft request' (duration: 188.652614ms)","trace[939472192] 'compare' (duration: 143.240796ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T08:14:42.595507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:14:42.262444Z","time spent":"332.984418ms","remote":"127.0.0.1:41158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-31T08:14:42.59551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:14:42.265626Z","time spent":"329.833188ms","remote":"127.0.0.1:41158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-31T08:14:42.920968Z","caller":"traceutil/trace.go:171","msg":"trace[263096367] transaction","detail":"{read_only:false; response_revision:22146; number_of_response:1; }","duration":"125.340444ms","start":"2026-08-31T08:14:42.795586Z","end":"2026-08-31T08:14:42.920927Z","steps":["trace[263096367] 'process raft request' (duration: 70.009221ms)","trace[263096367] 'compare' (duration: 54.737888ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T08:18:02.410719Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21496} {"level":"info","ts":"2026-08-31T08:18:02.528592Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21496,"took":"114.082153ms","hash":644623095,"current-db-size-bytes":57217024,"current-db-size":"57 MB","current-db-size-in-use-bytes":30740480,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-31T08:18:02.528656Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":644623095,"revision":21496,"compact-revision":19121} {"level":"info","ts":"2026-08-31T08:18:58.059973Z","caller":"traceutil/trace.go:171","msg":"trace[584817755] transaction","detail":"{read_only:false; response_revision:24703; number_of_response:1; }","duration":"353.4861ms","start":"2026-08-31T08:18:57.706445Z","end":"2026-08-31T08:18:58.059931Z","steps":["trace[584817755] 'process raft request' (duration: 330.739123ms)","trace[584817755] 'compare' (duration: 22.559002ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T08:18:58.060211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:18:57.706427Z","time spent":"353.668845ms","remote":"127.0.0.1:41158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-31T08:18:58.062532Z","caller":"traceutil/trace.go:171","msg":"trace[1431847574] transaction","detail":"{read_only:false; response_revision:24704; number_of_response:1; }","duration":"348.471843ms","start":"2026-08-31T08:18:57.714027Z","end":"2026-08-31T08:18:58.062499Z","steps":["trace[1431847574] 'process raft request' (duration: 348.245527ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:18:58.062792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:18:57.714011Z","time spent":"348.623866ms","remote":"127.0.0.1:41158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-31T08:18:58.0629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.246006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-31T08:18:58.062952Z","caller":"traceutil/trace.go:171","msg":"trace[1279694626] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:24706; }","duration":"333.303798ms","start":"2026-08-31T08:18:57.729633Z","end":"2026-08-31T08:18:58.062937Z","steps":["trace[1279694626] 'agreement among raft nodes before linearized reading' (duration: 333.209086ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:18:58.062963Z","caller":"traceutil/trace.go:171","msg":"trace[2135820739] transaction","detail":"{read_only:false; response_revision:24705; number_of_response:1; }","duration":"259.922887ms","start":"2026-08-31T08:18:57.803013Z","end":"2026-08-31T08:18:58.062936Z","steps":["trace[2135820739] 'process raft request' (duration: 259.426085ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T08:18:58.063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T08:18:57.729615Z","time spent":"333.37478ms","remote":"127.0.0.1:45634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-08-31T08:18:58.062814Z","caller":"traceutil/trace.go:171","msg":"trace[1899863424] linearizableReadLoop","detail":"{readStateIndex:25544; appliedIndex:25542; }","duration":"333.153994ms","start":"2026-08-31T08:18:57.729644Z","end":"2026-08-31T08:18:58.062798Z","steps":["trace[1899863424] 'read index received' (duration: 307.513924ms)","trace[1899863424] 'applied index is now lower than readState.Index' (duration: 25.63857ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T08:18:58.063132Z","caller":"traceutil/trace.go:171","msg":"trace[864274440] transaction","detail":"{read_only:false; response_revision:24706; number_of_response:1; }","duration":"195.866701ms","start":"2026-08-31T08:18:57.867245Z","end":"2026-08-31T08:18:58.063112Z","steps":["trace[864274440] 'process raft request' (duration: 195.466621ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T08:20:10.504667Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006719.wal"} {"level":"info","ts":"2026-08-31T08:23:02.419079Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23752} {"level":"info","ts":"2026-08-31T08:23:02.572829Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23752,"took":"149.922516ms","hash":967850578,"current-db-size-bytes":57217024,"current-db-size":"57 MB","current-db-size-in-use-bytes":42258432,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-08-31T08:23:02.572906Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":967850578,"revision":23752,"compact-revision":21496} {"level":"info","ts":"2026-08-31T08:27:58.730791Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"7a47c02f9bc0b904","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-31T08:27:58.737352Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-08-31T08:27:58.737463Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-08-31T08:28:02.42606Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26838} {"level":"info","ts":"2026-08-31T08:28:02.568436Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26838,"took":"138.992455ms","hash":3383131646,"current-db-size-bytes":57217024,"current-db-size":"57 MB","current-db-size-in-use-bytes":29757440,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-31T08:28:02.568508Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3383131646,"revision":26838,"compact-revision":23752}