{"level":"warn","ts":"2026-10-13T11:14:01.959904Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-13T11:14:01.960059Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.45: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.45:2380","--initial-cluster=instance=https://162.253.55.45:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.45:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.45: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-10-13T11:14:01.960157Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-13T11:14:01.960228Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.45:2380"]} {"level":"info","ts":"2026-10-13T11:14:01.960259Z","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-10-13T11:14:01.9612Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.45:2379"]} {"level":"info","ts":"2026-10-13T11:14:01.961357Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.45:2380"],"listen-peer-urls":["https://162.253.55.45:2380"],"advertise-client-urls":["https://162.253.55.45:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.45:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.45: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-10-13T11:14:01.966567Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.896481ms"} {"level":"info","ts":"2026-10-13T11:14:01.979774Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fbe9b088d8de907a","cluster-id":"332637b4e3714c3a"} {"level":"info","ts":"2026-10-13T11:14:01.980022Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fbe9b088d8de907a switched to configuration voters=()"} {"level":"info","ts":"2026-10-13T11:14:01.980068Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fbe9b088d8de907a became follower at term 0"} {"level":"info","ts":"2026-10-13T11:14:01.980121Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fbe9b088d8de907a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-13T11:14:01.980209Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fbe9b088d8de907a became follower at term 1"} {"level":"info","ts":"2026-10-13T11:14:01.980302Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fbe9b088d8de907a switched to configuration voters=(18152233874894000250)"} {"level":"warn","ts":"2026-10-13T11:14:01.989997Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-13T11:14:01.991459Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-13T11:14:01.992924Z","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-10-13T11:14:01.995373Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fbe9b088d8de907a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-13T11:14:01.998042Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fbe9b088d8de907a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-13T11:14:01.997731Z","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-10-13T11:14:01.998239Z","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-10-13T11:14:01.998256Z","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-10-13T11:14:01.999289Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fbe9b088d8de907a switched to configuration voters=(18152233874894000250)"} {"level":"info","ts":"2026-10-13T11:14:01.999411Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"332637b4e3714c3a","local-member-id":"fbe9b088d8de907a","added-peer-id":"fbe9b088d8de907a","added-peer-peer-urls":["https://162.253.55.45:2380"]} {"level":"info","ts":"2026-10-13T11:14:02.000939Z","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-10-13T11:14:02.001013Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.45:2380"} {"level":"info","ts":"2026-10-13T11:14:02.001059Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.45:2380"} {"level":"info","ts":"2026-10-13T11:14:02.00125Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fbe9b088d8de907a","initial-advertise-peer-urls":["https://162.253.55.45:2380"],"listen-peer-urls":["https://162.253.55.45:2380"],"advertise-client-urls":["https://162.253.55.45:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.45:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-13T11:14:02.001332Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-13T11:14:02.681356Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fbe9b088d8de907a is starting a new election at term 1"} {"level":"info","ts":"2026-10-13T11:14:02.681438Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fbe9b088d8de907a became pre-candidate at term 1"} {"level":"info","ts":"2026-10-13T11:14:02.681463Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fbe9b088d8de907a received MsgPreVoteResp from fbe9b088d8de907a at term 1"} {"level":"info","ts":"2026-10-13T11:14:02.68148Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fbe9b088d8de907a became candidate at term 2"} {"level":"info","ts":"2026-10-13T11:14:02.68169Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fbe9b088d8de907a received MsgVoteResp from fbe9b088d8de907a at term 2"} {"level":"info","ts":"2026-10-13T11:14:02.68178Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fbe9b088d8de907a became leader at term 2"} {"level":"info","ts":"2026-10-13T11:14:02.681804Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fbe9b088d8de907a elected leader fbe9b088d8de907a at term 2"} {"level":"info","ts":"2026-10-13T11:14:02.682952Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fbe9b088d8de907a","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.45:2379]}","request-path":"/0/members/fbe9b088d8de907a/attributes","cluster-id":"332637b4e3714c3a","publish-timeout":"7s"} {"level":"info","ts":"2026-10-13T11:14:02.683072Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-13T11:14:02.683783Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-13T11:14:02.684017Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-13T11:14:02.684155Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-13T11:14:02.684206Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-13T11:14:02.685073Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"332637b4e3714c3a","local-member-id":"fbe9b088d8de907a","cluster-version":"3.5"} {"level":"info","ts":"2026-10-13T11:14:02.685172Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-13T11:14:02.685258Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-13T11:14:02.685766Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.45:2379"} {"level":"info","ts":"2026-10-13T11:14:02.687018Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-10-13T11:14:04.483402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.131774ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-10-13T11:14:04.483565Z","caller":"traceutil/trace.go:171","msg":"trace[1668440702] linearizableReadLoop","detail":"{readStateIndex:111; appliedIndex:110; }","duration":"293.062474ms","start":"2026-10-13T11:14:04.190479Z","end":"2026-10-13T11:14:04.483542Z","steps":["trace[1668440702] 'read index received' (duration: 24.996059ms)","trace[1668440702] 'applied index is now lower than readState.Index' (duration: 268.064845ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T11:14:04.483744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.247628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-13T11:14:04.483801Z","caller":"traceutil/trace.go:171","msg":"trace[1793938018] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:106; }","duration":"293.35547ms","start":"2026-10-13T11:14:04.190425Z","end":"2026-10-13T11:14:04.48378Z","steps":["trace[1793938018] 'agreement among raft nodes before linearized reading' (duration: 293.201077ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T11:14:04.483881Z","caller":"traceutil/trace.go:171","msg":"trace[1043710374] transaction","detail":"{read_only:false; response_revision:106; number_of_response:1; }","duration":"294.756038ms","start":"2026-10-13T11:14:04.188932Z","end":"2026-10-13T11:14:04.483688Z","steps":["trace[1043710374] 'process raft request' (duration: 26.610182ms)","trace[1043710374] 'compare' (duration: 266.959481ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T11:14:04.70061Z","caller":"traceutil/trace.go:171","msg":"trace[1746099958] transaction","detail":"{read_only:false; response_revision:107; number_of_response:1; }","duration":"213.759657ms","start":"2026-10-13T11:14:04.486824Z","end":"2026-10-13T11:14:04.700583Z","steps":["trace[1746099958] 'process raft request' (duration: 213.528442ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T11:14:04.812696Z","caller":"traceutil/trace.go:171","msg":"trace[515493740] transaction","detail":"{read_only:false; response_revision:108; number_of_response:1; }","duration":"324.637377ms","start":"2026-10-13T11:14:04.488002Z","end":"2026-10-13T11:14:04.812639Z","steps":["trace[515493740] 'process raft request' (duration: 318.36655ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T11:14:04.8129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T11:14:04.487991Z","time spent":"324.812911ms","remote":"127.0.0.1:39958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":772,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-13T11:14:51.665238Z","caller":"traceutil/trace.go:171","msg":"trace[964174537] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"151.182454ms","start":"2026-10-13T11:14:51.514033Z","end":"2026-10-13T11:14:51.665215Z","steps":["trace[964174537] 'process raft request' (duration: 150.723236ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T11:14:51.955396Z","caller":"traceutil/trace.go:171","msg":"trace[462254074] linearizableReadLoop","detail":"{readStateIndex:675; appliedIndex:674; }","duration":"141.210046ms","start":"2026-10-13T11:14:51.814168Z","end":"2026-10-13T11:14:51.955378Z","steps":["trace[462254074] 'read index received' (duration: 141.067754ms)","trace[462254074] 'applied index is now lower than readState.Index' (duration: 141.552µs)"],"step_count":2} {"level":"warn","ts":"2026-10-13T11:14:51.955662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.47777ms","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-10-13T11:14:51.955684Z","caller":"traceutil/trace.go:171","msg":"trace[1615768879] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:648; }","duration":"141.558121ms","start":"2026-10-13T11:14:51.814119Z","end":"2026-10-13T11:14:51.955678Z","steps":["trace[1615768879] 'agreement among raft nodes before linearized reading' (duration: 141.342598ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T11:14:51.955838Z","caller":"traceutil/trace.go:171","msg":"trace[343634029] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"219.943403ms","start":"2026-10-13T11:14:51.735881Z","end":"2026-10-13T11:14:51.955824Z","steps":["trace[343634029] 'process raft request' (duration: 219.396114ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T11:14:52.475381Z","caller":"traceutil/trace.go:171","msg":"trace[1627245671] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"111.002235ms","start":"2026-10-13T11:14:52.364359Z","end":"2026-10-13T11:14:52.475361Z","steps":["trace[1627245671] 'process raft request' (duration: 110.949784ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T11:14:52.475389Z","caller":"traceutil/trace.go:171","msg":"trace[357097371] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"114.46278ms","start":"2026-10-13T11:14:52.360907Z","end":"2026-10-13T11:14:52.47537Z","steps":["trace[357097371] 'process raft request' (duration: 112.263046ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T11:15:07.91015Z","caller":"traceutil/trace.go:171","msg":"trace[1147075871] linearizableReadLoop","detail":"{readStateIndex:844; appliedIndex:843; }","duration":"111.563061ms","start":"2026-10-13T11:15:07.798572Z","end":"2026-10-13T11:15:07.910135Z","steps":["trace[1147075871] 'read index received' (duration: 111.432309ms)","trace[1147075871] 'applied index is now lower than readState.Index' (duration: 130.212µs)"],"step_count":2} {"level":"info","ts":"2026-10-13T11:15:07.910212Z","caller":"traceutil/trace.go:171","msg":"trace[1517741046] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"158.196654ms","start":"2026-10-13T11:15:07.752001Z","end":"2026-10-13T11:15:07.910198Z","steps":["trace[1517741046] 'process raft request' (duration: 158.049512ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T11:15:07.910377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.738074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-13T11:15:07.910409Z","caller":"traceutil/trace.go:171","msg":"trace[982618821] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:812; }","duration":"111.858656ms","start":"2026-10-13T11:15:07.798542Z","end":"2026-10-13T11:15:07.910401Z","steps":["trace[982618821] 'agreement among raft nodes before linearized reading' (duration: 111.657483ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T11:15:08.04347Z","caller":"traceutil/trace.go:171","msg":"trace[2037098963] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"128.415714ms","start":"2026-10-13T11:15:07.914935Z","end":"2026-10-13T11:15:08.043351Z","steps":["trace[2037098963] 'process raft request' (duration: 127.291386ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T11:16:25.185118Z","caller":"traceutil/trace.go:171","msg":"trace[813831069] linearizableReadLoop","detail":"{readStateIndex:1946; appliedIndex:1945; }","duration":"274.969447ms","start":"2026-10-13T11:16:24.910125Z","end":"2026-10-13T11:16:25.185095Z","steps":["trace[813831069] 'read index received' (duration: 274.825996ms)","trace[813831069] 'applied index is now lower than readState.Index' (duration: 142.371µs)"],"step_count":2} {"level":"info","ts":"2026-10-13T11:16:25.185223Z","caller":"traceutil/trace.go:171","msg":"trace[622926267] transaction","detail":"{read_only:false; response_revision:1872; number_of_response:1; }","duration":"297.530157ms","start":"2026-10-13T11:16:24.887685Z","end":"2026-10-13T11:16:25.185215Z","steps":["trace[622926267] 'process raft request' (duration: 297.190333ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T11:16:25.185369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.236981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T11:16:25.185395Z","caller":"traceutil/trace.go:171","msg":"trace[624806199] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1872; }","duration":"275.306471ms","start":"2026-10-13T11:16:24.910083Z","end":"2026-10-13T11:16:25.185389Z","steps":["trace[624806199] 'agreement among raft nodes before linearized reading' (duration: 275.2491ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T11:16:25.185669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.202747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-13T11:16:25.185697Z","caller":"traceutil/trace.go:171","msg":"trace[1614089920] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1872; }","duration":"187.258658ms","start":"2026-10-13T11:16:24.998431Z","end":"2026-10-13T11:16:25.18569Z","steps":["trace[1614089920] 'agreement among raft nodes before linearized reading' (duration: 187.157787ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T11:16:25.185966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.633014ms","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-10-13T11:16:25.186059Z","caller":"traceutil/trace.go:171","msg":"trace[5758360] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1872; }","duration":"100.764286ms","start":"2026-10-13T11:16:25.085267Z","end":"2026-10-13T11:16:25.186031Z","steps":["trace[5758360] 'agreement among raft nodes before linearized reading' (duration: 100.514563ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T11:16:25.486242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.712164ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-13T11:16:25.486412Z","caller":"traceutil/trace.go:171","msg":"trace[1884544950] transaction","detail":"{read_only:false; response_revision:1874; number_of_response:1; }","duration":"289.838428ms","start":"2026-10-13T11:16:25.196538Z","end":"2026-10-13T11:16:25.486377Z","steps":["trace[1884544950] 'process raft request' (duration: 164.83347ms)","trace[1884544950] 'compare' (duration: 124.614332ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T11:16:59.690923Z","caller":"traceutil/trace.go:171","msg":"trace[234757873] linearizableReadLoop","detail":"{readStateIndex:2195; appliedIndex:2194; }","duration":"277.725083ms","start":"2026-10-13T11:16:59.413178Z","end":"2026-10-13T11:16:59.690903Z","steps":["trace[234757873] 'read index received' (duration: 277.592988ms)","trace[234757873] 'applied index is now lower than readState.Index' (duration: 131.415µs)"],"step_count":2} {"level":"info","ts":"2026-10-13T11:16:59.691031Z","caller":"traceutil/trace.go:171","msg":"trace[854392911] transaction","detail":"{read_only:false; response_revision:2114; number_of_response:1; }","duration":"287.985735ms","start":"2026-10-13T11:16:59.403018Z","end":"2026-10-13T11:16:59.691004Z","steps":["trace[854392911] 'process raft request' (duration: 287.771805ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T11:16:59.691118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.923331ms","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-10-13T11:16:59.691147Z","caller":"traceutil/trace.go:171","msg":"trace[819212446] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2114; }","duration":"277.991454ms","start":"2026-10-13T11:16:59.413146Z","end":"2026-10-13T11:16:59.691138Z","steps":["trace[819212446] 'agreement among raft nodes before linearized reading' (duration: 277.832578ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T11:16:59.970662Z","caller":"traceutil/trace.go:171","msg":"trace[376404958] transaction","detail":"{read_only:false; response_revision:2116; number_of_response:1; }","duration":"151.176521ms","start":"2026-10-13T11:16:59.819416Z","end":"2026-10-13T11:16:59.970593Z","steps":["trace[376404958] 'process raft request' (duration: 86.333948ms)","trace[376404958] 'compare' (duration: 64.554452ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T11:22:35.818014Z","caller":"traceutil/trace.go:171","msg":"trace[1227764612] linearizableReadLoop","detail":"{readStateIndex:4869; appliedIndex:4868; }","duration":"103.430463ms","start":"2026-10-13T11:22:35.714555Z","end":"2026-10-13T11:22:35.817986Z","steps":["trace[1227764612] 'read index received' (duration: 54.917527ms)","trace[1227764612] 'applied index is now lower than readState.Index' (duration: 48.512386ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T11:22:35.818132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.566155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-6z5sx-tls\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T11:22:35.818121Z","caller":"traceutil/trace.go:171","msg":"trace[235847566] transaction","detail":"{read_only:false; response_revision:4687; number_of_response:1; }","duration":"149.659351ms","start":"2026-10-13T11:22:35.66842Z","end":"2026-10-13T11:22:35.818079Z","steps":["trace[235847566] 'process raft request' (duration: 101.037863ms)","trace[235847566] 'compare' (duration: 48.451055ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T11:22:35.818162Z","caller":"traceutil/trace.go:171","msg":"trace[1669543082] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-6z5sx-tls; range_end:; response_count:0; response_revision:4687; }","duration":"103.635186ms","start":"2026-10-13T11:22:35.714518Z","end":"2026-10-13T11:22:35.818154Z","steps":["trace[1669543082] 'agreement among raft nodes before linearized reading' (duration: 103.575555ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T11:22:35.940104Z","caller":"traceutil/trace.go:171","msg":"trace[2029724230] linearizableReadLoop","detail":"{readStateIndex:4870; appliedIndex:4869; }","duration":"101.686462ms","start":"2026-10-13T11:22:35.838394Z","end":"2026-10-13T11:22:35.94008Z","steps":["trace[2029724230] 'read index received' (duration: 84.254062ms)","trace[2029724230] 'applied index is now lower than readState.Index' (duration: 17.43153ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T11:22:35.940149Z","caller":"traceutil/trace.go:171","msg":"trace[600631367] transaction","detail":"{read_only:false; response_revision:4688; number_of_response:1; }","duration":"118.738617ms","start":"2026-10-13T11:22:35.821391Z","end":"2026-10-13T11:22:35.940129Z","steps":["trace[600631367] 'process raft request' (duration: 101.334747ms)","trace[600631367] 'compare' (duration: 17.189036ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T11:22:35.940312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.906617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-10-13T11:22:35.940349Z","caller":"traceutil/trace.go:171","msg":"trace[332322281] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4688; }","duration":"101.976948ms","start":"2026-10-13T11:22:35.838359Z","end":"2026-10-13T11:22:35.940336Z","steps":["trace[332322281] 'agreement among raft nodes before linearized reading' (duration: 101.810495ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T11:22:42.496304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.06873ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-13T11:22:42.496422Z","caller":"traceutil/trace.go:171","msg":"trace[639376162] linearizableReadLoop","detail":"{readStateIndex:4993; appliedIndex:4992; }","duration":"225.167351ms","start":"2026-10-13T11:22:42.271234Z","end":"2026-10-13T11:22:42.496401Z","steps":["trace[639376162] 'read index received' (duration: 66.836016ms)","trace[639376162] 'applied index is now lower than readState.Index' (duration: 158.329725ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T11:22:42.496853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.49297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-13T11:22:42.497022Z","caller":"traceutil/trace.go:171","msg":"trace[918533218] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4807; }","duration":"115.678483ms","start":"2026-10-13T11:22:42.381296Z","end":"2026-10-13T11:22:42.496975Z","steps":["trace[918533218] 'agreement among raft nodes before linearized reading' (duration: 115.411889ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T11:22:42.497256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.357927ms","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-10-13T11:22:42.497323Z","caller":"traceutil/trace.go:171","msg":"trace[1625017166] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4807; }","duration":"101.449999ms","start":"2026-10-13T11:22:42.395864Z","end":"2026-10-13T11:22:42.497314Z","steps":["trace[1625017166] 'agreement among raft nodes before linearized reading' (duration: 101.367098ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T11:22:42.497401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.156317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T11:22:42.497458Z","caller":"traceutil/trace.go:171","msg":"trace[142106489] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4807; }","duration":"226.251709ms","start":"2026-10-13T11:22:42.27119Z","end":"2026-10-13T11:22:42.497442Z","steps":["trace[142106489] 'agreement among raft nodes before linearized reading' (duration: 226.167018ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T11:22:42.497303Z","caller":"traceutil/trace.go:171","msg":"trace[124398624] transaction","detail":"{read_only:false; response_revision:4807; number_of_response:1; }","duration":"283.716634ms","start":"2026-10-13T11:22:42.213536Z","end":"2026-10-13T11:22:42.497253Z","steps":["trace[124398624] 'process raft request' (duration: 124.603116ms)","trace[124398624] 'compare' (duration: 157.957279ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T11:22:42.982172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.025326ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-13T11:22:42.982522Z","caller":"traceutil/trace.go:171","msg":"trace[427016836] transaction","detail":"{read_only:false; response_revision:4813; number_of_response:1; }","duration":"244.238327ms","start":"2026-10-13T11:22:42.738111Z","end":"2026-10-13T11:22:42.982349Z","steps":["trace[427016836] 'process raft request' (duration: 112.941176ms)","trace[427016836] 'compare' (duration: 130.905444ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T11:22:42.982584Z","caller":"traceutil/trace.go:171","msg":"trace[1854955150] transaction","detail":"{read_only:false; response_revision:4814; number_of_response:1; }","duration":"244.41509ms","start":"2026-10-13T11:22:42.738149Z","end":"2026-10-13T11:22:42.982564Z","steps":["trace[1854955150] 'process raft request' (duration: 244.112915ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T11:22:42.983482Z","caller":"traceutil/trace.go:171","msg":"trace[1606243090] transaction","detail":"{read_only:false; response_revision:4815; number_of_response:1; }","duration":"245.294236ms","start":"2026-10-13T11:22:42.738176Z","end":"2026-10-13T11:22:42.98347Z","steps":["trace[1606243090] 'process raft request' (duration: 244.337429ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T11:22:42.98363Z","caller":"traceutil/trace.go:171","msg":"trace[1324905386] transaction","detail":"{read_only:false; response_revision:4816; number_of_response:1; }","duration":"245.121082ms","start":"2026-10-13T11:22:42.738503Z","end":"2026-10-13T11:22:42.983624Z","steps":["trace[1324905386] 'process raft request' (duration: 244.728586ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T11:22:55.077749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.832105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T11:22:55.07821Z","caller":"traceutil/trace.go:171","msg":"trace[1011363338] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4975; }","duration":"167.13941ms","start":"2026-10-13T11:22:54.910851Z","end":"2026-10-13T11:22:55.077991Z","steps":["trace[1011363338] 'range keys from in-memory index tree' (duration: 166.763503ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T11:24:02.721549Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2883} {"level":"info","ts":"2026-10-13T11:24:02.832486Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2883,"took":"108.806739ms","hash":3945230579,"current-db-size-bytes":53514240,"current-db-size":"54 MB","current-db-size-in-use-bytes":29085696,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-13T11:24:02.832651Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3945230579,"revision":2883,"compact-revision":-1} {"level":"info","ts":"2026-10-13T11:26:42.999288Z","caller":"traceutil/trace.go:171","msg":"trace[719424492] transaction","detail":"{read_only:false; response_revision:6619; number_of_response:1; }","duration":"146.500411ms","start":"2026-10-13T11:26:42.852774Z","end":"2026-10-13T11:26:42.999275Z","steps":["trace[719424492] 'process raft request' (duration: 146.274403ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T11:26:42.999255Z","caller":"traceutil/trace.go:171","msg":"trace[344176757] linearizableReadLoop","detail":"{readStateIndex:6865; appliedIndex:6864; }","duration":"112.431297ms","start":"2026-10-13T11:26:42.886796Z","end":"2026-10-13T11:26:42.999227Z","steps":["trace[344176757] 'read index received' (duration: 112.120265ms)","trace[344176757] 'applied index is now lower than readState.Index' (duration: 309.912µs)"],"step_count":2} {"level":"warn","ts":"2026-10-13T11:26:42.999497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.629734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-13T11:26:42.999556Z","caller":"traceutil/trace.go:171","msg":"trace[856882465] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:6619; }","duration":"112.748848ms","start":"2026-10-13T11:26:42.88679Z","end":"2026-10-13T11:26:42.999539Z","steps":["trace[856882465] 'agreement among raft nodes before linearized reading' (duration: 112.52362ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T11:27:30.826963Z","caller":"traceutil/trace.go:171","msg":"trace[760620960] transaction","detail":"{read_only:false; response_revision:6926; number_of_response:1; }","duration":"158.220856ms","start":"2026-10-13T11:27:30.668714Z","end":"2026-10-13T11:27:30.826934Z","steps":["trace[760620960] 'process raft request' (duration: 157.761782ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T11:27:30.827233Z","caller":"traceutil/trace.go:171","msg":"trace[1545637919] transaction","detail":"{read_only:false; response_revision:6927; number_of_response:1; }","duration":"151.987643ms","start":"2026-10-13T11:27:30.675228Z","end":"2026-10-13T11:27:30.827216Z","steps":["trace[1545637919] 'process raft request' (duration: 151.301982ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T11:27:30.827307Z","caller":"traceutil/trace.go:171","msg":"trace[990074893] transaction","detail":"{read_only:false; response_revision:6928; number_of_response:1; }","duration":"147.283118ms","start":"2026-10-13T11:27:30.680018Z","end":"2026-10-13T11:27:30.827301Z","steps":["trace[990074893] 'process raft request' (duration: 146.554005ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T11:27:30.827247Z","caller":"traceutil/trace.go:171","msg":"trace[383514194] transaction","detail":"{read_only:false; response_revision:6925; number_of_response:1; }","duration":"159.963891ms","start":"2026-10-13T11:27:30.667238Z","end":"2026-10-13T11:27:30.827202Z","steps":["trace[383514194] 'process raft request' (duration: 114.998989ms)","trace[383514194] 'compare' (duration: 44.132896ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T11:27:30.827696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.492845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-13T11:27:30.827803Z","caller":"traceutil/trace.go:171","msg":"trace[680069712] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:6928; }","duration":"147.630059ms","start":"2026-10-13T11:27:30.680152Z","end":"2026-10-13T11:27:30.827782Z","steps":["trace[680069712] 'agreement among raft nodes before linearized reading' (duration: 147.33368ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T11:27:30.826654Z","caller":"traceutil/trace.go:171","msg":"trace[932063367] linearizableReadLoop","detail":"{readStateIndex:7186; appliedIndex:7181; }","duration":"146.455672ms","start":"2026-10-13T11:27:30.680171Z","end":"2026-10-13T11:27:30.826627Z","steps":["trace[932063367] 'read index received' (duration: 10.233437ms)","trace[932063367] 'applied index is now lower than readState.Index' (duration: 136.221265ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T11:27:30.965217Z","caller":"traceutil/trace.go:171","msg":"trace[201877383] transaction","detail":"{read_only:false; response_revision:6930; number_of_response:1; }","duration":"128.168487ms","start":"2026-10-13T11:27:30.837021Z","end":"2026-10-13T11:27:30.965189Z","steps":["trace[201877383] 'process raft request' (duration: 100.521871ms)","trace[201877383] 'compare' (duration: 27.444709ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T11:27:30.965923Z","caller":"traceutil/trace.go:171","msg":"trace[1504642151] transaction","detail":"{read_only:false; response_revision:6931; number_of_response:1; }","duration":"128.674382ms","start":"2026-10-13T11:27:30.837225Z","end":"2026-10-13T11:27:30.965899Z","steps":["trace[1504642151] 'process raft request' (duration: 127.889828ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T11:27:30.966066Z","caller":"traceutil/trace.go:171","msg":"trace[1264175615] transaction","detail":"{read_only:false; response_revision:6932; number_of_response:1; }","duration":"128.013272ms","start":"2026-10-13T11:27:30.838042Z","end":"2026-10-13T11:27:30.966055Z","steps":["trace[1264175615] 'process raft request' (duration: 127.783775ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T11:27:30.966189Z","caller":"traceutil/trace.go:171","msg":"trace[1439233038] transaction","detail":"{read_only:false; response_revision:6933; number_of_response:1; }","duration":"126.462803ms","start":"2026-10-13T11:27:30.8397Z","end":"2026-10-13T11:27:30.966162Z","steps":["trace[1439233038] 'process raft request' (duration: 126.259537ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T11:27:30.966288Z","caller":"traceutil/trace.go:171","msg":"trace[1167541597] transaction","detail":"{read_only:false; response_revision:6935; number_of_response:1; }","duration":"116.367501ms","start":"2026-10-13T11:27:30.849907Z","end":"2026-10-13T11:27:30.966274Z","steps":["trace[1167541597] 'process raft request' (duration: 116.169315ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T11:27:30.966325Z","caller":"traceutil/trace.go:171","msg":"trace[1694928349] transaction","detail":"{read_only:false; response_revision:6934; number_of_response:1; }","duration":"121.224971ms","start":"2026-10-13T11:27:30.845089Z","end":"2026-10-13T11:27:30.966314Z","steps":["trace[1694928349] 'process raft request' (duration: 120.923282ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T11:27:30.966532Z","caller":"traceutil/trace.go:171","msg":"trace[2093632345] transaction","detail":"{read_only:false; response_revision:6936; number_of_response:1; }","duration":"114.889275ms","start":"2026-10-13T11:27:30.851635Z","end":"2026-10-13T11:27:30.966525Z","steps":["trace[2093632345] 'process raft request' (duration: 114.571836ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T11:27:31.124576Z","caller":"traceutil/trace.go:171","msg":"trace[1496864524] linearizableReadLoop","detail":"{readStateIndex:7200; appliedIndex:7197; }","duration":"103.480308ms","start":"2026-10-13T11:27:31.021069Z","end":"2026-10-13T11:27:31.124549Z","steps":["trace[1496864524] 'read index received' (duration: 10.165204ms)","trace[1496864524] 'applied index is now lower than readState.Index' (duration: 93.313904ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T11:27:31.12469Z","caller":"traceutil/trace.go:171","msg":"trace[118694555] transaction","detail":"{read_only:false; response_revision:6940; number_of_response:1; }","duration":"103.853819ms","start":"2026-10-13T11:27:31.020823Z","end":"2026-10-13T11:27:31.124677Z","steps":["trace[118694555] 'process raft request' (duration: 103.682474ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T11:27:31.124716Z","caller":"traceutil/trace.go:171","msg":"trace[1994009078] transaction","detail":"{read_only:false; response_revision:6939; number_of_response:1; }","duration":"106.303314ms","start":"2026-10-13T11:27:31.018391Z","end":"2026-10-13T11:27:31.124695Z","steps":["trace[1994009078] 'process raft request' (duration: 106.051037ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T11:27:31.124829Z","caller":"traceutil/trace.go:171","msg":"trace[1557831360] transaction","detail":"{read_only:false; response_revision:6938; number_of_response:1; }","duration":"106.731118ms","start":"2026-10-13T11:27:31.018074Z","end":"2026-10-13T11:27:31.124806Z","steps":["trace[1557831360] 'process raft request' (duration: 48.461567ms)","trace[1557831360] 'compare' (duration: 57.783615ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T11:27:31.125017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.936742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/openstack/horizon-f7bd69c8b\" ","response":"range_response_count:1 size:8700"} {"level":"info","ts":"2026-10-13T11:27:31.125038Z","caller":"traceutil/trace.go:171","msg":"trace[1806793699] range","detail":"{range_begin:/registry/replicasets/openstack/horizon-f7bd69c8b; range_end:; response_count:1; response_revision:6940; }","duration":"103.992364ms","start":"2026-10-13T11:27:31.02104Z","end":"2026-10-13T11:27:31.125032Z","steps":["trace[1806793699] 'agreement among raft nodes before linearized reading' (duration: 103.897621ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T11:27:31.194393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.778807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/auth-system/keycloak\" ","response":"range_response_count:1 size:865"} {"level":"info","ts":"2026-10-13T11:27:31.194455Z","caller":"traceutil/trace.go:171","msg":"trace[1922488490] range","detail":"{range_begin:/registry/ingress/auth-system/keycloak; range_end:; response_count:1; response_revision:6941; }","duration":"116.875511ms","start":"2026-10-13T11:27:31.077566Z","end":"2026-10-13T11:27:31.194442Z","steps":["trace[1922488490] 'agreement among raft nodes before linearized reading' (duration: 116.686995ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T11:27:38.43405Z","caller":"traceutil/trace.go:171","msg":"trace[1472648147] transaction","detail":"{read_only:false; response_revision:6978; number_of_response:1; }","duration":"202.680783ms","start":"2026-10-13T11:27:38.231337Z","end":"2026-10-13T11:27:38.434017Z","steps":["trace[1472648147] 'process raft request' (duration: 202.507318ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T11:27:38.439283Z","caller":"traceutil/trace.go:171","msg":"trace[1746748752] linearizableReadLoop","detail":"{readStateIndex:7240; appliedIndex:7238; }","duration":"178.793293ms","start":"2026-10-13T11:27:38.260471Z","end":"2026-10-13T11:27:38.439265Z","steps":["trace[1746748752] 'read index received' (duration: 173.246103ms)","trace[1746748752] 'applied index is now lower than readState.Index' (duration: 5.54644ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T11:27:38.439374Z","caller":"traceutil/trace.go:171","msg":"trace[1530638077] transaction","detail":"{read_only:false; response_revision:6979; number_of_response:1; }","duration":"196.216465ms","start":"2026-10-13T11:27:38.243145Z","end":"2026-10-13T11:27:38.439362Z","steps":["trace[1530638077] 'process raft request' (duration: 196.031059ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T11:27:38.439645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.157914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-13T11:27:38.43982Z","caller":"traceutil/trace.go:171","msg":"trace[920640468] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6979; }","duration":"179.309168ms","start":"2026-10-13T11:27:38.26043Z","end":"2026-10-13T11:27:38.439739Z","steps":["trace[920640468] 'agreement among raft nodes before linearized reading' (duration: 179.03169ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T11:27:44.336705Z","caller":"traceutil/trace.go:171","msg":"trace[790783867] transaction","detail":"{read_only:false; response_revision:7007; number_of_response:1; }","duration":"109.797123ms","start":"2026-10-13T11:27:44.226886Z","end":"2026-10-13T11:27:44.336684Z","steps":["trace[790783867] 'process raft request' (duration: 109.69409ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T11:29:02.72802Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5546} {"level":"info","ts":"2026-10-13T11:29:02.846977Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5546,"took":"117.138712ms","hash":2262911830,"current-db-size-bytes":53514240,"current-db-size":"54 MB","current-db-size-in-use-bytes":18550784,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-10-13T11:29:02.847085Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2262911830,"revision":5546,"compact-revision":2883}