Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.03.2023 16:40
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16209624 kB
MemAvailable 30434928 kB
Buffers 0 kB
Cached 15514704 kB
SwapCached 3052 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.11
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2023-03-03 16:38:40
DTS_END                         2023-03-03 16:38:40.4240
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/47142
TRN_ID                          239075
ATT_ID                          712
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 9235.10 19805 1108212
customer order: creation 170.68 374 20482
customer order: refuse 14.67 345 1760
order to supplier: creation 45.11 1194 5413
order to supplier: removal 3.43 96268 412
invoice (draft): creation 44.83 3415 5380
invoice (draft): removal 53.35 684 6402
invoice accept: apply 40.91 19957 4909
invoice accept: cancel 48.25 787 5790
customer reserve: creation 3767.73 199 452128
customer reserve: removal 4756.58 55 570790
realization accept: apply 81.65 164 9798
realization accept: cancel 77.84 111 9341
payment from customer: creation 59.16 101 7099
payment from customer: removal 5.78 81 693
payment to supplier: creation 49.60 124 5952
payment to supplier: removal 3.28 60 393
service: total inventory turnovers 4.73 1112 568
service: total monetary turnovers 6.71 27 805
service: aggregate perf. data 0.78 24171 93
service: refresh index statistics 0.03 246868 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-03-2023 16:40:38 to 03-03-2023 16:40:40.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2228
TEST_TIME 2 4073
TEST_TIME 3 4965
TEST_TIME 4 5075
TEST_TIME 5 5322
TEST_TIME 6 4986
TEST_TIME 7 5227
TEST_TIME 8 5637
TEST_TIME 9 6037
TEST_TIME 10 6077
TEST_TIME 11 6294
TEST_TIME 12 6159
TEST_TIME 13 6152
TEST_TIME 14 6284
TEST_TIME 15 6270
TEST_TIME 16 6597
TEST_TIME 17 7208
TEST_TIME 18 7433
TEST_TIME 19 8117
TEST_TIME 20 8759
TEST_TIME 21 9183
TEST_TIME 22 9248
TEST_TIME 23 9363
TEST_TIME 24 9474
TEST_TIME 25 9527
TEST_TIME 26 9773
TEST_TIME 27 9908
TEST_TIME 28 9712
TEST_TIME 29 9643
TEST_TIME 30 9801
TEST_TIME 31 10080
TEST_TIME 32 10118
TEST_TIME 33 10100
TEST_TIME 34 9935
TEST_TIME 35 9767
TEST_TIME 36 9604
TEST_TIME 37 9645
TEST_TIME 38 9679
TEST_TIME 39 9606
TEST_TIME 40 9414
TEST_TIME 41 9457
TEST_TIME 42 9386
TEST_TIME 43 9413
TEST_TIME 44 9512
TEST_TIME 45 9368
TEST_TIME 46 9283
TEST_TIME 47 9335
TEST_TIME 48 9288
TEST_TIME 49 9203
TEST_TIME 50 9119
TEST_TIME 51 8988
TEST_TIME 52 8898
TEST_TIME 53 9050
TEST_TIME 54 9162
TEST_TIME 55 9150
TEST_TIME 56 9165
TEST_TIME 57 9151
TEST_TIME 58 9183
TEST_TIME 59 9179
TEST_TIME 60 9284
TEST_TIME 61 9305
TEST_TIME 62 9371
TEST_TIME 63 9363
TEST_TIME 64 9386
TEST_TIME 65 9421
TEST_TIME 66 9344
TEST_TIME 67 9405
TEST_TIME 68 9434
TEST_TIME 69 9310
TEST_TIME 70 9280
TEST_TIME 71 9199
TEST_TIME 72 9263
TEST_TIME 73 9297
TEST_TIME 74 9242
TEST_TIME 75 9223
TEST_TIME 76 9199
TEST_TIME 77 9171
TEST_TIME 78 9167
TEST_TIME 79 9099
TEST_TIME 80 9110
TEST_TIME 81 9094
TEST_TIME 82 9029
TEST_TIME 83 9038
TEST_TIME 84 9047
TEST_TIME 85 9015
TEST_TIME 86 9003
TEST_TIME 87 8997
TEST_TIME 88 9008
TEST_TIME 89 9073
TEST_TIME 90 9153
TEST_TIME 91 9138
TEST_TIME 92 9222
TEST_TIME 93 9190
TEST_TIME 94 9232
TEST_TIME 95 9239
TEST_TIME 96 9284
TEST_TIME 97 9258
TEST_TIME 98 9316
TEST_TIME 99 9277
TEST_TIME 100 9249
TEST_TIME 101 9243
TEST_TIME 102 9197
TEST_TIME 103 9194
TEST_TIME 104 9166
TEST_TIME 105 9123
TEST_TIME 106 9101
TEST_TIME 107 9136
TEST_TIME 108 9174
TEST_TIME 109 9137
TEST_TIME 110 9163
TEST_TIME 111 9202
TEST_TIME 112 9202
TEST_TIME 113 9197
TEST_TIME 114 9220
TEST_TIME 115 9228
TEST_TIME 116 9238
TEST_TIME 117 9256
TEST_TIME 118 9249
TEST_TIME 119 9286
TEST_TIME 120 9239

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 03-03-2023 16:40:42 to 03-03-2023 16:40:45.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1528671 1528671 0 0.00 0 25292 7 0 0
doc_list_biud 1528671 1528671 0 0.00 0 23024 26 0 0
sp_add_invoice_to_stock 5071 4909 162 3.19 46 197185 19958 0 162
sp_cancel_adding_invoice 5790 5790 0 0.00 4 64466 788 0 0
sp_cancel_client_order 1760 1760 0 0.00 4 10292 346 0 0
sp_cancel_customer_reserve 570790 570790 0 0.00 0 22550 55 0 0
sp_cancel_pay_from_customer 693 693 0 0.00 3 4972 82 0 0
sp_cancel_pay_to_supplier 393 393 0 0.00 3 3012 60 0 0
sp_cancel_supplier_invoice 6417 6402 15 0.23 2 25345 684 0 15
sp_cancel_supplier_order 412 412 0 0.00 8 1151574 96268 0 0
sp_cancel_write_off 9341 9341 0 0.00 1 6973 111 0 0
sp_client_order 20482 20482 0 0.00 3 24132 374 0 0
sp_customer_reserve 452128 452128 0 0.00 0 113995 200 0 0
sp_fill_shopping_cart 31274 31274 0 0.00 1 15589 37 0 0
sp_fill_shopping_cart_clo_res 452124 452124 0 0.00 0 12379 13 0 0
sp_get_clo_for_invoice 4896 4896 0 0.00 0 30285 331 0 0
sp_kill_cost_storno 16217 16217 0 0.00 0 11724 56 0 0
sp_kill_qstorno_ret_qs2qd 1157735 1157735 0 0.00 0 22515 29 0 0
sp_kill_qty_storno 609186 609186 0 0.00 0 23022 64 0 0
sp_lock_selected_doc 20532 20532 0 0.00 0 7226 6 0 0
sp_make_cost_storno 27742 27742 0 0.00 0 12916 52 0 0
sp_multiply_rows_for_pdistr 27742 27742 0 0.00 0 8416 28 0 0
sp_multiply_rows_for_qdistr 20482 20482 0 0.00 0 24014 169 0 0
sp_pay_from_customer 7099 7099 0 0.00 4 12922 102 0 0
sp_pay_to_supplier 5952 5952 0 0.00 4 12817 124 0 0
sp_payment_common 13051 13051 0 0.00 1 8147 25 0 0
sp_qd_handle_on_cancel_clo 1760 1760 0 0.00 0 10264 322 0 0
sp_qd_handle_on_invoice_upd_sts 10683 10683 0 0.00 0 23020 324 0 0
sp_qd_handle_on_reserve_upd_sts 9798 9798 0 0.00 0 20245 51 0 0
sp_reserve_write_off 9798 9798 0 0.00 6 20296 165 0 0
sp_supplier_invoice 5380 5380 0 0.00 7 86800 3415 0 0
sp_supplier_order 5413 5413 0 0.00 5 27374 1195 0 0
srv_aggregate_perf_data 93 93 0 0.00 2726 409043 24172 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1875 161009 45883 0 0
srv_find_qd_qs_mism 1061862 1061862 0 0.00 0 25292 6 0 0
srv_make_invnt_saldo 568 568 0 0.00 60 54428 1113 0 0
srv_make_money_saldo 805 805 0 0.00 1 657 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 193456 312467 246869 0 0
t$perf-abend:sp_add_invoice_to_stock 162 162 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4909 4909 0 0.00 0 20587 162 0 0
t$perf-norm:sp_cancel_adding_invoice 394 394 0 0.00 0 24974 154 0 0
t$perf-norm:sp_cancel_client_order 1760 1760 0 0.00 0 3952 25 0 0
t$perf-norm:sp_cancel_customer_reserve 743 743 0 0.00 0 1549 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 693 693 0 0.00 0 4845 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 393 393 0 0.00 0 2384 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 425 425 0 0.00 0 4371 27 0 0
t$perf-norm:sp_cancel_supplier_order 412 412 0 0.00 0 27605 1968 0 0
t$perf-norm:sp_cancel_write_off 1021 1021 0 0.00 0 2321 11 0 0
t$perf-norm:sp_client_order 20482 20482 0 0.00 0 13883 18 0 0
t$perf-norm:sp_customer_reserve 939 939 0 0.00 0 3299 11 0 0
t$perf-norm:sp_pay_from_customer 7099 7099 0 0.00 0 7811 12 0 0
t$perf-norm:sp_pay_to_supplier 5952 5952 0 0.00 0 15510 20 0 0
t$perf-norm:sp_reserve_write_off 9798 9798 0 0.00 0 8027 10 0 0
t$perf-norm:sp_supplier_invoice 5380 5380 0 0.00 0 8803 18 0 0
t$perf-norm:sp_supplier_order 5413 5413 0 0.00 0 12760 19 0 0
t$perf-norm:srv_aggregate_perf_data 93 93 0 0.00 0 257 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 1895 31 0 0
t$perf-norm:srv_make_invnt_saldo 568 568 0 0.00 0 12028 61 0 0
t$perf-norm:srv_make_money_saldo 805 805 0 0.00 0 5239 23 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10110 9786 324 3.20 0 5290 23 0 324
v_all_customers 37478 37478 0 0.00 0 33 1 0 0
v_all_suppliers 30534 30534 0 0.00 0 22 1 0 0
v_all_wares 40964 40964 0 0.00 0 35 1 0 0
v_cancel_adding_invoice 788 788 0 0.00 0 1359 7 0 0
v_cancel_client_order 3520 3520 0 0.00 0 2961 6 0 0
v_cancel_customer_prepayment 1386 1386 0 0.00 0 3065 8 0 0
v_cancel_customer_reserve 1486 1486 0 0.00 0 1792 7 0 0
v_cancel_payment_to_supplier 786 786 0 0.00 0 1779 6 0 0
v_cancel_supplier_invoice 880 850 30 3.40 0 1254 19 0 30
v_cancel_supplier_order 824 824 0 0.00 0 1561 5 0 0
v_cancel_write_off 2042 2042 0 0.00 0 5341 24 0 0
v_max_id_clo_ord 5413 5413 0 0.00 0 856 1 0 0
v_max_id_clo_res 939 939 0 0.00 0 4726 18 0 0
v_max_id_ord_sup 5379 5379 0 0.00 0 6413 5 0 0
v_max_non_paid_invoice 1477 1477 0 0.00 0 1181 4 0 0
v_max_non_paid_realizn 3765 3765 0 0.00 0 2568 5 0 0
v_min_id_clo_ord 5413 5413 0 0.00 0 4729 8 0 0
v_min_id_clo_res 939 939 0 0.00 0 4699 9 0 0
v_min_id_ord_sup 5379 5379 0 0.00 0 5917 4 0 0
v_min_non_paid_invoice 5952 5952 0 0.00 0 6642 18 0 0
v_min_non_paid_realizn 7099 7099 0 0.00 0 8140 17 0 0
v_our_firm 10154 10154 0 0.00 0 19 1 0 0
v_reserve_write_off 19596 19596 0 0.00 0 3718 6 0 0
x_lock_depdocs_on_canc_invoice 11767 11767 0 0.00 0 24630 123 0 0
x_lock_depdocs_on_canc_sup_ord 412 412 0 0.00 0 13183 442 0 0
x_make_qstorno_1000_1200 5413 5413 0 0.00 1 27180 1114 0 0
x_make_qstorno_1200_2000 5380 5380 0 0.00 4 86104 3285 0 0
x_make_qstorno_2100_3300 452128 452128 0 0.00 0 113995 184 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 03-03-2023 16:40:46 to 03-03-2023 16:40:51.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
14:08:44 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:45 19332 8192000000 9893522528 10200928256 973034832 10.616 100 89 178 0 1003522896 6124560 24363504
14:11:10 23284 8192000000 9947715088 10271006720 1031185696 11.180 100 85 170 0 1060972832 8129136 21658000
14:12:35 15448 8192000000 9980220400 10309722112 1062334928 11.479 100 89 178 0 1094283392 9567824 22380640
14:13:54 8476 8192000000 10019137712 10359549952 1106414016 11.898 100 85 169 0 1137422848 10459504 20549328
14:15:08 4120 8192000000 10071164944 10424074240 1156108592 12.367 100 81 163 0 1187622672 12665808 18848272
14:16:14 3831 8192000000 10113547792 10474975232 1195154304 12.731 100 76 152 0 1224855392 12651392 17049696
14:17:22 1449 8192000000 10151928448 10522394624 1237218736 13.121 100 79 156 1 1267876576 13718304 16939536
14:18:26 2053 8192000000 10171066016 10550153216 1261726448 13.346 100 70 141 1 1289680176 13394432 14559296
14:19:29 943 8192000000 10204493472 10587570176 1287460448 13.581 100 91 184 0 1320673024 12596864 20615712
14:20:31 6069 8192000000 10189950816 10588086272 1293113696 13.633 100 53 102 2 1314114720 9361248 11639776
14:21:40 9784 8192000000 10201256016 10602614784 1305598640 13.746 100 28 53 0 1317229648 4879552 6751456
14:22:50 9813 8192000000 10190013392 10610794496 1315043520 13.832 100 13 26 1 1323812592 5812592 2956480
14:24:01 57659 8192000000 10208335392 10629365760 1322276416 13.897 100 31 62 0 1335239776 5523632 7439728
14:25:59 2649 8192000000 10221822032 10641653760 1336543200 14.026 100 33 62 0 1350017952 6642208 6832544
14:27:05 6505 8192000000 10221646512 10645037056 1334337632 14.006 100 25 50 0 1346235568 6298896 5599040
14:28:14 47728 8192000000 10171016560 10574249984 1241335072 13.159 94 28 54 1 1252008048 4540896 6132080
14:30:03 7839 8192000000 10172952288 10576760832 1229853712 13.053 95 27 54 0 1242758432 6969664 5935056
14:31:11 5262 8192000000 10143480880 10544005120 1243552240 13.179 99 38 77 0 1257973936 5223664 9198032
14:32:18 6817 8192000000 10162355680 10568859648 1274505312 13.463 100 24 48 0 1286265584 6112240 5648032
14:33:25 40597 8192000000 10158839696 10566520832 1254338224 13.278 98 23 45 1 1264843456 5276928 5228304
14:35:06 4212 8192000000 10197742928 10602168320 1293908144 13.640 100 28 56 1 1305355984 5264912 6182928
14:36:15 10722 8192000000 10194845456 10611236864 1307115472 13.760 100 12 22 0 1314970016 5381952 2472592
14:37:26 17337 8192000000 10211489776 10631172096 1319025008 13.868 100 32 64 0 1332668544 5839360 7804176
14:38:49 12845 8192000000 10238658544 10648899584 1336223088 14.023 100 42 84 1 1352344416 6606240 9515088
14:40:08 6927 8192000000 10256713104 10670964736 1351968992 14.165 100 53 106 0 1375954048 11056256 12928800
14:41:17 3269 8192000000 10282076704 10699194368 1369314208 14.321 100 58 117 0 1397286320 14051360 13920752
14:42:23 9014 8192000000 10277968848 10704093184 1375292608 14.374 100 42 84 0 1399825088 14500496 10031984
14:43:38 30185 8192000000 10294610224 10718502912 1384689088 14.458 100 47 93 2 1410601216 15315504 10596624
14:45:15 20115 8192000000 10297644288 10729254912 1387935504 14.487 100 44 88 0 1416563888 18307056 10321328
14:46:38 14798 8192000000 10306934384 10728243200 1386213104 14.472 100 54 106 1 1418490416 19742144 12535168
14:47:58 1966 8192000000 10346489136 10772107264 1419914256 14.772 100 62 119 2 1456128400 22723376 13490768
14:49:04 10112 8192000000 10330861216 10749886464 1394368208 14.545 99 55 108 0 1431771696 24242928 13160560
14:50:27 5666 8192000000 10351892288 10777194496 1428140752 14.845 100 49 98 1 1464116240 23460016 12515472
14:51:36 6318 8192000000 10366777104 10784276480 1429916656 14.861 100 67 134 1 1476959472 30230400 16812416
14:52:46 3176 8192000000 10370401920 10779160576 1418889504 14.763 100 90 178 1 1469646688 28312640 22444544
14:53:53 4286 8192000000 10346500928 10753593344 1386210976 14.472 99 83 164 0 1439341216 33223584 19906656
14:55:05 2282 8192000000 10330707600 10739974144 1379013040 14.408 99 82 162 4 1436749984 37574464 20162480
14:56:11 2912 8192000000 10329841216 10738024448 1376259440 14.383 100 81 164 1 1435273520 37793328 21220752
14:57:18 1875 8192000000 10335658432 10738708480 1368011872 14.309 100 90 178 1 1435698928 45235840 22451216
14:58:26 3600 8192000000 10314426704 10733719552 1375522112 14.376 100 63 124 0 1423863728 33083040 15258576
14:59:32 6417 8192000000 10312833040 10743840768 1381885408 14.433 100 33 66 1 1421441232 30484512 9071312
15:01:01 37663 8192000000 10297358112 10743586816 1377396432 14.393 100 20 40 0 1411359792 28339376 5623984
15:02:51 11667 8192000000 10313177824 10752004096 1380295312 14.419 100 46 92 0 1423125712 31427936 11402464
15:04:11 12489 8192000000 10317325664 10760613888 1384212736 14.454 100 22 44 0 1425686784 35246064 6227984
15:05:30 12936 8192000000 10298507104 10735128576 1375086272 14.373 100 20 40 0 1402569328 22415024 5068032
15:06:45 101660 8192000000 10310281024 10753445888 1380204512 14.418 100 37 74 0 1416168416 26990800 8973104
15:09:36 59455 8192000000 10287964576 10741551104 1381344960 14.429 100 15 30 0 1405831264 21012512 3473792
15:11:37 36283 8192000000 10277475824 10731954176 1379733728 14.414 99 27 54 1 1394187872 8592400 5861744
15:13:19 161007 8192000000 10267769952 10721341440 1359036096 14.229 98 17 33 0 1372487408 9967456 3483856
15:17:57 114236 8192000000 10300805952 10750259200 1378818896 14.406 99 21 45 0 1398465728 14207008 5439824
15:21:01 105549 8192000000 10283263296 10747191296 1351965856 14.165 97 23 46 0 1376696752 18772624 5958272
15:23:58 18975 8192000000 10296405856 10737238016 1385891312 14.469 100 40 76 2 1403661168 9014592 8755264
15:25:17 32871 8192000000 10284321072 10735722496 1368823648 14.317 99 31 64 1 1388513952 12628032 7062272
15:26:54 43131 8192000000 10283523824 10742849536 1388155584 14.489 100 13 26 0 1403647168 12172352 3319232
15:28:44 78572 8192000000 10295055904 10752757760 1384092256 14.453 99 18 36 0 1398447488 10065920 4289312
15:31:39 74516 8192000000 10288716384 10756067328 1378311872 14.401 99 11 22 0 1397413136 16927520 2173744
15:33:54 77143 8192000000 10294512912 10736316416 1350204512 14.149 97 17 34 0 1364548208 10459200 3884496
15:36:12 71420 8192000000 10273852672 10737471488 1325460448 13.926 96 10 19 0 1344239664 16471856 2307360
15:38:27 83391 8192000000 10292692656 10743025664 1343814528 14.092 97 21 42 0 1363465664 15050240 4600896
15:40:59 69948 8192000000 10287353904 10736406528 1339840752 14.056 96 23 46 0 1353995312 9524544 4630016
15:43:18 61199 8192000000 10281162000 10722287616 1290848304 13.612 93 19 36 1 1304028672 9267088 3913280
15:45:26 24614 8192000000 10285065360 10727976960 1351524272 14.161 99 32 64 0 1372428976 13965024 6939680
15:46:53 95067 8192000000 10247493344 10683621376 1326033552 13.931 97 41 82 0 1348192016 12824960 9333504
15:49:29 46601 8192000000 10270119120 10706599936 1324483808 13.917 98 48 96 1 1358920496 23771024 10665664
15:51:18 57720 8192000000 10210378944 10654572544 1263519024 13.362 94 18 33 0 1276077792 8969024 3589744
15:53:16 57278 8192000000 10231433344 10675535872 1315108416 13.832 98 13 26 0 1325344464 7549296 2686752
15:55:13 59041 8192000000 10253989856 10698379264 1330144544 13.968 98 17 34 0 1349745312 15732512 3868256
15:57:13 87176 8192000000 10251744704 10696560640 1340207168 14.059 99 23 44 3 1362026096 16160480 5658448
15:59:44 40864 8192000000 10257573536 10707054592 1360961552 14.246 100 12 25 0 1373143968 9426704 2755712
16:01:26 78704 8192000000 10258842672 10732797952 1352146000 14.167 99 11 22 0 1363836976 9466656 2224320
16:03:48 33735 8192000000 10258991648 10706165760 1307171824 13.760 95 25 50 0 1320539488 7647616 5720048
16:05:27 15935 8192000000 10289975232 10731462656 1376590832 14.386 100 37 75 0 1397109328 12269136 8249360
16:06:45 72099 8192000000 10292976448 10747920384 1364737408 14.280 99 21 42 0 1391615088 21744240 5133440
16:08:58 73559 8192000000 10299497184 10746073088 1367915712 14.308 98 18 35 0 1388161536 16637232 3608592
16:11:17 59519 8192000000 10303641264 10759630848 1379054000 14.408 99 20 40 0 1398985424 15336992 4594432
16:13:19 46595 8192000000 10322833536 10756886528 1380519888 14.421 99 29 58 0 1405927760 19024224 6383648
16:15:12 73051 8192000000 10323385872 10779017216 1392131728 14.525 99 22 44 0 1414812704 17467248 5213728
16:17:33 96858 8192000000 10300678000 10745724928 1355052112 14.193 97 26 52 1 1372544960 11712224 5780624
16:20:11 27173 8192000000 10299259728 10760314880 1393999488 14.542 100 8 16 0 1409387488 13503152 1884848
16:21:46 49484 8192000000 10294843616 10750623744 1395698064 14.557 100 6 12 0 1409833968 13039296 1096608
16:23:42 64764 8192000000 10312256144 10774196224 1410222304 14.686 100 16 32 0 1428707760 14725568 3759888
16:26:01 46086 8192000000 10296315136 10754035712 1361096688 14.247 97 8 16 0 1376191264 13029296 2065280
16:27:49 48324 8192000000 10287111520 10734583808 1378380928 14.402 99 34 68 3 1397558432 11472368 7705136
16:29:40 57818 8192000000 10283686336 10737348608 1335750928 14.019 96 16 32 1 1348619792 9268880 3599984
16:31:40 70745 8192000000 10252238736 10685542400 1269939392 13.421 93 31 62 0 1289604352 13064176 6600784
16:33:53 61969 8192000000 10254679184 10696900608 1326744576 13.938 99 25 50 1 1351590304 19035712 5810016
16:35:57 65678 8192000000 10270996032 10696663040 1323868000 13.912 98 37 74 0 1349218560 17141520 8209040
16:38:05 61962 8192000000 9791089664 10167123968 917500736 10.071 69 4 8 0 931696192 13572096 623360

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 03-03-2023 16:40:52 to 03-03-2023 16:41:08.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 162 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 03-03-2023 16:41:09 to 03-03-2023 16:41:11.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          239262
MON$OLDEST_ACTIVE               239263
MON$OLDEST_SNAPSHOT             239263
MON$NEXT_TRANSACTION            239263
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-03 16:41:55.3470
MON$PAGES                       2023040
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33665 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33665 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33665 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33665 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:910: 
:911: mscs-test-161.budzdorov.ru    Fri Mar  3 16:39:29 2023
:912:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:913: 
--- end of diff output ---

Final processing ISQL logs in /var/tmp/logs-oltp30 according to config parameter 'remove_isql_logs'

03.03.23 16:41:12. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2023-03-03 16:41:15 No severe PSQL-related problems occured
03.03.23 16:41:16. End of report.