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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15094600 kB
MemAvailable 30395672 kB
Buffers 20 kB
Cached 16584800 kB
SwapCached 2332 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-16 04:37:24
DTS_END                         2023-01-16 04:37:24.3540
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/53702
TRN_ID                          208760
ATT_ID                          613
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: 8748.48 25123 1049817
customer order: creation 160.08 471 19210
customer order: refuse 13.62 490 1634
order to supplier: creation 42.37 1374 5084
order to supplier: removal 3.18 189190 382
invoice (draft): creation 42.65 3307 5118
invoice (draft): removal 48.91 1133 5869
invoice accept: apply 39.03 28981 4684
invoice accept: cancel 44.27 1327 5312
customer reserve: creation 3708.58 273 445030
customer reserve: removal 4360.70 108 523284
realization accept: apply 77.84 270 9341
realization accept: cancel 84.51 170 10141
payment from customer: creation 55.78 170 6693
payment from customer: removal 5.42 130 650
payment to supplier: creation 46.28 177 5554
payment to supplier: removal 3.58 187 429
service: total inventory turnovers 3.89 4023 467
service: total monetary turnovers 6.88 375 826
service: aggregate perf. data 0.88 16639 105
service: refresh index statistics 0.03 253673 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-01-2023 04:40:04 to 16-01-2023 04:40:06.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3664
TEST_TIME 2 2995
TEST_TIME 3 3789
TEST_TIME 4 3734
TEST_TIME 5 3709
TEST_TIME 6 3856
TEST_TIME 7 4252
TEST_TIME 8 4540
TEST_TIME 9 5089
TEST_TIME 10 5340
TEST_TIME 11 5595
TEST_TIME 12 5940
TEST_TIME 13 5945
TEST_TIME 14 6219
TEST_TIME 15 6322
TEST_TIME 16 6448
TEST_TIME 17 6398
TEST_TIME 18 6661
TEST_TIME 19 6644
TEST_TIME 20 7074
TEST_TIME 21 7132
TEST_TIME 22 7498
TEST_TIME 23 7696
TEST_TIME 24 7846
TEST_TIME 25 7826
TEST_TIME 26 8069
TEST_TIME 27 8589
TEST_TIME 28 8835
TEST_TIME 29 8993
TEST_TIME 30 9202
TEST_TIME 31 9344
TEST_TIME 32 9476
TEST_TIME 33 9905
TEST_TIME 34 10152
TEST_TIME 35 10450
TEST_TIME 36 10585
TEST_TIME 37 10638
TEST_TIME 38 10520
TEST_TIME 39 10452
TEST_TIME 40 10234
TEST_TIME 41 10140
TEST_TIME 42 10213
TEST_TIME 43 10154
TEST_TIME 44 10019
TEST_TIME 45 9935
TEST_TIME 46 9814
TEST_TIME 47 9774
TEST_TIME 48 9714
TEST_TIME 49 9812
TEST_TIME 50 9710
TEST_TIME 51 9667
TEST_TIME 52 9518
TEST_TIME 53 9469
TEST_TIME 54 9424
TEST_TIME 55 9523
TEST_TIME 56 9542
TEST_TIME 57 9472
TEST_TIME 58 9478
TEST_TIME 59 9401
TEST_TIME 60 9320
TEST_TIME 61 9410
TEST_TIME 62 9522
TEST_TIME 63 9511
TEST_TIME 64 9523
TEST_TIME 65 9463
TEST_TIME 66 9540
TEST_TIME 67 9616
TEST_TIME 68 9559
TEST_TIME 69 9486
TEST_TIME 70 9460
TEST_TIME 71 9350
TEST_TIME 72 9299
TEST_TIME 73 9320
TEST_TIME 74 9256
TEST_TIME 75 9256
TEST_TIME 76 9196
TEST_TIME 77 9135
TEST_TIME 78 9086
TEST_TIME 79 8999
TEST_TIME 80 8949
TEST_TIME 81 8963
TEST_TIME 82 8903
TEST_TIME 83 8914
TEST_TIME 84 8877
TEST_TIME 85 8838
TEST_TIME 86 8835
TEST_TIME 87 8809
TEST_TIME 88 8790
TEST_TIME 89 8788
TEST_TIME 90 8817
TEST_TIME 91 8873
TEST_TIME 92 8973
TEST_TIME 93 8979
TEST_TIME 94 9009
TEST_TIME 95 9016
TEST_TIME 96 9038
TEST_TIME 97 9010
TEST_TIME 98 8954
TEST_TIME 99 8952
TEST_TIME 100 8949
TEST_TIME 101 8902
TEST_TIME 102 8900
TEST_TIME 103 8869
TEST_TIME 104 8859
TEST_TIME 105 8796
TEST_TIME 106 8753
TEST_TIME 107 8772
TEST_TIME 108 8816
TEST_TIME 109 8783
TEST_TIME 110 8785
TEST_TIME 111 8791
TEST_TIME 112 8783
TEST_TIME 113 8789
TEST_TIME 114 8777
TEST_TIME 115 8834
TEST_TIME 116 8803
TEST_TIME 117 8834
TEST_TIME 118 8862
TEST_TIME 119 8806
TEST_TIME 120 8764

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 16-01-2023 04:40:08 to 16-01-2023 04:40:11.

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 1458924 1458924 0 0.00 0 31075 15 0 0
doc_list_biud 1458917 1458917 0 0.00 0 46458 45 0 0
sp_add_invoice_to_stock 4760 4684 76 1.59 26 301615 28982 0 76
sp_cancel_adding_invoice 5312 5312 0 0.00 4 95504 1327 0 0
sp_cancel_client_order 1634 1634 0 0.00 5 28468 490 0 0
sp_cancel_customer_reserve 523284 523284 0 0.00 1 30923 108 0 0
sp_cancel_pay_from_customer 650 650 0 0.00 4 3741 131 0 0
sp_cancel_pay_to_supplier 429 429 0 0.00 4 5513 188 0 0
sp_cancel_supplier_invoice 5876 5869 7 0.11 2 32964 1134 0 7
sp_cancel_supplier_order 382 382 0 0.00 9 1691317 189190 0 0
sp_cancel_write_off 10141 10141 0 0.00 2 30676 171 0 0
sp_client_order 19210 19210 0 0.00 6 24849 472 0 0
sp_customer_reserve 445030 445030 0 0.00 0 45019 273 0 0
sp_fill_shopping_cart 29408 29408 0 0.00 1 16818 82 0 0
sp_fill_shopping_cart_clo_res 445011 445011 0 0.00 0 24220 21 0 0
sp_get_clo_for_invoice 4662 4662 0 0.00 0 29482 290 0 0
sp_kill_cost_storno 16532 16532 0 0.00 0 9060 78 0 0
sp_kill_qstorno_ret_qs2qd 1062960 1062960 0 0.00 0 32943 48 0 0
sp_kill_qty_storno 560625 560625 0 0.00 0 46458 109 0 0
sp_lock_selected_doc 19440 19440 0 0.00 0 7579 9 0 0
sp_make_cost_storno 26250 26250 0 0.00 0 14829 76 0 0
sp_multiply_rows_for_pdistr 26250 26250 0 0.00 0 11241 35 0 0
sp_multiply_rows_for_qdistr 19210 19210 0 0.00 0 14065 178 0 0
sp_pay_from_customer 6693 6693 0 0.00 5 16862 170 0 0
sp_pay_to_supplier 5554 5554 0 0.00 6 12296 178 0 0
sp_payment_common 12247 12247 0 0.00 2 7110 44 0 0
sp_qd_handle_on_cancel_clo 1634 1634 0 0.00 0 12170 405 0 0
sp_qd_handle_on_invoice_upd_sts 9974 9974 0 0.00 0 46456 564 0 0
sp_qd_handle_on_reserve_upd_sts 9341 9341 0 0.00 0 17682 64 0 0
sp_reserve_write_off 9341 9341 0 0.00 8 18011 270 0 0
sp_supplier_invoice 5118 5118 0 0.00 10 92158 3307 0 0
sp_supplier_order 5084 5084 0 0.00 8 45040 1374 0 0
srv_aggregate_perf_data 105 105 0 0.00 2274 113064 16639 0 0
srv_fill_mon_cache_memory 76 76 0 0.00 830 102901 31265 0 0
srv_find_qd_qs_mism 1003118 1003118 0 0.00 0 31072 12 0 0
srv_make_invnt_saldo 467 467 0 0.00 44 124809 4023 0 0
srv_make_money_saldo 826 826 0 0.00 1 14972 375 0 0
srv_recalc_idx_stat 4 4 0 0.00 164059 383733 253673 0 0
t$perf-abend:sp_add_invoice_to_stock 76 76 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 4685 4685 0 0.00 1 11596 953 0 0
t$perf-norm:sp_cancel_adding_invoice 403 403 0 0.00 1 28122 514 0 0
t$perf-norm:sp_cancel_client_order 1634 1634 0 0.00 0 2321 24 0 0
t$perf-norm:sp_cancel_customer_reserve 629 629 0 0.00 0 1256 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 650 650 0 0.00 0 12971 35 0 0
t$perf-norm:sp_cancel_pay_to_supplier 429 429 0 0.00 0 1100 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 355 355 0 0.00 0 828 36 0 0
t$perf-norm:sp_cancel_supplier_order 382 382 0 0.00 0 112053 8113 0 0
t$perf-norm:sp_cancel_write_off 956 956 0 0.00 0 2320 17 0 0
t$perf-norm:sp_client_order 19210 19210 0 0.00 0 7378 37 0 0
t$perf-norm:sp_customer_reserve 915 915 0 0.00 0 1565 17 0 0
t$perf-norm:sp_pay_from_customer 6693 6693 0 0.00 0 9941 22 0 0
t$perf-norm:sp_pay_to_supplier 5554 5554 0 0.00 0 3663 20 0 0
t$perf-norm:sp_reserve_write_off 9341 9341 0 0.00 0 10117 29 0 0
t$perf-norm:sp_supplier_invoice 5118 5118 0 0.00 0 6701 32 0 0
t$perf-norm:sp_supplier_order 5086 5086 0 0.00 0 5370 31 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 843 23 0 0
t$perf-norm:srv_fill_mon_cache_memory 76 76 0 0.00 0 19 3 0 0
t$perf-norm:srv_make_invnt_saldo 467 467 0 0.00 0 6102 32 0 0
t$perf-norm:srv_make_money_saldo 826 826 0 0.00 0 2616 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 308 96 0 0
v_add_invoice_to_stock 9474 9322 152 1.60 0 4061 24 0 152
v_all_customers 35312 35312 0 0.00 0 115 4 0 0
v_all_suppliers 28670 28670 0 0.00 0 99 4 0 0
v_all_wares 38420 38420 0 0.00 0 131 4 0 0
v_cancel_adding_invoice 806 806 0 0.00 0 563 14 0 0
v_cancel_client_order 3268 3268 0 0.00 0 1628 9 0 0
v_cancel_customer_prepayment 1300 1300 0 0.00 0 3711 15 0 0
v_cancel_customer_reserve 1258 1258 0 0.00 0 296 9 0 0
v_cancel_payment_to_supplier 858 858 0 0.00 0 4533 27 0 0
v_cancel_supplier_invoice 724 710 14 1.93 0 2876 30 0 14
v_cancel_supplier_order 764 764 0 0.00 0 542 12 0 0
v_cancel_write_off 1912 1912 0 0.00 0 4290 24 0 0
v_max_id_clo_ord 5084 5084 0 0.00 0 685 5 0 0
v_max_id_clo_res 915 915 0 0.00 0 1924 13 0 0
v_max_id_ord_sup 5113 5113 0 0.00 0 3448 6 0 0
v_max_non_paid_invoice 1414 1414 0 0.00 0 1271 9 0 0
v_max_non_paid_realizn 3309 3309 0 0.00 0 316 7 0 0
v_min_id_clo_ord 5084 5084 0 0.00 0 1665 7 0 0
v_min_id_clo_res 915 915 0 0.00 0 6234 21 0 0
v_min_id_ord_sup 5113 5113 0 0.00 0 8211 14 0 0
v_min_non_paid_invoice 5554 5554 0 0.00 0 6859 15 0 0
v_min_non_paid_realizn 6692 6692 0 0.00 0 7102 19 0 0
v_our_firm 9876 9876 0 0.00 0 65 4 0 0
v_reserve_write_off 18682 18682 0 0.00 0 2639 9 0 0
x_lock_depdocs_on_canc_invoice 10826 10826 0 0.00 0 16565 134 0 0
x_lock_depdocs_on_canc_sup_ord 382 382 0 0.00 0 11922 463 0 0
x_make_qstorno_1000_1200 5084 5084 0 0.00 3 45006 1217 0 0
x_make_qstorno_1200_2000 5118 5118 0 0.00 4 89516 3074 0 0
x_make_qstorno_2100_3300 445026 445026 0 0.00 0 35493 240 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-01-2023 04:40:12 to 16-01-2023 04:40:16.

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
02:07:28 9 8192000000 8814265888 8831623168 15381792 0.187 9 3 4 0 15589664 120928 86944
02:08:29 31735 8192000000 9721057792 10020216832 791981776 8.815 100 88 176 0 821510976 7789280 21739920
02:10:05 18903 8192000000 9753921104 10065076224 835235632 9.252 100 85 170 0 867179696 11684704 20259360
02:11:27 15815 8192000000 9802402000 10123735040 866896960 9.569 100 83 165 0 902466000 16746432 18822608
02:12:45 15145 8192000000 9856257104 10185043968 909440320 9.992 100 91 181 0 962991968 33225056 20326592
02:14:04 4027 8192000000 9893837328 10237054976 937694064 10.270 100 82 164 0 1007817408 52808176 17315168
02:15:13 2418 8192000000 9950455536 10301501440 972112112 10.607 100 83 161 0 1066774496 77940608 16721776
02:16:19 1233 8192000000 9978085840 10335621120 999534016 10.874 100 91 180 1 1094861568 77187904 18139648
02:17:23 890 8192000000 9992494432 10357526528 1021801440 11.089 100 93 177 1 1109649392 70195024 17652928
02:18:26 963 8192000000 10041092064 10415517696 1051529328 11.375 100 97 189 1 1161917424 91265392 19122704
02:19:28 1032 8192000000 10046610304 10424774656 1060468976 11.461 100 95 188 0 1171948448 92774816 18704656
02:20:31 1018 8192000000 10045194320 10421551104 1062000064 11.476 100 99 190 0 1164682896 84217776 18465056
02:21:33 8941 8192000000 10025441520 10417467392 1072024992 11.571 100 63 123 0 1152255216 68733216 11497008
02:22:43 3177 8192000000 10033169936 10412523520 1074373520 11.594 100 90 167 3 1151657872 59856688 17427664
02:23:48 1033 8192000000 10059568208 10445344768 1080353328 11.651 100 97 186 2 1182609744 83070288 19186128
02:24:50 1090 8192000000 10043729520 10429218816 1089373776 11.737 100 96 187 5 1169564912 61453728 18737408
02:25:52 854 8192000000 10066971040 10457096192 1088382864 11.727 100 89 168 1 1191530480 85060368 18087248
02:26:55 1113 8192000000 10060361696 10450804736 1091274272 11.755 100 90 178 2 1181996928 73308464 17414192
02:27:57 1050 8192000000 10071511008 10459385856 1093960752 11.780 100 98 187 1 1193383456 80396592 19026112
02:28:59 1195 8192000000 10038381376 10433253376 1091654336 11.758 100 69 132 4 1159281536 54046032 13581168
02:30:01 1390 8192000000 10047994160 10443177984 1093706656 11.778 100 82 158 5 1173425872 64339008 15380208
02:31:03 1363 8192000000 10040518912 10427998208 1089679120 11.740 100 84 161 0 1160360496 53635440 17045936
02:32:06 3757 8192000000 10014747680 10402680832 1061743408 11.473 99 73 141 1 1128436640 51682896 15010336
02:33:10 873 8192000000 9992629184 10364428288 1036721056 11.233 100 96 175 2 1110944560 54594016 19629488
02:34:12 4842 8192000000 9977192528 10352582656 1027414272 11.144 98 63 118 1 1091108016 52255216 11438528
02:35:19 1089 8192000000 10002898944 10386378752 1039281680 11.258 100 74 147 2 1129571936 74912832 15377424
02:36:21 2550 8192000000 9990693440 10369552384 1032815152 11.196 99 85 163 0 1104962352 55892512 16254688
02:37:24 3075 8192000000 9966587520 10344595456 1035914432 11.225 99 72 120 2 1085332640 37152192 12266016
02:38:34 1041 8192000000 9984567312 10356674560 1058327648 11.440 100 89 165 1 1100003648 26353120 15322880
02:39:48 3563 8192000000 10069177872 10448674816 1082765136 11.674 100 94 171 1 1187743600 86900704 18077760
02:41:02 8249 8192000000 10065378240 10457481216 1090997424 11.752 100 70 134 3 1182551120 77141744 14411952
02:42:18 3225 8192000000 10138930720 10524786688 1099005360 11.828 100 69 136 0 1256272976 142878032 14389584
02:43:29 4185 8192000000 10195437744 10590449664 1110782304 11.940 100 72 139 0 1310239616 184382192 15075120
02:44:41 2042 8192000000 10222143760 10617933824 1118419952 12.012 100 81 151 0 1329140064 195118320 15601792
02:45:47 1496 8192000000 10270332096 10665840640 1130791536 12.129 100 91 182 0 1381105296 230891888 19421872
02:46:52 821 8192000000 10278516352 10677977088 1132194992 12.142 100 98 189 2 1390804448 238083600 20525856
02:47:59 5254 8192000000 10275692624 10687422464 1149075600 12.301 100 70 140 1 1396090816 231578336 15436880
02:49:11 1040 8192000000 10331636096 10737487872 1165366320 12.453 100 99 194 4 1443156064 255798400 21991344
02:50:14 949 8192000000 10337424000 10739724288 1165961392 12.459 100 85 166 0 1447415216 262523424 18930400
02:51:19 1016 8192000000 10399728704 10811215872 1180830688 12.598 100 95 180 2 1509765584 308007296 20927600
02:52:23 1558 8192000000 10442951824 10856636416 1189544640 12.679 100 98 193 3 1549984032 338366976 22072416
02:53:28 957 8192000000 10455044304 10862612480 1189850928 12.682 100 94 169 1 1559975984 351828960 18296096
02:54:33 907 8192000000 10478265504 10914525184 1192323904 12.705 100 94 189 1 1586328688 371329328 22675456
02:55:38 1960 8192000000 10496731840 10909822976 1197887728 12.757 100 88 164 2 1605842080 387321952 20632400
02:56:43 1006 8192000000 10509150384 10970333184 1208532960 12.856 100 96 183 1 1616694656 386289680 21872016
02:57:49 1566 8192000000 10517975776 10949693440 1192402704 12.706 100 96 192 0 1622388288 406048640 23936944
02:58:55 1431 8192000000 10512197344 10971430912 1187239472 12.658 99 95 182 1 1615612816 405364432 23008912
02:59:59 1672 8192000000 10551164112 10973495296 1196951248 12.748 100 90 175 1 1657073520 439767328 20354944
03:01:04 4518 8192000000 10533416160 10997329920 1197612112 12.754 100 66 126 2 1638998592 425777904 15608576
03:02:15 1581 8192000000 10577800896 11006648320 1198606032 12.763 100 80 160 2 1676933504 459612160 18715312
03:03:22 4522 8192000000 10521548096 11001876480 1174514208 12.539 100 57 111 1 1623307072 434326128 14466736
03:04:29 20740 8192000000 10456225776 10918219776 1162077168 12.423 99 37 74 0 1570761296 398217808 10466320
03:05:52 13103 8192000000 10424341520 10880512000 1164425648 12.445 100 33 66 0 1528773504 355665520 8682336
03:07:12 17976 8192000000 10391162432 10893926400 1158020800 12.385 99 32 60 0 1518674080 352907600 7745680
03:08:33 19901 8192000000 10396572672 10855309312 1160886288 12.412 100 34 68 0 1496492496 326704096 8902112
03:09:56 15623 8192000000 10352816304 10848362496 1153589376 12.343 99 30 60 0 1493802016 332440992 7771648
03:11:21 39406 8192000000 10258449120 10743488512 1151665584 12.325 100 14 27 0 1354220976 199228448 3326944
03:13:08 8794 8192000000 10138652496 10648485888 1144891648 12.262 99 33 65 0 1268847360 116660336 7295376
03:14:19 5879 8192000000 10132030176 10554384384 1155668912 12.363 100 64 119 0 1229305408 60616192 13020304
03:15:26 26858 8192000000 10178445616 10615652352 1153421232 12.342 99 37 73 0 1273795664 112271584 8102848
03:16:59 96256 8192000000 10172428448 10628083712 1117003536 11.999 97 43 80 0 1244808432 118003968 9800928
03:19:40 30645 8192000000 10078034384 10568671232 1139175488 12.208 100 8 14 0 1178826624 38134032 1517104
03:21:17 25713 8192000000 10066464816 10515447808 1109790000 11.930 98 22 41 1 1146154944 32001840 4363104
03:22:44 49801 8192000000 10116321904 10561097728 1110277440 11.935 97 22 44 0 1189921984 74822528 4822016
03:24:34 31341 8192000000 10144381520 10602565632 1142462544 12.239 100 17 34 0 1239233584 92753696 4017344
03:26:11 30355 8192000000 10123926112 10598625280 1145966880 12.272 100 16 31 1 1235140976 85929792 3244304
03:27:43 60513 8192000000 10104553040 10565308416 1109633824 11.929 96 21 42 0 1192489408 78364960 4490624
03:29:44 94018 8192000000 10138918592 10627735552 1139568320 12.211 100 17 34 0 1244878016 101419760 3889936
03:32:21 69266 8192000000 10092151920 10587992064 1103593216 11.872 98 15 30 0 1176381952 69863552 2925184
03:34:32 19307 8192000000 10117812928 10567684096 1113054960 11.961 98 28 56 0 1191972640 72483040 6434640
03:35:53 64700 8192000000 10138704432 10579345408 1123093696 12.056 99 19 38 0 1209483648 82462496 3927456
03:37:59 50439 8192000000 10140571744 10611396608 1147728592 12.288 100 30 58 0 1263615472 109944016 5942864
03:39:51 14944 8192000000 10076241040 10511060992 1123923984 12.064 98 31 62 1 1152518880 22486816 6108080
03:41:07 51973 8192000000 10134568432 10585288704 1108140048 11.915 96 28 56 0 1182755776 68946448 5669280
03:43:20 64646 8192000000 10137421344 10624221184 1119981600 12.027 98 24 48 0 1201188704 76436608 4770496
03:45:29 102900 8192000000 10091324048 10587664384 1148951712 12.300 100 32 64 0 1215264640 59084656 7228272
03:48:14 28947 8192000000 10099438032 10526801920 1138136272 12.198 100 69 131 1 1189785728 38044992 13604464
03:49:50 33689 8192000000 10139198976 10621911040 1147285536 12.284 100 37 75 0 1260835968 105620992 7929440
03:51:35 56189 8192000000 10076968240 10573524992 1146448272 12.276 100 5 10 0 1179128256 31853056 826928
03:53:32 60636 8192000000 10049583168 10501177344 1135109808 12.170 99 16 27 2 1159003264 20814384 3079072
03:55:53 75067 8192000000 10070705264 10541211648 1105815920 11.893 97 21 42 0 1170681680 60443232 4422528
03:58:14 32805 8192000000 10076757664 10525163520 1104157312 11.877 97 30 56 1 1164494224 54126448 6210464
03:59:51 98660 8192000000 10041637104 10489257984 1092761536 11.769 97 14 27 0 1121876160 26783680 2330944
04:02:31 31391 8192000000 10139352608 10578911232 1128149312 12.104 99 37 71 1 1224056544 88385440 7521792
04:04:03 36643 8192000000 10137253744 10578038784 1118913632 12.017 98 27 51 0 1225526944 101059664 5553648
04:05:44 44238 8192000000 10197532880 10659926016 1143979248 12.253 99 34 68 0 1266616640 114747664 7889728
04:07:29 56610 8192000000 10156364560 10645524480 1126795840 12.091 98 28 56 1 1282356704 149335184 6225680
04:09:27 28434 8192000000 10103780384 10561912832 1124326096 12.068 99 28 55 0 1206921072 77102592 5492384
04:10:59 23596 8192000000 10127792320 10578669568 1134615136 12.165 99 30 60 0 1199148768 58730544 5803088
04:12:28 41922 8192000000 10094715568 10558468096 1104882560 11.884 97 43 86 1 1169243936 55985408 8375968
04:14:13 82997 8192000000 10078136432 10549391360 1079580880 11.643 95 17 34 0 1154804784 71330976 3892928
04:16:54 65994 8192000000 10072712656 10553802752 1112847440 11.959 99 17 34 0 1165120992 49141760 3131792
04:19:00 85358 8192000000 10065179088 10552594432 1110114224 11.933 98 17 34 0 1185953856 72173280 3666352
04:21:27 66420 8192000000 10083894368 10550112256 1114620128 11.976 98 18 36 0 1169774656 51475232 3679296
04:23:34 77257 8192000000 10097859024 10594922496 1126899344 12.092 99 11 22 0 1193560688 63971120 2690224
04:25:53 49681 8192000000 10094980560 10537213952 1126229936 12.086 99 16 32 0 1165396272 35613776 3552560
04:27:46 18930 8192000000 10089116768 10532032512 1121167152 12.038 99 37 74 0 1170301056 41021360 8112544
04:29:07 10657 8192000000 10110913504 10540806144 1134196112 12.161 100 61 120 0 1203784960 57303040 12285808
04:30:17 14010 8192000000 10113402736 10545881088 1130752864 12.128 100 43 86 0 1217601904 78572528 8276512
04:31:39 61730 8192000000 10094284544 10521038848 1108796672 11.921 98 39 78 0 1195737376 79084752 7855952
04:33:41 33107 8192000000 10138990928 10597306368 1109499072 11.928 98 47 94 0 1204987136 86263024 9225040
04:35:18 30368 8192000000 10089503808 10513661952 1098470240 11.823 97 53 104 1 1175539312 66688864 10380208
04:36:50 47831 8192000000 9301669728 9617137664 440610832 5.104 40 10 20 0 464730208 22111328 2008048

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 16-01-2023 04:40:18 to 16-01-2023 04:40:35.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 76 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:02, from 16-01-2023 04:40:36 to 16-01-2023 04:40:38.

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          209462
MON$OLDEST_ACTIVE               209739
MON$OLDEST_SNAPSHOT             209739
MON$NEXT_TRANSACTION            209739
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                       2058016
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             758
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2890 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2890 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2890 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2890 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 ---
:715: 
:716: mscs-test-161.budzdorov.ru    Mon Jan 16 04:37:44 2023
:717:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:718: 
:719: 
:720: mscs-test-161.budzdorov.ru    Mon Jan 16 04:37:51 2023
:721:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:722: 
:723: 
:724: mscs-test-161.budzdorov.ru    Mon Jan 16 04:37:52 2023
:725:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:726: 
--- end of diff output ---

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

16.01.23 04:40:39. 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-16 04:40:42 No severe PSQL-related problems occured
16.01.23 04:40:43. End of report.