Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.07.2022 16: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 16119060 kB
MemAvailable 31030184 kB
Buffers 0 kB
Cached 15600308 kB
SwapCached 2516 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 3.0.11
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-07-27 16:38:27
DTS_END                         2022-07-27 16:38:27.5300
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(57:17)
IP                              ::1/58716
TRN_ID                          242662
ATT_ID                          667
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: 9192.91 26569 1103149
customer order: creation 175.91 362 21109
customer order: refuse 15.98 381 1917
order to supplier: creation 48.08 1171 5769
order to supplier: removal 3.38 96820 405
invoice (draft): creation 46.73 3009 5607
invoice (draft): removal 51.28 847 6154
invoice accept: apply 43.15 19152 5178
invoice accept: cancel 46.34 804 5561
customer reserve: creation 3982.11 190 477853
customer reserve: removal 4477.70 56 537324
realization accept: apply 86.03 201 10324
realization accept: cancel 81.27 119 9752
payment from customer: creation 61.48 116 7377
payment from customer: removal 5.97 70 716
payment to supplier: creation 51.18 140 6142
payment to supplier: removal 3.76 118 451
service: total inventory turnovers 4.79 1317 575
service: total monetary turnovers 6.93 34 832
service: aggregate perf. data 0.83 21639 99
service: refresh index statistics 0.03 384825 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 27-07-2022 16:39:44 to 27-07-2022 16:39:46.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4611
TEST_TIME 2 5505
TEST_TIME 3 3979
TEST_TIME 4 4458
TEST_TIME 5 5005
TEST_TIME 6 5709
TEST_TIME 7 6064
TEST_TIME 8 5816
TEST_TIME 9 5881
TEST_TIME 10 6007
TEST_TIME 11 6003
TEST_TIME 12 6091
TEST_TIME 13 6342
TEST_TIME 14 6501
TEST_TIME 15 6631
TEST_TIME 16 7122
TEST_TIME 17 7704
TEST_TIME 18 7989
TEST_TIME 19 8062
TEST_TIME 20 8021
TEST_TIME 21 8134
TEST_TIME 22 8381
TEST_TIME 23 8796
TEST_TIME 24 8830
TEST_TIME 25 8667
TEST_TIME 26 8556
TEST_TIME 27 8976
TEST_TIME 28 10286
TEST_TIME 29 10658
TEST_TIME 30 10408
TEST_TIME 31 10297
TEST_TIME 32 10106
TEST_TIME 33 10165
TEST_TIME 34 10018
TEST_TIME 35 9924
TEST_TIME 36 9877
TEST_TIME 37 9875
TEST_TIME 38 9761
TEST_TIME 39 9660
TEST_TIME 40 9677
TEST_TIME 41 9596
TEST_TIME 42 9459
TEST_TIME 43 9324
TEST_TIME 44 9336
TEST_TIME 45 9430
TEST_TIME 46 9289
TEST_TIME 47 9290
TEST_TIME 48 9351
TEST_TIME 49 9421
TEST_TIME 50 9372
TEST_TIME 51 9434
TEST_TIME 52 9408
TEST_TIME 53 9454
TEST_TIME 54 9442
TEST_TIME 55 9427
TEST_TIME 56 9421
TEST_TIME 57 9592
TEST_TIME 58 9550
TEST_TIME 59 9604
TEST_TIME 60 9767
TEST_TIME 61 9671
TEST_TIME 62 9619
TEST_TIME 63 9500
TEST_TIME 64 9438
TEST_TIME 65 9420
TEST_TIME 66 9378
TEST_TIME 67 9444
TEST_TIME 68 9425
TEST_TIME 69 9388
TEST_TIME 70 9443
TEST_TIME 71 9419
TEST_TIME 72 9403
TEST_TIME 73 9388
TEST_TIME 74 9364
TEST_TIME 75 9449
TEST_TIME 76 9488
TEST_TIME 77 9472
TEST_TIME 78 9484
TEST_TIME 79 9536
TEST_TIME 80 9525
TEST_TIME 81 9482
TEST_TIME 82 9419
TEST_TIME 83 9392
TEST_TIME 84 9373
TEST_TIME 85 9349
TEST_TIME 86 9341
TEST_TIME 87 9362
TEST_TIME 88 9345
TEST_TIME 89 9323
TEST_TIME 90 9268
TEST_TIME 91 9222
TEST_TIME 92 9210
TEST_TIME 93 9200
TEST_TIME 94 9185
TEST_TIME 95 9233
TEST_TIME 96 9233
TEST_TIME 97 9221
TEST_TIME 98 9272
TEST_TIME 99 9309
TEST_TIME 100 9378
TEST_TIME 101 9374
TEST_TIME 102 9377
TEST_TIME 103 9374
TEST_TIME 104 9444
TEST_TIME 105 9467
TEST_TIME 106 9419
TEST_TIME 107 9473
TEST_TIME 108 9415
TEST_TIME 109 9378
TEST_TIME 110 9339
TEST_TIME 111 9330
TEST_TIME 112 9331
TEST_TIME 113 9316
TEST_TIME 114 9290
TEST_TIME 115 9276
TEST_TIME 116 9261
TEST_TIME 117 9280
TEST_TIME 118 9269
TEST_TIME 119 9256
TEST_TIME 120 9199

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 27-07-2022 16:39:48 to 27-07-2022 16:39:51.

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 1545345 1545345 0 0.00 0 29648 7 0 0
doc_list_biud 1545345 1545345 0 0.00 0 66339 26 0 0
sp_add_invoice_to_stock 5315 5178 137 2.57 20 203999 19152 0 137
sp_cancel_adding_invoice 5561 5561 0 0.00 4 68717 804 0 0
sp_cancel_client_order 1917 1917 0 0.00 3 13455 382 0 0
sp_cancel_customer_reserve 537324 537324 0 0.00 0 66342 56 0 0
sp_cancel_pay_from_customer 716 716 0 0.00 2 4518 71 0 0
sp_cancel_pay_to_supplier 451 451 0 0.00 3 16890 119 0 0
sp_cancel_supplier_invoice 6162 6154 8 0.12 1 30159 847 0 8
sp_cancel_supplier_order 405 405 0 0.00 10 1002174 96821 0 0
sp_cancel_write_off 9752 9752 0 0.00 1 16219 120 0 0
sp_client_order 21109 21109 0 0.00 3 25723 363 0 0
sp_customer_reserve 477853 477853 0 0.00 0 109285 191 0 0
sp_fill_shopping_cart 32481 32481 0 0.00 1 9054 35 0 0
sp_fill_shopping_cart_clo_res 477847 477847 0 0.00 0 22839 15 0 0
sp_get_clo_for_invoice 5165 5165 0 0.00 0 44561 313 0 0
sp_kill_cost_storno 16480 16480 0 0.00 0 16880 59 0 0
sp_kill_qstorno_ret_qs2qd 1090959 1090959 0 0.00 0 66336 31 0 0
sp_kill_qty_storno 576602 576602 0 0.00 0 66339 68 0 0
sp_lock_selected_doc 21536 21536 0 0.00 0 6040 4 0 0
sp_make_cost_storno 29008 29008 0 0.00 0 19791 52 0 0
sp_multiply_rows_for_pdistr 29008 29008 0 0.00 0 19953 33 0 0
sp_multiply_rows_for_qdistr 21109 21109 0 0.00 0 16064 151 0 0
sp_pay_from_customer 7377 7377 0 0.00 3 19963 117 0 0
sp_pay_to_supplier 6142 6142 0 0.00 4 23638 140 0 0
sp_payment_common 13519 13519 0 0.00 1 18363 41 0 0
sp_qd_handle_on_cancel_clo 1917 1917 0 0.00 0 13444 336 0 0
sp_qd_handle_on_invoice_upd_sts 10726 10726 0 0.00 0 26508 332 0 0
sp_qd_handle_on_reserve_upd_sts 10324 10324 0 0.00 0 29663 74 0 0
sp_reserve_write_off 10324 10324 0 0.00 6 29682 202 0 0
sp_supplier_invoice 5607 5607 0 0.00 7 76194 3009 0 0
sp_supplier_order 5769 5769 0 0.00 4 22956 1172 0 0
srv_aggregate_perf_data 99 99 0 0.00 2036 547597 21639 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 996 134086 43433 0 0
srv_find_qd_qs_mism 1053635 1053635 0 0.00 0 15469 6 0 0
srv_make_invnt_saldo 575 575 0 0.00 52 88314 1317 0 0
srv_make_money_saldo 832 832 0 0.00 1 4742 35 0 0
srv_recalc_idx_stat 4 4 0 0.00 255817 547335 384826 0 0
t$perf-abend:sp_add_invoice_to_stock 137 137 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 5178 5178 0 0.00 0 22686 187 0 0
t$perf-norm:sp_cancel_adding_invoice 434 434 0 0.00 0 7736 90 0 0
t$perf-norm:sp_cancel_client_order 1917 1917 0 0.00 0 3012 11 0 0
t$perf-norm:sp_cancel_customer_reserve 698 698 0 0.00 0 2193 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 716 716 0 0.00 0 4179 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 451 451 0 0.00 0 310 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 437 437 0 0.00 0 6163 31 0 0
t$perf-norm:sp_cancel_supplier_order 405 405 0 0.00 0 26016 1740 0 0
t$perf-norm:sp_cancel_write_off 989 989 0 0.00 0 3704 9 0 0
t$perf-norm:sp_client_order 21109 21109 0 0.00 0 17561 19 0 0
t$perf-norm:sp_customer_reserve 1020 1020 0 0.00 0 4268 8 0 0
t$perf-norm:sp_pay_from_customer 7377 7377 0 0.00 0 7899 14 0 0
t$perf-norm:sp_pay_to_supplier 6142 6142 0 0.00 0 7429 11 0 0
t$perf-norm:sp_reserve_write_off 10324 10324 0 0.00 0 8688 13 0 0
t$perf-norm:sp_supplier_invoice 5607 5607 0 0.00 0 5590 18 0 0
t$perf-norm:sp_supplier_order 5769 5769 0 0.00 0 3586 17 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 63 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 1125 25 0 0
t$perf-norm:srv_make_invnt_saldo 575 575 0 0.00 0 871 26 0 0
t$perf-norm:srv_make_money_saldo 832 832 0 0.00 0 3694 25 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 3 0 0
v_add_invoice_to_stock 10604 10330 274 2.58 0 10904 23 0 274
v_all_customers 38640 38640 0 0.00 0 19 1 0 0
v_all_suppliers 31548 31548 0 0.00 0 24 1 0 0
v_all_wares 42216 42216 0 0.00 0 25 1 0 0
v_cancel_adding_invoice 868 868 0 0.00 0 10919 34 0 0
v_cancel_client_order 3834 3834 0 0.00 0 3095 7 0 0
v_cancel_customer_prepayment 1432 1432 0 0.00 0 3002 5 0 0
v_cancel_customer_reserve 1396 1396 0 0.00 0 1020 5 0 0
v_cancel_payment_to_supplier 902 902 0 0.00 0 1757 5 0 0
v_cancel_supplier_invoice 890 874 16 1.79 0 9902 37 0 16
v_cancel_supplier_order 810 810 0 0.00 0 761 4 0 0
v_cancel_write_off 1978 1978 0 0.00 0 4427 24 0 0
v_max_id_clo_ord 5769 5769 0 0.00 0 751 2 0 0
v_max_id_clo_res 1020 1020 0 0.00 0 1336 13 0 0
v_max_id_ord_sup 5604 5604 0 0.00 0 4542 5 0 0
v_max_non_paid_invoice 1741 1741 0 0.00 0 15214 14 0 0
v_max_non_paid_realizn 3859 3859 0 0.00 0 11234 8 0 0
v_min_id_clo_ord 5769 5769 0 0.00 0 6821 8 0 0
v_min_id_clo_res 1020 1020 0 0.00 0 4530 12 0 0
v_min_id_ord_sup 5604 5604 0 0.00 0 5992 7 0 0
v_min_non_paid_invoice 6142 6142 0 0.00 0 17345 30 0 0
v_min_non_paid_realizn 7377 7377 0 0.00 0 10915 26 0 0
v_our_firm 10612 10612 0 0.00 0 16 1 0 0
v_reserve_write_off 20648 20648 0 0.00 0 5531 8 0 0
x_lock_depdocs_on_canc_invoice 11278 11278 0 0.00 0 12781 109 0 0
x_lock_depdocs_on_canc_sup_ord 405 405 0 0.00 0 23315 500 0 0
x_make_qstorno_1000_1200 5769 5769 0 0.00 1 22944 1099 0 0
x_make_qstorno_1200_2000 5607 5607 0 0.00 3 75852 2883 0 0
x_make_qstorno_2100_3300 477853 477853 0 0.00 0 109284 173 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-07-2022 16:39:52 to 27-07-2022 16:39:56.

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
14:08:31 7 8192000000 8801882576 8813973504 7908448 0.096 2 2 3 0 8049536 66144 74944
14:09:31 30007 8192000000 9896719952 10206392320 976110768 10.646 100 85 170 0 1004014192 5549024 22354400
14:11:05 22700 8192000000 9945942080 10266669056 1028365216 11.153 100 85 170 0 1057998000 7247344 22385440
14:12:33 14211 8192000000 9973715024 10305687552 1064254496 11.497 100 78 154 1 1092246432 8720432 19271504
14:13:51 11659 8192000000 10028323072 10373312512 1115748960 11.987 100 74 147 0 1142827760 9494752 17584048
14:15:05 9666 8192000000 10080149296 10437484544 1161266320 12.415 100 82 164 0 1191673648 11248608 19158720
14:16:17 5911 8192000000 10114207552 10483527680 1202331584 12.798 100 76 150 1 1232995600 13936288 16727728
14:17:26 3488 8192000000 10140125696 10517032960 1237808784 13.126 100 77 153 0 1266945472 12139328 16997360
14:18:31 2350 8192000000 10185004992 10571390976 1276610512 13.482 100 73 140 0 1302514368 10138720 15765136
14:19:36 2011 8192000000 10208892512 10592247808 1299803312 13.693 100 86 172 1 1332145216 13552928 18788976
14:20:39 44236 8192000000 10204925824 10610339840 1309039600 13.777 100 50 100 2 1327108464 6437632 11631232
14:22:25 4941 8192000000 10204104480 10615021568 1314071232 13.823 100 32 62 0 1327770320 7208256 6490832
14:23:32 7098 8192000000 10199295312 10619432960 1317044400 13.850 100 25 50 0 1330369712 7046112 6279200
14:24:40 9886 8192000000 10215704480 10630877184 1325478704 13.926 100 14 28 1 1334563264 5877264 3207296
14:25:51 50040 8192000000 10218844688 10640125952 1329463408 13.962 100 35 71 0 1343231920 4729936 9038576
14:27:41 5418 8192000000 10226293920 10642493440 1330476848 13.971 100 41 80 1 1350742960 11735536 8530576
14:28:48 9625 8192000000 10214564176 10635522048 1325424704 13.926 100 20 40 0 1335438592 5941360 4072528
14:30:00 15746 8192000000 10174749920 10584502272 1256095424 13.294 97 12 25 0 1264154688 5111792 2947472
14:31:19 19512 8192000000 10141341440 10539016192 1134049488 12.160 89 8 16 0 1140276832 4392032 1835312
14:32:39 3904 8192000000 10141025744 10534240256 1242204464 13.167 100 46 92 0 1259876112 6485824 11185824
14:33:44 8114 8192000000 10152485504 10552418304 1259564816 13.326 100 23 46 1 1270550336 5205248 5780272
14:34:55 75372 8192000000 10167829216 10579963904 1261375904 13.343 98 30 58 2 1273101088 4954848 6770336
14:37:14 5253 8192000000 10185213440 10599518208 1297726816 13.675 100 47 92 0 1315342416 6370272 11245328
14:38:20 11249 8192000000 10218678800 10629300224 1319754880 13.874 100 20 41 0 1329424800 5262096 4407824
14:39:38 8139 8192000000 10245555824 10651426816 1338557856 14.044 100 61 122 1 1361931264 10159520 13213888
14:40:53 114212 8192000000 10254009840 10679431168 1348836480 14.137 100 34 68 1 1366428336 9307488 8284368
14:43:55 9903 8192000000 10291801888 10707382272 1371099792 14.337 100 65 128 2 1400063984 14356848 14607344
14:45:09 6927 8192000000 10298430480 10717388800 1380773344 14.423 100 66 130 0 1411115584 15167392 15174848
14:46:23 11422 8192000000 10310610832 10730893312 1391199184 14.517 100 47 94 0 1416602976 14600448 10803344
14:47:40 10526 8192000000 10329574544 10751275008 1405705376 14.646 100 54 106 0 1438986320 20220544 13060400
14:48:56 6669 8192000000 10339079760 10760749056 1413740432 14.717 100 61 117 0 1449710992 21743136 14227424
14:50:08 8501 8192000000 10346354288 10771369984 1415553872 14.733 100 60 120 0 1460119920 30024320 14541728
14:51:23 4462 8192000000 10351841808 10772746240 1413089472 14.711 100 50 100 0 1459105856 33865088 12151296
14:52:33 7610 8192000000 10354486656 10773401600 1411667152 14.699 100 68 133 0 1465043760 36826352 16550256
14:53:45 994 8192000000 10359064768 10768592896 1399557248 14.591 100 87 171 2 1462573120 43003216 20012656
14:54:52 6812 8192000000 10336756928 10751512576 1381388880 14.429 100 75 150 0 1446719472 46795264 18535328
14:56:07 3036 8192000000 10363290192 10777948160 1403564064 14.627 100 65 126 0 1463435744 44492128 15379552
14:57:12 1593 8192000000 10343163728 10763956224 1392606608 14.529 100 59 118 1 1450866736 42882624 15377504
14:58:25 4581 8192000000 10333376784 10752520192 1379982832 14.416 100 56 115 0 1442821248 48386432 14451984
14:59:37 2530 8192000000 10350101728 10768429056 1390619744 14.511 100 63 123 3 1455287504 49289888 15377872
15:00:45 7541 8192000000 10339670944 10761617408 1379399552 14.411 99 39 78 2 1437079312 47651200 10028560
15:02:01 8239 8192000000 10317375616 10747838464 1376881120 14.389 100 34 68 0 1427080528 40990064 9209344
15:03:14 15959 8192000000 10321079536 10751680512 1374447136 14.367 99 31 62 0 1424003280 41199168 8356976
15:04:44 15833 8192000000 10334071552 10766422016 1374066096 14.363 100 32 64 0 1441266048 57751696 9448256
15:06:07 29250 8192000000 10324226832 10770726912 1360007760 14.237 99 22 44 0 1428237760 61516416 6713584
15:07:48 19693 8192000000 10275625936 10706341888 1338500144 14.044 98 32 64 0 1355312992 9313744 7499104
15:09:09 37232 8192000000 10272550000 10701930496 1339142064 14.050 98 30 58 0 1356810864 10974768 6694032
15:10:47 60873 8192000000 10273625136 10719096832 1351880768 14.164 99 50 98 1 1377586448 14091472 11614208
15:12:57 14779 8192000000 10251677040 10692022272 1348075632 14.130 100 20 40 0 1361360992 8961168 4324192
15:14:17 47944 8192000000 10269248896 10713161728 1349166848 14.140 99 40 75 1 1365918352 8504800 8246704
15:16:28 134086 8192000000 10258542384 10713518080 1333847216 14.002 97 24 48 0 1347755984 8671680 5237088
15:19:49 51425 8192000000 10274446528 10726739968 1370589600 14.332 100 13 26 0 1384869712 11007328 3272784
15:21:42 76206 8192000000 10278580896 10737971200 1378351264 14.402 100 18 34 0 1402610448 19493824 4765360
15:24:03 78214 8192000000 10286046208 10740981760 1388197728 14.490 100 19 38 0 1404346032 11708960 4439344
15:26:28 76585 8192000000 10295213232 10748235776 1390946944 14.514 100 14 28 0 1403928384 9795984 3185456
15:28:44 108845 8192000000 10294144640 10756558848 1369951360 14.327 98 18 36 1 1384671072 10778096 3941616
15:31:35 35230 8192000000 10284939696 10746372096 1365589712 14.288 98 17 35 0 1378615728 9208688 3817328
15:33:20 42884 8192000000 10287628288 10744147968 1347403712 14.124 97 20 40 0 1365530064 13561056 4565296
15:35:09 48085 8192000000 10275669216 10726756352 1343460304 14.089 97 22 44 1 1368945072 20436624 5048144
15:37:02 50545 8192000000 10293448672 10740035584 1329754800 13.965 96 21 42 0 1354930624 20171696 5004128
15:39:07 40926 8192000000 10287463168 10715389952 1306578880 13.755 94 47 91 1 1324968800 7727872 10662048
15:40:53 56399 8192000000 10293157408 10725806080 1360520432 14.242 99 37 70 1 1378779376 8003184 10255760
15:42:53 16443 8192000000 10260929296 10692038656 1339638048 14.054 98 37 74 0 1362021488 14088480 8294960
15:44:16 81634 8192000000 10257233056 10715017216 1334566192 14.008 98 13 26 1 1358090672 20332768 3191712
15:46:40 34971 8192000000 10277851424 10718670848 1367794400 14.307 100 24 47 1 1384170464 10612912 5763152
15:48:19 46301 8192000000 10259625472 10719555584 1359290336 14.231 99 18 36 0 1374740528 11152128 4298064
15:50:06 26066 8192000000 10301289056 10748198912 1388010400 14.488 100 49 95 0 1413803712 14829872 10963440
15:51:32 15325 8192000000 10295574944 10728665088 1359709728 14.235 98 53 106 2 1382064176 10538016 11816432
15:52:49 44104 8192000000 10268213248 10720051200 1340514560 14.062 98 12 24 1 1358972560 15717648 2740352
15:54:33 111493 8192000000 10271290192 10712776704 1303285936 13.725 95 16 32 0 1318923152 12078496 3558720
15:57:25 71565 8192000000 10263105024 10707767296 1274580368 13.463 93 13 26 0 1299005824 21574656 2850800
15:59:43 119320 8192000000 10252813648 10707808256 1319908096 13.876 97 28 57 0 1335783680 9838688 6036896
16:02:43 24066 8192000000 10289934848 10728345600 1372619472 14.351 100 48 94 0 1397800112 14762080 10418560
16:04:08 58839 8192000000 10269294944 10726113280 1361184240 14.248 99 12 24 0 1375585952 11509584 2892128
16:07:05 59373 8192000000 10273301968 10733330432 1364341744 14.276 99 13 24 0 1373347552 6240256 2765552
16:09:10 39588 8192000000 10276453744 10726273024 1368851296 14.317 99 18 37 1 1379905920 6963264 4091360
16:10:50 59025 8192000000 10258998336 10724306944 1336663168 14.027 97 5 10 0 1348693840 11142864 887808
16:12:50 58650 8192000000 10293745744 10756468736 1379196480 14.409 99 14 28 0 1393998688 11790128 3012080
16:14:59 44371 8192000000 10305006224 10759536640 1391501328 14.519 99 32 64 1 1412216896 13467840 7247728
16:16:50 70235 8192000000 10296366544 10761773056 1339837632 14.056 96 11 22 1 1356676336 14612944 2225760
16:19:00 61686 8192000000 10286153184 10736553984 1338986032 14.048 97 21 42 1 1356247472 12521024 4740416
16:21:06 34897 8192000000 10303004944 10747224064 1367601568 14.306 99 45 90 0 1397484064 19749136 10133360
16:22:42 127766 8192000000 10294888288 10735656960 1313220896 13.815 95 15 31 1 1341486240 25001392 3263952
16:25:54 89720 8192000000 10263715968 10713563136 1355599552 14.198 99 25 50 0 1371471904 9769872 6102480
16:28:24 47856 8192000000 10276792864 10727018496 1346114528 14.113 98 31 62 1 1371399328 17341392 7943408
16:30:15 66683 8192000000 10261613408 10722590720 1360239616 14.240 99 11 22 0 1371133904 8682768 2211520
16:32:23 63403 8192000000 10266293008 10716389376 1324969616 13.922 96 14 29 0 1335021312 7128880 2922816
16:34:34 57127 8192000000 10266431680 10720239616 1347547584 14.125 98 15 29 1 1359106496 8489616 3069296
16:36:39 60047 8192000000 10268786400 10727931904 1343378304 14.088 97 17 34 0 1354637184 7830784 3428096

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 27-07-2022 16:39:58 to 27-07-2022 16:40:13.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 137 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 27-07-2022 16:40:14 to 27-07-2022 16:40:16.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          242169
MON$OLDEST_ACTIVE               243740
MON$OLDEST_SNAPSHOT             243740
MON$NEXT_TRANSACTION            243740
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-03 16:41:55.3470
MON$PAGES                       2017472
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33613 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33613 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33613 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33613 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:270: 
:271: mscs-test-161.budzdorov.ru    Wed Jul 27 16:38:31 2022
:272:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:273: 
:274: 
:275: mscs-test-161.budzdorov.ru    Wed Jul 27 16:38:32 2022
:276:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:277: 
:278: 
:279: mscs-test-161.budzdorov.ru    Wed Jul 27 16:38:33 2022
:280:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:281: 
:282: 
:283: mscs-test-161.budzdorov.ru    Wed Jul 27 16:38:33 2022
:284:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:285: 
:286: 
:287: mscs-test-161.budzdorov.ru    Wed Jul 27 16:38:33 2022
:288:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:289: 
:290: 
:291: mscs-test-161.budzdorov.ru    Wed Jul 27 16:38:33 2022
:292:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:293: 
:294: 
:295: mscs-test-161.budzdorov.ru    Wed Jul 27 16:38:33 2022
:296:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:297: 
:298: 
:299: mscs-test-161.budzdorov.ru    Wed Jul 27 16:38:34 2022
:300:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:301: 
--- end of diff output ---

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

27.07.22 16:40:17. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-07-27 16:40:19 No severe PSQL-related problems occured
27.07.22 16:40:20. End of report.