Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.12.2021 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 16491604 kB
MemAvailable 31464252 kB
Buffers 0 kB
Cached 15165556 kB
SwapCached 0 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.1
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 2021-12-08 07:37:05
DTS_END                         2021-12-08 07:37:05.8780
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/48984
TRN_ID                          235785
ATT_ID                          695
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: 9255.67 19383 1110680
customer order: creation 174.91 382 20989
customer order: refuse 14.13 292 1696
order to supplier: creation 46.65 1334 5598
order to supplier: removal 3.31 102323 397
invoice (draft): creation 46.51 3386 5581
invoice (draft): removal 51.73 978 6208
invoice accept: apply 43.10 17656 5172
invoice accept: cancel 46.18 727 5542
customer reserve: creation 4002.48 174 480298
customer reserve: removal 4535.74 57 544289
realization accept: apply 84.27 186 10112
realization accept: cancel 75.79 136 9095
payment from customer: creation 60.36 110 7243
payment from customer: removal 5.83 97 699
payment to supplier: creation 50.50 135 6060
payment to supplier: removal 3.54 81 425
service: total inventory turnovers 3.73 3667 447
service: total monetary turnovers 5.95 456 714
service: aggregate perf. data 0.93 14436 111
service: refresh index statistics 0.03 241053 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-12-2021 07:38:09 to 08-12-2021 07:38:11.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7041
TEST_TIME 2 5635
TEST_TIME 3 4882
TEST_TIME 4 4607
TEST_TIME 5 4072
TEST_TIME 6 4489
TEST_TIME 7 4741
TEST_TIME 8 4703
TEST_TIME 9 4914
TEST_TIME 10 5456
TEST_TIME 11 6039
TEST_TIME 12 6263
TEST_TIME 13 6473
TEST_TIME 14 6899
TEST_TIME 15 7453
TEST_TIME 16 7586
TEST_TIME 17 7880
TEST_TIME 18 8488
TEST_TIME 19 9082
TEST_TIME 20 9723
TEST_TIME 21 9787
TEST_TIME 22 10511
TEST_TIME 23 10614
TEST_TIME 24 10995
TEST_TIME 25 10764
TEST_TIME 26 11018
TEST_TIME 27 10877
TEST_TIME 28 10774
TEST_TIME 29 10775
TEST_TIME 30 10877
TEST_TIME 31 10686
TEST_TIME 32 10750
TEST_TIME 33 10825
TEST_TIME 34 10810
TEST_TIME 35 10822
TEST_TIME 36 10555
TEST_TIME 37 10419
TEST_TIME 38 10349
TEST_TIME 39 10261
TEST_TIME 40 10271
TEST_TIME 41 10227
TEST_TIME 42 10200
TEST_TIME 43 10118
TEST_TIME 44 10050
TEST_TIME 45 9987
TEST_TIME 46 9881
TEST_TIME 47 9783
TEST_TIME 48 9848
TEST_TIME 49 9899
TEST_TIME 50 10118
TEST_TIME 51 10006
TEST_TIME 52 10188
TEST_TIME 53 10116
TEST_TIME 54 10145
TEST_TIME 55 10118
TEST_TIME 56 10097
TEST_TIME 57 10203
TEST_TIME 58 10153
TEST_TIME 59 10200
TEST_TIME 60 10201
TEST_TIME 61 10094
TEST_TIME 62 10024
TEST_TIME 63 9977
TEST_TIME 64 9906
TEST_TIME 65 9792
TEST_TIME 66 9814
TEST_TIME 67 9794
TEST_TIME 68 9762
TEST_TIME 69 9690
TEST_TIME 70 9582
TEST_TIME 71 9603
TEST_TIME 72 9606
TEST_TIME 73 9605
TEST_TIME 74 9538
TEST_TIME 75 9509
TEST_TIME 76 9489
TEST_TIME 77 9488
TEST_TIME 78 9576
TEST_TIME 79 9693
TEST_TIME 80 9762
TEST_TIME 81 9729
TEST_TIME 82 9746
TEST_TIME 83 9728
TEST_TIME 84 9819
TEST_TIME 85 9720
TEST_TIME 86 9660
TEST_TIME 87 9676
TEST_TIME 88 9616
TEST_TIME 89 9576
TEST_TIME 90 9521
TEST_TIME 91 9499
TEST_TIME 92 9500
TEST_TIME 93 9462
TEST_TIME 94 9456
TEST_TIME 95 9448
TEST_TIME 96 9437
TEST_TIME 97 9451
TEST_TIME 98 9465
TEST_TIME 99 9467
TEST_TIME 100 9464
TEST_TIME 101 9479
TEST_TIME 102 9522
TEST_TIME 103 9528
TEST_TIME 104 9590
TEST_TIME 105 9621
TEST_TIME 106 9611
TEST_TIME 107 9530
TEST_TIME 108 9480
TEST_TIME 109 9486
TEST_TIME 110 9446
TEST_TIME 111 9408
TEST_TIME 112 9365
TEST_TIME 113 9306
TEST_TIME 114 9301
TEST_TIME 115 9328
TEST_TIME 116 9328
TEST_TIME 117 9334
TEST_TIME 118 9345
TEST_TIME 119 9338
TEST_TIME 120 9263

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 08-12-2021 07:38:13 to 08-12-2021 07:38:16.

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 1554521 1554521 0 0.00 0 21514 7 0 0
doc_list_biud 1554519 1554519 0 0.00 0 92358 26 0 0
sp_add_invoice_to_stock 5245 5172 73 1.39 44 210246 17657 0 73
sp_cancel_adding_invoice 5542 5542 0 0.00 5 62332 728 0 0
sp_cancel_client_order 1696 1696 0 0.00 5 6882 292 0 0
sp_cancel_customer_reserve 544289 544289 0 0.00 1 20349 57 0 0
sp_cancel_pay_from_customer 699 699 0 0.00 3 15057 98 0 0
sp_cancel_pay_to_supplier 425 425 0 0.00 3 5650 81 0 0
sp_cancel_supplier_invoice 6215 6208 7 0.11 1 92363 979 0 7
sp_cancel_supplier_order 397 397 0 0.00 9 948342 102323 0 0
sp_cancel_write_off 9095 9095 0 0.00 1 20270 136 0 0
sp_client_order 20989 20989 0 0.00 4 54476 382 0 0
sp_customer_reserve 480298 480298 0 0.00 0 124290 175 0 0
sp_fill_shopping_cart 32148 32148 0 0.00 1 10461 39 0 0
sp_fill_shopping_cart_clo_res 480285 480285 0 0.00 0 20712 15 0 0
sp_get_clo_for_invoice 5158 5158 0 0.00 0 35620 359 0 0
sp_kill_cost_storno 15761 15761 0 0.00 0 16341 61 0 0
sp_kill_qstorno_ret_qs2qd 1104278 1104278 0 0.00 0 92356 32 0 0
sp_kill_qty_storno 582494 582494 0 0.00 0 92357 69 0 0
sp_lock_selected_doc 21054 21054 0 0.00 0 10527 5 0 0
sp_make_cost_storno 28571 28571 0 0.00 0 21465 49 0 0
sp_multiply_rows_for_pdistr 28570 28570 0 0.00 0 21507 37 0 0
sp_multiply_rows_for_qdistr 20989 20989 0 0.00 0 54466 208 0 0
sp_pay_from_customer 7243 7243 0 0.00 4 16387 111 0 0
sp_pay_to_supplier 6060 6060 0 0.00 5 21893 135 0 0
sp_payment_common 13302 13302 0 0.00 1 19560 30 0 0
sp_qd_handle_on_cancel_clo 1696 1696 0 0.00 0 6644 265 0 0
sp_qd_handle_on_invoice_upd_sts 10698 10698 0 0.00 0 20756 315 0 0
sp_qd_handle_on_reserve_upd_sts 10111 10111 0 0.00 0 11173 56 0 0
sp_reserve_write_off 10112 10112 0 0.00 7 21523 186 0 0
sp_supplier_invoice 5581 5581 0 0.00 6 83663 3387 0 0
sp_supplier_order 5598 5598 0 0.00 6 25013 1334 0 0
srv_aggregate_perf_data 111 111 0 0.00 3153 178393 14437 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1737 143742 47221 0 0
srv_find_qd_qs_mism 1061308 1061308 0 0.00 0 19940 6 0 0
srv_make_invnt_saldo 447 447 0 0.00 66 96703 3667 0 0
srv_make_money_saldo 714 714 0 0.00 0 11749 457 0 0
srv_recalc_idx_stat 4 4 0 0.00 118978 286950 241054 0 0
t$perf-abend:sp_add_invoice_to_stock 73 73 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 5173 5173 0 0.00 0 24159 140 0 0
t$perf-norm:sp_cancel_adding_invoice 381 381 0 0.00 0 6536 96 0 0
t$perf-norm:sp_cancel_client_order 1696 1696 0 0.00 0 11807 17 0 0
t$perf-norm:sp_cancel_customer_reserve 721 721 0 0.00 0 4756 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 699 699 0 0.00 0 2247 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 425 425 0 0.00 0 808 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 430 430 0 0.00 0 2620 16 0 0
t$perf-norm:sp_cancel_supplier_order 397 397 0 0.00 0 25757 1411 0 0
t$perf-norm:sp_cancel_write_off 1040 1040 0 0.00 0 3909 11 0 0
t$perf-norm:sp_client_order 20989 20989 0 0.00 0 12300 16 0 0
t$perf-norm:sp_customer_reserve 1019 1019 0 0.00 0 2352 11 0 0
t$perf-norm:sp_pay_from_customer 7243 7243 0 0.00 0 9772 12 0 0
t$perf-norm:sp_pay_to_supplier 6060 6060 0 0.00 0 8579 10 0 0
t$perf-norm:sp_reserve_write_off 10112 10112 0 0.00 0 5400 8 0 0
t$perf-norm:sp_supplier_invoice 5581 5581 0 0.00 0 8199 19 0 0
t$perf-norm:sp_supplier_order 5598 5598 0 0.00 0 12395 15 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 685 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 2486 40 0 0
t$perf-norm:srv_make_invnt_saldo 447 447 0 0.00 0 3002 19 0 0
t$perf-norm:srv_make_money_saldo 714 714 0 0.00 0 1141 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 47 14 0 0
v_add_invoice_to_stock 10454 10308 146 1.39 0 8876 23 0 146
v_all_customers 38418 38418 0 0.00 0 22 1 0 0
v_all_suppliers 31128 31128 0 0.00 0 22 1 0 0
v_all_wares 41976 41976 0 0.00 0 27 1 0 0
v_cancel_adding_invoice 762 762 0 0.00 0 439 7 0 0
v_cancel_client_order 3392 3392 0 0.00 0 2119 4 0 0
v_cancel_customer_prepayment 1398 1398 0 0.00 0 3229 9 0 0
v_cancel_customer_reserve 1442 1442 0 0.00 0 3003 8 0 0
v_cancel_payment_to_supplier 850 850 0 0.00 0 2583 6 0 0
v_cancel_supplier_invoice 874 860 14 1.60 0 678 17 0 14
v_cancel_supplier_order 794 794 0 0.00 0 2898 6 0 0
v_cancel_write_off 2080 2080 0 0.00 0 8512 19 0 0
v_max_id_clo_ord 5589 5589 0 0.00 0 651 1 0 0
v_max_id_clo_res 1019 1019 0 0.00 0 1684 13 0 0
v_max_id_ord_sup 5571 5571 0 0.00 0 5532 6 0 0
v_max_non_paid_invoice 1656 1656 0 0.00 0 279 2 0 0
v_max_non_paid_realizn 3782 3782 0 0.00 0 2935 3 0 0
v_min_id_clo_ord 5589 5589 0 0.00 0 7044 7 0 0
v_min_id_clo_res 1019 1019 0 0.00 0 2711 8 0 0
v_min_id_ord_sup 5571 5571 0 0.00 0 5579 5 0 0
v_min_non_paid_invoice 6060 6060 0 0.00 0 19556 30 0 0
v_min_non_paid_realizn 7242 7242 0 0.00 0 11098 17 0 0
v_our_firm 10478 10478 0 0.00 0 14 1 0 0
v_reserve_write_off 20222 20222 0 0.00 0 6795 8 0 0
x_lock_depdocs_on_canc_invoice 11320 11320 0 0.00 0 19988 94 0 0
x_lock_depdocs_on_canc_sup_ord 397 397 0 0.00 0 14580 303 0 0
x_make_qstorno_1000_1200 5598 5598 0 0.00 2 24846 1257 0 0
x_make_qstorno_1200_2000 5581 5581 0 0.00 3 83652 3242 0 0
x_make_qstorno_2100_3300 480298 480298 0 0.00 0 124289 158 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-12-2021 07:38:17 to 08-12-2021 07:38:21.

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:09 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:10 28340 8192000000 9720154352 10017062912 799582496 8.892 100 84 169 0 827856880 8008720 20265664
05:09:43 16746 8192000000 9753978784 10059821056 828998752 9.189 100 87 174 0 863604880 13117040 21489088
05:11:05 15485 8192000000 9795587632 10116161536 866277872 9.563 100 80 160 2 906485856 21959040 18248944
05:12:26 13358 8192000000 9872447824 10203033600 911509216 10.012 100 86 172 0 976553456 46118160 18926080
05:13:43 8515 8192000000 9932564928 10276220928 963118432 10.519 100 85 170 0 1052152240 71142400 17891408
05:14:53 4548 8192000000 9988916688 10341580800 995258464 10.833 100 88 175 1 1099029968 85801024 17970480
05:15:59 3407 8192000000 9983067120 10347388928 1020295120 11.075 100 90 178 0 1098616096 60872288 17448688
05:17:04 2657 8192000000 10016878784 10384150528 1045054496 11.313 100 90 179 2 1130237600 66911008 18272096
05:18:09 1710 8192000000 10056330912 10427330560 1059263952 11.449 100 91 182 1 1172739808 95017008 18458848
05:19:12 1609 8192000000 10008320800 10393182208 1060072976 11.457 100 78 154 1 1127443760 51073280 16297504
05:20:15 36033 8192000000 9991372032 10372521984 1072322288 11.574 100 58 116 4 1109575104 24817440 12435376
05:21:52 5300 8192000000 9998675200 10384580608 1079880592 11.646 100 52 104 1 1103604640 13759392 9964656
05:22:58 10560 8192000000 9997692240 10402971648 1089568976 11.739 100 23 46 0 1125259024 30448816 5241232
05:24:10 5438 8192000000 10016635424 10421256192 1094462256 11.785 100 39 77 4 1141357504 38900528 7994720
05:25:20 17002 8192000000 10009524832 10400325632 1092429808 11.766 100 72 144 2 1119003824 10279200 16294816
05:26:37 5634 8192000000 10020436864 10414575616 1095677664 11.797 100 43 86 3 1132916720 28165904 9073152
05:27:44 4308 8192000000 9994991072 10386415616 1073790448 11.588 99 45 90 2 1092399984 9316976 9292560
05:28:49 8282 8192000000 9981018080 10386292736 1045445152 11.317 98 32 64 0 1073420272 20780496 7194624
05:30:00 21654 8192000000 9953383600 10324967424 983008256 10.713 93 56 112 5 1010039904 14952960 12078688
05:31:23 5420 8192000000 9923949856 10305343488 999596576 10.875 97 37 74 2 1039886736 32058416 8231744
05:32:30 9108 8192000000 9954089488 10339016704 992600240 10.807 97 39 78 2 1024782464 23534592 8647632
05:33:42 8513 8192000000 9972956848 10362228736 1047838240 11.340 100 30 58 1 1076408176 22367104 6202832
05:34:53 27531 8192000000 9973571584 10370289664 1046593344 11.328 99 37 72 3 1069340064 15087424 7659296
05:36:32 4099 8192000000 9992840288 10375213056 1068039840 11.533 100 55 108 1 1099886432 19150752 12695840
05:37:42 26414 8192000000 10028719360 10412965888 1094715840 11.787 100 51 102 0 1128029024 22381808 10931376
05:39:14 35477 8192000000 10102032720 10500763648 1107812656 11.912 100 51 102 0 1188469728 70507680 10149392
05:40:56 100914 8192000000 10134206448 10541748224 1126996256 12.093 100 30 59 0 1226388112 92833472 6558384
05:43:40 11435 8192000000 10190733824 10600632320 1142683360 12.241 100 45 90 1 1296712992 144571456 9458176
05:44:56 2157 8192000000 10238373344 10645340160 1153636640 12.344 100 64 128 2 1345353376 177679760 14036976
05:46:00 7470 8192000000 10289894256 10693677056 1158909440 12.393 100 64 128 0 1388092544 214874128 14308976
05:47:15 1733 8192000000 10295272704 10724839424 1173542304 12.530 100 65 122 0 1405206240 216728384 14935552
05:48:21 3325 8192000000 10301046240 10710749184 1185031520 12.637 99 59 118 0 1409416800 210560320 13824960
05:49:28 3079 8192000000 10379277088 10780561408 1193237824 12.713 100 86 170 0 1484505184 271757520 19509840
05:50:35 9204 8192000000 10431843072 10853376000 1200438048 12.780 100 64 126 0 1538111312 322947936 14725328
05:51:53 2677 8192000000 10452184944 10852278272 1188935648 12.673 100 88 174 2 1545586544 335641872 21009024
05:52:58 5606 8192000000 10400169920 10816204800 1148778304 12.298 98 58 116 1 1491085936 328495712 13811920
05:54:10 7051 8192000000 10376415712 10806677504 1133358976 12.153 99 67 134 0 1485582432 336757136 15466320
05:55:23 10354 8192000000 10379668096 10800271360 1144833728 12.261 100 40 79 0 1477407792 322605888 9968176
05:56:40 3303 8192000000 10327151440 10742865920 1137434528 12.191 100 56 107 1 1425255472 275312480 12508464
05:57:46 12122 8192000000 10229299600 10664833024 1116181184 11.991 99 24 48 0 1338612016 215971856 6458976
05:59:07 9809 8192000000 10218449152 10665906176 1100660512 11.844 99 21 42 0 1315934704 209433184 5841008
06:00:19 22688 8192000000 10125147440 10583633920 1092232432 11.764 98 12 24 2 1241440704 146348416 2859856
06:01:49 48003 8192000000 10053289168 10477506560 1090179184 11.744 98 15 30 0 1161398288 67930592 3288512
06:03:42 61912 8192000000 10040328848 10461552640 1084612928 11.691 97 14 29 1 1119959296 32784832 2561536
06:05:48 101482 8192000000 10062062752 10515767296 1064077680 11.495 95 23 46 0 1129440544 60481472 4881392
06:08:53 21110 8192000000 10078452800 10516869120 1108616080 11.919 100 25 50 2 1177544144 63803328 5124736
06:10:15 73265 8192000000 10045495968 10496323584 1090633920 11.749 97 8 16 0 1126984864 35031024 1319920
06:12:29 99872 8192000000 10066073776 10491359232 1126231776 12.086 99 29 58 1 1160162464 28160080 5770608
06:15:09 64523 8192000000 10110211392 10565275648 1143637504 12.250 100 39 77 0 1199566128 48040656 7887968
06:17:21 72597 8192000000 10116272208 10558607360 1126568736 12.089 98 18 36 0 1195271712 64700960 4002016
06:20:07 15565 8192000000 10148093552 10577145856 1161186880 12.414 100 49 93 0 1242370880 71915008 9268992
06:21:30 45217 8192000000 10181864064 10630385664 1168337760 12.481 100 20 40 0 1278187888 104963248 4886880
06:24:03 88950 8192000000 10225291216 10736582656 1156650560 12.372 99 16 32 1 1321915984 161372464 3892960
06:26:39 46374 8192000000 10148631008 10592223232 1133064752 12.150 98 16 32 0 1261881152 124866752 3949648
06:28:26 50996 8192000000 10137694208 10612948992 1147857712 12.289 99 21 42 0 1233254432 80534304 4862416
06:30:17 67386 8192000000 10096483856 10570539008 1126768272 12.091 97 9 18 1 1180742592 51883120 2091200
06:32:44 14575 8192000000 10117342672 10544066560 1133986480 12.159 99 47 94 0 1190117792 46247808 9883504
06:34:04 107213 8192000000 10074383744 10534948864 1019157328 11.064 89 13 26 0 1047720112 26352544 2210240
06:36:51 54961 8192000000 10088073344 10530926592 1109419600 11.927 98 22 44 0 1146119456 32281376 4418480
06:38:52 113813 8192000000 10058201952 10503532544 1090004016 11.743 97 38 75 0 1158857120 61343824 7509280
06:41:49 81221 8192000000 10069147504 10532372480 1080029728 11.648 96 11 22 1 1140319504 57896816 2392960
06:44:11 52926 8192000000 10033733456 10482274304 1083608080 11.682 98 15 30 1 1122588352 35523088 3457184
06:46:06 71777 8192000000 10095147136 10531344384 1102213040 11.859 99 24 48 1 1165176240 58079920 4883280
06:48:45 56492 8192000000 10068466368 10529296384 1095405216 11.794 99 20 40 1 1173528160 73581056 4541888
06:50:50 68823 8192000000 10092847168 10526412800 1076074256 11.610 96 21 42 2 1120012560 39662688 4275616
06:53:03 53977 8192000000 10162795360 10620854272 1115882496 11.988 99 27 52 0 1247923008 126413424 5627088
06:55:16 58296 8192000000 10071342032 10533003264 1100584000 11.843 99 11 22 0 1169168896 66503024 2081872
06:57:20 72867 8192000000 10080644464 10511601664 1116332384 11.992 99 14 28 0 1150570688 31401696 2836608
06:59:34 62905 8192000000 10116164048 10586296320 1118690352 12.015 100 30 60 0 1201778576 76912336 6175888
07:01:38 143742 8192000000 10086468976 10529316864 1105738896 11.892 99 18 36 1 1173568560 63552352 4277312
07:05:09 52291 8192000000 10016732224 10461417472 1064765888 11.502 96 14 28 0 1097618496 29997104 2855504
07:07:29 48483 8192000000 10058636832 10513063936 1110647728 11.939 100 7 14 0 1143733184 31887264 1198192
07:09:43 56888 8192000000 10048103520 10494312448 1115322496 11.983 99 9 18 0 1155192720 38272416 1597808
07:11:40 28562 8192000000 10078776496 10512883712 1123204096 12.057 99 33 65 0 1152436976 22794416 6438464
07:13:16 30610 8192000000 10082591872 10525802496 1128905216 12.111 99 25 49 0 1197335520 63567984 4862320
07:14:57 17788 8192000000 10117446000 10551746560 1126667520 12.090 99 54 108 0 1187673696 49989392 11016784
07:16:15 20303 8192000000 10104938608 10534494208 1133075568 12.150 100 36 72 2 1192380576 52097136 7207872
07:17:40 25383 8192000000 10090106288 10527666176 1125869664 12.082 100 40 79 1 1173297312 39372880 8054768
07:19:09 67368 8192000000 10094888208 10575904768 1126357968 12.087 99 31 62 0 1194319280 61720160 6241152
07:21:18 73042 8192000000 10113931392 10613559296 1120239024 12.029 98 8 16 0 1178793984 56970224 1584736
07:23:40 121296 8192000000 10064074208 10508976128 1108945872 11.922 97 34 68 0 1142146848 25830816 7370160
07:26:42 33060 8192000000 10054052080 10534002688 1118927472 12.017 99 11 22 0 1162452432 41446768 2078192
07:28:18 58489 8192000000 10048312912 10504044544 1095210336 11.792 97 11 22 0 1125903376 28790432 1902608
07:30:24 84251 8192000000 10098225440 10569400320 1100260960 11.840 98 18 35 0 1189243360 84843408 4138992
07:32:51 42407 8192000000 10030875280 10484994048 1087835584 11.722 98 20 40 0 1131888176 40085696 3966896
07:34:34 41728 8192000000 10074675024 10519388160 1087877632 11.722 98 50 100 0 1154808896 56868368 10062896
07:36:21 27238 8192000000 10076728992 10530430976 1094804992 11.788 98 29 58 2 1147638576 46880528 5953056

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 08-12-2021 07:38:23 to 08-12-2021 07:38:38.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 73 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 08-12-2021 07:38:39 to 08-12-2021 07:38:41.

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          235424
MON$OLDEST_ACTIVE               237152
MON$OLDEST_SNAPSHOT             237152
MON$NEXT_TRANSACTION            237152
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                       2056352
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             807
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2676 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2676 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2676 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2676 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 ---
:58: 
:59: mscs-test-161.budzdorov.ru    Wed Dec  8 07:37:16 2021
:60:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:61: 
:62: 
:63: mscs-test-161.budzdorov.ru    Wed Dec  8 07:37:22 2021
:64:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:65: 
:66: 
:67: mscs-test-161.budzdorov.ru    Wed Dec  8 07:37:22 2021
: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'

08.12.21 07:38:41. 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
2021-12-08 07:38:44 No severe PSQL-related problems occured
08.12.21 07:38:45. End of report.