Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.10.2022 04:38
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 15144748 kB
MemAvailable 30421940 kB
Buffers 20 kB
Cached 16482036 kB
SwapCached 2084 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 4.0.3
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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 2022-10-20 04:37:23
DTS_END                         2022-10-20 04:37:23.3230
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(134:18) ==> SP_CANCEL_SUPPLIER_INVOICE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/55366
TRN_ID                          207013
ATT_ID                          577
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: 8638.78 24084 1036654
customer order: creation 158.73 527 19048
customer order: refuse 13.45 466 1614
order to supplier: creation 42.43 1439 5092
order to supplier: removal 3.02 208532 362
invoice (draft): creation 42.02 3428 5042
invoice (draft): removal 49.13 1082 5895
invoice accept: apply 38.07 26634 4568
invoice accept: cancel 44.73 1421 5367
customer reserve: creation 3569.27 249 428312
customer reserve: removal 4410.04 112 529205
realization accept: apply 73.93 288 8871
realization accept: cancel 79.21 190 9505
payment from customer: creation 52.83 205 6339
payment from customer: removal 4.91 144 589
payment to supplier: creation 42.75 221 5130
payment to supplier: removal 2.68 128 322
service: total inventory turnovers 3.68 4305 441
service: total monetary turnovers 7.04 335 845
service: aggregate perf. data 0.84 19389 101
service: refresh index statistics 0.05 212582 6

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-10-2022 04:38:25 to 20-10-2022 04:38:27.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4578
TEST_TIME 2 5103
TEST_TIME 3 4917
TEST_TIME 4 5063
TEST_TIME 5 5183
TEST_TIME 6 5163
TEST_TIME 7 5039
TEST_TIME 8 5163
TEST_TIME 9 5165
TEST_TIME 10 5429
TEST_TIME 11 5839
TEST_TIME 12 5792
TEST_TIME 13 5774
TEST_TIME 14 5925
TEST_TIME 15 6299
TEST_TIME 16 6327
TEST_TIME 17 6521
TEST_TIME 18 6619
TEST_TIME 19 6759
TEST_TIME 20 6966
TEST_TIME 21 6903
TEST_TIME 22 7107
TEST_TIME 23 7280
TEST_TIME 24 7476
TEST_TIME 25 7471
TEST_TIME 26 7743
TEST_TIME 27 8254
TEST_TIME 28 8538
TEST_TIME 29 8959
TEST_TIME 30 8964
TEST_TIME 31 9004
TEST_TIME 32 9214
TEST_TIME 33 9509
TEST_TIME 34 9943
TEST_TIME 35 10372
TEST_TIME 36 10506
TEST_TIME 37 10384
TEST_TIME 38 10237
TEST_TIME 39 10217
TEST_TIME 40 10097
TEST_TIME 41 9970
TEST_TIME 42 9897
TEST_TIME 43 9715
TEST_TIME 44 9613
TEST_TIME 45 9495
TEST_TIME 46 9400
TEST_TIME 47 9522
TEST_TIME 48 9459
TEST_TIME 49 9404
TEST_TIME 50 9304
TEST_TIME 51 9254
TEST_TIME 52 9160
TEST_TIME 53 9040
TEST_TIME 54 9010
TEST_TIME 55 8975
TEST_TIME 56 8907
TEST_TIME 57 8863
TEST_TIME 58 8795
TEST_TIME 59 8807
TEST_TIME 60 8731
TEST_TIME 61 8612
TEST_TIME 62 8703
TEST_TIME 63 8741
TEST_TIME 64 8760
TEST_TIME 65 8737
TEST_TIME 66 8751
TEST_TIME 67 8766
TEST_TIME 68 8757
TEST_TIME 69 8757
TEST_TIME 70 8748
TEST_TIME 71 8844
TEST_TIME 72 8851
TEST_TIME 73 8870
TEST_TIME 74 8804
TEST_TIME 75 8848
TEST_TIME 76 8911
TEST_TIME 77 9007
TEST_TIME 78 9053
TEST_TIME 79 9039
TEST_TIME 80 8969
TEST_TIME 81 8886
TEST_TIME 82 8838
TEST_TIME 83 8819
TEST_TIME 84 8802
TEST_TIME 85 8769
TEST_TIME 86 8764
TEST_TIME 87 8710
TEST_TIME 88 8654
TEST_TIME 89 8669
TEST_TIME 90 8638
TEST_TIME 91 8740
TEST_TIME 92 8721
TEST_TIME 93 8704
TEST_TIME 94 8694
TEST_TIME 95 8691
TEST_TIME 96 8708
TEST_TIME 97 8707
TEST_TIME 98 8802
TEST_TIME 99 8798
TEST_TIME 100 8797
TEST_TIME 101 8854
TEST_TIME 102 8828
TEST_TIME 103 8856
TEST_TIME 104 8883
TEST_TIME 105 8826
TEST_TIME 106 8764
TEST_TIME 107 8733
TEST_TIME 108 8736
TEST_TIME 109 8703
TEST_TIME 110 8690
TEST_TIME 111 8636
TEST_TIME 112 8577
TEST_TIME 113 8560
TEST_TIME 114 8611
TEST_TIME 115 8638
TEST_TIME 116 8679
TEST_TIME 117 8652
TEST_TIME 118 8671
TEST_TIME 119 8694
TEST_TIME 120 8659

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 20-10-2022 04:38:29 to 20-10-2022 04:38:32.

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 1432565 1432565 0 0.00 0 20371 14 0 0
doc_list_biud 1432565 1432565 0 0.00 0 32905 47 0 0
sp_add_invoice_to_stock 4650 4568 82 1.76 56 188562 26634 0 82
sp_cancel_adding_invoice 5367 5367 0 0.00 6 83129 1421 0 0
sp_cancel_client_order 1614 1614 0 0.00 5 11412 467 0 0
sp_cancel_customer_reserve 529205 529205 0 0.00 1 32910 112 0 0
sp_cancel_pay_from_customer 589 589 0 0.00 3 6110 145 0 0
sp_cancel_pay_to_supplier 322 322 0 0.00 4 3713 128 0 0
sp_cancel_supplier_invoice 5902 5895 7 0.11 2 18853 1082 0 7
sp_cancel_supplier_order 362 362 0 0.00 13 1526736 208533 0 0
sp_cancel_write_off 9505 9505 0 0.00 2 12437 191 0 0
sp_client_order 19048 19048 0 0.00 5 40847 528 0 0
sp_customer_reserve 428312 428312 0 0.00 1 62259 249 0 0
sp_fill_shopping_cart 29175 29175 0 0.00 1 12844 85 0 0
sp_fill_shopping_cart_clo_res 428286 428286 0 0.00 0 17938 21 0 0
sp_get_clo_for_invoice 4518 4518 0 0.00 0 42893 372 0 0
sp_kill_cost_storno 15783 15783 0 0.00 0 6104 87 0 0
sp_kill_qstorno_ret_qs2qd 1074172 1074172 0 0.00 0 28896 49 0 0
sp_kill_qty_storno 565335 565335 0 0.00 0 32904 111 0 0
sp_lock_selected_doc 18532 18532 0 0.00 0 9823 7 0 0
sp_make_cost_storno 24856 24856 0 0.00 0 13703 92 0 0
sp_multiply_rows_for_pdistr 24856 24856 0 0.00 0 13381 44 0 0
sp_multiply_rows_for_qdistr 19048 19048 0 0.00 0 16009 192 0 0
sp_pay_from_customer 6339 6339 0 0.00 5 15038 205 0 0
sp_pay_to_supplier 5130 5130 0 0.00 6 12880 221 0 0
sp_payment_common 11469 11469 0 0.00 2 13377 52 0 0
sp_qd_handle_on_cancel_clo 1614 1614 0 0.00 1 11270 377 0 0
sp_qd_handle_on_invoice_upd_sts 9883 9883 0 0.00 0 23354 565 0 0
sp_qd_handle_on_reserve_upd_sts 8871 8871 0 0.00 0 11389 57 0 0
sp_reserve_write_off 8871 8871 0 0.00 8 15222 288 0 0
sp_supplier_invoice 5042 5042 0 0.00 8 84554 3428 0 0
sp_supplier_order 5092 5092 0 0.00 6 34570 1439 0 0
srv_aggregate_perf_data 101 101 0 0.00 2488 179928 19390 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 848 117497 38315 0 0
srv_find_qd_qs_mism 992768 992768 0 0.00 0 20368 10 0 0
srv_make_invnt_saldo 441 441 0 0.00 55 65945 4305 0 0
srv_make_money_saldo 845 845 0 0.00 1 10152 335 0 0
srv_recalc_idx_stat 6 6 0 0.00 88999 441514 212582 0 0
t$perf-abend:sp_add_invoice_to_stock 82 82 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4568 4568 0 0.00 1 31135 932 0 0
t$perf-norm:sp_cancel_adding_invoice 409 409 0 0.00 2 8554 572 0 0
t$perf-norm:sp_cancel_client_order 1614 1614 0 0.00 0 4554 29 0 0
t$perf-norm:sp_cancel_customer_reserve 587 587 0 0.00 0 2787 36 0 0
t$perf-norm:sp_cancel_pay_from_customer 589 589 0 0.00 0 1027 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 322 322 0 0.00 0 598 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 382 382 0 0.00 0 2986 53 0 0
t$perf-norm:sp_cancel_supplier_order 362 362 0 0.00 0 118423 9115 0 0
t$perf-norm:sp_cancel_write_off 880 880 0 0.00 0 2235 20 0 0
t$perf-norm:sp_client_order 19048 19048 0 0.00 0 27267 40 0 0
t$perf-norm:sp_customer_reserve 904 904 0 0.00 0 5406 20 0 0
t$perf-norm:sp_pay_from_customer 6339 6339 0 0.00 0 29541 27 0 0
t$perf-norm:sp_pay_to_supplier 5130 5130 0 0.00 0 21458 26 0 0
t$perf-norm:sp_reserve_write_off 8871 8871 0 0.00 0 11162 26 0 0
t$perf-norm:sp_supplier_invoice 5042 5042 0 0.00 0 3046 32 0 0
t$perf-norm:sp_supplier_order 5092 5092 0 0.00 0 3054 31 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 154 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 25 3 0 0
t$perf-norm:srv_make_invnt_saldo 441 441 0 0.00 0 1666 23 0 0
t$perf-norm:srv_make_money_saldo 845 845 0 0.00 0 2328 9 0 0
t$perf-norm:srv_recalc_idx_stat 6 6 0 0.00 10 210 81 0 0
v_add_invoice_to_stock 9196 9032 164 1.78 0 14875 31 0 164
v_all_customers 35012 35012 0 0.00 0 157 4 0 0
v_all_suppliers 27964 27964 0 0.00 0 76 4 0 0
v_all_wares 38096 38096 0 0.00 0 91 4 0 0
v_cancel_adding_invoice 818 818 0 0.00 0 3277 22 0 0
v_cancel_client_order 3228 3228 0 0.00 0 11273 20 0 0
v_cancel_customer_prepayment 1178 1178 0 0.00 0 4660 18 0 0
v_cancel_customer_reserve 1174 1174 0 0.00 0 5246 17 0 0
v_cancel_payment_to_supplier 644 644 0 0.00 0 2718 15 0 0
v_cancel_supplier_invoice 778 764 14 1.79 0 1057 24 0 14
v_cancel_supplier_order 724 724 0 0.00 0 766 11 0 0
v_cancel_write_off 1760 1760 0 0.00 0 12340 30 0 0
v_max_id_clo_ord 5092 5092 0 0.00 0 715 5 0 0
v_max_id_clo_res 904 904 0 0.00 0 1305 13 0 0
v_max_id_ord_sup 5035 5035 0 0.00 0 10053 12 0 0
v_max_non_paid_invoice 1275 1275 0 0.00 0 236 8 0 0
v_max_non_paid_realizn 3069 3069 0 0.00 0 4114 8 0 0
v_min_id_clo_ord 5092 5092 0 0.00 0 2313 10 0 0
v_min_id_clo_res 904 904 0 0.00 0 5410 22 0 0
v_min_id_ord_sup 5035 5035 0 0.00 0 4656 15 0 0
v_min_non_paid_invoice 5130 5130 0 0.00 0 10150 24 0 0
v_min_non_paid_realizn 6339 6339 0 0.00 0 13369 25 0 0
v_our_firm 9624 9624 0 0.00 0 65 4 0 0
v_reserve_write_off 17742 17742 0 0.00 0 14022 13 0 0
x_lock_depdocs_on_canc_invoice 10880 10880 0 0.00 0 18259 149 0 0
x_lock_depdocs_on_canc_sup_ord 362 362 0 0.00 0 5320 433 0 0
x_make_qstorno_1000_1200 5092 5092 0 0.00 2 34562 1278 0 0
x_make_qstorno_1200_2000 5042 5042 0 0.00 4 80950 3173 0 0
x_make_qstorno_2100_3300 428312 428312 0 0.00 0 62257 216 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-10-2022 04:38:33 to 20-10-2022 04:38:37.

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
02:07:27 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
02:08:28 31016 8192000000 9743090064 10048737280 825638352 9.155 100 88 176 0 854886560 7906976 21341232
02:10:07 14957 8192000000 9783611696 10095812608 853825440 9.438 100 80 160 0 885168736 12646816 18696480
02:11:34 11653 8192000000 9810535184 10133348352 877440272 9.674 100 88 176 0 919879600 22138352 20300976
02:12:51 9183 8192000000 9879680032 10215653376 921919248 10.115 100 81 162 0 997469936 57809584 17741104
02:14:06 2624 8192000000 9930341168 10274611200 960678512 10.496 100 85 164 1 1041734816 63903312 17152992
02:15:13 1527 8192000000 9981412400 10332528640 991722064 10.798 100 90 178 0 1092300896 82466160 18112672
02:16:17 1057 8192000000 9989062128 10349404160 1017533504 11.048 100 90 172 0 1112088224 77109888 17444832
02:17:20 1302 8192000000 10014429856 10380972032 1040264064 11.267 100 96 183 0 1137053872 78991760 17798048
02:18:23 1061 8192000000 10016658640 10389671936 1055809600 11.416 100 98 186 3 1137558416 63140416 18608400
02:19:25 1304 8192000000 10044511728 10421989376 1060318608 11.460 100 99 187 2 1166541872 86967024 19256240
02:20:28 5986 8192000000 10030409200 10407227392 1068507168 11.538 100 91 174 1 1150618064 64636720 17474176
02:21:35 1252 8192000000 10057803760 10439643136 1076426496 11.613 100 93 186 0 1180959760 86348880 18184384
02:22:37 991 8192000000 10047493024 10429743104 1079123792 11.639 100 96 185 2 1170064128 72123248 18817088
02:23:39 991 8192000000 10045406784 10431758336 1082193984 11.668 100 88 169 0 1165492784 65956688 17342112
02:24:41 1390 8192000000 10038362560 10426327040 1085150208 11.697 100 80 156 0 1163199040 61678992 16369840
02:25:43 929 8192000000 10066535840 10454802432 1089304112 11.736 100 89 175 0 1188970240 82102128 17564000
02:26:45 1206 8192000000 10067700688 10455683072 1094509712 11.786 100 93 184 0 1192693360 78713280 19470368
02:27:47 1305 8192000000 10050354064 10439344128 1091981296 11.761 100 89 164 3 1173892992 65872016 16039680
02:28:50 1067 8192000000 10056597424 10445578240 1092947424 11.771 100 96 184 0 1176120000 63856704 19315872
02:29:52 2870 8192000000 10032356752 10424967168 1067947504 11.532 99 77 155 1 1146623568 63559040 15117024
02:30:56 5743 8192000000 10019199920 10408722432 1058634304 11.443 99 60 114 1 1133150272 63286912 11229056
02:32:03 1510 8192000000 9992526400 10371805184 1047253712 11.334 99 78 153 0 1116413280 52348320 16811248
02:33:05 1204 8192000000 10000089248 10377871360 1048346192 11.345 100 83 158 1 1119883440 55661616 15875632
02:34:07 9635 8192000000 9987031616 10371895296 1045469120 11.317 100 63 112 2 1118929040 62075824 11384096
02:35:18 1213 8192000000 9981730112 10356035584 1035072528 11.217 100 85 165 1 1098989584 46839744 17077312
02:36:21 869 8192000000 9961774928 10331365376 1023953504 11.110 100 94 181 3 1084929440 40838416 20137520
02:37:23 11364 8192000000 9961240512 10343763968 1026780912 11.137 99 62 121 0 1080002544 41471600 11750032
02:38:57 1643 8192000000 10006433600 10385125376 1057228656 11.430 99 76 134 2 1126550752 54914736 14407360
02:40:06 4735 8192000000 10056581664 10441342976 1084849472 11.694 100 79 156 0 1179219856 77047456 17322928
02:41:19 9393 8192000000 10075154864 10473730048 1082784336 11.674 99 71 137 0 1197868160 100832736 14251088
02:42:38 2465 8192000000 10099509040 10490601472 1109899840 11.931 100 72 136 0 1233843456 109022352 14921264
02:43:47 1126 8192000000 10141646928 10527145984 1115976608 11.989 100 93 171 4 1258436992 124304624 18155760
02:44:53 3658 8192000000 10186739760 10582474752 1127523072 12.098 100 74 141 1 1295415824 152583136 15309616
02:46:01 2106 8192000000 10221497840 10615418880 1136725840 12.185 100 94 175 3 1333995488 177258880 20010768
02:47:09 1529 8192000000 10260921872 10665381888 1150312560 12.312 100 89 172 0 1382135424 212920592 18902272
02:48:15 1646 8192000000 10275920272 10692624384 1158891120 12.393 100 89 174 1 1389280672 210482704 19906848
02:49:21 906 8192000000 10333502640 10731032576 1175613952 12.549 100 98 184 2 1439754864 243812720 20328192
02:50:28 2807 8192000000 10354731856 10771050496 1182692288 12.615 100 89 172 0 1465119296 262064464 20362544
02:51:34 1510 8192000000 10389453744 10805739520 1188404976 12.669 100 95 187 0 1499551200 290100736 21045488
02:52:39 909 8192000000 10399998704 10815082496 1197942560 12.757 100 95 187 1 1509677664 289548992 22186112
02:53:43 888 8192000000 10423429616 10838470656 1188880880 12.673 100 88 171 1 1529748032 321479808 19387344
02:54:49 2601 8192000000 10442950672 10860593152 1190701616 12.690 100 91 180 0 1550883664 338724496 21457552
02:55:55 894 8192000000 10487406752 10892730368 1186416512 12.650 100 96 189 0 1592924672 384931648 21576512
02:56:58 1828 8192000000 10498971072 10937970688 1196174352 12.741 100 94 186 0 1607637968 389117456 22346160
02:58:04 866 8192000000 10548272768 10956804096 1202890656 12.803 100 97 194 1 1654732336 428706400 23135280
02:59:08 2624 8192000000 10563285520 11000557568 1191758128 12.700 100 89 172 2 1670001680 457159088 21084464
03:00:14 3625 8192000000 10549666880 11010134016 1187668064 12.662 100 68 136 0 1656818352 451100368 18049920
03:01:23 839 8192000000 10567787632 11002056704 1182421216 12.613 100 98 193 2 1671834448 465228768 24184464
03:02:29 1449 8192000000 10577900464 11023335424 1186145392 12.647 100 78 157 0 1678520288 472795632 19579264
03:03:34 1767 8192000000 10528852752 11004391424 1197949616 12.757 100 84 166 1 1638619312 420599456 20070240
03:04:38 1000 8192000000 10526629424 10999840768 1198212384 12.760 100 77 149 0 1627609184 411510016 17886784
03:05:44 18027 8192000000 10476051824 10983047168 1173455552 12.529 99 46 92 0 1574002496 388079488 12467456
03:07:46 34472 8192000000 10500907136 10974810112 1173704000 12.531 100 30 60 0 1588195488 406783184 7708304
03:10:25 38849 8192000000 10284883216 10755837952 1152365824 12.332 100 28 54 0 1455863008 297100288 6396896
03:12:05 31076 8192000000 10232181552 10707730432 1139305552 12.209 99 19 38 0 1318104528 173622384 5176592
03:13:37 28088 8192000000 10112166240 10605457408 1127245760 12.095 98 24 48 0 1224378288 92222384 4910144
03:15:06 53518 8192000000 10116940256 10577018880 1118018544 12.008 98 37 72 0 1201590624 75911856 7660224
03:17:02 83340 8192000000 10074334064 10516647936 1108320768 11.917 97 46 91 0 1159050528 41755040 8974720
03:19:32 33755 8192000000 10040334608 10485383168 1115289328 11.982 99 27 52 0 1137044672 16342240 5413104
03:21:19 54224 8192000000 10109545888 10551148544 1123721792 12.062 99 18 36 0 1172585056 44912288 3950976
03:23:19 82480 8192000000 10130349088 10617417728 1148513200 12.296 100 16 32 0 1255192928 102772064 3907664
03:25:41 101987 8192000000 10099505648 10593447936 1132476992 12.145 99 14 28 0 1219402832 83142432 3783408
03:28:24 112323 8192000000 10060281296 10526011392 1098139904 11.820 96 29 58 0 1133346048 29208000 5998144
03:31:22 46273 8192000000 10087334848 10538270720 1129583216 12.117 99 36 72 0 1185931216 48865664 7482336
03:33:14 44910 8192000000 10112679056 10563756032 1133600416 12.155 99 37 72 1 1202071152 60266288 8204448
03:35:18 86344 8192000000 10130674464 10631884800 1146593360 12.278 100 17 34 0 1234034384 83375984 4065040
03:37:54 96364 8192000000 10121786832 10597699584 1119300320 12.020 97 28 56 1 1223882128 98690208 5891600
03:40:31 100138 8192000000 10053950416 10523078656 1085794144 11.703 95 13 23 0 1116355312 29046624 1514544
03:43:15 57208 8192000000 10080029040 10547150848 1126728400 12.091 99 20 38 0 1160192160 29527904 3935856
03:45:13 51140 8192000000 10107557680 10561302528 1136728912 12.185 99 24 47 0 1193244832 51882016 4633904
03:47:05 19799 8192000000 10114860416 10545741824 1132609328 12.146 99 56 111 0 1206298496 62616928 11072240
03:48:27 82345 8192000000 10072194160 10546028544 1109580752 11.928 98 13 26 0 1209609344 96736432 3292160
03:50:55 35831 8192000000 10133321312 10569781248 1122314528 12.049 99 31 60 1 1205917024 77195408 6407088
03:52:40 90950 8192000000 10080537408 10569887744 1087963760 11.723 96 15 30 0 1222440032 130859504 3616768
03:55:12 73509 8192000000 10053274512 10534715392 1085788912 11.703 97 23 41 1 1134160768 44102400 4269456
03:57:26 117497 8192000000 10070832944 10512908288 1086671120 11.711 97 43 82 0 1143139808 48259008 8209680
04:00:27 3104 8192000000 10069193776 10519433216 1115733696 11.987 100 40 73 1 1172095824 49363920 6998208
04:01:31 40628 8192000000 10092189024 10537426944 1122479312 12.050 100 23 43 2 1160612400 33545408 4587680
04:03:16 60647 8192000000 10108973616 10579001344 1118213248 12.010 98 16 32 0 1206197936 84066848 3917840
04:05:17 84810 8192000000 10137675856 10605174784 1121651648 12.043 99 20 40 0 1226497984 100435920 4410416
04:08:44 36871 8192000000 10032684576 10493845504 1066849424 11.522 96 21 42 0 1099111392 27160768 5101200
04:10:28 45680 8192000000 10083154160 10517110784 1094585872 11.786 98 29 58 0 1155904480 54924512 6394096
04:12:14 56861 8192000000 10120314240 10559840256 1113226320 11.963 99 23 46 0 1204537808 85924640 5386848
04:14:24 72631 8192000000 10138379584 10601963520 1114659536 11.977 98 18 37 0 1225481392 107021632 3800224
04:16:48 16992 8192000000 10109028336 10530205696 1124143136 12.066 99 40 78 0 1188672928 56470064 8059728
04:18:11 79543 8192000000 10093142112 10552221696 1091480416 11.757 96 25 48 1 1158030704 61764384 4785904
04:20:35 41953 8192000000 10142494224 10606231552 1139120496 12.207 100 42 82 1 1222463184 75194624 8148064
04:23:21 46904 8192000000 10059940832 10504192000 1136976112 12.187 100 27 50 0 1165886496 22920992 5989392
04:25:10 38612 8192000000 10094342208 10554445824 1146656176 12.278 100 24 47 2 1196527536 45135040 4736320
04:27:26 59925 8192000000 10105156032 10552160256 1121118992 12.038 97 10 20 0 1162441488 38840176 2482320
04:29:27 116496 8192000000 10181334144 10648289280 1135592960 12.174 98 16 32 0 1235547488 96062336 3892192
04:32:24 79902 8192000000 10072783520 10543169536 1119626112 12.023 97 17 34 0 1225967232 102716880 3624240
04:34:59 74925 8192000000 10106946592 10562129920 1124845984 12.073 99 17 34 0 1206646640 77683120 4117536
04:37:22 10023 8192000000 9161267312 9454161920 303404864 3.571 27 2 4 0 319881488 16096064 380560

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 20-10-2022 04:38:39 to 20-10-2022 04:38:55.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 82 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 20-10-2022 04:38:56 to 20-10-2022 04:38:58.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          207656
MON$OLDEST_ACTIVE               207657
MON$OLDEST_SNAPSHOT             207657
MON$NEXT_TRANSACTION            207657
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-30 07:30:08.6030 GMT
MON$PAGES                       2061792
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             751
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2860 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2860 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2860 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2860 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.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 ---
:169: 
:170: mscs-test-161.budzdorov.ru    Thu Oct 20 04:37:33 2022
:171:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:172: 
--- end of diff output ---

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

20.10.22 04:39:00. There are 3 logs in /var/tmp/logs-oltp40 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
2022-10-20 04:39:02 No severe PSQL-related problems occured
20.10.22 04:39:04. End of report.