Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 29.01.2023 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 15136712 kB
MemAvailable 30461076 kB
Buffers 0 kB
Cached 16593884 kB
SwapCached 4576 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 2023-01-29 10:37:25
DTS_END                         2023-01-29 10:37:25.0820
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/58976
TRN_ID                          213386
ATT_ID                          655
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: 8717.45 23596 1046094
customer order: creation 162.54 446 19505
customer order: refuse 14.33 450 1720
order to supplier: creation 43.13 1308 5176
order to supplier: removal 3.34 166575 401
invoice (draft): creation 42.92 3214 5150
invoice (draft): removal 48.88 1033 5865
invoice accept: apply 39.49 26356 4739
invoice accept: cancel 44.48 1226 5338
customer reserve: creation 3671.41 251 440569
customer reserve: removal 4358.51 100 523021
realization accept: apply 79.42 274 9530
realization accept: cancel 84.96 162 10195
payment from customer: creation 57.19 156 6863
payment from customer: removal 5.57 111 668
payment to supplier: creation 46.43 187 5572
payment to supplier: removal 3.20 152 384
service: total inventory turnovers 3.58 4155 430
service: total monetary turnovers 7.18 375 861
service: aggregate perf. data 0.86 16059 103
service: refresh index statistics 0.03 249335 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 29-01-2023 10:39:36 to 29-01-2023 10:39:39.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5452
TEST_TIME 2 5274
TEST_TIME 3 5942
TEST_TIME 4 5392
TEST_TIME 5 4780
TEST_TIME 6 5027
TEST_TIME 7 5163
TEST_TIME 8 5506
TEST_TIME 9 5566
TEST_TIME 10 5843
TEST_TIME 11 6032
TEST_TIME 12 6140
TEST_TIME 13 6442
TEST_TIME 14 6505
TEST_TIME 15 6660
TEST_TIME 16 7162
TEST_TIME 17 7181
TEST_TIME 18 7204
TEST_TIME 19 7262
TEST_TIME 20 7555
TEST_TIME 21 7747
TEST_TIME 22 8142
TEST_TIME 23 8073
TEST_TIME 24 8226
TEST_TIME 25 8138
TEST_TIME 26 8291
TEST_TIME 27 8328
TEST_TIME 28 9030
TEST_TIME 29 9463
TEST_TIME 30 10091
TEST_TIME 31 10529
TEST_TIME 32 11103
TEST_TIME 33 11273
TEST_TIME 34 11052
TEST_TIME 35 10909
TEST_TIME 36 10973
TEST_TIME 37 10737
TEST_TIME 38 10565
TEST_TIME 39 10488
TEST_TIME 40 10356
TEST_TIME 41 10278
TEST_TIME 42 10155
TEST_TIME 43 10149
TEST_TIME 44 9980
TEST_TIME 45 9917
TEST_TIME 46 9766
TEST_TIME 47 9622
TEST_TIME 48 9568
TEST_TIME 49 9515
TEST_TIME 50 9539
TEST_TIME 51 9523
TEST_TIME 52 9421
TEST_TIME 53 9307
TEST_TIME 54 9205
TEST_TIME 55 9136
TEST_TIME 56 9179
TEST_TIME 57 9106
TEST_TIME 58 9139
TEST_TIME 59 9084
TEST_TIME 60 9125
TEST_TIME 61 9226
TEST_TIME 62 9325
TEST_TIME 63 9300
TEST_TIME 64 9356
TEST_TIME 65 9377
TEST_TIME 66 9386
TEST_TIME 67 9364
TEST_TIME 68 9383
TEST_TIME 69 9372
TEST_TIME 70 9467
TEST_TIME 71 9418
TEST_TIME 72 9322
TEST_TIME 73 9316
TEST_TIME 74 9243
TEST_TIME 75 9230
TEST_TIME 76 9207
TEST_TIME 77 9166
TEST_TIME 78 9144
TEST_TIME 79 9052
TEST_TIME 80 9083
TEST_TIME 81 9035
TEST_TIME 82 9009
TEST_TIME 83 8987
TEST_TIME 84 8984
TEST_TIME 85 8984
TEST_TIME 86 9015
TEST_TIME 87 9034
TEST_TIME 88 9018
TEST_TIME 89 9003
TEST_TIME 90 9040
TEST_TIME 91 9195
TEST_TIME 92 9204
TEST_TIME 93 9162
TEST_TIME 94 9103
TEST_TIME 95 9047
TEST_TIME 96 9016
TEST_TIME 97 8963
TEST_TIME 98 8951
TEST_TIME 99 8907
TEST_TIME 100 8899
TEST_TIME 101 8898
TEST_TIME 102 8867
TEST_TIME 103 8851
TEST_TIME 104 8816
TEST_TIME 105 8792
TEST_TIME 106 8815
TEST_TIME 107 8818
TEST_TIME 108 8836
TEST_TIME 109 8986
TEST_TIME 110 9004
TEST_TIME 111 8991
TEST_TIME 112 8992
TEST_TIME 113 8980
TEST_TIME 114 8908
TEST_TIME 115 8876
TEST_TIME 116 8873
TEST_TIME 117 8825
TEST_TIME 118 8825
TEST_TIME 119 8793
TEST_TIME 120 8742

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 29-01-2023 10:39:40 to 29-01-2023 10:39:44.

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 1453171 1453171 0 0.00 0 28010 13 0 0
doc_list_biud 1453169 1453169 0 0.00 0 59790 42 0 0
sp_add_invoice_to_stock 4896 4739 157 3.20 54 219963 26357 0 157
sp_cancel_adding_invoice 5338 5338 0 0.00 6 91080 1227 0 0
sp_cancel_client_order 1720 1720 0 0.00 6 44653 450 0 0
sp_cancel_customer_reserve 523021 523021 0 0.00 1 59792 101 0 0
sp_cancel_pay_from_customer 668 668 0 0.00 4 2271 111 0 0
sp_cancel_pay_to_supplier 384 384 0 0.00 3 7200 153 0 0
sp_cancel_supplier_invoice 5879 5865 14 0.23 3 30840 1034 0 14
sp_cancel_supplier_order 401 401 0 0.00 7 1635575 166575 0 0
sp_cancel_write_off 10195 10195 0 0.00 2 28014 162 0 0
sp_client_order 19505 19505 0 0.00 5 35253 446 0 0
sp_customer_reserve 440569 440569 0 0.00 1 85832 251 0 0
sp_fill_shopping_cart 29825 29825 0 0.00 1 36079 84 0 0
sp_fill_shopping_cart_clo_res 440536 440536 0 0.00 0 20992 21 0 0
sp_get_clo_for_invoice 4701 4701 0 0.00 0 26558 344 0 0
sp_kill_cost_storno 16585 16585 0 0.00 0 28009 71 0 0
sp_kill_qstorno_ret_qs2qd 1062503 1062503 0 0.00 0 59788 44 0 0
sp_kill_qty_storno 560771 560771 0 0.00 0 59790 101 0 0
sp_lock_selected_doc 19809 19809 0 0.00 0 9535 8 0 0
sp_make_cost_storno 26666 26666 0 0.00 0 13242 74 0 0
sp_multiply_rows_for_pdistr 26666 26666 0 0.00 0 14196 36 0 0
sp_multiply_rows_for_qdistr 19505 19505 0 0.00 0 22815 189 0 0
sp_pay_from_customer 6863 6863 0 0.00 5 14209 157 0 0
sp_pay_to_supplier 5572 5572 0 0.00 6 13600 187 0 0
sp_payment_common 12435 12435 0 0.00 2 8658 47 0 0
sp_qd_handle_on_cancel_clo 1720 1720 0 0.00 0 44642 364 0 0
sp_qd_handle_on_invoice_upd_sts 10039 10039 0 0.00 0 21971 523 0 0
sp_qd_handle_on_reserve_upd_sts 9530 9530 0 0.00 0 20285 66 0 0
sp_reserve_write_off 9530 9530 0 0.00 8 25295 275 0 0
sp_supplier_invoice 5150 5150 0 0.00 10 103418 3214 0 0
sp_supplier_order 5176 5176 0 0.00 7 40651 1309 0 0
srv_aggregate_perf_data 103 103 0 0.00 2563 160747 16059 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 709 153894 38425 0 0
srv_find_qd_qs_mism 1000238 1000238 0 0.00 0 11998 10 0 0
srv_make_invnt_saldo 430 430 0 0.00 55 79458 4156 0 0
srv_make_money_saldo 861 861 0 0.00 1 10975 375 0 0
srv_recalc_idx_stat 4 4 0 0.00 54723 346345 249336 0 0
t$perf-abend:sp_add_invoice_to_stock 157 157 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4742 4742 0 0.00 1 10954 874 0 0
t$perf-norm:sp_cancel_adding_invoice 387 387 0 0.00 1 7347 484 0 0
t$perf-norm:sp_cancel_client_order 1720 1720 0 0.00 0 7191 28 0 0
t$perf-norm:sp_cancel_customer_reserve 649 649 0 0.00 0 3750 34 0 0
t$perf-norm:sp_cancel_pay_from_customer 668 668 0 0.00 0 2183 18 0 0
t$perf-norm:sp_cancel_pay_to_supplier 384 384 0 0.00 0 2478 19 0 0
t$perf-norm:sp_cancel_supplier_invoice 388 388 0 0.00 0 645 27 0 0
t$perf-norm:sp_cancel_supplier_order 401 401 0 0.00 0 99974 6549 0 0
t$perf-norm:sp_cancel_write_off 982 982 0 0.00 0 3893 23 0 0
t$perf-norm:sp_client_order 19505 19505 0 0.00 0 6048 35 0 0
t$perf-norm:sp_customer_reserve 952 952 0 0.00 0 558 13 0 0
t$perf-norm:sp_pay_from_customer 6863 6863 0 0.00 0 4788 18 0 0
t$perf-norm:sp_pay_to_supplier 5572 5572 0 0.00 0 1981 16 0 0
t$perf-norm:sp_reserve_write_off 9530 9530 0 0.00 0 5270 22 0 0
t$perf-norm:sp_supplier_invoice 5150 5150 0 0.00 0 6397 29 0 0
t$perf-norm:sp_supplier_order 5176 5176 0 0.00 0 5707 32 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 300 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 415 10 0 0
t$perf-norm:srv_make_invnt_saldo 430 430 0 0.00 0 570 15 0 0
t$perf-norm:srv_make_money_saldo 861 861 0 0.00 0 1091 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 261 94 0 0
v_add_invoice_to_stock 9714 9400 314 3.23 0 18735 31 0 314
v_all_customers 35994 35994 0 0.00 0 66 3 0 0
v_all_suppliers 28898 28898 0 0.00 0 80 4 0 0
v_all_wares 39008 39008 0 0.00 0 81 4 0 0
v_cancel_adding_invoice 774 774 0 0.00 0 2285 15 0 0
v_cancel_client_order 3440 3440 0 0.00 0 4511 15 0 0
v_cancel_customer_prepayment 1336 1336 0 0.00 0 1868 13 0 0
v_cancel_customer_reserve 1298 1298 0 0.00 0 13635 25 0 0
v_cancel_payment_to_supplier 768 768 0 0.00 0 3174 17 0 0
v_cancel_supplier_invoice 804 776 28 3.48 0 2259 25 0 28
v_cancel_supplier_order 802 802 0 0.00 0 2847 13 0 0
v_cancel_write_off 1964 1964 0 0.00 0 496 21 0 0
v_max_id_clo_ord 5176 5176 0 0.00 0 140 5 0 0
v_max_id_clo_res 952 952 0 0.00 0 1907 13 0 0
v_max_id_ord_sup 5145 5145 0 0.00 0 6569 10 0 0
v_max_non_paid_invoice 1444 1444 0 0.00 0 111 7 0 0
v_max_non_paid_realizn 3373 3373 0 0.00 0 1438 7 0 0
v_min_id_clo_ord 5176 5176 0 0.00 0 3770 9 0 0
v_min_id_clo_res 952 952 0 0.00 0 4717 13 0 0
v_min_id_ord_sup 5145 5145 0 0.00 0 9392 11 0 0
v_min_non_paid_invoice 5572 5572 0 0.00 0 6049 21 0 0
v_min_non_paid_realizn 6863 6863 0 0.00 0 7178 17 0 0
v_our_firm 9994 9994 0 0.00 0 70 4 0 0
v_reserve_write_off 19060 19060 0 0.00 0 19293 13 0 0
x_lock_depdocs_on_canc_invoice 10815 10815 0 0.00 0 15134 123 0 0
x_lock_depdocs_on_canc_sup_ord 401 401 0 0.00 0 11274 421 0 0
x_make_qstorno_1000_1200 5176 5176 0 0.00 2 40641 1158 0 0
x_make_qstorno_1200_2000 5150 5150 0 0.00 5 103238 2964 0 0
x_make_qstorno_2100_3300 440569 440569 0 0.00 0 85831 218 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-01-2023 10:39:45 to 29-01-2023 10:39:49.

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:29 11 8192000000 8814542560 8831565824 15329504 0.186 8 3 4 0 15573296 156832 86960
08:08:30 25459 8192000000 9738820816 10041966592 819942528 9.098 100 88 174 0 848730816 7846720 20941568
08:09:58 24628 8192000000 9774114416 10086158336 846676752 9.367 100 86 170 0 880016256 12404048 20935456
08:11:29 13469 8192000000 9800604352 10119069696 872671008 9.627 100 90 180 0 913371728 19593952 21106768
08:12:50 8943 8192000000 9861444928 10196070400 910084944 9.998 100 82 162 0 969326416 41151776 18089696
08:14:00 4394 8192000000 9921317232 10260697088 951564432 10.406 100 85 168 1 1028922224 59492976 17864816
08:15:06 2312 8192000000 9956769680 10310426624 980825136 10.692 100 88 174 1 1074880048 75693936 18360976
08:16:10 2430 8192000000 9986960816 10344845312 1010549440 10.981 100 88 170 1 1104283888 76949648 16784800
08:17:15 1136 8192000000 10019552880 10388557824 1041758560 11.282 100 94 186 0 1146025200 86014896 18251744
08:18:17 1014 8192000000 10015258752 10385133568 1050263072 11.363 100 95 185 2 1135384048 66956736 18164240
08:19:20 1157 8192000000 10022155040 10395447296 1064220624 11.497 100 96 177 3 1146249744 64592960 17436160
08:20:23 1031 8192000000 10022000608 10401382400 1070003840 11.552 100 97 176 0 1145418048 56491216 18922992
08:21:25 1055 8192000000 10069605248 10455379968 1078861664 11.637 100 91 179 0 1195390032 98702064 17826304
08:22:28 1180 8192000000 10078301440 10465374208 1084474480 11.690 100 96 182 3 1201464832 98699904 18290448
08:23:30 1069 8192000000 10050593152 10436243456 1089517840 11.738 100 84 163 1 1172416704 66672752 16226112
08:24:32 1350 8192000000 10068305136 10458910720 1091854336 11.760 100 94 187 1 1193782240 82660512 19267392
08:25:34 992 8192000000 10074512736 10462932992 1097569168 11.815 100 92 174 0 1193718256 77895648 18253440
08:26:39 991 8192000000 10083708128 10473570304 1099763712 11.835 100 95 185 4 1202882640 84461424 18657504
08:27:41 3281 8192000000 10035248256 10432679936 1097833696 11.817 100 66 124 0 1165782432 55189584 12759152
08:28:45 1284 8192000000 10040586304 10428133376 1091434448 11.756 100 91 178 1 1162537264 52848112 18254704
08:29:47 1238 8192000000 10023272704 10403655680 1065190288 11.506 99 95 184 3 1144636272 60224480 19221504
08:30:50 1547 8192000000 10033656352 10419752960 1079651552 11.644 100 81 151 0 1155461920 60620336 15190032
08:31:52 1200 8192000000 10038082368 10416496640 1061992144 11.476 100 96 187 2 1159928592 79533456 18402992
08:32:55 1206 8192000000 10014564640 10392944640 1056235664 11.420 100 82 154 0 1131828864 59986240 15606960
08:33:56 1082 8192000000 10003608976 10379685888 1052325712 11.383 100 96 185 1 1126232384 55055104 18851568
08:34:58 1668 8192000000 9978742128 10359468032 1025217280 11.122 99 68 127 2 1105761328 66319968 14224080
08:36:00 1045 8192000000 9987975344 10369331200 1036893552 11.235 100 71 137 1 1114453488 62561280 14998656
08:37:02 901 8192000000 10025406160 10405564416 1048372736 11.345 100 88 170 0 1149078336 82952192 17753408
08:38:09 29537 8192000000 10026876416 10414276608 1066657232 11.520 100 63 121 0 1139813008 61386816 11768960
08:39:59 8509 8192000000 10059729456 10446266368 1081570672 11.662 100 70 132 2 1175269248 79505664 14192912
08:41:11 2679 8192000000 10077230960 10463498240 1106002400 11.895 100 84 148 2 1192546128 70861520 15682208
08:42:22 25208 8192000000 10152109440 10547716096 1109844400 11.931 100 64 126 2 1253495312 130095312 13555600
08:43:51 5114 8192000000 10177955616 10573934592 1118762768 12.015 100 78 147 1 1297971920 163896544 15312608
08:45:01 9082 8192000000 10211079120 10618208256 1135391264 12.172 100 69 130 1 1325142320 176134336 13616720
08:46:13 2567 8192000000 10252571040 10647195648 1150605408 12.315 100 83 160 2 1364475888 196099824 17770656
08:47:22 1477 8192000000 10291584528 10688434176 1162906400 12.430 100 86 168 2 1402525696 221203840 18415456
08:48:30 3112 8192000000 10294082112 10714165248 1172474688 12.520 100 65 128 0 1401585184 214297712 14812784
08:49:36 1494 8192000000 10335954240 10755837952 1181734096 12.606 100 87 173 1 1447204048 245549360 19920592
08:50:41 1256 8192000000 10368841664 10786967552 1193303904 12.714 100 89 169 0 1478548464 265582992 19661568
08:51:46 836 8192000000 10406524496 10829119488 1198432464 12.762 100 96 187 1 1515056512 294309968 22314080
08:52:50 3376 8192000000 10413296192 10856775680 1188733664 12.672 100 91 179 4 1535138224 326041616 20362944
08:53:59 796 8192000000 10424773696 10836549632 1186710592 12.653 100 95 180 1 1533978512 325758016 21509904
08:55:04 923 8192000000 10473001872 10887225344 1193100496 12.712 100 79 156 1 1579733664 368168560 18464608
08:56:08 1022 8192000000 10510468480 10930143232 1200362416 12.780 100 93 182 1 1620821024 397631136 22827472
08:57:12 1321 8192000000 10556249392 11012034560 1215901712 12.924 100 98 193 1 1665854384 426588192 23364480
08:58:17 696 8192000000 10527527408 10958090240 1208171600 12.852 100 96 191 1 1633850704 402333792 23345312
08:59:21 928 8192000000 10520804720 10959159296 1196210064 12.741 100 90 174 3 1627589056 410723568 20655424
09:00:23 7472 8192000000 10545215216 10980454400 1194469760 12.725 100 48 96 0 1651946848 445192832 12284256
09:01:33 12410 8192000000 10583968768 11016065024 1197719296 12.755 100 50 101 0 1686882800 476415600 12747904
09:02:48 2167 8192000000 10602072144 11044343808 1183799264 12.626 100 58 113 2 1706319712 507182912 15337536
09:03:55 7835 8192000000 10636608240 11071942656 1180338672 12.593 100 56 112 0 1741443680 546506576 14598432
09:05:03 21798 8192000000 10517994912 11070271488 1166177616 12.461 100 43 87 0 1713180592 534915744 12087232
09:06:43 16735 8192000000 10399999728 10903703552 1158658368 12.391 100 42 84 0 1499988704 330685664 10644672
09:08:00 38123 8192000000 10257742880 10801106944 1123412944 12.059 98 24 48 0 1373105376 243755824 5936608
09:10:17 57571 8192000000 10075606176 10561028096 1095004192 11.790 96 17 34 0 1132742400 33855728 3882480
09:12:33 10538 8192000000 10096847424 10525134848 1134382448 12.163 100 54 104 1 1199138512 53349760 11406304
09:13:52 81636 8192000000 10083728592 10547695616 1129552656 12.117 99 8 16 1 1170291744 39132752 1606336
09:16:15 47904 8192000000 10067877440 10520039424 1113678064 11.967 98 18 31 3 1150650576 33882784 3089728
09:18:04 86660 8192000000 10081482080 10528579584 1127818784 12.101 99 29 54 0 1163852784 29523328 6510672
09:20:35 87611 8192000000 10113602832 10559971328 1133478032 12.154 100 20 40 0 1211352352 73490912 4383408
09:23:31 87274 8192000000 10059509936 10517487616 1133197456 12.151 100 38 74 0 1162756448 22168400 7390592
09:26:02 44353 8192000000 10103896336 10564382720 1144100496 12.254 100 33 66 0 1194234016 42652480 7481040
09:27:49 35686 8192000000 10068806576 10523791360 1108687808 11.920 97 28 56 0 1142295280 28034576 5572896
09:29:25 66976 8192000000 10046216832 10492411904 1085627424 11.701 96 13 26 0 1116495216 28031968 2835824
09:31:33 101161 8192000000 10077701840 10586808320 1119128720 12.019 99 15 28 0 1185163376 63045344 2989312
09:34:22 64514 8192000000 10106884672 10580852736 1133958992 12.159 99 21 42 0 1229921408 91173152 4789264
09:36:29 53654 8192000000 10159858000 10615873536 1118800480 12.016 98 23 43 0 1223692352 100075792 4816080
09:38:23 46611 8192000000 10114752592 10575691776 1132372656 12.144 99 21 42 0 1213836832 77207488 4256688
09:40:17 62458 8192000000 10116180032 10573885440 1126180832 12.085 98 25 49 0 1205296784 74003344 5112608
09:42:20 73887 8192000000 10126167904 10548494336 1076678704 11.616 94 42 81 0 1152144512 66976128 8489680
09:44:37 5401 8192000000 10094952576 10516946944 1129899648 12.120 100 64 128 0 1186415072 43962112 12553312
09:45:44 28024 8192000000 10151765776 10599903232 1110679744 11.939 98 45 90 0 1220048640 100178032 9190864
09:47:13 34910 8192000000 10067291696 10507923456 1132665008 12.146 100 23 45 0 1175548096 37441872 5441216
09:48:53 112225 8192000000 10054532400 10519068672 1104602800 11.881 98 30 60 0 1193681472 83131152 5947520
09:51:55 47384 8192000000 10034997696 10516381696 1089170624 11.735 96 21 42 0 1142428256 48175568 5082064
09:53:56 74389 8192000000 10040783760 10493083648 1081223680 11.659 96 11 23 0 1123117536 39638848 2255008
09:56:12 75934 8192000000 10079117696 10531090432 1101037088 11.847 98 8 16 0 1173628736 70383968 2207680
09:58:28 35039 8192000000 10042418192 10544463872 1104805136 11.883 98 7 12 1 1144839904 38941424 1093344
10:00:04 21424 8192000000 10077763824 10516082688 1117738560 12.006 100 35 70 0 1164236592 38945808 7552224
10:01:26 14397 8192000000 10104692768 10543210496 1125614080 12.080 100 49 91 0 1204513664 68306704 10592880
10:02:44 22461 8192000000 10125750016 10554048512 1094734288 11.788 96 48 94 0 1213117168 107719792 10663088
10:04:11 31187 8192000000 10133317424 10576916480 1107494976 11.909 98 47 90 1 1215312208 98255440 9561792
10:05:43 26521 8192000000 10141544144 10569838592 1116703728 11.996 99 39 73 0 1235656608 111479168 7473712
10:07:10 70231 8192000000 10137943648 10616619008 1113115008 11.962 99 17 32 0 1238941952 122190240 3636704
10:09:20 41238 8192000000 10095161040 10574249984 1142143248 12.236 100 32 64 0 1213417360 65088656 6185456
10:11:03 46695 8192000000 10121860448 10557313024 1141559248 12.230 100 51 103 0 1204903664 53208688 10135728
10:12:56 78362 8192000000 10087280688 10554064896 1123760848 12.063 98 12 23 0 1190673360 64560480 2352032
10:15:19 52501 8192000000 10064488720 10570915840 1106045664 11.895 97 12 24 0 1169827456 60872464 2909328
10:17:11 44477 8192000000 10099530000 10546364416 1137168672 12.189 100 34 66 1 1207009568 62881344 6959552
10:19:26 47268 8192000000 10106607776 10562056192 1133863984 12.158 99 33 61 0 1205231344 64160688 7206672
10:21:32 26208 8192000000 10179481040 10620538880 1129516640 12.117 99 29 58 0 1230462432 94846384 6099408
10:23:17 39232 8192000000 10167121024 10632556544 1131722384 12.138 99 24 48 0 1266013312 128763024 5527904
10:25:00 82603 8192000000 10169794800 10668912640 1139655296 12.212 99 22 44 0 1271004848 126811728 4537824
10:27:25 75842 8192000000 10067073376 10512105472 1125324320 12.077 98 11 20 0 1173084128 45983664 1776144
10:29:44 153893 8192000000 10063852992 10508611584 1133272464 12.152 99 26 52 0 1174058704 35811280 4974960
10:33:18 73753 8192000000 10076620784 10555015168 1106768944 11.902 97 17 34 0 1173911056 63967280 3174832
10:35:32 74562 8192000000 10100412112 10575888384 1127231632 12.095 99 13 26 0 1161925504 31471104 3222768

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 29-01-2023 10:39:51 to 29-01-2023 10:40:07.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 157 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 29-01-2023 10:40:08 to 29-01-2023 10:40:10.

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          212638
MON$OLDEST_ACTIVE               214088
MON$OLDEST_SNAPSHOT             214088
MON$NEXT_TRANSACTION            214088
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                       2058784
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             758
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2894 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2894 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2894 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2894 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 ---
:127: 
:128: mscs-test-161.budzdorov.ru    Sun Jan 29 10:37:41 2023
:129:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:130: 
:131: 
:132: mscs-test-161.budzdorov.ru    Sun Jan 29 10:37:44 2023
:133:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:134: 
:135: 
:136: mscs-test-161.budzdorov.ru    Sun Jan 29 10:37:55 2023
:137:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:138: 
--- end of diff output ---

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

29.01.23 10:40:11. 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
2023-01-29 10:40:14 No severe PSQL-related problems occured
29.01.23 10:40:15. End of report.