Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.12.2022 07:37
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 14722292 kB
MemAvailable 30076068 kB
Buffers 0 kB
Cached 16952996 kB
SwapCached 3296 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-12-11 07:37:19
DTS_END                         2022-12-11 07:37:19.2550
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(108:9) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/42386
TRN_ID                          225146
ATT_ID                          584
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: 9056.34 24773 1086761
customer order: creation 177.10 487 21252
customer order: refuse 14.93 405 1791
order to supplier: creation 46.76 1400 5611
order to supplier: removal 3.40 159385 408
invoice (draft): creation 46.88 2993 5626
invoice (draft): removal 47.59 983 5711
invoice accept: apply 43.89 26071 5267
invoice accept: cancel 43.04 1227 5165
customer reserve: creation 4113.96 246 493675
customer reserve: removal 4220.07 99 506408
realization accept: apply 85.28 278 10234
realization accept: cancel 79.38 186 9525
payment from customer: creation 60.86 185 7303
payment from customer: removal 6.02 142 722
payment to supplier: creation 50.40 198 6048
payment to supplier: removal 3.55 147 426
service: total inventory turnovers 4.38 3596 526
service: total monetary turnovers 7.83 366 940
service: aggregate perf. data 0.99 14878 119
service: refresh index statistics 0.03 282185 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 11-12-2022 07:38:04 to 11-12-2022 07:38:07.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7278
TEST_TIME 2 4728
TEST_TIME 3 5725
TEST_TIME 4 5962
TEST_TIME 5 6058
TEST_TIME 6 5871
TEST_TIME 7 5882
TEST_TIME 8 6194
TEST_TIME 9 6527
TEST_TIME 10 6795
TEST_TIME 11 7347
TEST_TIME 12 7604
TEST_TIME 13 7773
TEST_TIME 14 7967
TEST_TIME 15 7951
TEST_TIME 16 8050
TEST_TIME 17 8062
TEST_TIME 18 8048
TEST_TIME 19 8134
TEST_TIME 20 8101
TEST_TIME 21 8233
TEST_TIME 22 8172
TEST_TIME 23 8359
TEST_TIME 24 8380
TEST_TIME 25 8455
TEST_TIME 26 8468
TEST_TIME 27 8658
TEST_TIME 28 9017
TEST_TIME 29 9778
TEST_TIME 30 9865
TEST_TIME 31 10742
TEST_TIME 32 10918
TEST_TIME 33 11585
TEST_TIME 34 11421
TEST_TIME 35 11270
TEST_TIME 36 11149
TEST_TIME 37 11023
TEST_TIME 38 10849
TEST_TIME 39 10662
TEST_TIME 40 10534
TEST_TIME 41 10624
TEST_TIME 42 10497
TEST_TIME 43 10386
TEST_TIME 44 10568
TEST_TIME 45 10439
TEST_TIME 46 10392
TEST_TIME 47 10295
TEST_TIME 48 10225
TEST_TIME 49 10234
TEST_TIME 50 10145
TEST_TIME 51 10205
TEST_TIME 52 10088
TEST_TIME 53 9922
TEST_TIME 54 9802
TEST_TIME 55 9817
TEST_TIME 56 9797
TEST_TIME 57 9792
TEST_TIME 58 9710
TEST_TIME 59 9697
TEST_TIME 60 9727
TEST_TIME 61 9639
TEST_TIME 62 9552
TEST_TIME 63 9588
TEST_TIME 64 9517
TEST_TIME 65 9493
TEST_TIME 66 9512
TEST_TIME 67 9487
TEST_TIME 68 9456
TEST_TIME 69 9428
TEST_TIME 70 9475
TEST_TIME 71 9481
TEST_TIME 72 9478
TEST_TIME 73 9492
TEST_TIME 74 9524
TEST_TIME 75 9553
TEST_TIME 76 9546
TEST_TIME 77 9591
TEST_TIME 78 9611
TEST_TIME 79 9606
TEST_TIME 80 9658
TEST_TIME 81 9553
TEST_TIME 82 9505
TEST_TIME 83 9433
TEST_TIME 84 9441
TEST_TIME 85 9445
TEST_TIME 86 9372
TEST_TIME 87 9335
TEST_TIME 88 9297
TEST_TIME 89 9293
TEST_TIME 90 9245
TEST_TIME 91 9257
TEST_TIME 92 9305
TEST_TIME 93 9265
TEST_TIME 94 9295
TEST_TIME 95 9294
TEST_TIME 96 9287
TEST_TIME 97 9287
TEST_TIME 98 9317
TEST_TIME 99 9295
TEST_TIME 100 9318
TEST_TIME 101 9389
TEST_TIME 102 9380
TEST_TIME 103 9378
TEST_TIME 104 9323
TEST_TIME 105 9258
TEST_TIME 106 9232
TEST_TIME 107 9221
TEST_TIME 108 9176
TEST_TIME 109 9130
TEST_TIME 110 9077
TEST_TIME 111 9060
TEST_TIME 112 9038
TEST_TIME 113 9047
TEST_TIME 114 9041
TEST_TIME 115 9023
TEST_TIME 116 9050
TEST_TIME 117 9104
TEST_TIME 118 9154
TEST_TIME 119 9137
TEST_TIME 120 9078

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-12-2022 07:38:08 to 11-12-2022 07:38:11.

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 1538635 1538635 0 0.00 0 17722 14 0 0
doc_list_biud 1538627 1538627 0 0.00 0 40656 38 0 0
sp_add_invoice_to_stock 5439 5267 172 3.16 56 206472 26072 0 172
sp_cancel_adding_invoice 5165 5165 0 0.00 5 66894 1228 0 0
sp_cancel_client_order 1791 1791 0 0.00 6 15089 405 0 0
sp_cancel_customer_reserve 506408 506408 0 0.00 1 26429 100 0 0
sp_cancel_pay_from_customer 722 722 0 0.00 4 7953 142 0 0
sp_cancel_pay_to_supplier 426 426 0 0.00 4 9273 147 0 0
sp_cancel_supplier_invoice 5726 5711 15 0.26 2 40756 984 0 15
sp_cancel_supplier_order 408 408 0 0.00 10 1666535 159385 0 0
sp_cancel_write_off 9525 9525 0 0.00 2 13570 186 0 0
sp_client_order 21252 21252 0 0.00 5 31334 487 0 0
sp_customer_reserve 493675 493675 0 0.00 0 45237 247 0 0
sp_fill_shopping_cart 32484 32484 0 0.00 1 22546 88 0 0
sp_fill_shopping_cart_clo_res 493657 493657 0 0.00 0 13944 18 0 0
sp_get_clo_for_invoice 5241 5241 0 0.00 0 13080 270 0 0
sp_kill_cost_storno 15838 15838 0 0.00 0 11718 76 0 0
sp_kill_qstorno_ret_qs2qd 1028460 1028460 0 0.00 0 40652 42 0 0
sp_kill_qty_storno 544482 544482 0 0.00 0 40654 98 0 0
sp_lock_selected_doc 21424 21424 0 0.00 0 11677 8 0 0
sp_make_cost_storno 28825 28825 0 0.00 0 15095 82 0 0
sp_multiply_rows_for_pdistr 28825 28825 0 0.00 0 9176 37 0 0
sp_multiply_rows_for_qdistr 21252 21252 0 0.00 0 16071 170 0 0
sp_pay_from_customer 7303 7303 0 0.00 5 15761 185 0 0
sp_pay_to_supplier 6048 6048 0 0.00 6 13952 199 0 0
sp_payment_common 13350 13350 0 0.00 2 11942 50 0 0
sp_qd_handle_on_cancel_clo 1791 1791 0 0.00 0 15081 320 0 0
sp_qd_handle_on_invoice_upd_sts 10405 10405 0 0.00 0 27146 525 0 0
sp_qd_handle_on_reserve_upd_sts 10234 10234 0 0.00 0 21455 59 0 0
sp_reserve_write_off 10234 10234 0 0.00 8 21620 279 0 0
sp_supplier_invoice 5626 5626 0 0.00 7 89768 2993 0 0
sp_supplier_order 5611 5611 0 0.00 7 41249 1401 0 0
srv_aggregate_perf_data 119 119 0 0.00 2520 196882 14879 0 0
srv_fill_mon_cache_memory 73 73 0 0.00 743 126075 32416 0 0
srv_find_qd_qs_mism 1034316 1034316 0 0.00 0 13500 11 0 0
srv_make_invnt_saldo 526 526 0 0.00 43 75427 3597 0 0
srv_make_money_saldo 940 940 0 0.00 1 28144 366 0 0
srv_recalc_idx_stat 4 4 0 0.00 109078 413900 282186 0 0
t$perf-abend:sp_add_invoice_to_stock 172 172 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 5269 5269 0 0.00 1 16894 932 0 0
t$perf-norm:sp_cancel_adding_invoice 432 432 0 0.00 1 9313 434 0 0
t$perf-norm:sp_cancel_client_order 1791 1791 0 0.00 0 886 26 0 0
t$perf-norm:sp_cancel_customer_reserve 668 668 0 0.00 0 2260 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 722 722 0 0.00 0 1362 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 426 426 0 0.00 0 478 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 444 444 0 0.00 0 2155 40 0 0
t$perf-norm:sp_cancel_supplier_order 408 408 0 0.00 0 70659 6813 0 0
t$perf-norm:sp_cancel_write_off 1060 1060 0 0.00 0 7885 29 0 0
t$perf-norm:sp_client_order 21252 21252 0 0.00 0 20041 38 0 0
t$perf-norm:sp_customer_reserve 1061 1061 0 0.00 0 362 12 0 0
t$perf-norm:sp_pay_from_customer 7303 7303 0 0.00 0 5586 18 0 0
t$perf-norm:sp_pay_to_supplier 6048 6048 0 0.00 0 3402 19 0 0
t$perf-norm:sp_reserve_write_off 10234 10234 0 0.00 0 10439 24 0 0
t$perf-norm:sp_supplier_invoice 5626 5626 0 0.00 0 11129 33 0 0
t$perf-norm:sp_supplier_order 5611 5611 0 0.00 0 26378 40 0 0
t$perf-norm:srv_aggregate_perf_data 119 119 0 0.00 0 635 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 73 73 0 0.00 0 174 7 0 0
t$perf-norm:srv_make_invnt_saldo 526 526 0 0.00 0 2669 18 0 0
t$perf-norm:srv_make_money_saldo 940 940 0 0.00 0 392 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 169 57 0 0
v_add_invoice_to_stock 10824 10480 344 3.17 0 8997 29 0 344
v_all_customers 39478 39478 0 0.00 0 190 4 0 0
v_all_suppliers 31356 31356 0 0.00 0 221 4 0 0
v_all_wares 42504 42504 0 0.00 0 228 4 0 0
v_cancel_adding_invoice 864 864 0 0.00 0 2367 21 0 0
v_cancel_client_order 3582 3582 0 0.00 0 7262 15 0 0
v_cancel_customer_prepayment 1444 1444 0 0.00 0 4672 22 0 0
v_cancel_customer_reserve 1336 1336 0 0.00 0 3403 16 0 0
v_cancel_payment_to_supplier 852 852 0 0.00 0 4002 16 0 0
v_cancel_supplier_invoice 918 888 30 3.26 0 1072 23 0 30
v_cancel_supplier_order 816 816 0 0.00 0 1857 16 0 0
v_cancel_write_off 2120 2120 0 0.00 0 3965 28 0 0
v_max_id_clo_ord 5610 5610 0 0.00 0 10570 7 0 0
v_max_id_clo_res 1061 1061 0 0.00 0 1882 14 0 0
v_max_id_ord_sup 5621 5621 0 0.00 0 3076 7 0 0
v_max_non_paid_invoice 1601 1601 0 0.00 0 433 8 0 0
v_max_non_paid_realizn 3570 3570 0 0.00 0 895 7 0 0
v_min_id_clo_ord 5610 5610 0 0.00 0 1318 7 0 0
v_min_id_clo_res 1061 1061 0 0.00 0 1303 8 0 0
v_min_id_ord_sup 5621 5621 0 0.00 0 8051 9 0 0
v_min_non_paid_invoice 6048 6048 0 0.00 0 7702 18 0 0
v_min_non_paid_realizn 7302 7302 0 0.00 0 11126 21 0 0
v_our_firm 10488 10488 0 0.00 0 65 5 0 0
v_reserve_write_off 20466 20466 0 0.00 0 9221 14 0 0
x_lock_depdocs_on_canc_invoice 10432 10432 0 0.00 0 8530 123 0 0
x_lock_depdocs_on_canc_sup_ord 408 408 0 0.00 0 2643 326 0 0
x_make_qstorno_1000_1200 5611 5611 0 0.00 3 41203 1226 0 0
x_make_qstorno_1200_2000 5626 5626 0 0.00 3 85952 2752 0 0
x_make_qstorno_2100_3300 493674 493674 0 0.00 0 43410 216 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 11-12-2022 07:38:12 to 11-12-2022 07:38:17.

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
05:07:23 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
05:08:24 20991 8192000000 9710760208 10008657920 789859280 8.793 100 88 176 0 820056016 7415152 22781584
05:09:49 22532 8192000000 9751338528 10058240000 830006864 9.199 100 89 178 0 863337408 12030560 21299984
05:11:19 13804 8192000000 9797490144 10113974272 860692304 9.507 100 85 166 0 897751200 17582000 19476896
05:12:43 8442 8192000000 9834990176 10167578624 900560784 9.904 100 69 136 0 949344976 34623344 14160848
05:13:56 3544 8192000000 9919311776 10261549056 957047888 10.460 100 91 177 0 1031503680 55928544 18527248
05:15:02 1653 8192000000 9956448368 10309550080 986448832 10.747 100 87 171 0 1077659600 72945680 18265088
05:16:07 1367 8192000000 10001099744 10360057856 1023448976 11.105 100 91 174 0 1116618208 75095376 18073856
05:17:10 1346 8192000000 10012199712 10386296832 1043384048 11.297 100 90 176 0 1136742576 76015616 17342912
05:18:13 961 8192000000 10044803968 10423836672 1060211408 11.459 100 96 190 3 1165783216 86776992 18794816
05:19:17 4870 8192000000 10020465040 10394316800 1065351088 11.508 100 90 166 1 1136454608 54899696 16203824
05:20:23 1432 8192000000 10023611808 10405158912 1075258224 11.602 100 90 169 0 1151010768 58772208 16980336
05:21:25 1135 8192000000 10049694096 10433261568 1080761040 11.655 100 94 182 1 1172833232 73747808 18324384
05:22:27 1369 8192000000 10056744848 10444394496 1082282384 11.669 100 94 185 2 1185496208 84573376 18640448
05:23:30 5737 8192000000 10043014272 10436132864 1086353600 11.708 100 64 117 3 1169286048 72988160 9944288
05:24:36 1060 8192000000 10068107648 10456727552 1090927216 11.751 100 92 172 0 1190419760 82524656 16967888
05:25:39 1097 8192000000 10059683904 10455822336 1097234608 11.811 100 74 137 1 1184260816 73591824 13434384
05:26:41 3264 8192000000 10072327264 10462834688 1097757408 11.816 100 82 157 1 1197179952 83724496 15698048
05:27:45 1050 8192000000 10084209552 10471186432 1097504384 11.814 100 94 180 2 1204935392 89345872 18085136
05:28:47 1151 8192000000 10050731216 10438774784 1098364352 11.822 100 90 164 1 1176474544 60594032 17516160
05:29:49 1100 8192000000 10051116576 10439589888 1088297792 11.726 100 95 179 3 1170562112 64677632 17586688
05:30:51 1056 8192000000 10021784928 10405593088 1070679952 11.559 100 94 177 0 1146390464 57045472 18665040
05:31:53 1761 8192000000 10010769824 10385924096 1035169536 11.218 98 88 170 2 1137234704 85064736 17000432
05:32:56 1154 8192000000 10028454464 10410647552 1061404064 11.470 100 96 184 0 1149230400 68582448 19243888
05:33:58 1313 8192000000 10030884160 10409435136 1058423168 11.441 100 95 183 3 1149468704 72839824 18205712
05:35:00 1114 8192000000 10007779712 10379816960 1040454320 11.269 100 99 194 0 1131601280 70993760 20153200
05:36:02 956 8192000000 9990435328 10363588608 1045200208 11.315 100 84 169 2 1113774080 51279680 17294192
05:37:04 16188 8192000000 9994298928 10388594688 1014508992 11.019 98 65 120 3 1092605568 65991120 12105456
05:38:38 6794 8192000000 9988438608 10369150976 1066877616 11.522 100 68 123 1 1102107136 23295104 11934416
05:39:49 916 8192000000 10061380000 10440093696 1088385920 11.727 100 94 166 0 1183345728 76797008 18162800
05:40:55 739 8192000000 10104590688 10493669376 1113017408 11.961 100 94 181 0 1234687024 99437984 22231632
05:42:02 1999 8192000000 10117225920 10517716992 1116687552 11.996 100 89 167 0 1241321568 107435264 17198752
05:43:08 2431 8192000000 10162154176 10568167424 1122758896 12.053 100 80 153 0 1278909680 139498384 16652400
05:44:19 6124 8192000000 10191392784 10599182336 1135501152 12.173 100 71 138 0 1303586320 152717648 15367520
05:45:29 1892 8192000000 10220591456 10616446976 1135519600 12.173 100 90 173 2 1328613680 174399696 18694384
05:46:37 2099 8192000000 10232714656 10644213760 1142240160 12.237 100 97 186 1 1342781920 179470736 21071024
05:47:43 1071 8192000000 10237661104 10651893760 1152823344 12.336 100 93 183 1 1353707824 180496160 20388320
05:48:46 1527 8192000000 10245160944 10656247808 1164379856 12.444 100 93 170 2 1355058256 171488096 19190304
05:49:51 1944 8192000000 10301239456 10704605184 1174370352 12.538 100 90 173 1 1411300912 216675984 20254576
05:50:57 868 8192000000 10341257664 10747420672 1194084048 12.721 100 96 188 1 1454231728 238897472 21250208
05:51:59 1115 8192000000 10370318800 10777817088 1198369504 12.761 100 97 188 3 1482115936 262140320 21606112
05:53:03 983 8192000000 10411246704 10826825728 1207360848 12.845 100 91 181 0 1520218080 291926640 20930592
05:54:08 1006 8192000000 10445830816 10853859328 1203121296 12.805 100 97 188 0 1547388144 322616304 21650544
05:55:15 782 8192000000 10468608400 10891550720 1206205104 12.834 100 99 194 0 1574468000 344857712 23405184
05:56:21 1823 8192000000 10497657488 10911408128 1210624432 12.875 100 93 180 1 1600799696 369936912 20238352
05:57:24 860 8192000000 10577973264 10992672768 1221751008 12.978 100 99 189 1 1675867104 431883712 22232384
05:58:27 1211 8192000000 10559384752 10990977024 1203141744 12.805 100 88 174 0 1660686976 437248496 20296736
05:59:30 789 8192000000 10608218800 11035881472 1202844944 12.803 100 95 188 0 1713607072 488013424 22748704
06:00:35 899 8192000000 10624431264 11041558528 1197802880 12.756 100 89 169 1 1730670384 510931968 21935536
06:01:38 3170 8192000000 10649253216 11079487488 1183544208 12.623 100 76 152 0 1753040800 550931008 18565584
06:02:45 2420 8192000000 10645526832 11108081664 1177703248 12.569 100 64 128 2 1749287904 555314608 16270048
06:03:54 5374 8192000000 10656134208 11122761728 1188022592 12.665 100 58 111 0 1759681808 556866880 14792336
06:05:06 6903 8192000000 10636823552 11135045632 1194864784 12.729 100 54 108 1 1747260800 538256000 14140016
06:06:24 12338 8192000000 10481790144 10937745408 1174190192 12.536 100 39 75 1 1614626800 430279840 10156768
06:07:39 22395 8192000000 10419791248 10964377600 1166104176 12.460 100 30 60 0 1591291104 417064592 8122336
06:09:06 38650 8192000000 10259357648 10766966784 1156804096 12.373 100 17 34 0 1389228688 228076368 4348224
06:10:45 92516 8192000000 10077158176 10513498112 1071544752 11.567 93 37 70 2 1113386288 33400912 8440624
06:13:19 22998 8192000000 10051541600 10486984704 1073455936 11.585 95 27 52 2 1114151472 35737376 4958160
06:14:43 117045 8192000000 10096944064 10543685632 1096352112 11.803 97 33 65 0 1171472592 68322192 6798288
06:17:41 90483 8192000000 10098552032 10571309056 1126298080 12.086 99 15 30 0 1197135712 67619920 3217712
06:20:12 126075 8192000000 10077742752 10550427648 1128817008 12.110 99 12 24 0 1203680368 72393664 2469696
06:23:22 101140 8192000000 10080458176 10530205696 1099350208 11.831 96 48 85 0 1159303952 50146144 9807600
06:26:04 83917 8192000000 10099218480 10537684992 1115934976 11.989 98 10 20 0 1139378112 21534608 1908528
06:28:40 112744 8192000000 10073391696 10530594816 1100798112 11.845 97 23 45 1 1155971776 50296976 4876688
06:32:34 38483 8192000000 10118014192 10548523008 1131530496 12.136 100 47 87 0 1215996048 75425680 9039872
06:34:15 24935 8192000000 10093681952 10554441728 1144808208 12.261 100 20 40 0 1211799184 62747776 4243200
06:35:40 4930 8192000000 10141279936 10577838080 1140989248 12.225 99 68 130 0 1228293792 73483792 13820752
06:36:48 54714 8192000000 10083243072 10578726912 1108107232 11.914 97 19 38 1 1183406448 71479152 3820064
06:38:43 52681 8192000000 10112741472 10569129984 1138309520 12.200 99 14 28 0 1219510736 78327840 2873376
06:42:03 67902 8192000000 10103552080 10581164032 1148406880 12.295 100 19 39 0 1197927104 46062960 3457264
06:44:11 41200 8192000000 10047742000 10501656576 1083138400 11.677 95 43 87 1 1115698432 22652896 9907136
06:45:55 12035 8192000000 10103486304 10537320448 1128561168 12.108 100 43 85 0 1185368512 48573888 8233456
06:47:10 23818 8192000000 10075594672 10511937536 1118155088 12.010 98 37 75 0 1179918528 54695856 7067584
06:48:35 39002 8192000000 10115103120 10542190592 1121948240 12.045 99 34 66 0 1189847568 60542480 7356848
06:50:21 75729 8192000000 10096581664 10570305536 1135522944 12.173 100 24 48 0 1230108720 89639968 4945808
06:52:36 74893 8192000000 10126230400 10562879488 1120984416 12.036 98 15 30 0 1173382816 49522112 2876288
06:54:52 56217 8192000000 10091023360 10545561600 1117663904 12.005 98 16 32 1 1179462112 58254464 3543744
06:56:52 49081 8192000000 10096479552 10596782080 1126844768 12.092 99 28 55 1 1199565680 67153760 5567152
06:58:43 84368 8192000000 10096325312 10540437504 1129885216 12.120 99 26 52 0 1176143312 40920576 5337520
07:01:13 4780 8192000000 10084557072 10529763328 1123917840 12.064 100 31 58 1 1185067408 55189552 5960016
07:02:21 59865 8192000000 10046086048 10521935872 1080268512 11.650 96 6 12 0 1111776880 30258784 1249584
07:04:29 56339 8192000000 10115944960 10563084288 1125183056 12.076 99 15 30 0 1208081088 79875680 3022352
07:08:18 53689 8192000000 10081180336 10529718272 1102913584 11.865 97 12 22 1 1141722176 36648704 2159888
07:10:12 16796 8192000000 10064476480 10490036224 1113102368 11.962 98 40 79 1 1141393360 19663552 8627440
07:11:30 23470 8192000000 10088582256 10533519360 1110675696 11.939 99 37 74 0 1191248656 73337616 7235344
07:13:01 17076 8192000000 10093484896 10527617024 1124687856 12.071 100 39 78 0 1177364800 45097840 7579104
07:14:20 71139 8192000000 10101792624 10578407424 1127949872 12.102 99 37 72 1 1195073296 60138720 6984704
07:16:34 22882 8192000000 10152829936 10585653248 1134237392 12.161 99 32 64 0 1232680064 91846080 6596592
07:17:58 21515 8192000000 10127339664 10584522752 1141179712 12.227 100 39 76 0 1249890096 100331872 8378512
07:19:22 43155 8192000000 10093421472 10520481792 1119590528 12.023 98 40 78 0 1154303792 26784800 7928464
07:21:07 35729 8192000000 10095278240 10520076288 1103845744 11.874 97 57 114 0 1166021760 49960240 12215776
07:22:55 23741 8192000000 10063028768 10494697472 1119518768 12.022 99 39 71 0 1169670432 43213328 6938336
07:24:19 36620 8192000000 10055451376 10504540160 1110434320 11.937 98 31 58 0 1156279408 40033328 5811760
07:25:59 69995 8192000000 10054256528 10518564864 1133197232 12.151 100 9 16 0 1155305872 20789056 1319584
07:28:10 56171 8192000000 10084798656 10539819008 1115943264 11.989 98 14 28 0 1160082176 41315536 2823376
07:30:12 40931 8192000000 10095664464 10563444736 1131852112 12.139 99 30 60 0 1207669808 68816336 7001360
07:32:03 11033 8192000000 10133120896 10566615040 1136892800 12.186 99 49 98 2 1227561104 80807680 9860624
07:33:25 36446 8192000000 10151710384 10610786304 1118501776 12.013 98 37 74 1 1246860128 120111360 8246992
07:35:16 59223 8192000000 10089609632 10567262208 1109104672 11.924 98 17 34 0 1162801456 50137968 3558816
07:37:15 13204 8192000000 8999849696 9234264064 155063184 1.857 14 3 6 0 173646624 18073472 509968

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 11-12-2022 07:38:19 to 11-12-2022 07:38:35.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 172 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 11-12-2022 07:38:36 to 11-12-2022 07:38:38.

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          225859
MON$OLDEST_ACTIVE               225860
MON$OLDEST_SNAPSHOT             225860
MON$NEXT_TRANSACTION            225860
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                       2069728
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             785
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2884 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2884 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2884 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2884 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 ---
:62: 
:63: mscs-test-161.budzdorov.ru    Sun Dec 11 07:37:31 2022
:64:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:65: 
:66: 
:67: mscs-test-161.budzdorov.ru    Sun Dec 11 07:37:34 2022
:68:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:69: 
--- end of diff output ---

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

11.12.22 07:38:39. 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-12-11 07:38:41 No severe PSQL-related problems occured
11.12.22 07:38:43. End of report.