Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.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 15039352 kB
MemAvailable 30435940 kB
Buffers 0 kB
Cached 16683876 kB
SwapCached 4408 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-11 07:37:24
DTS_END                         2023-01-11 07:37:24.1890
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/36036
TRN_ID                          222544
ATT_ID                          665
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: 9074.44 22077 1088933
customer order: creation 173.18 479 20781
customer order: refuse 14.76 393 1771
order to supplier: creation 45.60 1381 5472
order to supplier: removal 3.53 167892 423
invoice (draft): creation 45.96 2929 5515
invoice (draft): removal 50.53 985 6064
invoice accept: apply 41.64 26461 4997
invoice accept: cancel 45.38 1197 5445
customer reserve: creation 3885.58 253 466269
customer reserve: removal 4477.75 104 537330
realization accept: apply 82.13 266 9856
realization accept: cancel 79.04 171 9485
payment from customer: creation 58.57 170 7028
payment from customer: removal 5.76 122 691
payment to supplier: creation 48.91 184 5869
payment to supplier: removal 3.61 109 433
service: total inventory turnovers 3.94 3863 473
service: total monetary turnovers 7.59 336 911
service: aggregate perf. data 0.97 14653 116
service: refresh index statistics 0.03 219594 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-01-2023 07:38:55 to 11-01-2023 07:38:57.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8748
TEST_TIME 2 7071
TEST_TIME 3 6328
TEST_TIME 4 6016
TEST_TIME 5 5751
TEST_TIME 6 5695
TEST_TIME 7 5640
TEST_TIME 8 5786
TEST_TIME 9 5617
TEST_TIME 10 5889
TEST_TIME 11 6140
TEST_TIME 12 6549
TEST_TIME 13 6746
TEST_TIME 14 6970
TEST_TIME 15 7076
TEST_TIME 16 7062
TEST_TIME 17 7539
TEST_TIME 18 7502
TEST_TIME 19 7605
TEST_TIME 20 7525
TEST_TIME 21 7598
TEST_TIME 22 7608
TEST_TIME 23 7889
TEST_TIME 24 7840
TEST_TIME 25 8045
TEST_TIME 26 8238
TEST_TIME 27 8885
TEST_TIME 28 9166
TEST_TIME 29 9667
TEST_TIME 30 9764
TEST_TIME 31 9806
TEST_TIME 32 9975
TEST_TIME 33 10678
TEST_TIME 34 11183
TEST_TIME 35 11173
TEST_TIME 36 11160
TEST_TIME 37 10934
TEST_TIME 38 10823
TEST_TIME 39 10907
TEST_TIME 40 10691
TEST_TIME 41 10509
TEST_TIME 42 10384
TEST_TIME 43 10468
TEST_TIME 44 10333
TEST_TIME 45 10215
TEST_TIME 46 10176
TEST_TIME 47 10061
TEST_TIME 48 9977
TEST_TIME 49 9961
TEST_TIME 50 10037
TEST_TIME 51 9995
TEST_TIME 52 9965
TEST_TIME 53 9926
TEST_TIME 54 9815
TEST_TIME 55 9823
TEST_TIME 56 9821
TEST_TIME 57 9842
TEST_TIME 58 9818
TEST_TIME 59 9906
TEST_TIME 60 9820
TEST_TIME 61 9905
TEST_TIME 62 9909
TEST_TIME 63 9895
TEST_TIME 64 9912
TEST_TIME 65 9870
TEST_TIME 66 9937
TEST_TIME 67 9893
TEST_TIME 68 9929
TEST_TIME 69 9968
TEST_TIME 70 9853
TEST_TIME 71 9773
TEST_TIME 72 9693
TEST_TIME 73 9658
TEST_TIME 74 9655
TEST_TIME 75 9585
TEST_TIME 76 9515
TEST_TIME 77 9449
TEST_TIME 78 9378
TEST_TIME 79 9328
TEST_TIME 80 9373
TEST_TIME 81 9579
TEST_TIME 82 9542
TEST_TIME 83 9512
TEST_TIME 84 9530
TEST_TIME 85 9465
TEST_TIME 86 9496
TEST_TIME 87 9513
TEST_TIME 88 9596
TEST_TIME 89 9650
TEST_TIME 90 9665
TEST_TIME 91 9585
TEST_TIME 92 9522
TEST_TIME 93 9513
TEST_TIME 94 9455
TEST_TIME 95 9421
TEST_TIME 96 9344
TEST_TIME 97 9435
TEST_TIME 98 9375
TEST_TIME 99 9383
TEST_TIME 100 9354
TEST_TIME 101 9362
TEST_TIME 102 9366
TEST_TIME 103 9333
TEST_TIME 104 9346
TEST_TIME 105 9402
TEST_TIME 106 9429
TEST_TIME 107 9427
TEST_TIME 108 9461
TEST_TIME 109 9408
TEST_TIME 110 9347
TEST_TIME 111 9329
TEST_TIME 112 9308
TEST_TIME 113 9288
TEST_TIME 114 9229
TEST_TIME 115 9201
TEST_TIME 116 9202
TEST_TIME 117 9194
TEST_TIME 118 9170
TEST_TIME 119 9157
TEST_TIME 120 9094

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-01-2023 07:38:59 to 11-01-2023 07:39:02.

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 1518821 1518821 0 0.00 0 18503 14 0 0
doc_list_biud 1518797 1518797 0 0.00 0 28089 42 0 0
sp_add_invoice_to_stock 5103 4997 106 2.07 47 312694 26461 0 106
sp_cancel_adding_invoice 5445 5445 0 0.00 6 78713 1197 0 0
sp_cancel_client_order 1771 1771 0 0.00 6 18429 394 0 0
sp_cancel_customer_reserve 537330 537330 0 0.00 1 28095 105 0 0
sp_cancel_pay_from_customer 691 691 0 0.00 3 4286 122 0 0
sp_cancel_pay_to_supplier 433 433 0 0.00 3 3891 110 0 0
sp_cancel_supplier_invoice 6069 6064 5 0.08 2 15491 986 0 5
sp_cancel_supplier_order 423 423 0 0.00 7 1566904 167892 0 0
sp_cancel_write_off 9485 9485 0 0.00 2 7468 171 0 0
sp_client_order 20781 20781 0 0.00 5 30192 480 0 0
sp_customer_reserve 466269 466269 0 0.00 0 104006 254 0 0
sp_fill_shopping_cart 31756 31756 0 0.00 1 14579 72 0 0
sp_fill_shopping_cart_clo_res 466225 466225 0 0.00 0 21070 18 0 0
sp_get_clo_for_invoice 4948 4948 0 0.00 0 20804 265 0 0
sp_kill_cost_storno 16054 16054 0 0.00 0 7202 73 0 0
sp_kill_qstorno_ret_qs2qd 1090632 1090632 0 0.00 0 28077 46 0 0
sp_kill_qty_storno 575319 575319 0 0.00 0 28087 103 0 0
sp_lock_selected_doc 20683 20683 0 0.00 0 8704 9 0 0
sp_make_cost_storno 27698 27698 0 0.00 0 18493 68 0 0
sp_multiply_rows_for_pdistr 27696 27696 0 0.00 0 8646 35 0 0
sp_multiply_rows_for_qdistr 20780 20780 0 0.00 0 10664 175 0 0
sp_pay_from_customer 7028 7028 0 0.00 5 16865 171 0 0
sp_pay_to_supplier 5869 5869 0 0.00 6 14078 185 0 0
sp_payment_common 12893 12893 0 0.00 2 7014 40 0 0
sp_qd_handle_on_cancel_clo 1771 1771 0 0.00 1 8861 306 0 0
sp_qd_handle_on_invoice_upd_sts 10390 10390 0 0.00 0 9293 488 0 0
sp_qd_handle_on_reserve_upd_sts 9856 9856 0 0.00 0 16204 54 0 0
sp_reserve_write_off 9856 9856 0 0.00 8 21206 266 0 0
sp_supplier_invoice 5515 5515 0 0.00 8 82042 2930 0 0
sp_supplier_order 5472 5472 0 0.00 6 67655 1381 0 0
srv_aggregate_perf_data 116 116 0 0.00 2185 126771 14653 0 0
srv_fill_mon_cache_memory 73 73 0 0.00 739 134999 32961 0 0
srv_find_qd_qs_mism 1040076 1040076 0 0.00 0 13249 12 0 0
srv_make_invnt_saldo 473 473 0 0.00 61 104572 3864 0 0
srv_make_money_saldo 911 911 0 0.00 1 13231 336 0 0
srv_recalc_idx_stat 4 4 0 0.00 55118 343577 219594 0 0
t$perf-abend:sp_add_invoice_to_stock 106 106 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 5000 5000 0 0.00 1 11566 839 0 0
t$perf-norm:sp_cancel_adding_invoice 428 428 0 0.00 2 5049 356 0 0
t$perf-norm:sp_cancel_client_order 1771 1771 0 0.00 0 10139 31 0 0
t$perf-norm:sp_cancel_customer_reserve 693 693 0 0.00 0 1557 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 691 691 0 0.00 0 533 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 433 433 0 0.00 0 1442 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 407 407 0 0.00 0 508 28 0 0
t$perf-norm:sp_cancel_supplier_order 423 423 0 0.00 0 76721 6395 0 0
t$perf-norm:sp_cancel_write_off 1039 1039 0 0.00 0 2905 22 0 0
t$perf-norm:sp_client_order 20781 20781 0 0.00 0 10110 35 0 0
t$perf-norm:sp_customer_reserve 968 968 0 0.00 0 7179 24 0 0
t$perf-norm:sp_pay_from_customer 7028 7028 0 0.00 0 7870 19 0 0
t$perf-norm:sp_pay_to_supplier 5869 5869 0 0.00 0 11889 21 0 0
t$perf-norm:sp_reserve_write_off 9857 9857 0 0.00 0 7869 21 0 0
t$perf-norm:sp_supplier_invoice 5515 5515 0 0.00 0 7075 31 0 0
t$perf-norm:sp_supplier_order 5472 5472 0 0.00 0 18083 30 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 525 18 0 0
t$perf-norm:srv_fill_mon_cache_memory 73 73 0 0.00 0 1262 25 0 0
t$perf-norm:srv_make_invnt_saldo 473 473 0 0.00 0 2206 24 0 0
t$perf-norm:srv_make_money_saldo 911 911 0 0.00 0 389 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 208 59 0 0
v_add_invoice_to_stock 10096 9884 212 2.09 0 17835 33 0 212
v_all_customers 38092 38092 0 0.00 0 72 3 0 0
v_all_suppliers 30836 30836 0 0.00 0 78 4 0 0
v_all_wares 41554 41554 0 0.00 0 160 4 0 0
v_cancel_adding_invoice 856 856 0 0.00 0 2682 20 0 0
v_cancel_client_order 3542 3542 0 0.00 0 18125 16 0 0
v_cancel_customer_prepayment 1382 1382 0 0.00 0 4243 16 0 0
v_cancel_customer_reserve 1386 1386 0 0.00 0 2643 12 0 0
v_cancel_payment_to_supplier 866 866 0 0.00 0 3770 19 0 0
v_cancel_supplier_invoice 824 814 10 1.21 0 5178 37 0 10
v_cancel_supplier_order 846 846 0 0.00 0 2385 17 0 0
v_cancel_write_off 2078 2078 0 0.00 0 2382 22 0 0
v_max_id_clo_ord 5468 5468 0 0.00 0 588 4 0 0
v_max_id_clo_res 968 968 0 0.00 0 760 9 0 0
v_max_id_ord_sup 5510 5510 0 0.00 0 4638 7 0 0
v_max_non_paid_invoice 1426 1426 0 0.00 0 62 7 0 0
v_max_non_paid_realizn 3627 3627 0 0.00 0 1171 7 0 0
v_min_id_clo_ord 5467 5467 0 0.00 0 3703 8 0 0
v_min_id_clo_res 968 968 0 0.00 0 2401 13 0 0
v_min_id_ord_sup 5510 5510 0 0.00 0 6599 13 0 0
v_min_non_paid_invoice 5867 5867 0 0.00 0 4436 12 0 0
v_min_non_paid_realizn 7026 7026 0 0.00 0 7009 16 0 0
v_our_firm 10258 10258 0 0.00 0 58 4 0 0
v_reserve_write_off 19709 19709 0 0.00 0 21124 21 0 0
x_lock_depdocs_on_canc_invoice 11102 11102 0 0.00 0 15631 139 0 0
x_lock_depdocs_on_canc_sup_ord 423 423 0 0.00 0 3786 329 0 0
x_make_qstorno_1000_1200 5472 5472 0 0.00 2 67641 1238 0 0
x_make_qstorno_1200_2000 5515 5515 0 0.00 4 82001 2733 0 0
x_make_qstorno_2100_3300 466267 466267 0 0.00 0 103995 225 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 11-01-2023 07:39:03 to 11-01-2023 07:39:08.

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:28 16 8192000000 8804711280 8818319360 7311312 0.089 4 1 2 0 7414288 67200 35776
05:08:28 23147 8192000000 9736201744 10036568064 808184640 8.979 100 85 170 0 836723264 7911168 20627456
05:10:03 10657 8192000000 9764929360 10078466048 846987328 9.370 100 83 166 0 879322400 12500576 19834496
05:11:23 9435 8192000000 9802671024 10122260480 871018368 9.610 100 87 172 0 911030848 20370896 19641584
05:12:49 4091 8192000000 9876871440 10207232000 916027792 10.057 100 90 180 0 991578960 55864128 19687040
05:13:59 3454 8192000000 9929835648 10271363072 951613440 10.407 100 87 173 1 1044231168 74497296 18120432
05:15:08 3168 8192000000 9956980736 10310946816 998836928 10.867 100 82 158 1 1072736640 57424288 16475424
05:16:12 1479 8192000000 10002404928 10364293120 1022856032 11.100 100 90 171 1 1114869104 74930432 17082640
05:17:15 1241 8192000000 10031839232 10403942400 1047774656 11.339 100 95 180 1 1148692320 82317168 18600496
05:18:17 895 8192000000 10016188464 10392891392 1059444448 11.451 100 96 188 0 1135614704 56968224 19202032
05:19:19 5501 8192000000 10039961728 10416570368 1071873008 11.570 100 96 177 1 1158953488 69969984 17110496
05:20:25 1269 8192000000 10045992016 10426957824 1077855280 11.627 100 90 175 0 1166184960 69691904 18637776
05:21:27 2792 8192000000 10051584800 10445090816 1085149216 11.697 100 77 150 0 1168838320 68417552 15271552
05:22:45 6430 8192000000 10048837328 10451116032 1087820688 11.722 100 57 106 2 1174297392 74954256 11522448
05:23:53 2750 8192000000 10042570000 10446290944 1090891584 11.751 100 55 106 0 1166296800 63794368 11610848
05:24:57 1295 8192000000 10088227360 10479230976 1099866992 11.836 100 89 174 2 1206760080 89329056 17564032
05:25:59 1224 8192000000 10084985744 10475245568 1105188816 11.887 100 98 187 2 1204722640 80565792 18968032
05:27:01 982 8192000000 10080076144 10472058880 1104628144 11.882 100 91 177 1 1197458400 73592576 19237680
05:28:03 1042 8192000000 10053323888 10443870208 1094205840 11.783 99 89 170 1 1177952144 65148672 18597632
05:29:05 975 8192000000 10082753136 10472923136 1094336496 11.784 100 91 172 0 1202555136 89331600 18887040
05:30:08 1101 8192000000 10026691952 10405486592 1059850144 11.455 100 92 171 0 1146583360 69705856 17027360
05:31:10 1205 8192000000 10053836080 10433683456 1075472432 11.604 100 97 188 0 1174667184 81172672 18022080
05:32:12 1465 8192000000 10059096880 10436476928 1063232624 11.487 100 97 185 2 1181130576 99574320 18323632
05:33:14 976 8192000000 9999076064 10372268032 1039460944 11.259 100 86 155 3 1119692496 63411200 16820352
05:34:16 1204 8192000000 9974621568 10352513024 1032058512 11.188 100 76 144 1 1099650208 52649936 14941760
05:35:18 5563 8192000000 9977644176 10361036800 1031743728 11.185 100 61 118 1 1107350176 64339888 11266560
05:36:25 1533 8192000000 9964448384 10342969344 1037677200 11.242 100 85 161 2 1093716096 38074464 17964432
05:37:28 23386 8192000000 9987404128 10364624896 1048974864 11.351 100 75 138 1 1113774688 51367664 13432160
05:38:58 10375 8192000000 10047087360 10424741888 1077713920 11.626 100 72 138 0 1163061952 71568688 13779344
05:40:14 13923 8192000000 10066262992 10468384768 1086010160 11.705 100 58 116 0 1183490176 85563120 11916896
05:41:35 5174 8192000000 10063524672 10453385216 1105713056 11.892 100 69 123 2 1187455216 69235440 12506720
05:42:47 6151 8192000000 10130155104 10523582464 1121483664 12.041 100 75 140 1 1238508960 101842448 15182848
05:43:57 8416 8192000000 10164971648 10562490368 1132647008 12.146 100 69 134 2 1281435776 134112576 14676192
05:45:10 3933 8192000000 10200454992 10609786880 1148288016 12.293 100 72 135 1 1319000400 153736208 16976176
05:46:20 2764 8192000000 10255554608 10658344960 1161563792 12.418 100 83 165 1 1366812112 186378944 18869376
05:47:29 3392 8192000000 10258657344 10672803840 1168845952 12.486 100 73 140 0 1379963968 195055248 16062768
05:48:35 858 8192000000 10316986640 10730958848 1179162880 12.582 100 98 190 2 1430154400 229852384 21139136
05:49:39 7370 8192000000 10339890208 10754170880 1175839424 12.551 99 82 163 2 1447720176 253216944 18663808
05:50:51 3343 8192000000 10335266576 10761797632 1191281920 12.695 100 76 147 0 1446850000 238464864 17103216
05:51:57 985 8192000000 10383713968 10796371968 1186638592 12.652 99 96 188 0 1492067952 282783344 22646016
05:53:01 1044 8192000000 10403881808 10827313152 1202088112 12.796 100 94 180 0 1512427520 289490832 20848576
05:54:06 2311 8192000000 10443283952 10870820864 1199131136 12.768 100 89 171 0 1555079120 335051984 20896000
05:55:12 4833 8192000000 10416716864 10840256512 1203757168 12.811 100 69 130 0 1521470768 301714208 15999392
05:56:19 2565 8192000000 10491576944 10919206912 1201510432 12.790 100 78 156 1 1594792560 374431888 18850240
05:57:24 1980 8192000000 10469178304 10895409152 1181275776 12.602 99 79 153 0 1575370064 375404320 18689968
05:58:31 1211 8192000000 10520800784 10952343552 1190519552 12.688 100 94 181 1 1621111168 409669488 20922128
05:59:34 751 8192000000 10549863312 10982129664 1185087840 12.638 100 94 186 1 1647576400 438375520 24113040
06:00:38 927 8192000000 10572001376 11008475136 1202672320 12.801 100 87 166 1 1674634256 451286800 20675136
06:01:44 3920 8192000000 10551159312 11022966784 1186924848 12.655 100 63 123 3 1654991328 451025040 17041440
06:02:52 727 8192000000 10590810320 11006808064 1171572592 12.512 100 83 160 2 1691042720 498502928 20967200
06:03:55 3860 8192000000 10562099040 11044114432 1175627376 12.549 100 66 130 2 1665630288 472933952 17068960
06:05:04 8390 8192000000 10481621040 10948005888 1172927456 12.524 100 44 88 0 1580914944 396470864 11516624
06:06:16 8163 8192000000 10435000112 10930315264 1154076240 12.348 99 42 82 1 1529457040 364859872 10520928
06:07:46 15939 8192000000 10386572928 10829713408 1159792464 12.401 100 26 52 0 1486370544 320165296 6412784
06:09:05 34851 8192000000 10373159824 10877145088 1119092736 12.018 96 26 53 0 1472041024 346110064 6838224
06:10:44 69483 8192000000 10067241856 10592919552 1094074192 11.781 96 14 28 0 1232360336 135037232 3248912
06:12:56 75565 8192000000 10058788720 10511212544 1093919104 11.780 97 23 43 0 1145400128 47249104 4231920
06:15:14 23715 8192000000 10106779200 10533658624 1124436848 12.069 99 63 118 2 1205559152 68110336 13011968
06:16:44 92092 8192000000 10066827232 10497404928 1130298288 12.124 100 24 48 0 1160910032 25319136 5292608
06:19:20 93055 8192000000 10090820192 10551341056 1106558448 11.900 97 34 67 0 1160783136 46840464 7384224
06:22:07 45889 8192000000 10083844368 10544357376 1138590560 12.202 100 24 48 0 1189355504 45220464 5544480
06:23:55 32934 8192000000 10126559984 10563641344 1134251136 12.161 99 34 68 0 1210113312 68194000 7668176
06:25:31 21770 8192000000 10193482176 10622357504 1139244944 12.208 100 56 109 1 1268483872 116989696 12249232
06:26:55 51275 8192000000 10119176768 10642300928 1133527568 12.155 99 18 36 0 1233970160 95961952 4480640
06:28:48 25765 8192000000 10114663280 10603839488 1118084464 12.009 99 11 22 0 1227123504 106287504 2751536
06:30:41 134999 8192000000 10103677504 10603790336 1096932960 11.809 97 17 34 0 1170417536 69962144 3522432
06:33:56 48407 8192000000 10094827728 10542317568 1137594864 12.193 100 24 46 0 1186819072 44587920 4636288
06:36:03 82194 8192000000 10104663408 10566602752 1135954032 12.177 99 17 34 0 1214523056 74892384 3676640
06:38:26 51402 8192000000 10100978064 10562895872 1136969088 12.187 98 12 24 0 1176794752 37567728 2257936
06:40:17 41780 8192000000 10084497840 10535260160 1110004912 11.932 96 35 64 1 1170744320 52598704 8140704
06:42:02 23316 8192000000 10091978528 10524798976 1133855104 12.158 99 40 80 0 1189729664 47946752 7927808
06:43:29 82340 8192000000 10102631696 10538774528 1106611088 11.900 96 30 55 0 1168131984 55977904 5542992
06:45:51 55370 8192000000 10070437216 10529886208 1128675216 12.109 99 25 49 0 1178208432 44629840 4903376
06:47:47 112332 8192000000 10074837904 10530037760 1109235520 11.925 97 29 56 0 1143716640 28407440 6073680
06:51:36 40423 8192000000 10072498336 10526572544 1114203712 11.972 98 11 22 0 1169258816 52294752 2760352
06:53:16 36314 8192000000 10138944624 10580627456 1125113616 12.075 98 21 42 0 1210545664 80708560 4723488
06:54:54 55098 8192000000 10205267712 10665541632 1152336480 12.331 100 13 26 0 1297976048 142209344 3430224
06:56:50 55820 8192000000 10215577360 10737876992 1152767584 12.335 100 21 42 0 1345439728 187928400 4743744
06:58:51 26657 8192000000 10060154864 10501107712 1095725792 11.797 97 14 27 0 1131665696 33317552 2622352
07:00:20 39811 8192000000 10125843312 10560847872 1115796928 11.987 98 32 62 0 1199725552 77406672 6521952
07:02:19 18905 8192000000 10181725040 10615255040 1128288592 12.105 99 51 97 1 1248380864 109974416 10117856
07:03:38 37494 8192000000 10210992816 10665857024 1124971344 12.074 99 38 74 1 1306464336 173547776 7945216
07:05:39 36339 8192000000 10088692464 10550603776 1098831328 11.827 98 24 48 0 1217929856 113786640 5311888
07:07:32 9313 8192000000 10079488512 10503016448 1113902256 11.969 99 39 78 0 1161908864 40475360 7531248
07:08:42 47654 8192000000 10059446320 10487263232 1109004160 11.923 99 54 107 0 1157811488 38507808 10299520
07:10:31 64909 8192000000 10060250528 10515939328 1099291568 11.831 98 13 26 0 1142778896 41079744 2407584
07:12:37 58403 8192000000 10116933376 10600280064 1118005888 12.008 99 7 14 1 1179970448 60255600 1708960
07:14:35 61280 8192000000 10048419776 10519199744 1096032640 11.800 98 8 14 1 1134046080 36677696 1335744
07:16:39 49721 8192000000 10058280352 10516742144 1095193872 11.792 97 11 20 0 1119114672 21721792 2199008
07:18:29 22150 8192000000 10092932464 10539765760 1113800848 11.968 99 39 78 0 1197943616 76205248 7937520
07:19:55 29011 8192000000 10086297728 10531500032 1103968368 11.875 98 22 44 0 1175737776 67382336 4387072
07:21:30 37517 8192000000 10138396800 10618630144 1119577056 12.023 99 29 58 0 1232571968 106515856 6479056
07:23:08 23756 8192000000 10093818032 10537709568 1121408208 12.040 99 27 52 0 1188857312 62443856 5005248
07:24:37 24883 8192000000 10090339872 10520363008 1105156064 11.887 98 39 77 0 1159425920 46647984 7621872
07:26:02 25147 8192000000 10090006640 10533462016 1116931792 11.998 99 41 81 1 1170654400 46189536 7533072
07:27:28 45358 8192000000 10105462256 10536833024 1138187584 12.198 100 69 130 2 1200078640 47876048 14015008
07:29:14 31860 8192000000 10083434416 10541330432 1138915696 12.205 100 37 74 0 1186021568 39658576 7447296
07:30:55 66308 8192000000 10093650672 10561511424 1139032832 12.206 100 9 18 0 1186856160 46038064 1785264
07:33:04 63461 8192000000 10073691120 10542465024 1123864672 12.063 98 13 26 0 1181658704 54664912 3129120
07:35:40 53120 8192000000 10115895600 10567569408 1134424480 12.163 99 20 40 0 1188554480 49389456 4740544

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 106 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 11-01-2023 07:39:27 to 11-01-2023 07:39:29.

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          219696
MON$OLDEST_ACTIVE               223009
MON$OLDEST_SNAPSHOT             223009
MON$NEXT_TRANSACTION            223009
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                       2069856
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             774
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 ---
:170: 
:171: mscs-test-161.budzdorov.ru    Wed Jan 11 07:37:37 2023
:172:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:173: 
:174: 
:175: mscs-test-161.budzdorov.ru    Wed Jan 11 07:37:38 2023
:176:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:177: 
:178: 
:179: mscs-test-161.budzdorov.ru    Wed Jan 11 07:37:38 2023
:180:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:181: 
:182: 
:183: mscs-test-161.budzdorov.ru    Wed Jan 11 07:37:39 2023
:184:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:185: 
--- end of diff output ---

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

11.01.23 07:39:30. 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-11 07:39:33 No severe PSQL-related problems occured
11.01.23 07:39:34. End of report.