Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.12.2021 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 16192404 kB
MemAvailable 31426532 kB
Buffers 0 kB
Cached 15519092 kB
SwapCached 440 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.1
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 2021-12-25 13:37:11
DTS_END                         2021-12-25 13:37:11.1010
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/35338
TRN_ID                          231275
ATT_ID                          598
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: 9157.93 24260 1098952
customer order: creation 164.15 388 19698
customer order: refuse 14.23 366 1707
order to supplier: creation 44.43 1352 5331
order to supplier: removal 3.31 132345 397
invoice (draft): creation 44.68 3611 5361
invoice (draft): removal 53.15 985 6378
invoice accept: apply 40.42 20481 4850
invoice accept: cancel 48.08 981 5769
customer reserve: creation 3767.39 200 452087
customer reserve: removal 4689.47 74 562736
realization accept: apply 81.49 200 9779
realization accept: cancel 82.98 127 9958
payment from customer: creation 57.38 112 6885
payment from customer: removal 5.49 79 659
payment to supplier: creation 46.96 132 5635
payment to supplier: removal 3.66 88 439
service: total inventory turnovers 3.53 4210 424
service: total monetary turnovers 6.33 633 759
service: aggregate perf. data 0.80 22791 96
service: refresh index statistics 0.03 296052 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 25-12-2021 13:39:07 to 25-12-2021 13:39:10.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2533
TEST_TIME 2 2995
TEST_TIME 3 3833
TEST_TIME 4 5791
TEST_TIME 5 5442
TEST_TIME 6 5297
TEST_TIME 7 5350
TEST_TIME 8 5378
TEST_TIME 9 5533
TEST_TIME 10 5626
TEST_TIME 11 5930
TEST_TIME 12 6277
TEST_TIME 13 6228
TEST_TIME 14 6269
TEST_TIME 15 6705
TEST_TIME 16 6690
TEST_TIME 17 7023
TEST_TIME 18 7485
TEST_TIME 19 7939
TEST_TIME 20 8254
TEST_TIME 21 8581
TEST_TIME 22 8833
TEST_TIME 23 8810
TEST_TIME 24 8846
TEST_TIME 25 8799
TEST_TIME 26 8987
TEST_TIME 27 9196
TEST_TIME 28 9325
TEST_TIME 29 9614
TEST_TIME 30 10466
TEST_TIME 31 10698
TEST_TIME 32 10810
TEST_TIME 33 11120
TEST_TIME 34 10923
TEST_TIME 35 11039
TEST_TIME 36 11026
TEST_TIME 37 10856
TEST_TIME 38 10715
TEST_TIME 39 10709
TEST_TIME 40 10540
TEST_TIME 41 10495
TEST_TIME 42 10470
TEST_TIME 43 10286
TEST_TIME 44 10150
TEST_TIME 45 10083
TEST_TIME 46 9974
TEST_TIME 47 9827
TEST_TIME 48 9999
TEST_TIME 49 10052
TEST_TIME 50 10151
TEST_TIME 51 10044
TEST_TIME 52 9936
TEST_TIME 53 9928
TEST_TIME 54 10187
TEST_TIME 55 10154
TEST_TIME 56 10038
TEST_TIME 57 10029
TEST_TIME 58 10203
TEST_TIME 59 10121
TEST_TIME 60 10118
TEST_TIME 61 10199
TEST_TIME 62 10191
TEST_TIME 63 10156
TEST_TIME 64 10252
TEST_TIME 65 10266
TEST_TIME 66 10311
TEST_TIME 67 10309
TEST_TIME 68 10194
TEST_TIME 69 10139
TEST_TIME 70 10126
TEST_TIME 71 10040
TEST_TIME 72 9999
TEST_TIME 73 9925
TEST_TIME 74 9932
TEST_TIME 75 9902
TEST_TIME 76 9862
TEST_TIME 77 9808
TEST_TIME 78 9735
TEST_TIME 79 9799
TEST_TIME 80 9786
TEST_TIME 81 9757
TEST_TIME 82 9698
TEST_TIME 83 9703
TEST_TIME 84 9729
TEST_TIME 85 9784
TEST_TIME 86 9767
TEST_TIME 87 9802
TEST_TIME 88 9792
TEST_TIME 89 9812
TEST_TIME 90 9786
TEST_TIME 91 9761
TEST_TIME 92 9708
TEST_TIME 93 9647
TEST_TIME 94 9620
TEST_TIME 95 9574
TEST_TIME 96 9519
TEST_TIME 97 9482
TEST_TIME 98 9455
TEST_TIME 99 9407
TEST_TIME 100 9404
TEST_TIME 101 9373
TEST_TIME 102 9343
TEST_TIME 103 9329
TEST_TIME 104 9315
TEST_TIME 105 9340
TEST_TIME 106 9349
TEST_TIME 107 9329
TEST_TIME 108 9375
TEST_TIME 109 9336
TEST_TIME 110 9433
TEST_TIME 111 9447
TEST_TIME 112 9449
TEST_TIME 113 9394
TEST_TIME 114 9345
TEST_TIME 115 9296
TEST_TIME 116 9301
TEST_TIME 117 9285
TEST_TIME 118 9257
TEST_TIME 119 9234
TEST_TIME 120 9158

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 25-12-2021 13:39:11 to 25-12-2021 13:39:14.

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 1516886 1516886 0 0.00 0 18724 7 0 0
doc_list_biud 1516886 1516886 0 0.00 0 32932 34 0 0
sp_add_invoice_to_stock 4973 4850 123 2.47 34 184906 20481 0 123
sp_cancel_adding_invoice 5769 5769 0 0.00 4 82531 981 0 0
sp_cancel_client_order 1707 1707 0 0.00 4 5921 367 0 0
sp_cancel_customer_reserve 562736 562736 0 0.00 1 32935 75 0 0
sp_cancel_pay_from_customer 659 659 0 0.00 3 5054 79 0 0
sp_cancel_pay_to_supplier 439 439 0 0.00 3 5963 88 0 0
sp_cancel_supplier_invoice 6386 6378 8 0.12 1 13685 986 0 8
sp_cancel_supplier_order 397 397 0 0.00 5 1249364 132346 0 0
sp_cancel_write_off 9958 9958 0 0.00 1 14127 127 0 0
sp_client_order 19698 19698 0 0.00 4 18334 389 0 0
sp_customer_reserve 452087 452087 0 0.00 0 117952 200 0 0
sp_fill_shopping_cart 30371 30371 0 0.00 1 24077 41 0 0
sp_fill_shopping_cart_clo_res 452085 452085 0 0.00 0 22359 17 0 0
sp_get_clo_for_invoice 4848 4848 0 0.00 0 24439 345 0 0
sp_kill_cost_storno 16825 16825 0 0.00 0 16401 69 0 0
sp_kill_qstorno_ret_qs2qd 1142205 1142205 0 0.00 0 32924 40 0 0
sp_kill_qty_storno 601572 601572 0 0.00 0 32932 86 0 0
sp_lock_selected_doc 20326 20326 0 0.00 0 13362 5 0 0
sp_make_cost_storno 27147 27147 0 0.00 0 12198 61 0 0
sp_multiply_rows_for_pdistr 27147 27147 0 0.00 0 8332 32 0 0
sp_multiply_rows_for_qdistr 19698 19698 0 0.00 0 15446 185 0 0
sp_pay_from_customer 6885 6885 0 0.00 4 13126 112 0 0
sp_pay_to_supplier 5635 5635 0 0.00 5 15408 132 0 0
sp_payment_common 12520 12520 0 0.00 1 9078 22 0 0
sp_qd_handle_on_cancel_clo 1707 1707 0 0.00 0 5915 339 0 0
sp_qd_handle_on_invoice_upd_sts 10617 10617 0 0.00 0 30830 390 0 0
sp_qd_handle_on_reserve_upd_sts 9779 9779 0 0.00 0 21254 63 0 0
sp_reserve_write_off 9779 9779 0 0.00 7 22777 200 0 0
sp_supplier_invoice 5361 5361 0 0.00 7 105669 3611 0 0
sp_supplier_order 5331 5331 0 0.00 5 32782 1352 0 0
srv_aggregate_perf_data 96 96 0 0.00 3191 419112 22792 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1616 155045 46163 0 0
srv_find_qd_qs_mism 1052429 1052429 0 0.00 0 18722 6 0 0
srv_make_invnt_saldo 424 424 0 0.00 61 106193 4210 0 0
srv_make_money_saldo 759 759 0 0.00 1 12206 633 0 0
srv_recalc_idx_stat 4 4 0 0.00 238789 335156 296052 0 0
t$perf-abend:sp_add_invoice_to_stock 123 123 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4850 4850 0 0.00 0 17147 190 0 0
t$perf-norm:sp_cancel_adding_invoice 412 412 0 0.00 0 6444 114 0 0
t$perf-norm:sp_cancel_client_order 1707 1707 0 0.00 0 1971 13 0 0
t$perf-norm:sp_cancel_customer_reserve 727 727 0 0.00 0 5194 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 659 659 0 0.00 0 17990 32 0 0
t$perf-norm:sp_cancel_pay_to_supplier 439 439 0 0.00 0 2725 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 393 393 0 0.00 0 303 10 0 0
t$perf-norm:sp_cancel_supplier_order 397 397 0 0.00 0 23655 1646 0 0
t$perf-norm:sp_cancel_write_off 965 965 0 0.00 0 5624 13 0 0
t$perf-norm:sp_client_order 19698 19698 0 0.00 0 20961 22 0 0
t$perf-norm:sp_customer_reserve 993 993 0 0.00 0 1270 5 0 0
t$perf-norm:sp_pay_from_customer 6885 6885 0 0.00 0 23808 13 0 0
t$perf-norm:sp_pay_to_supplier 5635 5635 0 0.00 0 4109 9 0 0
t$perf-norm:sp_reserve_write_off 9779 9779 0 0.00 0 8591 11 0 0
t$perf-norm:sp_supplier_invoice 5361 5361 0 0.00 0 14565 22 0 0
t$perf-norm:sp_supplier_order 5331 5331 0 0.00 0 5136 17 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 91 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 4 1 0 0
t$perf-norm:srv_make_invnt_saldo 424 424 0 0.00 0 1185 8 0 0
t$perf-norm:srv_make_money_saldo 759 759 0 0.00 0 474 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 6 4 0 0
v_add_invoice_to_stock 9942 9696 246 2.47 0 7990 20 0 246
v_all_customers 36018 36018 0 0.00 0 23 1 0 0
v_all_suppliers 29578 29578 0 0.00 0 16 1 0 0
v_all_wares 39396 39396 0 0.00 0 15 1 0 0
v_cancel_adding_invoice 824 824 0 0.00 0 768 6 0 0
v_cancel_client_order 3414 3414 0 0.00 0 3395 7 0 0
v_cancel_customer_prepayment 1318 1318 0 0.00 0 2688 6 0 0
v_cancel_customer_reserve 1454 1454 0 0.00 0 3053 6 0 0
v_cancel_payment_to_supplier 878 878 0 0.00 0 288 3 0 0
v_cancel_supplier_invoice 802 786 16 1.99 0 1838 19 0 16
v_cancel_supplier_order 794 794 0 0.00 0 4288 11 0 0
v_cancel_write_off 1930 1930 0 0.00 0 2702 17 0 0
v_max_id_clo_ord 5331 5331 0 0.00 0 876 1 0 0
v_max_id_clo_res 993 993 0 0.00 0 3736 15 0 0
v_max_id_ord_sup 5342 5342 0 0.00 0 5972 5 0 0
v_max_non_paid_invoice 1519 1519 0 0.00 0 2266 5 0 0
v_max_non_paid_realizn 3593 3593 0 0.00 0 3223 4 0 0
v_min_id_clo_ord 5331 5331 0 0.00 0 2834 8 0 0
v_min_id_clo_res 993 993 0 0.00 0 5438 13 0 0
v_min_id_ord_sup 5342 5342 0 0.00 0 7344 11 0 0
v_min_non_paid_invoice 5635 5635 0 0.00 0 7962 12 0 0
v_min_non_paid_realizn 6885 6885 0 0.00 0 7438 15 0 0
v_our_firm 9962 9962 0 0.00 0 14 1 0 0
v_reserve_write_off 19558 19558 0 0.00 0 6174 6 0 0
x_lock_depdocs_on_canc_invoice 11754 11754 0 0.00 0 21999 127 0 0
x_lock_depdocs_on_canc_sup_ord 397 397 0 0.00 0 9708 378 0 0
x_make_qstorno_1000_1200 5331 5331 0 0.00 2 32772 1266 0 0
x_make_qstorno_1200_2000 5361 5361 0 0.00 4 100012 3461 0 0
x_make_qstorno_2100_3300 452087 452087 0 0.00 0 117951 180 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 25-12-2021 13:39:15 to 25-12-2021 13:39:19.

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:14 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:15 23066 8192000000 9707496880 10002538496 779782768 8.691 100 90 180 0 810996320 7368944 23844608
11:09:46 7020 8192000000 9758847152 10064490496 839127952 9.291 100 85 170 0 870349888 10970336 20251600
11:11:08 11251 8192000000 9797108512 10109313024 865583216 9.556 100 91 182 0 906744528 19606416 21554896
11:12:32 14649 8192000000 9868417280 10200784896 922559648 10.121 100 79 158 2 983595504 43800736 17235120
11:13:49 9739 8192000000 9907587056 10252292096 953550176 10.426 100 77 154 1 1020498896 50513072 16435648
11:15:04 7602 8192000000 9962741552 10316308480 993040000 10.811 100 85 168 0 1074544368 64378864 17125504
11:16:14 3529 8192000000 9999561952 10358398976 1018863856 11.061 100 89 178 1 1112409840 75509312 18036672
11:17:19 2668 8192000000 10019438240 10383990784 1043044832 11.294 100 81 159 2 1133826992 75444096 15338064
11:18:24 2283 8192000000 10048329040 10417352704 1067203952 11.525 100 93 185 1 1154904944 68780672 18920320
11:19:27 6445 8192000000 9985459824 10382708736 1070448320 11.556 100 67 138 1 1128219760 43518720 14252720
11:20:35 4287 8192000000 10017396992 10404491264 1073980688 11.590 100 58 116 3 1130159072 43279024 12899360
11:21:43 5764 8192000000 9993210704 10394189824 1080224416 11.650 100 38 74 1 1112951856 24420240 8307200
11:22:56 42989 8192000000 9994813232 10412265472 1086835776 11.713 100 39 76 3 1124829296 29509120 8484400
11:24:44 8420 8192000000 10007446992 10414718976 1088531152 11.729 100 25 50 0 1116591856 22864784 5195920
11:25:54 5765 8192000000 10027881184 10419826688 1093335440 11.774 100 41 82 4 1130632064 27961376 9335248
11:27:01 5127 8192000000 10010219472 10417700864 1087527776 11.719 100 43 85 0 1124385136 27951856 8905504
11:28:07 21818 8192000000 10011227360 10416852992 1042627344 11.290 96 38 76 1 1077018528 26320720 8070464
11:29:34 6545 8192000000 9975631616 10367619072 1028500608 11.154 97 48 97 1 1066651136 28050800 10099728
11:30:41 7397 8192000000 9941894464 10334957568 1010218128 10.977 97 39 76 1 1036533456 17510384 8804944
11:31:49 5732 8192000000 9928841776 10297958400 985654400 10.739 96 38 79 0 1010571920 16077696 8839824
11:32:57 32532 8192000000 9954553952 10349940736 1023077376 11.102 98 25 49 1 1046416784 17372736 5966672
11:34:32 7557 8192000000 9985583264 10368569344 1056852384 11.426 100 30 60 0 1093548800 30111280 6585136
11:35:41 8730 8192000000 9972451552 10375327744 1060368880 11.460 100 34 67 1 1099378560 31366912 7642768
11:36:52 116203 8192000000 10008778320 10406645760 1076066208 11.610 100 25 50 0 1092475104 10939680 5469216
11:39:53 25047 8192000000 10073813520 10473160704 1105037712 11.885 100 46 89 0 1186925520 72473168 9414640
11:41:24 13217 8192000000 10112504624 10513641472 1126319760 12.087 100 45 87 1 1220005232 83588208 10097264
11:42:41 11242 8192000000 10131001264 10536378368 1139072304 12.207 100 56 113 2 1239385040 88018416 12294320
11:44:04 12231 8192000000 10154117184 10559897600 1151322976 12.322 100 50 100 1 1274911088 112649232 10938880
11:45:22 19454 8192000000 10207177648 10620612608 1158058912 12.385 100 48 91 1 1307274256 138663040 10552304
11:46:45 4701 8192000000 10254202832 10667692032 1170314992 12.500 100 54 107 2 1358892976 177172608 11405376
11:47:55 9711 8192000000 10284904880 10696802304 1168718000 12.485 99 64 126 0 1372221568 189247632 14255936
11:49:10 9833 8192000000 10302013904 10726051840 1184872896 12.636 100 61 122 1 1400802512 203002944 12926672
11:50:28 3117 8192000000 10303378608 10709377024 1168123184 12.479 99 79 157 0 1403066880 216856144 18087552
11:51:42 1841 8192000000 10369529312 10778685440 1196488480 12.744 100 82 164 0 1466102624 250255840 19358304
11:52:52 2007 8192000000 10379897728 10795388928 1197076464 12.749 100 80 159 0 1476456656 260657968 18722224
11:54:00 3177 8192000000 10420749136 10827485184 1177602816 12.568 100 80 157 1 1510127728 314550112 17974800
11:55:11 2343 8192000000 10410289040 10828816384 1164013968 12.441 99 88 175 1 1501538208 316775840 20748400
11:56:17 1612 8192000000 10358884336 10797731840 1173490192 12.529 100 84 166 1 1457031904 263878352 19663360
11:57:23 6826 8192000000 10349840192 10781622272 1137840912 12.195 99 49 96 1 1443906144 293964800 12100432
11:58:35 5629 8192000000 10287454736 10693554176 1131509312 12.136 100 55 108 1 1382109776 237029408 13571056
11:59:44 18959 8192000000 10275906080 10698297344 1131044352 12.131 100 31 62 2 1385487904 246590784 7852768
12:01:22 7416 8192000000 10284318288 10689916928 1122971072 12.055 99 38 74 3 1381566176 250217456 8377648
12:02:35 2467 8192000000 10388815104 10781696000 1151985408 12.328 100 89 177 4 1476223328 304046000 20191920
12:03:40 10116 8192000000 10396785040 10821824512 1156521360 12.371 100 41 82 0 1488262800 321415664 10325776
12:04:52 17282 8192000000 10413550256 10871005184 1151015472 12.319 100 32 64 0 1525268880 365646624 8606784
12:06:15 14134 8192000000 10361383168 10817900544 1122849840 12.054 100 32 64 0 1493171008 361582928 8738240
12:07:39 21092 8192000000 10200442800 10700951552 1125604464 12.080 99 26 52 1 1306780800 175447520 5728816
12:09:05 35594 8192000000 10143586048 10568695808 1139461712 12.210 100 17 34 0 1254846640 111680096 3704832
12:10:41 60607 8192000000 10099122464 10532671488 1130284736 12.124 99 15 30 1 1202050512 69247680 2518096
12:12:49 79146 8192000000 10070269216 10525048832 1114690624 11.977 97 18 36 0 1164163456 45792864 3679968
12:15:09 42126 8192000000 10116002368 10540539904 1122919568 12.055 98 36 71 0 1171540208 41430576 7190064
12:16:52 155044 8192000000 10078999008 10515206144 1114996464 11.980 97 28 55 1 1175422256 54413632 6012160
12:20:35 74561 8192000000 10141987904 10567131136 1104494192 11.880 96 33 65 1 1184469568 72731360 7244016
12:22:53 41706 8192000000 10217161104 10664529920 1155685904 12.363 100 24 48 1 1293773856 132010976 6076976
12:24:43 42703 8192000000 10283140928 10768486400 1171896560 12.515 100 60 120 0 1395711440 210117792 13697088
12:27:19 142142 8192000000 10195609856 10663911424 1123870384 12.064 97 16 32 0 1265095344 137773280 3451680
12:30:44 52009 8192000000 10128450992 10642247680 1128383728 12.106 99 18 36 2 1250248304 117605952 4258624
12:32:40 104587 8192000000 10190764848 10629316608 1130509904 12.126 98 19 38 0 1216360688 81689088 4161696
12:35:28 31802 8192000000 10109660496 10612097024 1137687616 12.194 99 18 36 1 1238740320 96703776 4348928
12:37:07 67212 8192000000 10144920192 10617888768 1138621136 12.203 99 20 40 0 1256379376 113644640 4113600
12:39:16 60480 8192000000 10115984784 10565230592 1126907984 12.092 98 26 50 2 1166352112 34953456 4490672
12:41:24 72761 8192000000 10095941136 10563182592 1069480080 11.547 94 14 28 0 1121242320 49130080 2632160
12:43:37 59127 8192000000 10090477136 10559361024 1113987760 11.970 99 15 30 0 1169507968 52736992 2783216
12:45:37 105721 8192000000 10063825424 10548183040 1112840928 11.959 99 30 55 3 1174372304 55724128 5807248
12:48:30 37122 8192000000 10088839840 10524434432 1116225744 11.991 99 32 62 1 1165798672 43267200 6305728
12:50:16 108081 8192000000 10120273152 10604683264 1088802608 11.731 95 20 40 0 1157028000 63827792 4397600
12:53:06 70183 8192000000 10088166928 10560262144 1095040848 11.791 97 19 38 1 1161662096 62186208 4435040
12:55:38 72953 8192000000 10066580832 10581762048 1114908496 11.979 100 20 40 0 1192795936 72828032 5059408
12:57:59 26102 8192000000 10042185424 10474496000 1082181456 11.668 98 23 46 0 1117633632 31154720 4297456
12:59:31 31943 8192000000 10093601568 10535235584 1109834768 11.931 99 22 43 2 1176203680 61189376 5179536
13:01:22 57355 8192000000 10104126304 10574548992 1097751312 11.816 99 20 40 0 1178026272 76113312 4161648
13:03:23 80418 8192000000 10102455728 10562904064 1107286128 11.907 99 26 52 1 1160846080 48583824 4976128
13:05:52 77176 8192000000 10081553792 10549891072 1100270864 11.840 98 22 44 0 1168339008 63127600 4940544
13:08:09 131348 8192000000 10070538080 10541477888 1139505840 12.211 100 29 58 1 1192876480 47439872 5930768
13:11:32 29639 8192000000 10065603040 10519494656 1134316880 12.162 100 12 22 1 1167475840 31251216 1907744
13:13:04 83807 8192000000 10055696304 10541731840 1118326704 12.011 98 8 16 0 1157055376 36741040 1987632
13:17:06 62996 8192000000 10054171296 10542411776 1115152240 11.981 99 23 45 1 1166982608 46828112 5002256
13:19:25 25228 8192000000 10077822096 10521554944 1138690896 12.203 100 42 84 1 1174595776 27798256 8106624
13:20:50 15037 8192000000 10098791904 10530119680 1134801824 12.167 99 50 98 0 1192083696 47413312 9868560
13:22:08 46987 8192000000 10098685136 10549383168 1151377264 12.322 100 32 64 0 1198573824 40935888 6260672
13:23:56 22259 8192000000 10094434576 10531414016 1129821008 12.120 98 33 66 0 1191297840 55011616 6465216
13:25:19 64006 8192000000 10119101392 10564411392 1127007728 12.093 98 23 46 1 1202554976 70897168 4650080
13:27:25 126012 8192000000 10082983696 10567065600 1052022208 11.380 92 15 30 0 1108690128 53701632 2966288
13:30:40 86785 8192000000 10055531952 10499293184 1108718784 11.920 99 20 40 0 1135730656 23074512 3937360
13:33:07 42620 8192000000 10043646144 10520477696 1098734704 11.826 98 20 40 2 1169622096 66812928 4074464
13:34:56 41261 8192000000 10061628080 10524545024 1123579520 12.061 100 20 40 0 1159598928 32015712 4003696
13:36:40 48078 8192000000 9263268560 9583730688 411407616 4.781 37 5 10 0 432056272 19500768 1147888

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 25-12-2021 13:39:21 to 25-12-2021 13:39:36.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 123 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 25-12-2021 13:39:37 to 25-12-2021 13:39:39.

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          231581
MON$OLDEST_ACTIVE               231582
MON$OLDEST_SNAPSHOT             231582
MON$NEXT_TRANSACTION            231582
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                       2084256
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             793
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2696 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2696 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2696 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2696 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 ---
:188: 
:189: mscs-test-161.budzdorov.ru    Sat Dec 25 13:37:37 2021
:190:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:191: 
--- end of diff output ---

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

25.12.21 13:39:40. 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
2021-12-25 13:39:43 No severe PSQL-related problems occured
25.12.21 13:39:44. End of report.