Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.01.2023 07:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 14603884 kB
MemAvailable 30392748 kB
Buffers 0 kB
Cached 17069068 kB
SwapCached 4392 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-10 07:37:25
DTS_END                         2023-01-10 07:37:25.2650
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/48706
TRN_ID                          215837
ATT_ID                          574
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: 8611.21 29472 1033345
customer order: creation 168.31 510 20197
customer order: refuse 14.68 483 1762
order to supplier: creation 44.10 1483 5292
order to supplier: removal 3.19 193405 383
invoice (draft): creation 44.29 3528 5315
invoice (draft): removal 46.39 1182 5567
invoice accept: apply 41.06 26220 4927
invoice accept: cancel 41.66 1400 4999
customer reserve: creation 3800.69 246 456083
customer reserve: removal 4127.02 115 495243
realization accept: apply 81.94 331 9833
realization accept: cancel 72.43 211 8691
payment from customer: creation 56.73 220 6808
payment from customer: removal 5.46 162 655
payment to supplier: creation 48.24 226 5789
payment to supplier: removal 3.25 114 390
service: total inventory turnovers 3.40 5259 408
service: total monetary turnovers 7.38 451 886
service: aggregate perf. data 0.94 17385 113
service: refresh index statistics 0.03 336516 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 10-01-2023 07:38:42 to 10-01-2023 07:38:44.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6587
TEST_TIME 2 4611
TEST_TIME 3 4741
TEST_TIME 4 5425
TEST_TIME 5 5211
TEST_TIME 6 5266
TEST_TIME 7 5168
TEST_TIME 8 5708
TEST_TIME 9 5767
TEST_TIME 10 6080
TEST_TIME 11 6167
TEST_TIME 12 6108
TEST_TIME 13 6024
TEST_TIME 14 6189
TEST_TIME 15 6220
TEST_TIME 16 6487
TEST_TIME 17 6696
TEST_TIME 18 6908
TEST_TIME 19 6928
TEST_TIME 20 6902
TEST_TIME 21 6919
TEST_TIME 22 7083
TEST_TIME 23 7177
TEST_TIME 24 7281
TEST_TIME 25 7333
TEST_TIME 26 7456
TEST_TIME 27 8005
TEST_TIME 28 8056
TEST_TIME 29 8321
TEST_TIME 30 8692
TEST_TIME 31 9090
TEST_TIME 32 9154
TEST_TIME 33 9209
TEST_TIME 34 9626
TEST_TIME 35 9714
TEST_TIME 36 10031
TEST_TIME 37 9937
TEST_TIME 38 10169
TEST_TIME 39 10353
TEST_TIME 40 10401
TEST_TIME 41 10179
TEST_TIME 42 10142
TEST_TIME 43 10013
TEST_TIME 44 10043
TEST_TIME 45 10333
TEST_TIME 46 10362
TEST_TIME 47 10242
TEST_TIME 48 10140
TEST_TIME 49 9980
TEST_TIME 50 9889
TEST_TIME 51 9804
TEST_TIME 52 9837
TEST_TIME 53 9795
TEST_TIME 54 9707
TEST_TIME 55 9662
TEST_TIME 56 9589
TEST_TIME 57 9513
TEST_TIME 58 9434
TEST_TIME 59 9338
TEST_TIME 60 9251
TEST_TIME 61 9171
TEST_TIME 62 9095
TEST_TIME 63 9003
TEST_TIME 64 8983
TEST_TIME 65 9012
TEST_TIME 66 9105
TEST_TIME 67 9028
TEST_TIME 68 8983
TEST_TIME 69 8909
TEST_TIME 70 8897
TEST_TIME 71 8884
TEST_TIME 72 8909
TEST_TIME 73 8898
TEST_TIME 74 8844
TEST_TIME 75 8873
TEST_TIME 76 8883
TEST_TIME 77 8952
TEST_TIME 78 8916
TEST_TIME 79 8885
TEST_TIME 80 8944
TEST_TIME 81 8990
TEST_TIME 82 8951
TEST_TIME 83 9047
TEST_TIME 84 9079
TEST_TIME 85 9036
TEST_TIME 86 9027
TEST_TIME 87 8962
TEST_TIME 88 8903
TEST_TIME 89 8897
TEST_TIME 90 8854
TEST_TIME 91 8860
TEST_TIME 92 8811
TEST_TIME 93 8761
TEST_TIME 94 8725
TEST_TIME 95 8718
TEST_TIME 96 8718
TEST_TIME 97 8726
TEST_TIME 98 8745
TEST_TIME 99 8761
TEST_TIME 100 8757
TEST_TIME 101 8773
TEST_TIME 102 8820
TEST_TIME 103 8855
TEST_TIME 104 8871
TEST_TIME 105 8947
TEST_TIME 106 8936
TEST_TIME 107 8889
TEST_TIME 108 8848
TEST_TIME 109 8859
TEST_TIME 110 8842
TEST_TIME 111 8839
TEST_TIME 112 8811
TEST_TIME 113 8778
TEST_TIME 114 8740
TEST_TIME 115 8710
TEST_TIME 116 8708
TEST_TIME 117 8672
TEST_TIME 118 8664
TEST_TIME 119 8657
TEST_TIME 120 8634

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 10-01-2023 07:38:46 to 10-01-2023 07:38:49.

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 1451005 1451005 0 0.00 0 34561 15 0 0
doc_list_biud 1450995 1450995 0 0.00 0 38349 46 0 0
sp_add_invoice_to_stock 5054 4927 127 2.51 26 225379 26220 0 127
sp_cancel_adding_invoice 4999 4999 0 0.00 6 97498 1400 0 0
sp_cancel_client_order 1762 1762 0 0.00 9 8452 484 0 0
sp_cancel_customer_reserve 495243 495243 0 0.00 1 36256 116 0 0
sp_cancel_pay_from_customer 655 655 0 0.00 4 7075 163 0 0
sp_cancel_pay_to_supplier 390 390 0 0.00 4 1471 114 0 0
sp_cancel_supplier_invoice 5574 5567 7 0.12 4 38358 1182 0 7
sp_cancel_supplier_order 383 383 0 0.00 12 1509522 193406 0 0
sp_cancel_write_off 8691 8691 0 0.00 2 12670 212 0 0
sp_client_order 20197 20197 0 0.00 6 44747 510 0 0
sp_customer_reserve 456083 456083 0 0.00 0 43575 246 0 0
sp_fill_shopping_cart 30793 30793 0 0.00 1 37153 97 0 0
sp_fill_shopping_cart_clo_res 456055 456055 0 0.00 0 16662 21 0 0
sp_get_clo_for_invoice 4887 4887 0 0.00 0 42370 370 0 0
sp_kill_cost_storno 14735 14735 0 0.00 0 33598 96 0 0
sp_kill_qstorno_ret_qs2qd 1005127 1005127 0 0.00 0 38347 50 0 0
sp_kill_qty_storno 531364 531364 0 0.00 0 38348 116 0 0
sp_lock_selected_doc 20347 20347 0 0.00 0 6546 7 0 0
sp_make_cost_storno 27317 27317 0 0.00 0 18794 100 0 0
sp_multiply_rows_for_pdistr 27316 27316 0 0.00 0 11350 41 0 0
sp_multiply_rows_for_qdistr 20196 20196 0 0.00 0 25461 171 0 0
sp_pay_from_customer 6808 6808 0 0.00 6 18811 221 0 0
sp_pay_to_supplier 5789 5789 0 0.00 7 18823 226 0 0
sp_payment_common 12597 12597 0 0.00 2 11551 57 0 0
sp_qd_handle_on_cancel_clo 1762 1762 0 0.00 1 8430 401 0 0
sp_qd_handle_on_invoice_upd_sts 9885 9885 0 0.00 0 35875 634 0 0
sp_qd_handle_on_reserve_upd_sts 9833 9833 0 0.00 0 33270 83 0 0
sp_reserve_write_off 9833 9833 0 0.00 9 33470 332 0 0
sp_supplier_invoice 5315 5315 0 0.00 8 79421 3528 0 0
sp_supplier_order 5292 5292 0 0.00 7 36826 1483 0 0
srv_aggregate_perf_data 113 113 0 0.00 2430 307578 17386 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 785 126380 34381 0 0
srv_find_qd_qs_mism 984370 984370 0 0.00 0 34557 11 0 0
srv_make_invnt_saldo 408 408 0 0.00 66 132102 5259 0 0
srv_make_money_saldo 886 886 0 0.00 1 13223 452 0 0
srv_recalc_idx_stat 4 4 0 0.00 41251 569943 336516 0 0
t$perf-abend:sp_add_invoice_to_stock 127 127 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 4929 4929 0 0.00 1 20417 1075 0 0
t$perf-norm:sp_cancel_adding_invoice 374 374 0 0.00 1 9263 549 0 0
t$perf-norm:sp_cancel_client_order 1762 1762 0 0.00 0 5678 40 0 0
t$perf-norm:sp_cancel_customer_reserve 712 712 0 0.00 0 776 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 655 655 0 0.00 0 4157 29 0 0
t$perf-norm:sp_cancel_pay_to_supplier 390 390 0 0.00 0 417 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 384 384 0 0.00 0 644 26 0 0
t$perf-norm:sp_cancel_supplier_order 383 383 0 0.00 0 144567 9198 0 0
t$perf-norm:sp_cancel_write_off 969 969 0 0.00 0 4322 24 0 0
t$perf-norm:sp_client_order 20197 20197 0 0.00 0 6933 39 0 0
t$perf-norm:sp_customer_reserve 1029 1029 0 0.00 0 3072 20 0 0
t$perf-norm:sp_pay_from_customer 6808 6808 0 0.00 0 5878 21 0 0
t$perf-norm:sp_pay_to_supplier 5789 5789 0 0.00 0 6333 23 0 0
t$perf-norm:sp_reserve_write_off 9833 9833 0 0.00 0 4134 26 0 0
t$perf-norm:sp_supplier_invoice 5315 5315 0 0.00 0 7912 38 0 0
t$perf-norm:sp_supplier_order 5293 5293 0 0.00 0 7216 38 0 0
t$perf-norm:srv_aggregate_perf_data 113 113 0 0.00 0 4680 65 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 271 8 0 0
t$perf-norm:srv_make_invnt_saldo 408 408 0 0.00 0 1168 23 0 0
t$perf-norm:srv_make_money_saldo 886 886 0 0.00 0 340 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 219 83 0 0
v_add_invoice_to_stock 10024 9770 254 2.53 0 6348 30 0 254
v_all_customers 36284 36284 0 0.00 0 126 4 0 0
v_all_suppliers 29736 29736 0 0.00 0 180 5 0 0
v_all_wares 40392 40392 0 0.00 0 125 5 0 0
v_cancel_adding_invoice 748 748 0 0.00 0 7581 28 0 0
v_cancel_client_order 3524 3524 0 0.00 0 7596 14 0 0
v_cancel_customer_prepayment 1310 1310 0 0.00 0 5503 20 0 0
v_cancel_customer_reserve 1424 1424 0 0.00 1 4706 19 0 0
v_cancel_payment_to_supplier 780 780 0 0.00 0 448 12 0 0
v_cancel_supplier_invoice 782 768 14 1.79 0 2705 30 0 14
v_cancel_supplier_order 766 766 0 0.00 0 1622 17 0 0
v_cancel_write_off 1938 1938 0 0.00 0 1267 27 0 0
v_max_id_clo_ord 5291 5291 0 0.00 0 6845 8 0 0
v_max_id_clo_res 1029 1029 0 0.00 0 1548 13 0 0
v_max_id_ord_sup 5306 5306 0 0.00 0 3736 8 0 0
v_max_non_paid_invoice 1518 1518 0 0.00 0 74 8 0 0
v_max_non_paid_realizn 3636 3636 0 0.00 0 4258 11 0 0
v_min_id_clo_ord 5291 5291 0 0.00 0 7338 11 0 0
v_min_id_clo_res 1029 1029 0 0.00 0 2716 13 0 0
v_min_id_ord_sup 5306 5306 0 0.00 0 7376 11 0 0
v_min_non_paid_invoice 5789 5789 0 0.00 0 6362 20 0 0
v_min_non_paid_realizn 6808 6808 0 0.00 0 11540 28 0 0
v_our_firm 10452 10452 0 0.00 0 167 5 0 0
v_reserve_write_off 19666 19666 0 0.00 0 8465 14 0 0
x_lock_depdocs_on_canc_invoice 10182 10182 0 0.00 0 13349 143 0 0
x_lock_depdocs_on_canc_sup_ord 383 383 0 0.00 0 7886 452 0 0
x_make_qstorno_1000_1200 5292 5292 0 0.00 2 36750 1284 0 0
x_make_qstorno_1200_2000 5315 5315 0 0.00 3 79388 3267 0 0
x_make_qstorno_2100_3300 456083 456083 0 0.00 0 37835 212 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-01-2023 07:38:50 to 10-01-2023 07:38:54.

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:29 7 8192000000 8803245040 8816160768 7893328 0.096 4 1 2 0 8001024 71920 35776
05:08:29 24304 8192000000 9729757712 10033303552 809879232 8.996 100 87 174 1 839164448 8276112 21009104
05:10:10 10319 8192000000 9766003920 10076164096 843547088 9.335 100 83 166 0 876785264 13882704 19355472
05:11:24 12444 8192000000 9800578624 10119913472 868249088 9.583 100 87 174 0 910757056 22580432 19927536
05:12:46 5761 8192000000 9849189728 10182119424 905219104 9.950 100 76 150 0 965047648 43357984 16470560
05:14:00 4584 8192000000 9897748480 10239221760 939316592 10.286 100 78 154 0 1020407200 64968720 16121888
05:15:09 2111 8192000000 9942764688 10289311744 976131264 10.647 100 91 178 1 1059443664 64338752 18973648
05:16:14 1790 8192000000 9996148720 10348916736 999297472 10.872 100 84 166 0 1108882448 92460720 17124256
05:17:18 1743 8192000000 9999726112 10361749504 1027603888 11.145 100 88 172 1 1114138208 69439296 17095024
05:18:23 1199 8192000000 10021251360 10395459584 1048312336 11.344 100 95 182 3 1143708960 76381712 19014912
05:19:25 1246 8192000000 10051693584 10422996992 1061541088 11.471 100 90 169 1 1171303792 93034464 16728240
05:20:29 1451 8192000000 10027112240 10410237952 1065581472 11.510 100 79 146 0 1150494688 69314224 15598992
05:21:33 1255 8192000000 10053910432 10436112384 1076837376 11.617 100 98 189 1 1176366688 80064144 19465168
05:22:36 1205 8192000000 10045340096 10430304256 1080639248 11.654 100 89 172 1 1166493856 68846656 17007952
05:23:39 1078 8192000000 10062280560 10444480512 1084676496 11.692 100 98 196 1 1181877264 76882800 20317968
05:24:43 1474 8192000000 10061841232 10448896000 1089072288 11.734 100 90 174 1 1184440080 76975248 18392544
05:25:45 872 8192000000 10058452736 10445406208 1096480800 11.804 100 99 182 3 1178567824 63698832 18388192
05:26:48 1256 8192000000 10046178272 10440253440 1097463888 11.814 100 93 177 1 1173490224 57371936 18654400
05:27:53 1288 8192000000 10040319504 10433662976 1104988496 11.885 100 87 163 3 1165874176 42568320 18317360
05:28:56 806 8192000000 10081080096 10472603648 1106781920 11.902 100 88 170 2 1204116016 78650560 18683536
05:29:59 1108 8192000000 10058825600 10442899456 1087929648 11.723 100 92 178 1 1181534768 75671840 17933280
05:31:01 1051 8192000000 10047419136 10436263936 1092370592 11.765 100 92 177 0 1171013120 59760752 18881776
05:32:04 1538 8192000000 10043987120 10424770560 1071217824 11.564 100 95 176 2 1166124848 76579232 18327792
05:33:07 2017 8192000000 10003634208 10381615104 1047651104 11.338 100 75 134 2 1128729760 66710560 14368096
05:34:10 1924 8192000000 10005747488 10382016512 1051108000 11.371 100 85 152 2 1123853744 56102160 16643584
05:35:14 2156 8192000000 9993138928 10379620352 1045659232 11.319 100 74 141 2 1122438736 62448000 14331504
05:36:18 1959 8192000000 9994329488 10376536064 1038311872 11.248 99 73 138 2 1111335344 58408112 14615360
05:37:21 1056 8192000000 9997874160 10374074368 1044316112 11.306 100 97 179 4 1120510176 57780784 18413280
05:38:34 26691 8192000000 9991284656 10376962048 1065661920 11.511 100 52 96 1 1108958672 34473200 8823552
05:40:07 14023 8192000000 10047589696 10427805696 1072251152 11.574 100 79 151 1 1165484320 76859920 16373248
05:41:25 1806 8192000000 10064977456 10449543168 1090232112 11.745 100 77 141 0 1185047728 79742736 15072880
05:42:34 2888 8192000000 10099120800 10485948416 1100319920 11.841 100 75 139 2 1222421680 108140544 13961216
05:43:40 3870 8192000000 10128994608 10524164096 1114643776 11.976 100 69 134 0 1253956048 125169840 14142432
05:44:47 15045 8192000000 10210906896 10604941312 1125243136 12.076 100 73 146 0 1328828000 188244448 15340416
05:46:08 4938 8192000000 10199795760 10602352640 1142939120 12.243 100 70 137 1 1322362128 161806928 17616080
05:47:23 878 8192000000 10253697760 10648543232 1159168368 12.395 100 95 182 0 1368513104 189811200 19533536
05:48:27 1631 8192000000 10268755808 10668806144 1161461920 12.417 100 88 165 1 1383155920 202786496 18907504
05:49:34 2571 8192000000 10297213472 10705686528 1170040768 12.497 100 82 155 0 1426088000 238811984 17235248
05:50:42 4137 8192000000 10325102176 10740727808 1174307872 12.537 100 69 138 1 1439692992 249501728 15883392
05:51:50 3576 8192000000 10354270688 10761801728 1187360544 12.659 100 82 159 1 1469383568 263676640 18346384
05:53:00 1465 8192000000 10403341232 10828886016 1198425648 12.762 100 90 177 0 1519241648 300594784 20221216
05:54:05 1044 8192000000 10456548064 10860335104 1204994432 12.823 100 98 192 1 1560892464 334295568 21602464
05:55:09 1012 8192000000 10460509760 10884632576 1205540064 12.828 100 95 180 1 1568233280 341073568 21619648
05:56:12 836 8192000000 10478326672 10882215936 1193096736 12.712 100 99 197 1 1588973152 373146288 22730128
05:57:15 1083 8192000000 10527128416 10930540544 1199324624 12.770 100 93 180 0 1643771344 422937184 21509536
05:58:20 1720 8192000000 10606956336 11019927552 1213464000 12.901 100 96 185 0 1706063616 470022784 22576832
05:59:28 1695 8192000000 10591087760 11030261760 1189632992 12.680 99 95 183 1 1696391088 482896768 23861328
06:00:34 772 8192000000 10619848640 11072385024 1201140192 12.787 100 95 190 2 1721613216 497976288 22496736
06:01:39 1867 8192000000 10630204992 11075129344 1186140512 12.647 100 97 189 1 1730376224 521168064 23067648
06:02:47 2262 8192000000 10606672320 11071668224 1182795808 12.616 100 72 137 0 1716272176 515502704 17973664
06:03:54 3387 8192000000 10579991472 11072126976 1191470336 12.697 100 74 146 1 1685560896 475463280 18627280
06:05:03 2238 8192000000 10522312416 10988068864 1179865728 12.589 100 68 124 0 1648367056 452471040 16030288
06:06:11 2362 8192000000 10514484688 11000168448 1175152928 12.545 100 75 148 4 1618733296 425658368 17922000
06:07:16 11480 8192000000 10457864848 10911092736 1162224592 12.424 100 34 67 0 1559807024 389034064 8548368
06:08:50 15465 8192000000 10420760736 10859352064 1172160848 12.517 100 39 76 0 1533200688 351590192 9449648
06:10:11 4154 8192000000 10433383712 10862628864 1168377024 12.482 100 52 104 0 1533162976 352317568 12468384
06:11:17 22408 8192000000 10348145984 10813644800 1172089392 12.516 100 36 72 1 1467161696 286487744 8584560
06:12:51 29252 8192000000 10260470832 10778685440 1136436368 12.182 99 27 52 0 1417837456 274921152 6479936
06:14:34 3640 8192000000 10201639200 10694148096 1126973136 12.093 99 67 126 1 1294252256 152904656 14374464
06:15:39 2173 8192000000 10225506736 10722316288 1150296448 12.312 100 91 176 0 1319999152 150289568 19413136
06:16:42 3807 8192000000 10235387184 10723979264 1162505904 12.427 100 59 111 1 1340139040 165166112 12467024
06:17:48 83481 8192000000 10250746640 10684108800 1149627456 12.306 99 57 103 0 1342980432 180155152 13197824
06:20:28 50108 8192000000 10267668128 10780946432 1146922528 12.281 100 15 29 0 1375821664 225233248 3665888
06:22:32 39300 8192000000 10104570464 10718429184 1110419120 11.936 98 16 33 0 1256596496 142571008 3606368
06:24:15 111049 8192000000 10075339024 10530062336 1093932288 11.780 97 21 39 0 1144875456 47553264 3389904
06:27:34 105854 8192000000 10104684208 10578411520 1115434720 11.984 98 29 59 1 1220352736 97749264 7168752
06:30:38 95714 8192000000 10090052512 10529452032 1110047568 11.933 98 36 68 0 1155489888 37356384 8085936
06:33:15 68546 8192000000 10069905408 10517024768 1124194512 12.067 99 21 41 0 1174208880 45692352 4322016
06:35:23 86284 8192000000 10087112480 10536787968 1126733152 12.091 99 46 90 0 1163799840 27463552 9603136
06:37:53 46479 8192000000 10109291904 10572017664 1138454576 12.201 100 38 75 0 1219255104 73669984 7130544
06:39:58 29981 8192000000 10133158800 10568388608 1139688896 12.213 100 35 66 1 1227994560 81148512 7157152
06:41:28 66614 8192000000 10094802048 10649247744 1132037856 12.141 100 26 53 0 1263668528 125424928 6205744
06:45:05 58917 8192000000 10034216480 10504540160 1116422336 11.993 99 7 14 0 1151021504 33196832 1402336
06:47:04 46962 8192000000 10072471056 10539835392 1127568352 12.098 100 10 20 0 1192778768 63193936 2016480
06:48:52 68668 8192000000 10045063824 10512125952 1096514640 11.805 97 24 46 2 1126698016 25510688 4672688
06:51:03 33091 8192000000 10105138704 10542592000 1116202496 11.991 98 23 46 0 1177971360 56998528 4770336
06:52:45 51790 8192000000 10139671776 10587820032 1138734400 12.204 100 24 48 1 1236894816 93023776 5136640
06:54:37 67393 8192000000 10092435136 10542526464 1109585600 11.928 97 25 48 0 1160698480 46019856 5093024
06:56:55 76760 8192000000 10085267056 10576556032 1116227344 11.991 98 16 32 0 1183758160 63917520 3613296
06:59:12 126379 8192000000 10123072608 10602020864 1087078688 11.715 96 19 38 0 1199240160 108306608 3854864
07:02:22 116970 8192000000 10074174528 10579402752 1123496384 12.060 99 21 41 0 1174210336 46781824 3932128
07:05:20 26626 8192000000 10083987536 10518118400 1139868784 12.214 100 50 99 2 1187839424 38709072 9261568
07:06:47 38606 8192000000 10042590288 10490548224 1096937808 11.809 97 22 42 1 1157518752 56253456 4327488
07:08:40 57231 8192000000 10029638208 10510422016 1089182336 11.735 97 5 10 0 1114881264 25235920 463008
07:11:58 46810 8192000000 10096062256 10536902656 1133718576 12.156 99 20 40 0 1221475936 83487328 4270032
07:13:47 31946 8192000000 10127251616 10585591808 1136423840 12.182 99 38 77 0 1222560656 78211840 7924976
07:15:25 48563 8192000000 10150261040 10592276480 1106613168 11.900 96 28 54 0 1194495392 81918560 5963664
07:17:24 26359 8192000000 10153004320 10586767360 1129390016 12.116 98 40 79 0 1234288704 96474816 8423872
07:19:08 38127 8192000000 10190529056 10666541056 1143284096 12.246 99 51 101 0 1279453920 125559136 10610688
07:21:45 114023 8192000000 10080013280 10541215744 1112209488 11.953 97 21 41 1 1240534528 123830608 4494432
07:24:42 31337 8192000000 10123264912 10561077248 1098058944 11.819 96 59 118 1 1164743232 54528480 12155808
07:27:13 33633 8192000000 10092805584 10586603520 1132418112 12.144 100 12 24 0 1205173744 70315936 2439696
07:28:49 79668 8192000000 10060993232 10549575680 1129151376 12.113 99 10 20 0 1168938688 37744736 2042576
07:32:02 52155 8192000000 10066918400 10534772736 1129461584 12.116 99 11 22 0 1176649504 44814768 2373152
07:34:09 45392 8192000000 10071209168 10519891968 1135849664 12.176 99 15 26 0 1159214560 20640336 2724560
07:35:55 39930 8192000000 10119467712 10584444928 1154812560 12.355 99 37 72 0 1225236592 61230080 9193952

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 10-01-2023 07:38:56 to 10-01-2023 07:39:12.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 127 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:03, from 10-01-2023 07:39:13 to 10-01-2023 07:39:16.

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          211727
MON$OLDEST_ACTIVE               216024
MON$OLDEST_SNAPSHOT             216024
MON$NEXT_TRANSACTION            216024
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                       2120896
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             762
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2887 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2887 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2887 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2887 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 ---
:114: 
:115: mscs-test-161.budzdorov.ru    Tue Jan 10 07:37:27 2023
:116:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:117: 
:118: 
:119: mscs-test-161.budzdorov.ru    Tue Jan 10 07:37:30 2023
:120:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:121: 
:122: 
:123: mscs-test-161.budzdorov.ru    Tue Jan 10 07:37:31 2023
:124:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:125: 
:126: 
:127: mscs-test-161.budzdorov.ru    Tue Jan 10 07:37:31 2023
:128:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:129: 
:130: 
:131: mscs-test-161.budzdorov.ru    Tue Jan 10 07:37:31 2023
:132:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:133: 
--- end of diff output ---

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

10.01.23 07:39:17. 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-10 07:39:19 No severe PSQL-related problems occured
10.01.23 07:39:21. End of report.