Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.09.2022 13: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 15363696 kB
MemAvailable 30597360 kB
Buffers 0 kB
Cached 16359800 kB
SwapCached 2040 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.3
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-09-28 13:37:20
DTS_END                         2022-09-28 13:37:20.6170
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/33290
TRN_ID                          212717
ATT_ID                          634
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: 8630.32 25243 1035638
customer order: creation 164.23 462 19707
customer order: refuse 14.76 398 1771
order to supplier: creation 43.71 1401 5245
order to supplier: removal 3.25 174747 390
invoice (draft): creation 44.06 3407 5287
invoice (draft): removal 46.87 1063 5624
invoice accept: apply 40.28 27896 4833
invoice accept: cancel 42.60 1300 5112
customer reserve: creation 3741.23 266 448948
customer reserve: removal 4216.38 105 505965
realization accept: apply 79.98 288 9598
realization accept: cancel 70.23 198 8428
payment from customer: creation 55.51 171 6661
payment from customer: removal 5.33 125 640
payment to supplier: creation 46.04 186 5525
payment to supplier: removal 3.28 90 394
service: total inventory turnovers 4.18 4011 502
service: total monetary turnovers 7.36 330 883
service: aggregate perf. data 1.00 13514 120
service: refresh index statistics 0.04 274901 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-09-2022 13:38:50 to 28-09-2022 13:38:52.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4123
TEST_TIME 2 4196
TEST_TIME 3 4809
TEST_TIME 4 5659
TEST_TIME 5 5882
TEST_TIME 6 5781
TEST_TIME 7 6068
TEST_TIME 8 6282
TEST_TIME 9 6519
TEST_TIME 10 6634
TEST_TIME 11 7034
TEST_TIME 12 6888
TEST_TIME 13 7122
TEST_TIME 14 7606
TEST_TIME 15 7647
TEST_TIME 16 7815
TEST_TIME 17 7915
TEST_TIME 18 7935
TEST_TIME 19 8020
TEST_TIME 20 8141
TEST_TIME 21 8289
TEST_TIME 22 8485
TEST_TIME 23 8475
TEST_TIME 24 8481
TEST_TIME 25 8589
TEST_TIME 26 8929
TEST_TIME 27 9178
TEST_TIME 28 9417
TEST_TIME 29 9924
TEST_TIME 30 10160
TEST_TIME 31 10680
TEST_TIME 32 11075
TEST_TIME 33 11414
TEST_TIME 34 11376
TEST_TIME 35 11336
TEST_TIME 36 11077
TEST_TIME 37 10989
TEST_TIME 38 10761
TEST_TIME 39 10554
TEST_TIME 40 10375
TEST_TIME 41 10280
TEST_TIME 42 10198
TEST_TIME 43 10031
TEST_TIME 44 9979
TEST_TIME 45 9864
TEST_TIME 46 9864
TEST_TIME 47 9964
TEST_TIME 48 9931
TEST_TIME 49 9803
TEST_TIME 50 9697
TEST_TIME 51 9587
TEST_TIME 52 9609
TEST_TIME 53 9607
TEST_TIME 54 9483
TEST_TIME 55 9417
TEST_TIME 56 9302
TEST_TIME 57 9308
TEST_TIME 58 9250
TEST_TIME 59 9236
TEST_TIME 60 9147
TEST_TIME 61 9145
TEST_TIME 62 9122
TEST_TIME 63 9067
TEST_TIME 64 9061
TEST_TIME 65 9005
TEST_TIME 66 8994
TEST_TIME 67 8973
TEST_TIME 68 9036
TEST_TIME 69 9099
TEST_TIME 70 9069
TEST_TIME 71 9100
TEST_TIME 72 9092
TEST_TIME 73 9066
TEST_TIME 74 9077
TEST_TIME 75 9137
TEST_TIME 76 9136
TEST_TIME 77 9118
TEST_TIME 78 9165
TEST_TIME 79 9065
TEST_TIME 80 9017
TEST_TIME 81 8966
TEST_TIME 82 8962
TEST_TIME 83 8898
TEST_TIME 84 8844
TEST_TIME 85 8833
TEST_TIME 86 8751
TEST_TIME 87 8798
TEST_TIME 88 8787
TEST_TIME 89 8758
TEST_TIME 90 8724
TEST_TIME 91 8717
TEST_TIME 92 8727
TEST_TIME 93 8746
TEST_TIME 94 8779
TEST_TIME 95 8770
TEST_TIME 96 8845
TEST_TIME 97 8803
TEST_TIME 98 8961
TEST_TIME 99 8945
TEST_TIME 100 8940
TEST_TIME 101 8867
TEST_TIME 102 8860
TEST_TIME 103 8812
TEST_TIME 104 8816
TEST_TIME 105 8769
TEST_TIME 106 8775
TEST_TIME 107 8763
TEST_TIME 108 8719
TEST_TIME 109 8730
TEST_TIME 110 8734
TEST_TIME 111 8740
TEST_TIME 112 8711
TEST_TIME 113 8717
TEST_TIME 114 8722
TEST_TIME 115 8705
TEST_TIME 116 8727
TEST_TIME 117 8712
TEST_TIME 118 8710
TEST_TIME 119 8689
TEST_TIME 120 8643

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 28-09-2022 13:38:54 to 28-09-2022 13:38:57.

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 1449008 1449008 0 0.00 0 26325 14 0 0
doc_list_biud 1449002 1449002 0 0.00 0 79355 43 0 0
sp_add_invoice_to_stock 4916 4833 83 1.68 28 233127 27896 0 83
sp_cancel_adding_invoice 5112 5112 0 0.00 5 60449 1300 0 0
sp_cancel_client_order 1771 1771 0 0.00 6 10970 398 0 0
sp_cancel_customer_reserve 505965 505965 0 0.00 1 79356 106 0 0
sp_cancel_pay_from_customer 640 640 0 0.00 4 3984 125 0 0
sp_cancel_pay_to_supplier 394 394 0 0.00 3 2388 91 0 0
sp_cancel_supplier_invoice 5629 5624 5 0.08 2 29361 1063 0 5
sp_cancel_supplier_order 390 390 0 0.00 12 1487241 174747 0 0
sp_cancel_write_off 8428 8428 0 0.00 2 10486 199 0 0
sp_client_order 19707 19707 0 0.00 5 40484 462 0 0
sp_customer_reserve 448948 448948 0 0.00 0 51968 267 0 0
sp_fill_shopping_cart 30224 30224 0 0.00 1 20155 89 0 0
sp_fill_shopping_cart_clo_res 448933 448933 0 0.00 0 18815 20 0 0
sp_get_clo_for_invoice 4817 4817 0 0.00 0 55484 386 0 0
sp_kill_cost_storno 14574 14574 0 0.00 0 26315 83 0 0
sp_kill_qstorno_ret_qs2qd 1026372 1026372 0 0.00 0 79316 45 0 0
sp_kill_qty_storno 541702 541702 0 0.00 0 79354 105 0 0
sp_lock_selected_doc 19954 19954 0 0.00 0 7692 8 0 0
sp_make_cost_storno 26601 26601 0 0.00 0 17250 83 0 0
sp_multiply_rows_for_pdistr 26598 26598 0 0.00 0 8315 40 0 0
sp_multiply_rows_for_qdistr 19707 19707 0 0.00 0 12471 171 0 0
sp_pay_from_customer 6661 6661 0 0.00 5 15366 172 0 0
sp_pay_to_supplier 5525 5525 0 0.00 6 12159 186 0 0
sp_payment_common 12186 12186 0 0.00 2 10352 46 0 0
sp_qd_handle_on_cancel_clo 1771 1771 0 0.00 0 10962 320 0 0
sp_qd_handle_on_invoice_upd_sts 9927 9927 0 0.00 0 47707 536 0 0
sp_qd_handle_on_reserve_upd_sts 9597 9597 0 0.00 0 39586 69 0 0
sp_reserve_write_off 9598 9598 0 0.00 9 39624 289 0 0
sp_supplier_invoice 5287 5287 0 0.00 8 108005 3408 0 0
sp_supplier_order 5245 5245 0 0.00 8 33738 1401 0 0
srv_aggregate_perf_data 120 120 0 0.00 2520 190311 13515 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 701 178208 37286 0 0
srv_find_qd_qs_mism 988752 988752 0 0.00 0 11961 11 0 0
srv_make_invnt_saldo 502 502 0 0.00 50 187227 4011 0 0
srv_make_money_saldo 883 883 0 0.00 1 10186 330 0 0
srv_recalc_idx_stat 5 5 0 0.00 110225 475397 274901 0 0
t$perf-abend:sp_add_invoice_to_stock 83 83 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4835 4835 0 0.00 1 11819 917 0 0
t$perf-norm:sp_cancel_adding_invoice 422 422 0 0.00 1 6670 493 0 0
t$perf-norm:sp_cancel_client_order 1771 1771 0 0.00 0 5367 29 0 0
t$perf-norm:sp_cancel_customer_reserve 681 681 0 0.00 0 3390 30 0 0
t$perf-norm:sp_cancel_pay_from_customer 640 640 0 0.00 0 4744 20 0 0
t$perf-norm:sp_cancel_pay_to_supplier 394 394 0 0.00 0 666 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 351 351 0 0.00 0 8585 59 0 0
t$perf-norm:sp_cancel_supplier_order 390 390 0 0.00 0 111372 7098 0 0
t$perf-norm:sp_cancel_write_off 896 896 0 0.00 0 3465 23 0 0
t$perf-norm:sp_client_order 19707 19707 0 0.00 0 8545 36 0 0
t$perf-norm:sp_customer_reserve 879 879 0 0.00 0 5601 25 0 0
t$perf-norm:sp_pay_from_customer 6661 6661 0 0.00 0 6100 18 0 0
t$perf-norm:sp_pay_to_supplier 5526 5526 0 0.00 0 5631 18 0 0
t$perf-norm:sp_reserve_write_off 9598 9598 0 0.00 0 8995 23 0 0
t$perf-norm:sp_supplier_invoice 5287 5287 0 0.00 0 7782 31 0 0
t$perf-norm:sp_supplier_order 5245 5245 0 0.00 0 4279 29 0 0
t$perf-norm:srv_aggregate_perf_data 120 120 0 0.00 0 7648 77 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 478 14 0 0
t$perf-norm:srv_make_invnt_saldo 502 502 0 0.00 0 675 11 0 0
t$perf-norm:srv_make_money_saldo 883 883 0 0.00 0 5585 12 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 132 48 0 0
v_add_invoice_to_stock 9790 9624 166 1.69 0 5616 26 0 166
v_all_customers 35714 35714 0 0.00 0 80 4 0 0
v_all_suppliers 29542 29542 0 0.00 0 162 4 0 0
v_all_wares 39406 39406 0 0.00 0 108 4 0 0
v_cancel_adding_invoice 844 844 0 0.00 0 164 13 0 0
v_cancel_client_order 3542 3542 0 0.00 0 4676 14 0 0
v_cancel_customer_prepayment 1280 1280 0 0.00 0 3964 13 0 0
v_cancel_customer_reserve 1362 1362 0 0.00 0 1019 12 0 0
v_cancel_payment_to_supplier 788 788 0 0.00 0 647 11 0 0
v_cancel_supplier_invoice 712 702 10 1.40 0 3104 30 0 10
v_cancel_supplier_order 780 780 0 0.00 0 921 13 0 0
v_cancel_write_off 1792 1792 0 0.00 0 8316 27 0 0
v_max_id_clo_ord 5244 5244 0 0.00 0 5503 6 0 0
v_max_id_clo_res 878 878 0 0.00 0 753 10 0 0
v_max_id_ord_sup 5277 5277 0 0.00 0 5244 9 0 0
v_max_non_paid_invoice 1274 1274 0 0.00 0 77 8 0 0
v_max_non_paid_realizn 3528 3528 0 0.00 0 730 7 0 0
v_min_id_clo_ord 5244 5244 0 0.00 0 5992 14 0 0
v_min_id_clo_res 878 878 0 0.00 0 3373 14 0 0
v_min_id_ord_sup 5277 5277 0 0.00 0 5358 14 0 0
v_min_non_paid_invoice 5525 5525 0 0.00 0 8308 17 0 0
v_min_non_paid_realizn 6660 6660 0 0.00 0 10337 17 0 0
v_our_firm 9956 9956 0 0.00 0 66 5 0 0
v_reserve_write_off 19192 19192 0 0.00 0 3611 10 0 0
x_lock_depdocs_on_canc_invoice 10385 10385 0 0.00 0 22192 149 0 0
x_lock_depdocs_on_canc_sup_ord 390 390 0 0.00 0 4615 346 0 0
x_make_qstorno_1000_1200 5245 5245 0 0.00 3 33728 1226 0 0
x_make_qstorno_1200_2000 5287 5287 0 0.00 4 87846 3160 0 0
x_make_qstorno_2100_3300 448948 448948 0 0.00 0 45172 234 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-09-2022 13:38:58 to 28-09-2022 13:39:02.

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
11:07:24 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
11:08:25 32762 8192000000 9718414240 10016657408 792844208 8.824 100 87 174 0 822389728 7782720 21762800
11:10:02 16390 8192000000 9752368912 10055811072 824481808 9.144 100 89 178 0 859314864 13401520 21431536
11:11:25 13316 8192000000 9788399328 10106642432 858495808 9.485 100 78 155 0 898082672 21600880 17985984
11:12:42 6063 8192000000 9867725600 10197975040 911436832 10.012 100 79 155 0 979153520 50633392 17083296
11:13:50 4707 8192000000 9918869168 10259779584 954192224 10.432 100 80 157 0 1034510000 63838368 16479408
11:14:57 2246 8192000000 9955510736 10305032192 993781344 10.818 100 87 166 1 1073646880 62643520 17222016
11:16:02 1446 8192000000 10016166768 10378010624 1013775056 11.012 100 96 190 0 1130866128 98046288 19044784
11:17:05 809 8192000000 10009688656 10376237056 1029955088 11.168 100 96 185 0 1129962560 81045168 18962304
11:18:08 1032 8192000000 10007288016 10377539584 1049186368 11.353 100 90 175 0 1131565088 64922832 17455888
11:19:10 968 8192000000 10036467024 10412597248 1059640096 11.453 100 97 186 1 1159435136 80301680 19493360
11:20:11 5290 8192000000 10030104736 10403135488 1061524432 11.471 100 97 179 0 1148752560 70092400 17135728
11:21:18 2485 8192000000 10040092928 10424713216 1069775744 11.550 100 72 135 2 1157137728 74738576 12623408
11:22:22 955 8192000000 10050044816 10434961408 1076189488 11.611 100 92 174 1 1172588768 78681984 17717296
11:23:26 878 8192000000 10055525312 10446184448 1086145696 11.706 100 85 159 1 1179006768 75918672 16942400
11:24:27 1524 8192000000 10045522176 10435293184 1088334848 11.727 100 85 168 2 1166139872 60466144 17338880
11:25:29 1503 8192000000 10055412784 10445889536 1090539840 11.748 100 82 156 3 1175725776 69010688 16175248
11:26:32 900 8192000000 10057680752 10446811136 1094769280 11.788 100 88 170 0 1176198896 64738080 16691536
11:27:33 970 8192000000 10054918960 10446417920 1099499776 11.833 100 94 179 1 1172824448 55671264 17653408
11:28:35 7885 8192000000 10066181056 10456842240 1098161120 11.820 100 65 126 1 1182446400 72092496 12192784
11:29:44 1463 8192000000 10022697136 10405359616 1073790640 11.588 99 88 169 4 1146099536 54593024 17715872
11:30:47 1003 8192000000 10048575264 10436243456 1081312720 11.660 100 89 171 1 1169367232 70187376 17867136
11:31:49 905 8192000000 10019776224 10401918976 1061822736 11.474 100 86 157 2 1141143088 62751072 16569280
11:32:50 1142 8192000000 10002432192 10374414336 1034382016 11.211 100 99 187 1 1121585552 69167200 18036336
11:33:53 1224 8192000000 10013800096 10389164032 1049771920 11.358 100 96 180 0 1132255936 64153184 18330832
11:34:55 1573 8192000000 10021893376 10400022528 1044327600 11.306 100 77 149 1 1135625040 76148192 15149248
11:35:57 2012 8192000000 9992823280 10372685824 1037983056 11.245 100 67 133 2 1114770752 63197120 13590576
11:37:00 1218 8192000000 9978519472 10352766976 1039354112 11.258 100 94 175 2 1103418784 45197568 18867104
11:38:08 894 8192000000 9973014816 10348548096 1051275888 11.373 100 86 149 1 1090746736 24873968 14596880
11:39:16 3484 8192000000 10077657024 10467266560 1073495904 11.585 100 75 148 1 1194809488 105558368 15755216
11:40:24 844 8192000000 10101905472 10482028544 1087380512 11.718 100 94 172 2 1221368048 115835744 18151792
11:41:29 4286 8192000000 10085395760 10481876992 1095781120 11.798 100 60 116 0 1204958256 96551984 12625152
11:42:36 1444 8192000000 10126885904 10511826944 1108581872 11.919 100 91 176 0 1242490240 115488640 18419728
11:43:40 3576 8192000000 10150987504 10542141440 1116484336 11.994 100 74 142 1 1263494608 131758736 15251536
11:44:53 2408 8192000000 10218204016 10611167232 1132921552 12.149 100 98 191 0 1333471344 179560224 20989568
11:45:59 1764 8192000000 10229827216 10633822208 1140523088 12.220 100 80 150 1 1345925984 187456048 17946848
11:47:03 1600 8192000000 10279156480 10677882880 1149578800 12.306 100 77 153 0 1387989280 221616736 16793744
11:48:08 1479 8192000000 10289775936 10701778944 1162971408 12.431 100 94 183 0 1404066160 219519440 21575312
11:49:13 1890 8192000000 10322807248 10727215104 1172209520 12.517 100 88 165 2 1428554288 237081440 19263328
11:50:19 1852 8192000000 10356627072 10792038400 1175272912 12.546 100 77 154 0 1463938896 270256048 18409936
11:51:24 2931 8192000000 10370351408 10782928896 1182335744 12.612 100 85 165 1 1479948448 278717200 18895504
11:52:30 1225 8192000000 10357987696 10790920192 1177569696 12.568 100 87 172 1 1467687488 271192944 18924848
11:53:35 1858 8192000000 10384004640 10793086976 1183820176 12.626 100 91 179 2 1488940096 284848432 20271488
11:54:43 1609 8192000000 10435360800 10852622336 1185952912 12.646 100 96 189 1 1539038000 331644320 21440768
11:55:48 1196 8192000000 10454107168 10882297856 1194380352 12.724 100 91 174 0 1553370848 338600592 20389904
11:56:52 707 8192000000 10510164784 10934902784 1204112928 12.815 100 94 186 0 1610885360 384194000 22578432
11:57:55 1416 8192000000 10510816784 10942201856 1197403232 12.752 100 92 182 1 1612262944 392536480 22323232
11:58:58 692 8192000000 10543255792 10974810112 1206780720 12.839 100 98 188 1 1651095088 421030048 23284320
12:00:02 1961 8192000000 10518145552 10963177472 1202808304 12.802 100 81 162 1 1629224080 406096032 20319744
12:01:10 1640 8192000000 10553676816 10983907328 1201244976 12.788 100 83 155 2 1663279872 442206688 19828208
12:02:12 2252 8192000000 10561770928 11033169920 1201859136 12.794 100 69 135 0 1665127648 445745872 17522640
12:03:25 5789 8192000000 10551754416 11030536192 1183657552 12.624 100 53 105 0 1657300480 460207520 13435408
12:04:51 16131 8192000000 10563257472 11010596864 1177281936 12.565 100 44 88 0 1673091472 484137696 11671840
12:06:20 10128 8192000000 10451777872 10953310208 1151263712 12.321 99 36 68 0 1558045984 397169344 9612928
12:07:37 17603 8192000000 10376478544 10855563264 1145499664 12.267 100 33 64 1 1481515952 328201312 7814976
12:09:08 56823 8192000000 10179401328 10664128512 1092934240 11.771 96 17 34 0 1286317056 189404800 3978016
12:11:12 39396 8192000000 10071528048 10551459840 1105162416 11.887 98 17 34 0 1156064096 47680432 3221248
12:12:51 103275 8192000000 10073540800 10558771200 1108247760 11.916 98 27 56 1 1168545920 53974400 6323760
12:15:35 61330 8192000000 10059164448 10484269056 1082488416 11.671 96 32 62 1 1112777568 24091808 6197344
12:17:42 16360 8192000000 10135355648 10559582208 1124844352 12.073 99 63 124 1 1215272464 76855264 13572848
12:19:03 69407 8192000000 10138106480 10591870976 1103435520 11.870 97 43 86 0 1224439184 111241856 9761808
12:21:16 178208 8192000000 10130604336 10657779712 1124107408 12.066 99 23 46 2 1280969776 151619856 5242512
12:25:26 48991 8192000000 10084008784 10522062848 1110154496 11.934 99 18 35 0 1141075616 26712032 4209088
12:27:33 157601 8192000000 10091686272 10565140480 1129006768 12.112 100 24 48 0 1236824448 102132448 5685232
12:31:38 32569 8192000000 10067615248 10514501632 1140068720 12.216 100 29 56 0 1168490096 23017712 5403664
12:33:11 77690 8192000000 10121204656 10599624704 1125419728 12.078 98 13 26 0 1192399408 63769184 3210496
12:36:18 99830 8192000000 10083918960 10572238848 1124676800 12.071 99 13 26 0 1176241936 48741440 2823696
12:38:58 94479 8192000000 10051818176 10522660864 1100623216 11.844 97 13 24 0 1132007984 28797072 2587696
12:41:37 51951 8192000000 10049807104 10489020416 1100262800 11.840 97 28 56 0 1136275936 30766608 5246528
12:43:30 19560 8192000000 10116555520 10544713728 1131022512 12.131 99 50 98 1 1198757024 58267040 9467472
12:44:58 88434 8192000000 10101111520 10603560960 1126546560 12.089 98 19 38 0 1222814080 92249040 4018480
12:47:29 18883 8192000000 10103654352 10547171328 1143189680 12.246 100 38 76 0 1195443536 44524128 7729728
12:48:50 51404 8192000000 10110900880 10547601408 1150250464 12.312 100 35 68 0 1214375200 57472288 6652448
12:50:51 22370 8192000000 10122439168 10552614912 1142795072 12.242 99 45 85 1 1203947536 52792944 8359520
12:52:15 85949 8192000000 10077990576 10537512960 1108663808 11.920 97 24 48 0 1189382848 75938096 4780944
12:54:42 53634 8192000000 10121057232 10581057536 1109977920 11.932 97 38 76 0 1195454000 77055248 8420832
12:56:35 78168 8192000000 10092686160 10541252608 1139034064 12.206 100 19 38 0 1190924528 47867440 4023024
12:59:04 27147 8192000000 10062279824 10503163904 1127129936 12.094 99 38 75 0 1170525728 36330352 7065440
13:00:32 59810 8192000000 10053278960 10513666048 1097195136 11.811 97 8 14 0 1136964496 38314560 1454800
13:02:34 91792 8192000000 10099106464 10552819712 1123194064 12.057 99 20 40 0 1189696560 62793120 3709376
13:06:08 16529 8192000000 10057959440 10490204160 1115269040 11.982 98 48 89 2 1140571920 15270368 10032512
13:07:28 24004 8192000000 10119032768 10552569856 1133385952 12.153 100 36 72 0 1214504512 73896608 7221952
13:09:01 14965 8192000000 10156130704 10583040000 1119699216 12.024 98 45 89 1 1240395040 110863584 9832240
13:10:19 45566 8192000000 10181706288 10631503872 1122784800 12.053 98 38 76 0 1264546048 133570576 8190672
13:12:19 51847 8192000000 10182782576 10614734848 1117221216 12.001 98 28 54 0 1254194400 130941008 6032176
13:14:15 74432 8192000000 10071796976 10606854144 1081735280 11.664 96 23 46 1 1224830384 138264208 4830896
13:16:39 56314 8192000000 10092769936 10509058048 1106351072 11.898 98 44 88 0 1172121040 56897280 8872688
13:18:36 87537 8192000000 10074543040 10528583680 1119978640 12.027 99 34 67 0 1165856848 39164448 6713760
13:21:06 37470 8192000000 10109818176 10578427904 1126569088 12.089 99 24 48 1 1227136496 95670320 4897088
13:22:55 72686 8192000000 10100068928 10587095040 1123057472 12.056 99 13 26 0 1179355328 53332880 2964976
13:25:08 70975 8192000000 10119882352 10623094784 1131776176 12.138 99 19 38 0 1193478576 57110384 4592016
13:27:24 64179 8192000000 10090743472 10538749952 1129736672 12.119 99 15 28 1 1184460896 51035440 3688784
13:29:30 30368 8192000000 10082073280 10525933568 1136045104 12.178 100 21 42 0 1174061872 33618416 4398352
13:31:01 27216 8192000000 10060758944 10500427776 1133396368 12.153 100 24 48 1 1160148784 21547200 5205216
13:32:29 27114 8192000000 10114625120 10553376768 1130786864 12.129 99 42 82 0 1195376784 56550400 8039520
13:34:00 45145 8192000000 10129410288 10568212480 1135229024 12.171 99 32 64 0 1202507376 60814848 6463504
13:35:51 48372 8192000000 10212523600 10686652416 1140474448 12.220 99 27 55 0 1267424096 121011936 5937712

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 28-09-2022 13:39:04 to 28-09-2022 13:39:20.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 83 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 28-09-2022 13:39:21 to 28-09-2022 13:39:23.

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          210910
MON$OLDEST_ACTIVE               214504
MON$OLDEST_SNAPSHOT             214504
MON$NEXT_TRANSACTION            214504
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                       2062624
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             764
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2850 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2850 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2850 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2850 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 ---
:72: 
:73: mscs-test-161.budzdorov.ru    Wed Sep 28 13:37:29 2022
:74:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:75: 
:76: 
:77: mscs-test-161.budzdorov.ru    Wed Sep 28 13:37:33 2022
:78:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:79: 
:80: 
:81: mscs-test-161.budzdorov.ru    Wed Sep 28 13:37:36 2022
:82:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:83: 
--- end of diff output ---

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

28.09.22 13:39:24. 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-09-28 13:39:27 No severe PSQL-related problems occured
28.09.22 13:39:28. End of report.