Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.05.2022 10:39
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 16387784 kB
MemAvailable 31411092 kB
Buffers 0 kB
Cached 15359700 kB
SwapCached 2552 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.2
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-05-27 10:37:16
DTS_END                         2022-05-27 10:37:16.7570
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/40510
TRN_ID                          208624
ATT_ID                          611
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: 8457.73 29155 1014928
customer order: creation 154.50 532 18540
customer order: refuse 13.73 475 1647
order to supplier: creation 42.40 1512 5088
order to supplier: removal 3.33 183026 399
invoice (draft): creation 41.87 3421 5024
invoice (draft): removal 49.08 1114 5889
invoice accept: apply 38.48 29682 4617
invoice accept: cancel 43.85 1330 5262
customer reserve: creation 3554.48 284 426537
customer reserve: removal 4247.28 112 509674
realization accept: apply 75.72 316 9087
realization accept: cancel 74.13 185 8896
payment from customer: creation 54.22 229 6506
payment from customer: removal 5.30 141 636
payment to supplier: creation 44.40 222 5328
payment to supplier: removal 3.29 150 395
service: total inventory turnovers 3.75 4412 450
service: total monetary turnovers 7.07 429 848
service: aggregate perf. data 0.84 16962 101
service: refresh index statistics 0.03 338569 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 27-05-2022 10:40:19 to 27-05-2022 10:40:22.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5932
TEST_TIME 2 4330
TEST_TIME 3 5116
TEST_TIME 4 5003
TEST_TIME 5 5346
TEST_TIME 6 5254
TEST_TIME 7 5391
TEST_TIME 8 5309
TEST_TIME 9 5232
TEST_TIME 10 5456
TEST_TIME 11 5522
TEST_TIME 12 5904
TEST_TIME 13 5962
TEST_TIME 14 6106
TEST_TIME 15 6404
TEST_TIME 16 6515
TEST_TIME 17 6730
TEST_TIME 18 6763
TEST_TIME 19 7152
TEST_TIME 20 7226
TEST_TIME 21 7197
TEST_TIME 22 7367
TEST_TIME 23 7385
TEST_TIME 24 7547
TEST_TIME 25 7594
TEST_TIME 26 7748
TEST_TIME 27 8346
TEST_TIME 28 8367
TEST_TIME 29 8802
TEST_TIME 30 8690
TEST_TIME 31 9232
TEST_TIME 32 9615
TEST_TIME 33 9778
TEST_TIME 34 10049
TEST_TIME 35 10113
TEST_TIME 36 10149
TEST_TIME 37 10188
TEST_TIME 38 10192
TEST_TIME 39 9951
TEST_TIME 40 9801
TEST_TIME 41 9852
TEST_TIME 42 9799
TEST_TIME 43 9707
TEST_TIME 44 9593
TEST_TIME 45 9557
TEST_TIME 46 9494
TEST_TIME 47 9411
TEST_TIME 48 9349
TEST_TIME 49 9302
TEST_TIME 50 9182
TEST_TIME 51 9124
TEST_TIME 52 9258
TEST_TIME 53 9146
TEST_TIME 54 9061
TEST_TIME 55 8986
TEST_TIME 56 8941
TEST_TIME 57 8838
TEST_TIME 58 8833
TEST_TIME 59 8791
TEST_TIME 60 8875
TEST_TIME 61 8860
TEST_TIME 62 9008
TEST_TIME 63 8910
TEST_TIME 64 8994
TEST_TIME 65 8956
TEST_TIME 66 9014
TEST_TIME 67 9036
TEST_TIME 68 8986
TEST_TIME 69 8985
TEST_TIME 70 8963
TEST_TIME 71 8973
TEST_TIME 72 8906
TEST_TIME 73 8856
TEST_TIME 74 8816
TEST_TIME 75 8791
TEST_TIME 76 8812
TEST_TIME 77 8768
TEST_TIME 78 8712
TEST_TIME 79 8683
TEST_TIME 80 8617
TEST_TIME 81 8559
TEST_TIME 82 8514
TEST_TIME 83 8564
TEST_TIME 84 8579
TEST_TIME 85 8535
TEST_TIME 86 8530
TEST_TIME 87 8567
TEST_TIME 88 8628
TEST_TIME 89 8602
TEST_TIME 90 8652
TEST_TIME 91 8693
TEST_TIME 92 8686
TEST_TIME 93 8688
TEST_TIME 94 8727
TEST_TIME 95 8666
TEST_TIME 96 8609
TEST_TIME 97 8635
TEST_TIME 98 8651
TEST_TIME 99 8625
TEST_TIME 100 8564
TEST_TIME 101 8523
TEST_TIME 102 8527
TEST_TIME 103 8504
TEST_TIME 104 8497
TEST_TIME 105 8488
TEST_TIME 106 8461
TEST_TIME 107 8443
TEST_TIME 108 8410
TEST_TIME 109 8391
TEST_TIME 110 8412
TEST_TIME 111 8489
TEST_TIME 112 8470
TEST_TIME 113 8587
TEST_TIME 114 8611
TEST_TIME 115 8602
TEST_TIME 116 8628
TEST_TIME 117 8638
TEST_TIME 118 8574
TEST_TIME 119 8539
TEST_TIME 120 8486

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 27-05-2022 10:40:23 to 27-05-2022 10:40:26.

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 1407382 1407382 0 0.00 0 30350 15 0 0
doc_list_biud 1407365 1407365 0 0.00 0 56635 47 0 0
sp_add_invoice_to_stock 4748 4617 131 2.75 31 277173 29682 0 131
sp_cancel_adding_invoice 5262 5262 0 0.00 6 75823 1331 0 0
sp_cancel_client_order 1647 1647 0 0.00 7 8986 475 0 0
sp_cancel_customer_reserve 509674 509674 0 0.00 1 42340 113 0 0
sp_cancel_pay_from_customer 636 636 0 0.00 3 4279 141 0 0
sp_cancel_pay_to_supplier 395 395 0 0.00 4 10421 151 0 0
sp_cancel_supplier_invoice 5902 5889 13 0.22 2 56659 1115 0 13
sp_cancel_supplier_order 399 399 0 0.00 28 1474867 183027 0 0
sp_cancel_write_off 8896 8896 0 0.00 2 39384 186 0 0
sp_client_order 18540 18540 0 0.00 6 57815 533 0 0
sp_customer_reserve 426537 426537 0 0.00 1 74817 284 0 0
sp_fill_shopping_cart 28637 28637 0 0.00 1 10890 85 0 0
sp_fill_shopping_cart_clo_res 426515 426515 0 0.00 0 18719 21 0 0
sp_get_clo_for_invoice 4567 4567 0 0.00 0 33370 404 0 0
sp_kill_cost_storno 15189 15189 0 0.00 0 25616 87 0 0
sp_kill_qstorno_ret_qs2qd 1034532 1034532 0 0.00 0 56632 50 0 0
sp_kill_qty_storno 545415 545415 0 0.00 0 56634 114 0 0
sp_lock_selected_doc 19000 19000 0 0.00 0 8555 8 0 0
sp_make_cost_storno 25487 25487 0 0.00 0 20704 97 0 0
sp_multiply_rows_for_pdistr 25480 25480 0 0.00 0 30330 49 0 0
sp_multiply_rows_for_qdistr 18539 18539 0 0.00 0 57798 220 0 0
sp_pay_from_customer 6506 6506 0 0.00 5 30365 230 0 0
sp_pay_to_supplier 5328 5328 0 0.00 6 25142 222 0 0
sp_payment_common 11832 11832 0 0.00 2 18539 57 0 0
sp_qd_handle_on_cancel_clo 1647 1647 0 0.00 0 8979 395 0 0
sp_qd_handle_on_invoice_upd_sts 9824 9824 0 0.00 1 35375 573 0 0
sp_qd_handle_on_reserve_upd_sts 9086 9086 0 0.00 0 27630 79 0 0
sp_reserve_write_off 9087 9087 0 0.00 9 27679 317 0 0
sp_supplier_invoice 5024 5024 0 0.00 12 127246 3421 0 0
sp_supplier_order 5088 5088 0 0.00 8 84620 1513 0 0
srv_aggregate_perf_data 101 101 0 0.00 2350 144355 16962 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 737 140832 34619 0 0
srv_find_qd_qs_mism 969975 969975 0 0.00 0 14193 11 0 0
srv_make_invnt_saldo 450 450 0 0.00 77 86256 4412 0 0
srv_make_money_saldo 848 848 0 0.00 1 18186 430 0 0
srv_recalc_idx_stat 4 4 0 0.00 144232 450781 338569 0 0
t$perf-abend:sp_add_invoice_to_stock 131 131 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4624 4624 0 0.00 1 10101 993 0 0
t$perf-norm:sp_cancel_adding_invoice 362 362 0 0.00 1 6695 473 0 0
t$perf-norm:sp_cancel_client_order 1647 1647 0 0.00 0 3857 33 0 0
t$perf-norm:sp_cancel_customer_reserve 649 649 0 0.00 0 956 33 0 0
t$perf-norm:sp_cancel_pay_from_customer 636 636 0 0.00 0 18076 41 0 0
t$perf-norm:sp_cancel_pay_to_supplier 395 395 0 0.00 0 2769 20 0 0
t$perf-norm:sp_cancel_supplier_invoice 375 375 0 0.00 0 1588 53 0 0
t$perf-norm:sp_cancel_supplier_order 399 399 0 0.00 0 98568 7191 0 0
t$perf-norm:sp_cancel_write_off 890 890 0 0.00 0 618 16 0 0
t$perf-norm:sp_client_order 18540 18540 0 0.00 0 12620 41 0 0
t$perf-norm:sp_customer_reserve 917 917 0 0.00 0 3290 16 0 0
t$perf-norm:sp_pay_from_customer 6506 6506 0 0.00 0 27936 27 0 0
t$perf-norm:sp_pay_to_supplier 5328 5328 0 0.00 0 2717 19 0 0
t$perf-norm:sp_reserve_write_off 9089 9089 0 0.00 0 12619 28 0 0
t$perf-norm:sp_supplier_invoice 5024 5024 0 0.00 0 5282 38 0 0
t$perf-norm:sp_supplier_order 5088 5088 0 0.00 0 3381 34 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 317 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 527 19 0 0
t$perf-norm:srv_make_invnt_saldo 450 450 0 0.00 0 681 18 0 0
t$perf-norm:srv_make_money_saldo 848 848 0 0.00 0 450 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 183 58 0 0
v_add_invoice_to_stock 9384 9122 262 2.79 0 6399 25 0 262
v_all_customers 34224 34224 0 0.00 0 128 4 0 0
v_all_suppliers 27964 27964 0 0.00 0 149 4 0 0
v_all_wares 37068 37068 0 0.00 0 114 4 0 0
v_cancel_adding_invoice 724 724 0 0.00 0 1264 15 0 0
v_cancel_client_order 3294 3294 0 0.00 0 1614 9 0 0
v_cancel_customer_prepayment 1272 1272 0 0.00 0 2847 15 0 0
v_cancel_customer_reserve 1298 1298 0 0.00 0 853 12 0 0
v_cancel_payment_to_supplier 790 790 0 0.00 0 4796 17 0 0
v_cancel_supplier_invoice 776 750 26 3.35 0 8278 34 0 26
v_cancel_supplier_order 798 798 0 0.00 0 645 12 0 0
v_cancel_write_off 1780 1780 0 0.00 0 4635 24 0 0
v_max_id_clo_ord 5086 5086 0 0.00 0 1443 5 0 0
v_max_id_clo_res 917 917 0 0.00 0 3275 22 0 0
v_max_id_ord_sup 5016 5016 0 0.00 0 6915 10 0 0
v_max_non_paid_invoice 1447 1447 0 0.00 0 1492 9 0 0
v_max_non_paid_realizn 3373 3373 0 0.00 0 4453 10 0 0
v_min_id_clo_ord 5086 5086 0 0.00 0 5454 10 0 0
v_min_id_clo_res 917 917 0 0.00 0 5923 26 0 0
v_min_id_ord_sup 5016 5016 0 0.00 0 7940 9 0 0
v_min_non_paid_invoice 5327 5327 0 0.00 0 12168 24 0 0
v_min_non_paid_realizn 6505 6505 0 0.00 0 10192 24 0 0
v_our_firm 9108 9108 0 0.00 0 65 5 0 0
v_reserve_write_off 18172 18172 0 0.00 0 6768 11 0 0
x_lock_depdocs_on_canc_invoice 10776 10776 0 0.00 0 15127 131 0 0
x_lock_depdocs_on_canc_sup_ord 399 399 0 0.00 0 6268 433 0 0
x_make_qstorno_1000_1200 5088 5088 0 0.00 3 84594 1328 0 0
x_make_qstorno_1200_2000 5024 5024 0 0.00 7 127207 3196 0 0
x_make_qstorno_2100_3300 426537 426537 0 0.00 0 74814 250 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-05-2022 10:40:27 to 27-05-2022 10:40:31.

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
08:07:20 2 8192000000 8796669024 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:21 28372 8192000000 9725863664 10026557440 798951296 8.886 100 82 164 0 826186272 7648416 19586560
08:10:00 17665 8192000000 9769053936 10078482432 837459184 9.274 100 89 178 0 871600864 12770256 21371424
08:11:22 16419 8192000000 9797944656 10117079040 859830832 9.498 100 88 176 0 904940928 24010400 21099696
08:12:42 9648 8192000000 9866943680 10198298624 911544016 10.013 100 75 148 1 972901216 45018016 16339184
08:13:56 7839 8192000000 9914487984 10257133568 952519264 10.416 100 84 164 1 1031732640 61277280 17936096
08:15:07 2079 8192000000 9976904080 10329710592 992873008 10.809 100 96 188 1 1094923376 83325472 18724896
08:16:12 1720 8192000000 9990674352 10351259648 1020566864 11.077 100 93 180 0 1113339536 74321312 18451360
08:17:15 704 8192000000 9995005264 10363367424 1039346880 11.258 100 93 183 2 1116328640 57817024 19164736
08:18:17 953 8192000000 10026869328 10404061184 1055652288 11.415 100 96 187 0 1146749728 71673968 19423472
08:19:19 1274 8192000000 10044454016 10419478528 1059082656 11.448 100 96 183 0 1162591376 85121312 18387408
08:20:21 1103 8192000000 10013908960 10393120768 1063371104 11.489 100 92 174 0 1132149808 51092480 17686224
08:21:23 875 8192000000 10052916224 10436104192 1069606832 11.548 100 93 172 1 1172106704 84647520 17852352
08:22:25 1206 8192000000 10043317536 10426531840 1078002560 11.628 100 95 184 1 1166375568 70416464 17956544
08:23:28 1330 8192000000 10045534624 10430627840 1080324448 11.651 100 94 178 3 1168185536 68693888 19167200
08:24:30 1239 8192000000 10051187904 10435874816 1086159184 11.706 100 95 186 3 1165799984 61441808 18198992
08:25:33 1181 8192000000 10048445248 10437275648 1090969248 11.752 100 85 161 2 1167378304 59773680 16635376
08:26:36 1148 8192000000 10068635200 10458566656 1090739264 11.750 100 95 183 2 1189550912 80392000 18419648
08:27:38 1272 8192000000 10070094688 10454372352 1094460304 11.785 100 86 170 3 1190111664 77145600 18505760
08:28:41 1193 8192000000 10056723888 10443845632 1083423632 11.680 99 94 180 0 1176893936 75173120 18297184
08:29:43 2224 8192000000 10035682048 10429833216 1082994928 11.676 100 69 128 4 1158039568 62385504 12659136
08:30:46 907 8192000000 10033309408 10420846592 1070266112 11.555 100 77 153 1 1151004256 66272288 14465856
08:31:49 14412 8192000000 9991347984 10379542528 1058850928 11.445 100 59 115 2 1115096208 44808864 11436416
08:33:04 10083 8192000000 9969547360 10353651712 1027655136 11.146 97 60 115 0 1103740320 64438000 11647184
08:34:15 1649 8192000000 9980385776 10360930304 1037813952 11.244 100 73 143 1 1105586912 53449664 14323296
08:35:17 3079 8192000000 9976989040 10358435840 1030929648 11.177 100 80 152 2 1101098688 52809312 17359728
08:36:21 805 8192000000 9966401216 10339504128 1021014896 11.082 100 90 171 0 1089796544 52999200 15782448
08:37:25 733 8192000000 9991080464 10363506688 1041654528 11.281 100 89 164 0 1113952368 56352896 15944944
08:38:36 11884 8192000000 10001878560 10387857408 1055604816 11.414 100 59 118 0 1129260432 61065120 12590496
08:39:57 12695 8192000000 10046388096 10433572864 1068229296 11.535 100 54 108 0 1161861968 82535872 11096800
08:41:17 1359 8192000000 10061671840 10436427776 1068321840 11.536 99 97 192 2 1177922512 88708560 20892112
08:42:23 10119 8192000000 10105935776 10497290240 1090758272 11.750 100 68 128 1 1227301040 123327184 13215584
08:43:39 22185 8192000000 10134227344 10536284160 1101793728 11.855 100 58 114 1 1244853136 131051936 12007472
08:45:04 2922 8192000000 10194719344 10581106688 1117556592 12.004 100 82 151 0 1306774512 172300032 16917888
08:46:15 1679 8192000000 10207385616 10603909120 1132022352 12.140 100 85 154 1 1322294240 173783872 16488016
08:47:29 8404 8192000000 10241378768 10639765504 1145050912 12.263 100 75 144 1 1348011472 187668576 15291984
08:48:40 1501 8192000000 10274882448 10686935040 1156835600 12.374 100 89 170 0 1386830928 211059552 18935776
08:49:46 3211 8192000000 10289951184 10688835584 1161342848 12.416 100 86 162 0 1398971344 219794080 17834416
08:50:54 871 8192000000 10328192064 10736627712 1173530480 12.530 100 95 179 2 1440007696 246999168 19478048
08:52:00 1179 8192000000 10345599312 10765926400 1178528016 12.576 100 96 192 0 1450629312 250907072 21194224
08:53:04 1120 8192000000 10360088416 10766622720 1172067040 12.516 100 94 177 4 1465916224 274385952 19463232
08:54:07 1072 8192000000 10371777872 10788528128 1174843200 12.542 100 95 181 0 1481971344 286450720 20677424
08:55:11 903 8192000000 10364986080 10801299456 1172675216 12.522 99 96 185 0 1475773440 280540976 22557248
08:56:14 1400 8192000000 10393719152 10829283328 1190021232 12.684 100 89 176 0 1502808704 292395120 20392352
08:57:20 849 8192000000 10425686560 10829361152 1185481664 12.641 100 92 178 0 1531561632 324800784 21279184
08:58:25 897 8192000000 10453555104 10871296000 1191205520 12.695 100 97 190 2 1556358656 342019808 23133328
08:59:31 1165 8192000000 10487606688 10936209408 1195025328 12.730 100 91 177 1 1595871648 380066576 20779744
09:00:36 2404 8192000000 10505450080 10943901696 1201130656 12.787 100 90 172 0 1612644656 389819200 21694800
09:01:43 1012 8192000000 10509883280 10940829696 1198692304 12.764 100 95 187 0 1619107024 396720640 23694080
09:02:47 1479 8192000000 10519913648 10977411072 1196105056 12.740 100 71 140 0 1620612816 407175680 17332080
09:03:55 795 8192000000 10561663920 11026055168 1207915504 12.850 100 88 174 0 1664056256 433919120 22221632
09:05:02 15512 8192000000 10476127392 10943115264 1189149280 12.675 100 43 85 0 1590348272 389947920 11251072
09:06:21 6246 8192000000 10463947168 10964017152 1170142208 12.498 99 41 82 0 1560237792 379449888 10645696
09:07:32 10546 8192000000 10425550144 10874953728 1162134592 12.423 100 34 68 1 1540018080 368490256 9393232
09:08:45 12763 8192000000 10295327232 10821816320 1157789056 12.383 100 27 54 1 1426292592 261440528 7063008
09:09:59 33631 8192000000 10218209088 10740224000 1144429440 12.257 100 19 38 0 1314219360 164860800 4929120
09:11:36 54983 8192000000 10139370896 10582081536 1150723088 12.316 100 23 42 0 1247866208 92587552 4555568
09:13:42 17510 8192000000 10188466208 10658988032 1149352752 12.303 100 32 63 2 1291517664 134829936 7334976
09:15:27 140831 8192000000 10113375696 10550169600 1113360720 11.964 97 28 55 0 1185523232 66175520 5986992
09:18:49 37447 8192000000 10091762880 10571390976 1146699648 12.279 100 33 67 0 1211309504 55894128 8715728
09:20:28 72473 8192000000 10128403648 10570862592 1148192480 12.293 99 29 57 2 1203470336 48885168 6392688
09:22:43 73426 8192000000 10095328256 10571448320 1129678208 12.118 98 18 34 1 1187786016 54420080 3687728
09:25:18 71459 8192000000 10105856288 10586771456 1119538608 12.023 97 19 38 0 1185079376 61156096 4384672
09:27:36 63906 8192000000 10167099712 10630885376 1146456496 12.276 100 16 32 1 1249114048 98836480 3821072
09:29:42 77151 8192000000 10095566320 10551795712 1117162896 12.000 97 25 50 0 1171737088 49206992 5367200
09:32:14 28290 8192000000 10142697200 10581864448 1129386112 12.116 98 45 90 0 1232809856 93629776 9793968
09:33:50 65326 8192000000 10177029136 10637299712 1115311472 11.983 97 23 45 0 1243208832 122437440 5459920
09:36:00 67731 8192000000 10154694192 10642579456 1126306464 12.087 100 28 54 0 1258245776 126288080 5651232
09:38:41 82369 8192000000 10076335920 10560819200 1130935296 12.130 100 12 25 0 1200520288 66590592 2994400
09:41:04 10102 8192000000 10118302640 10548805632 1126373072 12.087 99 66 131 1 1207845088 68317888 13154128
09:42:16 48740 8192000000 10121036672 10565578752 1126848848 12.092 99 30 60 0 1182729232 49717024 6163360
09:44:19 47463 8192000000 10075171968 10519719936 1123001152 12.055 99 30 55 1 1158403232 30568128 4833952
09:46:16 24927 8192000000 10083031472 10513350656 1140160352 12.217 100 35 70 1 1177691648 30604272 6927024
09:47:44 57578 8192000000 10080028144 10527416320 1085561824 11.700 95 47 95 2 1162358576 67710848 9085904
09:49:45 62181 8192000000 10139420560 10581364736 1133478624 12.154 100 57 114 0 1213274000 67649744 12145632
09:52:02 20270 8192000000 10079649520 10529771520 1137344992 12.191 100 58 115 0 1183866208 34912512 11608704
09:53:24 67700 8192000000 10065848128 10560847872 1128936816 12.111 99 24 48 1 1192594384 58838384 4819184
09:55:32 46484 8192000000 10048189088 10492502016 1100539328 11.843 97 24 47 2 1126290784 21216544 4534912
09:57:22 76461 8192000000 10058230080 10523869184 1107878032 11.912 99 10 20 1 1172799376 63078688 1842656
09:59:50 91692 8192000000 10069403504 10542370816 1098529328 11.824 97 12 23 0 1168114976 67211616 2374032
10:02:22 29808 8192000000 10129706944 10569248768 1137193600 12.189 100 42 84 1 1216941248 70522096 9225552
10:04:02 31266 8192000000 10117470144 10563629056 1105859856 11.893 97 16 32 0 1205400928 95921904 3619168
10:05:35 43304 8192000000 10118667040 10559062016 1114562576 11.976 98 37 73 0 1194679728 72443744 7673408
10:07:25 19436 8192000000 10121791712 10552827904 1110668256 11.939 98 40 80 1 1193841072 75125392 8047424
10:08:54 15479 8192000000 10084280224 10518151168 1133954720 12.159 100 45 88 3 1176032832 32967136 9110976
10:10:15 27621 8192000000 10128746128 10579955712 1138152000 12.198 100 39 76 0 1217697024 71935232 7609792
10:11:47 75310 8192000000 10146727632 10593357824 1125728656 12.081 98 19 37 2 1214742480 84720880 4292944
10:14:39 30565 8192000000 10093927216 10573049856 1134233984 12.161 99 25 47 1 1200605520 61801952 4569584
10:16:10 72983 8192000000 10063626480 10539323392 1110046352 11.933 97 12 24 0 1156552192 44119488 2386352
10:18:45 74753 8192000000 10068157232 10544721920 1111146832 11.943 97 17 34 1 1161872064 47571536 3153696
10:21:00 47749 8192000000 10096225840 10546343936 1137143008 12.189 99 20 40 0 1182350496 41034512 4172976
10:22:48 34319 8192000000 10120841856 10559127552 1095291536 11.793 95 27 54 1 1177093328 75698176 6103616
10:24:27 66742 8192000000 10201662880 10646327296 1130304656 12.124 99 29 58 1 1276443504 139443264 6695584
10:26:36 38435 8192000000 10263190624 10711494656 1164564880 12.446 100 42 84 1 1337742768 163367616 9810272
10:28:24 51360 8192000000 10214635008 10716598272 1148314400 12.294 99 33 65 0 1343519680 187770320 7434960
10:30:44 63589 8192000000 10058220288 10493722624 1092960640 11.771 95 20 37 1 1132698160 35854928 3882592
10:32:59 42152 8192000000 10142796848 10597535744 1119506032 12.022 98 48 95 1 1231129904 102176944 9446928
10:34:42 115771 8192000000 10094253696 10532372480 1150584912 12.315 100 45 88 2 1204084400 44336416 9163072

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 27-05-2022 10:40:33 to 27-05-2022 10:40:49.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 131 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 27-05-2022 10:40:50 to 27-05-2022 10:40:52.

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          207575
MON$OLDEST_ACTIVE               208926
MON$OLDEST_SNAPSHOT             208926
MON$NEXT_TRANSACTION            208926
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                       2044320
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             756
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2770 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2770 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2770 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2770 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 ---
:83: 
:84: mscs-test-161.budzdorov.ru    Fri May 27 10:37:38 2022
:85:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:86: 
--- end of diff output ---

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

27.05.22 10:40:53. 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-05-27 10:40:56 No severe PSQL-related problems occured
27.05.22 10:40:57. End of report.