Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.01.2023 16:40
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 15410812 kB
MemAvailable 30413580 kB
Buffers 0 kB
Cached 16326552 kB
SwapCached 5560 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 2023-01-03 16:38:37
DTS_END                         2023-01-03 16:38:37.6430
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/44686
TRN_ID                          241941
ATT_ID                          662
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: 8943.29 23203 1073195
customer order: creation 170.66 369 20479
customer order: refuse 14.52 390 1742
order to supplier: creation 45.48 1338 5458
order to supplier: removal 3.27 108754 392
invoice (draft): creation 45.57 3532 5468
invoice (draft): removal 50.03 822 6004
invoice accept: apply 41.63 17949 4995
invoice accept: cancel 44.98 827 5398
customer reserve: creation 3783.70 180 454044
customer reserve: removal 4448.18 63 533781
realization accept: apply 83.63 193 10035
realization accept: cancel 82.14 119 9857
payment from customer: creation 58.34 108 7001
payment from customer: removal 5.41 84 649
payment to supplier: creation 50.26 125 6031
payment to supplier: removal 3.54 70 425
service: total inventory turnovers 4.55 1100 546
service: total monetary turnovers 6.49 28 779
service: aggregate perf. data 0.89 21104 107
service: refresh index statistics 0.03 306896 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-01-2023 16:41:07 to 03-01-2023 16:41:09.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3063
TEST_TIME 2 2614
TEST_TIME 3 2910
TEST_TIME 4 3911
TEST_TIME 5 3581
TEST_TIME 6 4391
TEST_TIME 7 4342
TEST_TIME 8 4468
TEST_TIME 9 4706
TEST_TIME 10 4776
TEST_TIME 11 5062
TEST_TIME 12 5173
TEST_TIME 13 5281
TEST_TIME 14 5573
TEST_TIME 15 6118
TEST_TIME 16 6118
TEST_TIME 17 6351
TEST_TIME 18 6794
TEST_TIME 19 7737
TEST_TIME 20 7571
TEST_TIME 21 7564
TEST_TIME 22 7676
TEST_TIME 23 8048
TEST_TIME 24 8294
TEST_TIME 25 8733
TEST_TIME 26 9016
TEST_TIME 27 9458
TEST_TIME 28 9882
TEST_TIME 29 10398
TEST_TIME 30 10066
TEST_TIME 31 9969
TEST_TIME 32 9852
TEST_TIME 33 9959
TEST_TIME 34 9989
TEST_TIME 35 9817
TEST_TIME 36 9651
TEST_TIME 37 9731
TEST_TIME 38 9599
TEST_TIME 39 9530
TEST_TIME 40 9447
TEST_TIME 41 9356
TEST_TIME 42 9296
TEST_TIME 43 9276
TEST_TIME 44 9203
TEST_TIME 45 9100
TEST_TIME 46 9070
TEST_TIME 47 9047
TEST_TIME 48 9013
TEST_TIME 49 8996
TEST_TIME 50 8888
TEST_TIME 51 8882
TEST_TIME 52 8836
TEST_TIME 53 8804
TEST_TIME 54 8778
TEST_TIME 55 8846
TEST_TIME 56 8867
TEST_TIME 57 8869
TEST_TIME 58 8849
TEST_TIME 59 8958
TEST_TIME 60 8949
TEST_TIME 61 9010
TEST_TIME 62 9108
TEST_TIME 63 9162
TEST_TIME 64 9160
TEST_TIME 65 9193
TEST_TIME 66 9194
TEST_TIME 67 9321
TEST_TIME 68 9255
TEST_TIME 69 9206
TEST_TIME 70 9163
TEST_TIME 71 9080
TEST_TIME 72 9080
TEST_TIME 73 9028
TEST_TIME 74 9034
TEST_TIME 75 9040
TEST_TIME 76 9029
TEST_TIME 77 9001
TEST_TIME 78 8961
TEST_TIME 79 8882
TEST_TIME 80 8902
TEST_TIME 81 8946
TEST_TIME 82 8959
TEST_TIME 83 8925
TEST_TIME 84 8979
TEST_TIME 85 9068
TEST_TIME 86 9104
TEST_TIME 87 9096
TEST_TIME 88 9087
TEST_TIME 89 9155
TEST_TIME 90 9289
TEST_TIME 91 9277
TEST_TIME 92 9327
TEST_TIME 93 9300
TEST_TIME 94 9238
TEST_TIME 95 9170
TEST_TIME 96 9171
TEST_TIME 97 9147
TEST_TIME 98 9124
TEST_TIME 99 9122
TEST_TIME 100 9096
TEST_TIME 101 9075
TEST_TIME 102 9051
TEST_TIME 103 9098
TEST_TIME 104 9092
TEST_TIME 105 9119
TEST_TIME 106 9086
TEST_TIME 107 9131
TEST_TIME 108 9136
TEST_TIME 109 9118
TEST_TIME 110 9150
TEST_TIME 111 9143
TEST_TIME 112 9125
TEST_TIME 113 9093
TEST_TIME 114 9048
TEST_TIME 115 9020
TEST_TIME 116 8989
TEST_TIME 117 8984
TEST_TIME 118 9007
TEST_TIME 119 8983
TEST_TIME 120 8944

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 03-01-2023 16:41:10 to 03-01-2023 16:41: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 1495897 1495897 0 0.00 0 19246 6 0 0
doc_list_biud 1495896 1495896 0 0.00 0 84663 29 0 0
sp_add_invoice_to_stock 5096 4995 101 1.98 13 176282 17950 0 101
sp_cancel_adding_invoice 5398 5398 0 0.00 5 74225 828 0 0
sp_cancel_client_order 1742 1742 0 0.00 4 12526 391 0 0
sp_cancel_customer_reserve 533781 533781 0 0.00 0 84664 64 0 0
sp_cancel_pay_from_customer 649 649 0 0.00 3 4117 84 0 0
sp_cancel_pay_to_supplier 425 425 0 0.00 3 2757 70 0 0
sp_cancel_supplier_invoice 6018 6004 14 0.23 2 16840 822 0 14
sp_cancel_supplier_order 392 392 0 0.00 8 1137688 108754 0 0
sp_cancel_write_off 9857 9857 0 0.00 1 11499 120 0 0
sp_client_order 20479 20479 0 0.00 3 21870 369 0 0
sp_customer_reserve 454044 454044 0 0.00 0 116716 181 0 0
sp_fill_shopping_cart 31404 31404 0 0.00 1 31020 45 0 0
sp_fill_shopping_cart_clo_res 454042 454042 0 0.00 0 16096 15 0 0
sp_get_clo_for_invoice 4993 4993 0 0.00 0 27193 343 0 0
sp_kill_cost_storno 16329 16329 0 0.00 0 9631 56 0 0
sp_kill_qstorno_ret_qs2qd 1083815 1083815 0 0.00 0 84661 35 0 0
sp_kill_qty_storno 572201 572201 0 0.00 0 84663 75 0 0
sp_lock_selected_doc 20657 20657 0 0.00 0 9978 4 0 0
sp_make_cost_storno 28060 28060 0 0.00 0 10657 55 0 0
sp_multiply_rows_for_pdistr 28059 28059 0 0.00 0 5266 24 0 0
sp_multiply_rows_for_qdistr 20479 20479 0 0.00 0 19236 164 0 0
sp_pay_from_customer 7001 7001 0 0.00 3 13648 109 0 0
sp_pay_to_supplier 6031 6031 0 0.00 5 19723 125 0 0
sp_payment_common 13032 13032 0 0.00 2 19391 30 0 0
sp_qd_handle_on_cancel_clo 1742 1742 0 0.00 0 12519 366 0 0
sp_qd_handle_on_invoice_upd_sts 10390 10390 0 0.00 0 28477 341 0 0
sp_qd_handle_on_reserve_upd_sts 10035 10035 0 0.00 0 10770 78 0 0
sp_reserve_write_off 10035 10035 0 0.00 6 11994 193 0 0
sp_supplier_invoice 5468 5468 0 0.00 6 99485 3533 0 0
sp_supplier_order 5458 5458 0 0.00 4 53235 1338 0 0
srv_aggregate_perf_data 107 107 0 0.00 3160 292679 21104 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1637 145946 47283 0 0
srv_find_qd_qs_mism 1026997 1026997 0 0.00 0 19246 5 0 0
srv_make_invnt_saldo 546 546 0 0.00 21 27066 1100 0 0
srv_make_money_saldo 779 779 0 0.00 1 2526 29 0 0
srv_recalc_idx_stat 4 4 0 0.00 193931 454519 306896 0 0
t$perf-abend:sp_add_invoice_to_stock 101 101 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4995 4995 0 0.00 0 12461 154 0 0
t$perf-norm:sp_cancel_adding_invoice 394 394 0 0.00 0 3380 99 0 0
t$perf-norm:sp_cancel_client_order 1742 1742 0 0.00 0 3453 13 0 0
t$perf-norm:sp_cancel_customer_reserve 667 667 0 0.00 0 1558 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 649 649 0 0.00 0 3472 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 425 425 0 0.00 0 3148 17 0 0
t$perf-norm:sp_cancel_supplier_invoice 400 400 0 0.00 0 855 16 0 0
t$perf-norm:sp_cancel_supplier_order 392 392 0 0.00 0 25507 1653 0 0
t$perf-norm:sp_cancel_write_off 961 961 0 0.00 0 2595 9 0 0
t$perf-norm:sp_client_order 20479 20479 0 0.00 0 23377 19 0 0
t$perf-norm:sp_customer_reserve 1001 1001 0 0.00 0 7109 12 0 0
t$perf-norm:sp_pay_from_customer 7001 7001 0 0.00 0 5033 9 0 0
t$perf-norm:sp_pay_to_supplier 6031 6031 0 0.00 0 15470 17 0 0
t$perf-norm:sp_reserve_write_off 10035 10035 0 0.00 0 5070 11 0 0
t$perf-norm:sp_supplier_invoice 5468 5468 0 0.00 0 5309 16 0 0
t$perf-norm:sp_supplier_order 5458 5458 0 0.00 0 8080 21 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 68 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 3057 49 0 0
t$perf-norm:srv_make_invnt_saldo 546 546 0 0.00 0 4140 23 0 0
t$perf-norm:srv_make_money_saldo 779 779 0 0.00 0 3318 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10186 9984 202 1.98 0 9265 19 0 202
v_all_customers 37412 37412 0 0.00 0 88 1 0 0
v_all_suppliers 30406 30406 0 0.00 0 19 1 0 0
v_all_wares 40958 40958 0 0.00 0 26 1 0 0
v_cancel_adding_invoice 788 788 0 0.00 0 1411 10 0 0
v_cancel_client_order 3484 3484 0 0.00 0 2131 6 0 0
v_cancel_customer_prepayment 1298 1298 0 0.00 0 4088 13 0 0
v_cancel_customer_reserve 1334 1334 0 0.00 0 2231 7 0 0
v_cancel_payment_to_supplier 850 850 0 0.00 0 535 4 0 0
v_cancel_supplier_invoice 828 800 28 3.38 0 3845 22 0 28
v_cancel_supplier_order 784 784 0 0.00 0 692 5 0 0
v_cancel_write_off 1922 1922 0 0.00 0 1127 13 0 0
v_max_id_clo_ord 5457 5457 0 0.00 0 563 1 0 0
v_max_id_clo_res 1001 1001 0 0.00 0 2062 13 0 0
v_max_id_ord_sup 5468 5468 0 0.00 0 3986 4 0 0
v_max_non_paid_invoice 1753 1753 0 0.00 0 1630 3 0 0
v_max_non_paid_realizn 3622 3622 0 0.00 0 4752 9 0 0
v_min_id_clo_ord 5457 5457 0 0.00 0 19100 16 0 0
v_min_id_clo_res 1001 1001 0 0.00 0 802 3 0 0
v_min_id_ord_sup 5468 5468 0 0.00 0 6514 13 0 0
v_min_non_paid_invoice 6031 6031 0 0.00 0 19386 17 0 0
v_min_non_paid_realizn 7001 7001 0 0.00 0 13318 24 0 0
v_our_firm 10304 10304 0 0.00 0 19 1 0 0
v_reserve_write_off 20070 20070 0 0.00 0 3869 7 0 0
x_lock_depdocs_on_canc_invoice 11002 11002 0 0.00 0 15595 118 0 0
x_lock_depdocs_on_canc_sup_ord 392 392 0 0.00 0 4375 326 0 0
x_make_qstorno_1000_1200 5458 5458 0 0.00 1 53030 1253 0 0
x_make_qstorno_1200_2000 5468 5468 0 0.00 3 99477 3363 0 0
x_make_qstorno_2100_3300 454044 454044 0 0.00 0 116714 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-01-2023 16:41:15 to 03-01-2023 16:41: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
14:08:40 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:41 21891 8192000000 9889347808 10198437888 975946816 10.645 100 88 175 0 1004831632 5899040 22985776
14:11:13 19347 8192000000 9931214512 10251251712 1016957232 11.043 100 86 170 0 1046848608 8012208 21879168
14:12:33 11275 8192000000 9959708912 10284924928 1044678352 11.310 100 85 170 0 1075159440 9113008 21368080
14:13:52 10050 8192000000 10009283456 10354520064 1103732208 11.873 100 71 143 0 1129973424 9149168 17092048
14:15:05 9930 8192000000 10071339136 10428973056 1164009632 12.441 100 79 158 0 1193727712 11548640 18169440
14:16:16 3900 8192000000 10121586560 10488532992 1207450096 12.845 100 81 164 0 1239420112 13712480 18257536
14:17:22 1765 8192000000 10155337648 10528944128 1242133088 13.166 100 90 179 0 1277039824 15168032 19738704
14:18:26 959 8192000000 10193709936 10571022336 1271214112 13.433 100 97 192 1 1307404080 14976640 21213328
14:19:28 771 8192000000 10212612512 10596634624 1295308320 13.653 100 93 190 1 1329698960 13261280 21129360
14:20:29 10296 8192000000 10204143264 10604122112 1298783328 13.684 100 54 108 0 1317880800 6421456 12676016
14:21:40 4679 8192000000 10216071712 10629320704 1313209392 13.815 100 30 60 1 1327184864 7872544 6102928
14:22:46 9291 8192000000 10206514992 10627194880 1320813872 13.884 100 17 34 0 1330175728 5661840 3700016
14:23:57 12395 8192000000 10214931696 10634964992 1326436224 13.935 100 14 28 0 1335349104 5299872 3613008
14:25:09 49647 8192000000 10242710624 10650787840 1334015088 14.003 100 46 92 0 1350553888 6152480 10386320
14:27:00 5760 8192000000 10220404560 10635325440 1317118272 13.851 99 27 54 0 1330453712 7206672 6128768
14:28:06 8579 8192000000 10211425392 10630729728 1313081200 13.814 99 18 36 0 1322968112 5324864 4562048
14:29:15 68351 8192000000 10172264640 10591612928 1260341072 13.333 96 19 38 0 1269490416 5126848 4022496
14:31:28 8509 8192000000 10138513040 10530766848 1192446240 12.706 94 17 35 1 1200993824 4827360 3720224
14:32:37 8695 8192000000 10137337664 10533482496 1216706416 12.931 97 28 56 1 1228347440 4927152 6713872
14:33:48 21680 8192000000 10167844416 10575253504 1276991328 13.486 100 34 69 1 1290050976 5814000 7245648
14:35:41 4094 8192000000 10173164496 10585845760 1287883232 13.585 100 36 73 0 1303565792 7883888 7798672
14:36:46 6167 8192000000 10201818896 10606084096 1301514848 13.709 100 33 71 0 1314925744 5175360 8235536
14:37:56 13951 8192000000 10206152800 10622259200 1316059968 13.841 100 14 26 0 1324050128 4959584 3030576
14:39:15 10821 8192000000 10247957872 10654269440 1339265840 14.051 100 50 100 0 1358772080 7927744 11578496
14:40:52 85780 8192000000 10260255824 10680926208 1358462128 14.224 100 44 87 1 1377673696 8487680 10723888
14:43:22 39806 8192000000 10278016176 10703417344 1370888672 14.335 100 46 92 0 1394996064 13511632 10595760
14:45:33 13969 8192000000 10291531536 10717822976 1388825552 14.495 100 44 88 1 1412588656 13278960 10484144
14:46:51 3229 8192000000 10321129168 10736971776 1397224544 14.570 100 59 114 0 1425604640 15518512 12861584
14:47:58 6174 8192000000 10317058368 10741256192 1397053728 14.569 100 47 93 0 1426913728 18638704 11221296
14:49:08 3008 8192000000 10347338528 10770542592 1422474128 14.795 100 71 142 0 1462768528 23204592 17089808
14:50:16 21410 8192000000 10344890480 10766704640 1397270752 14.571 98 53 106 0 1438648896 27390976 13987168
14:51:41 9706 8192000000 10353728944 10774847488 1405287136 14.642 99 55 110 0 1453246144 33223184 14735824
14:53:02 1636 8192000000 10370826672 10782691328 1421702720 14.788 100 77 154 2 1479204880 38327680 19174480
14:54:10 2753 8192000000 10374626336 10788855808 1410677168 14.690 99 67 130 1 1470531536 43370896 16483472
14:55:17 2389 8192000000 10360420672 10768965632 1394767104 14.548 99 79 156 2 1463693744 48353872 20572768
14:56:26 6040 8192000000 10355634208 10776301568 1398110352 14.578 100 57 113 0 1464444368 51324480 15009536
14:57:38 23415 8192000000 10339990672 10763079680 1382544768 14.439 99 42 82 0 1438934944 45110768 11279408
14:59:09 2388 8192000000 10360106480 10772926464 1397259520 14.571 100 74 148 1 1464569264 48264240 19045504
15:00:18 9395 8192000000 10344538944 10773516288 1390342400 14.509 100 39 76 0 1454867760 53511696 11013664
15:01:48 10354 8192000000 10322492144 10745692160 1360547696 14.242 100 40 80 0 1424166144 52484768 11133680
15:03:28 13432 8192000000 10307099488 10739322880 1345334432 14.105 99 24 48 0 1407656976 55246064 7076480
15:04:44 28514 8192000000 10295028208 10740686848 1354613920 14.189 100 21 42 0 1408601824 47835264 6152640
15:06:14 57135 8192000000 10266541744 10710061056 1304919552 13.740 97 20 40 0 1354012816 43286480 5806784
15:08:12 47325 8192000000 10269846224 10695786496 1348177408 14.131 100 50 95 0 1370322416 11107040 11037968
15:10:02 44213 8192000000 10285241152 10718109696 1367091664 14.301 100 36 72 1 1388880384 13355040 8433680
15:11:51 145946 8192000000 10278920768 10720149504 1338037888 14.040 97 25 51 0 1353383520 9645280 5700352
15:15:18 60962 8192000000 10289829616 10734579712 1375181360 14.373 100 22 44 0 1389558704 9188448 5188896
15:18:32 71360 8192000000 10267200288 10707808256 1352672368 14.172 98 29 58 1 1371380176 11985056 6722752
15:20:47 75140 8192000000 10280251536 10733494272 1369282944 14.321 99 25 50 0 1384222448 9195584 5743920
15:23:04 61555 8192000000 10280010048 10726813696 1373564192 14.359 99 13 24 0 1383271248 6883056 2824000
15:25:07 41597 8192000000 10293495280 10739830784 1377585904 14.395 99 19 38 1 1390676096 8631312 4458880
15:26:53 70256 8192000000 10277420208 10730450944 1371025888 14.336 99 14 27 1 1387705312 13146000 3533424
15:29:20 54613 8192000000 10284632944 10747830272 1387017424 14.479 100 12 24 0 1398583296 8749472 2816400
15:31:19 44348 8192000000 10295045360 10743152640 1371525920 14.341 98 22 44 1 1383278208 6468384 5283904
15:33:06 29050 8192000000 10288375232 10749026304 1375782064 14.379 98 23 46 0 1388985392 8071136 5132192
15:34:37 37527 8192000000 10303364944 10756644864 1373598624 14.359 98 15 30 0 1384147680 6649872 3899184
15:36:20 71224 8192000000 10295953488 10763587584 1373677888 14.360 98 21 43 0 1392973280 14553536 4741856
15:38:36 68259 8192000000 10287699904 10751131648 1328270208 13.952 95 11 22 0 1346271456 15561728 2439520
15:40:45 95858 8192000000 10276498816 10734206976 1308445040 13.772 95 10 20 0 1325795168 15123296 2226832
15:43:23 64135 8192000000 10313410992 10749685760 1363243440 14.266 98 26 52 0 1382476448 13553808 5679200
15:45:39 65679 8192000000 10273273728 10730082304 1326251952 13.933 96 10 20 1 1348392160 19922224 2217984
15:47:45 58348 8192000000 10259359856 10691358720 1290790480 13.611 94 27 53 0 1304759472 8502016 5466976
15:49:45 19400 8192000000 10281084816 10717528064 1362799280 14.262 100 46 92 0 1385949072 13022912 10126880
15:51:10 61152 8192000000 10273166320 10721730560 1351610816 14.162 99 14 24 0 1368763616 14189856 2962944
15:53:16 24298 8192000000 10252189408 10696278016 1344089760 14.094 99 21 42 0 1355243392 6529840 4623792
15:54:49 58019 8192000000 10248112800 10698948608 1323551920 13.909 97 15 30 0 1335669024 8579072 3538032
15:56:48 58869 8192000000 10246227552 10686365696 1304041424 13.732 96 22 44 0 1319747872 11032864 4673584
15:58:54 96884 8192000000 10275923072 10735054848 1350823888 14.155 99 15 30 1 1367318144 13147648 3346608
16:01:41 51521 8192000000 10277412000 10732449792 1349931024 14.147 98 12 24 0 1366044144 13420000 2693120
16:03:41 65473 8192000000 10269036800 10723426304 1352308784 14.168 99 19 36 0 1366077632 9573280 4195568
16:05:47 71477 8192000000 10315805072 10765529088 1390021216 14.506 100 24 48 0 1413480608 17837824 5621568
16:07:59 138613 8192000000 10300260656 10754043904 1335237344 14.014 96 7 13 0 1352001536 15694624 1069568
16:11:48 75228 8192000000 10282815120 10724569088 1351131696 14.158 98 21 42 0 1364671760 8560944 4979120
16:14:04 57467 8192000000 10291067584 10752131072 1393237760 14.535 100 22 44 1 1415200224 17066784 4895680
16:16:21 62592 8192000000 10289215904 10761334784 1391474608 14.519 100 7 14 0 1404584832 11490816 1619408
16:18:24 42405 8192000000 10314990224 10765398016 1388337088 14.491 99 25 48 0 1403050608 9398608 5314912
16:20:12 45348 8192000000 10291892864 10743992320 1365281136 14.285 97 15 28 0 1380988160 12669392 3037632
16:21:58 40884 8192000000 10286162352 10742726656 1345863664 14.110 97 21 42 0 1363805072 12874896 5066512
16:23:39 51652 8192000000 10272518208 10714943488 1312175824 13.806 95 15 30 1 1323999216 8566976 3256416
16:25:32 65893 8192000000 10295136448 10738143232 1369821664 14.325 99 24 48 0 1387780048 12698112 5260272
16:27:45 19242 8192000000 10303624112 10746191872 1376938672 14.389 99 28 56 0 1392363664 9546480 5878512
16:29:06 60323 8192000000 10297209760 10737975296 1328637920 13.955 96 22 43 1 1343510224 9765456 5106848
16:31:09 89543 8192000000 10269342400 10716233728 1320204896 13.879 96 28 57 0 1338283392 10917696 7160800
16:33:40 34078 8192000000 10265180288 10720509952 1353222992 14.176 99 26 52 0 1372695744 13622640 5850112
16:35:28 72867 8192000000 10263784976 10730000384 1368888896 14.317 100 12 24 0 1384850176 12903024 3058256
16:37:41 56729 8192000000 10234405440 10686775296 1323645600 13.910 96 20 40 0 1339645520 11225904 4774016

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 03-01-2023 16:41:21 to 03-01-2023 16:41:36.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 101 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 03-01-2023 16:41:37 to 03-01-2023 16:41:39.

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          242477
MON$OLDEST_ACTIVE               242478
MON$OLDEST_SNAPSHOT             242478
MON$NEXT_TRANSACTION            242478
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                       2021632
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.33650 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33650 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33650 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33650 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 ---
--- end of diff output ---

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

03.01.23 16:41:40. 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
2023-01-03 16:41:42 No severe PSQL-related problems occured
03.01.23 16:41:44. End of report.