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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15299592 kB
MemAvailable 30414828 kB
Buffers 0 kB
Cached 16430208 kB
SwapCached 3580 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.3
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2023-02-07 07:37:24
DTS_END                         2023-02-07 07:37:24.5580
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/53856
TRN_ID                          218296
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: 8623.91 22380 1034869
customer order: creation 167.79 449 20135
customer order: refuse 14.66 384 1759
order to supplier: creation 42.98 1434 5158
order to supplier: removal 3.34 160370 401
invoice (draft): creation 44.14 3313 5297
invoice (draft): removal 46.66 1046 5599
invoice accept: apply 41.12 26271 4934
invoice accept: cancel 42.06 1204 5047
customer reserve: creation 3805.75 253 456690
customer reserve: removal 4129.98 103 495598
realization accept: apply 82.46 253 9895
realization accept: cancel 77.17 165 9260
payment from customer: creation 58.14 160 6977
payment from customer: removal 5.68 121 681
payment to supplier: creation 46.79 178 5615
payment to supplier: removal 3.17 93 380
service: total inventory turnovers 4.03 3544 483
service: total monetary turnovers 6.92 349 830
service: aggregate perf. data 1.05 12914 126
service: refresh index statistics 0.03 235003 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 07-02-2023 07:39:00 to 07-02-2023 07:39:02.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9422
TEST_TIME 2 7901
TEST_TIME 3 6775
TEST_TIME 4 6940
TEST_TIME 5 6888
TEST_TIME 6 6503
TEST_TIME 7 6451
TEST_TIME 8 6770
TEST_TIME 9 6680
TEST_TIME 10 6721
TEST_TIME 11 6976
TEST_TIME 12 7110
TEST_TIME 13 7283
TEST_TIME 14 7387
TEST_TIME 15 7557
TEST_TIME 16 7629
TEST_TIME 17 7817
TEST_TIME 18 8003
TEST_TIME 19 8233
TEST_TIME 20 8303
TEST_TIME 21 8296
TEST_TIME 22 8290
TEST_TIME 23 8242
TEST_TIME 24 8193
TEST_TIME 25 8284
TEST_TIME 26 8756
TEST_TIME 27 9494
TEST_TIME 28 9887
TEST_TIME 29 9980
TEST_TIME 30 10571
TEST_TIME 31 10777
TEST_TIME 32 10772
TEST_TIME 33 10631
TEST_TIME 34 10575
TEST_TIME 35 10432
TEST_TIME 36 10343
TEST_TIME 37 10180
TEST_TIME 38 10023
TEST_TIME 39 9845
TEST_TIME 40 9755
TEST_TIME 41 9837
TEST_TIME 42 9773
TEST_TIME 43 9683
TEST_TIME 44 9626
TEST_TIME 45 9589
TEST_TIME 46 9450
TEST_TIME 47 9360
TEST_TIME 48 9352
TEST_TIME 49 9269
TEST_TIME 50 9278
TEST_TIME 51 9212
TEST_TIME 52 9156
TEST_TIME 53 9078
TEST_TIME 54 9098
TEST_TIME 55 9069
TEST_TIME 56 9181
TEST_TIME 57 9109
TEST_TIME 58 9213
TEST_TIME 59 9182
TEST_TIME 60 9132
TEST_TIME 61 9125
TEST_TIME 62 9185
TEST_TIME 63 9202
TEST_TIME 64 9216
TEST_TIME 65 9199
TEST_TIME 66 9163
TEST_TIME 67 9229
TEST_TIME 68 9100
TEST_TIME 69 9052
TEST_TIME 70 9066
TEST_TIME 71 9004
TEST_TIME 72 8949
TEST_TIME 73 8874
TEST_TIME 74 8815
TEST_TIME 75 8798
TEST_TIME 76 8810
TEST_TIME 77 8829
TEST_TIME 78 8841
TEST_TIME 79 8781
TEST_TIME 80 8769
TEST_TIME 81 8765
TEST_TIME 82 8784
TEST_TIME 83 8764
TEST_TIME 84 8771
TEST_TIME 85 8804
TEST_TIME 86 8872
TEST_TIME 87 9003
TEST_TIME 88 9063
TEST_TIME 89 9074
TEST_TIME 90 9049
TEST_TIME 91 8994
TEST_TIME 92 8961
TEST_TIME 93 8908
TEST_TIME 94 8895
TEST_TIME 95 8896
TEST_TIME 96 8842
TEST_TIME 97 8799
TEST_TIME 98 8767
TEST_TIME 99 8750
TEST_TIME 100 8739
TEST_TIME 101 8765
TEST_TIME 102 8747
TEST_TIME 103 8742
TEST_TIME 104 8747
TEST_TIME 105 8711
TEST_TIME 106 8696
TEST_TIME 107 8763
TEST_TIME 108 8908
TEST_TIME 109 8960
TEST_TIME 110 8906
TEST_TIME 111 8838
TEST_TIME 112 8778
TEST_TIME 113 8791
TEST_TIME 114 8769
TEST_TIME 115 8712
TEST_TIME 116 8692
TEST_TIME 117 8711
TEST_TIME 118 8699
TEST_TIME 119 8678
TEST_TIME 120 8659

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 07-02-2023 07:39:03 to 07-02-2023 07:39:07.

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 1454991 1454991 0 0.00 0 18206 13 0 0
doc_list_biud 1454978 1454978 0 0.00 0 34400 41 0 0
sp_add_invoice_to_stock 5100 4934 166 3.25 57 263374 26272 0 166
sp_cancel_adding_invoice 5047 5047 0 0.00 7 74753 1204 0 0
sp_cancel_client_order 1759 1759 0 0.00 5 15015 385 0 0
sp_cancel_customer_reserve 495598 495598 0 0.00 1 30489 104 0 0
sp_cancel_pay_from_customer 681 681 0 0.00 3 4460 122 0 0
sp_cancel_pay_to_supplier 380 380 0 0.00 3 1218 94 0 0
sp_cancel_supplier_invoice 5612 5599 13 0.23 4 15881 1047 0 13
sp_cancel_supplier_order 401 401 0 0.00 9 1202135 160371 0 0
sp_cancel_write_off 9260 9260 0 0.00 2 7907 165 0 0
sp_client_order 20135 20135 0 0.00 5 39497 449 0 0
sp_customer_reserve 456690 456690 0 0.00 0 89003 254 0 0
sp_fill_shopping_cart 30582 30582 0 0.00 1 45444 85 0 0
sp_fill_shopping_cart_clo_res 456649 456649 0 0.00 0 17394 20 0 0
sp_get_clo_for_invoice 4884 4884 0 0.00 0 20848 289 0 0
sp_kill_cost_storno 15368 15368 0 0.00 0 15969 68 0 0
sp_kill_qstorno_ret_qs2qd 1006456 1006456 0 0.00 0 30440 47 0 0
sp_kill_qty_storno 532442 532442 0 0.00 0 34398 104 0 0
sp_lock_selected_doc 20495 20495 0 0.00 0 6826 6 0 0
sp_make_cost_storno 27371 27371 0 0.00 0 15839 70 0 0
sp_multiply_rows_for_pdistr 27370 27370 0 0.00 0 6558 34 0 0
sp_multiply_rows_for_qdistr 20135 20135 0 0.00 0 10708 178 0 0
sp_pay_from_customer 6977 6977 0 0.00 5 12326 160 0 0
sp_pay_to_supplier 5615 5615 0 0.00 6 13803 179 0 0
sp_payment_common 12591 12591 0 0.00 2 8848 42 0 0
sp_qd_handle_on_cancel_clo 1759 1759 0 0.00 0 14981 311 0 0
sp_qd_handle_on_invoice_upd_sts 9931 9931 0 0.00 0 34396 467 0 0
sp_qd_handle_on_reserve_upd_sts 9894 9894 0 0.00 0 9262 60 0 0
sp_reserve_write_off 9895 9895 0 0.00 8 11461 254 0 0
sp_supplier_invoice 5297 5297 0 0.00 9 108410 3313 0 0
sp_supplier_order 5158 5158 0 0.00 8 88101 1435 0 0
srv_aggregate_perf_data 126 126 0 0.00 2575 146318 12914 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 776 118682 35015 0 0
srv_find_qd_qs_mism 986904 986904 0 0.00 0 18200 11 0 0
srv_make_invnt_saldo 483 483 0 0.00 75 68942 3545 0 0
srv_make_money_saldo 830 830 0 0.00 1 18020 349 0 0
srv_recalc_idx_stat 4 4 0 0.00 78480 368841 235004 0 0
t$perf-abend:sp_add_invoice_to_stock 166 166 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4941 4941 0 0.00 1 43584 845 0 0
t$perf-norm:sp_cancel_adding_invoice 414 414 0 0.00 2 5289 359 0 0
t$perf-norm:sp_cancel_client_order 1759 1759 0 0.00 0 24496 50 0 0
t$perf-norm:sp_cancel_customer_reserve 690 690 0 0.00 0 570 21 0 0
t$perf-norm:sp_cancel_pay_from_customer 681 681 0 0.00 0 4091 18 0 0
t$perf-norm:sp_cancel_pay_to_supplier 380 380 0 0.00 0 1694 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 414 414 0 0.00 0 1179 31 0 0
t$perf-norm:sp_cancel_supplier_order 401 401 0 0.00 0 90302 6122 0 0
t$perf-norm:sp_cancel_write_off 982 982 0 0.00 0 1356 17 0 0
t$perf-norm:sp_client_order 20135 20135 0 0.00 0 36663 34 0 0
t$perf-norm:sp_customer_reserve 1004 1004 0 0.00 0 561 13 0 0
t$perf-norm:sp_pay_from_customer 6978 6978 0 0.00 0 16774 21 0 0
t$perf-norm:sp_pay_to_supplier 5615 5615 0 0.00 0 8878 19 0 0
t$perf-norm:sp_reserve_write_off 9895 9895 0 0.00 0 31074 25 0 0
t$perf-norm:sp_supplier_invoice 5297 5297 0 0.00 0 17568 38 0 0
t$perf-norm:sp_supplier_order 5158 5158 0 0.00 0 2158 27 0 0
t$perf-norm:srv_aggregate_perf_data 126 126 0 0.00 0 1386 27 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 25 3 0 0
t$perf-norm:srv_make_invnt_saldo 483 483 0 0.00 0 2217 13 0 0
t$perf-norm:srv_make_money_saldo 830 830 0 0.00 0 332 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 101 54 0 0
v_add_invoice_to_stock 10096 9764 332 3.28 0 6348 26 0 332
v_all_customers 37202 37202 0 0.00 0 129 3 0 0
v_all_suppliers 28871 28871 0 0.00 0 149 4 0 0
v_all_wares 40268 40268 0 0.00 0 92 4 0 0
v_cancel_adding_invoice 828 828 0 0.00 0 2838 21 0 0
v_cancel_client_order 3518 3518 0 0.00 0 3000 11 0 0
v_cancel_customer_prepayment 1362 1362 0 0.00 0 4448 19 0 0
v_cancel_customer_reserve 1380 1380 0 0.00 0 958 10 0 0
v_cancel_payment_to_supplier 760 760 0 0.00 0 239 9 0 0
v_cancel_supplier_invoice 854 828 26 3.04 0 6723 34 0 26
v_cancel_supplier_order 802 802 0 0.00 0 7244 25 0 0
v_cancel_write_off 1964 1964 0 0.00 0 1138 23 0 0
v_max_id_clo_ord 5156 5156 0 0.00 0 1296 5 0 0
v_max_id_clo_res 1004 1004 0 0.00 0 3619 20 0 0
v_max_id_ord_sup 5291 5291 0 0.00 0 5403 11 0 0
v_max_non_paid_invoice 1624 1624 0 0.00 0 5387 10 0 0
v_max_non_paid_realizn 3515 3515 0 0.00 0 1527 7 0 0
v_min_id_clo_ord 5156 5156 0 0.00 0 2668 7 0 0
v_min_id_clo_res 1004 1004 0 0.00 0 4126 14 0 0
v_min_id_ord_sup 5290 5290 0 0.00 0 8970 12 0 0
v_min_non_paid_invoice 5614 5614 0 0.00 0 3905 12 0 0
v_min_non_paid_realizn 6977 6977 0 0.00 0 5748 15 0 0
v_our_firm 9988 9988 0 0.00 0 97 4 0 0
v_reserve_write_off 19784 19784 0 0.00 0 4164 11 0 0
x_lock_depdocs_on_canc_invoice 10232 10232 0 0.00 0 17780 141 0 0
x_lock_depdocs_on_canc_sup_ord 401 401 0 0.00 0 6841 347 0 0
x_make_qstorno_1000_1200 5157 5157 0 0.00 2 88090 1288 0 0
x_make_qstorno_1200_2000 5297 5297 0 0.00 5 108321 3052 0 0
x_make_qstorno_2100_3300 456690 456690 0 0.00 0 89001 223 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-02-2023 07:39:08 to 07-02-2023 07:39:12.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
05:07:28 4 8192000000 8802718304 8815693824 6346512 0.077 5 1 2 0 6451152 68864 35776
05:08:29 29909 8192000000 9719573712 10020069376 793355712 8.829 100 83 166 0 820682080 7255024 20071344
05:10:11 7404 8192000000 9763921152 10072182784 837890880 9.279 100 85 170 0 870561552 12464320 20206352
05:11:22 18047 8192000000 9818413648 10136985600 864420656 9.544 100 84 168 0 909305888 25449248 19435984
05:12:48 8759 8192000000 9867124832 10199773184 910239904 10.000 100 87 170 2 968444672 39584192 18620576
05:14:02 4220 8192000000 9918908320 10264272896 955217408 10.442 100 90 176 1 1024123856 51457968 17448480
05:15:09 3495 8192000000 9991534144 10347737088 992205456 10.803 100 88 170 0 1094518736 84604832 17708448
05:16:14 1860 8192000000 9993346528 10354528256 1017579920 11.049 100 88 174 0 1096626192 61308752 17737520
05:17:17 2582 8192000000 10009882128 10380099584 1032089584 11.189 100 88 168 0 1122009680 73691072 16229024
05:18:21 1321 8192000000 10035831776 10414133248 1054404384 11.403 100 93 181 4 1145819184 73578368 17836432
05:19:24 1772 8192000000 10028377056 10411782144 1058324112 11.440 100 88 167 3 1137988976 62409696 17255168
05:20:28 1656 8192000000 10049853600 10438651904 1065765088 11.512 100 84 160 1 1160484720 78845936 15873696
05:21:31 1038 8192000000 10056142832 10442047488 1069497120 11.547 100 98 190 2 1167758336 79632672 18628544
05:22:33 1232 8192000000 10036312448 10420494336 1080809056 11.655 100 95 175 2 1147321840 48082992 18429792
05:23:35 1348 8192000000 10065675248 10459643904 1083019856 11.676 100 86 164 2 1174253424 73408336 17825232
05:24:42 1065 8192000000 10057570896 10445586432 1082857888 11.675 100 92 170 2 1170310992 69371456 18081648
05:25:44 1005 8192000000 10052448272 10440998912 1087593984 11.720 100 94 176 4 1167149632 61294880 18260768
05:26:46 3183 8192000000 10038095392 10432737280 1089601600 11.739 100 65 116 4 1158163952 58070640 10491712
05:27:50 1578 8192000000 10055627968 10446372864 1084111904 11.687 99 86 157 0 1162960704 63506240 15342560
05:28:52 1264 8192000000 10068923808 10466144256 1092664560 11.768 100 83 159 1 1183710480 75332080 15713840
05:29:55 1065 8192000000 10036054928 10419261440 1079782304 11.645 100 97 187 1 1146462736 47862688 18817744
05:30:56 1090 8192000000 10025909776 10406273024 1060799312 11.464 100 93 181 2 1137328144 56211280 20317552
05:31:58 941 8192000000 10033408880 10416902144 1058050016 11.438 100 89 175 2 1145067616 69024256 17993344
05:33:00 1126 8192000000 10027658112 10404077568 1052998080 11.389 100 93 184 1 1137557536 65955120 18604336
05:34:02 1002 8192000000 10028245456 10409660416 1057624272 11.434 100 94 179 1 1140488448 64056896 18807280
05:35:04 1563 8192000000 9996626240 10377310208 1051243088 11.373 100 73 142 2 1114912256 49206272 14462896
05:36:07 2503 8192000000 9977975488 10352242688 1021746288 11.089 99 67 123 0 1096595456 62963568 11885600
05:37:11 4895 8192000000 10014285088 10392776704 1045072560 11.313 100 70 133 1 1129808352 72031584 12704208
05:38:23 1746 8192000000 10011942320 10390061056 1061915392 11.475 100 91 177 2 1134406048 54164720 18325936
05:39:30 13636 8192000000 10029151120 10407874560 1075032544 11.600 100 76 138 1 1143620848 54525632 14062672
05:40:48 958 8192000000 10085672752 10470854656 1097869552 11.817 100 92 169 0 1196338144 81815200 16653392
05:41:57 5503 8192000000 10095138432 10492370944 1107056592 11.905 100 64 124 1 1216907696 96199744 13651360
05:43:08 7089 8192000000 10136711008 10530799616 1120305936 12.030 100 63 123 2 1252720768 119188160 13226672
05:44:20 4221 8192000000 10154461696 10559488000 1126887712 12.092 100 79 153 3 1278090736 135438944 15764080
05:45:29 951 8192000000 10198537968 10599153664 1133061632 12.150 100 90 176 0 1314060560 162220816 18778112
05:46:34 9729 8192000000 10178991840 10583175168 1142001200 12.234 100 65 126 1 1311850832 154336992 15512640
05:47:51 1795 8192000000 10216426240 10623774720 1150086224 12.310 100 88 171 1 1328286016 159890880 18308912
05:48:57 1549 8192000000 10261460704 10657390592 1167297104 12.472 100 90 178 1 1373703744 185236928 21169712
05:50:01 956 8192000000 10279809024 10690437120 1184496352 12.632 100 93 180 0 1388508576 183851344 20160880
05:51:05 1714 8192000000 10326685264 10743615488 1190113536 12.684 100 85 161 2 1436236880 227407824 18715520
05:52:11 2680 8192000000 10353405648 10784321536 1197495728 12.753 100 85 163 0 1467207696 251151952 18560016
05:53:16 880 8192000000 10399868080 10805637120 1201264576 12.788 100 98 191 0 1507236896 284229952 21742368
05:54:20 771 8192000000 10416490128 10839887872 1193828160 12.719 100 95 180 2 1529356784 313389616 22139008
05:55:23 1016 8192000000 10454910672 10889842688 1203726144 12.811 100 93 182 0 1566100736 341277280 21097312
05:56:27 12260 8192000000 10469747104 10899943424 1206375328 12.835 100 54 107 1 1578588752 358535392 13678032
05:57:41 5507 8192000000 10471752160 10899472384 1202692032 12.801 100 58 113 1 1579307408 363163696 13451680
05:59:00 6398 8192000000 10499999696 10929274880 1189792000 12.681 100 54 107 1 1597968672 395089248 13087424
06:00:27 15202 8192000000 10514792032 10944802816 1181353040 12.603 99 44 86 0 1607259712 414942352 10964320
06:01:47 7501 8192000000 10541729728 10992467968 1181184416 12.601 100 56 106 1 1645524528 450670128 13669984
06:02:58 4174 8192000000 10523658848 10985017344 1160124048 12.404 99 60 117 0 1618239872 443467072 14648752
06:04:03 12955 8192000000 10373798464 10859286528 1157808960 12.383 99 51 101 1 1529107728 358903120 12395648
06:05:24 8929 8192000000 10320723360 10805334016 1164565088 12.446 100 43 83 0 1438746544 262852192 11329264
06:06:40 21950 8192000000 10266790736 10773409792 1108516352 11.918 97 18 36 0 1383855056 270477648 4861056
06:08:12 23424 8192000000 10081521920 10545291264 1059692064 11.454 95 21 42 0 1125967488 61962304 4313120
06:09:36 15108 8192000000 10097458784 10518179840 1122992512 12.055 100 56 100 2 1182373632 48458272 10922848
06:10:54 3155 8192000000 10060027392 10469769216 1110169504 11.934 99 73 126 0 1158184128 35591472 12423152
06:12:03 87091 8192000000 10050763984 10478911488 1081301840 11.660 96 20 40 0 1124315216 38785296 4228080
06:14:31 28144 8192000000 10080801648 10539941888 1125140448 12.076 100 15 31 0 1181367760 52988624 3238688
06:15:59 53552 8192000000 10137117536 10579836928 1130575488 12.127 100 16 32 0 1233138016 99496112 3066416
06:18:18 114145 8192000000 10040880016 10519982080 1109182752 11.925 99 26 52 2 1138379520 22763408 6433360
06:21:14 89950 8192000000 10068041520 10535280640 1101954832 11.856 98 28 56 0 1141887328 33843824 6088672
06:23:54 32782 8192000000 10066733984 10509795328 1124420896 12.069 100 28 57 0 1178092880 47566464 6105520
06:25:29 53355 8192000000 10091844992 10532925440 1126072640 12.084 100 26 50 1 1194554112 62912704 5568768
06:27:31 44435 8192000000 10083412464 10569830400 1109345568 11.926 98 14 28 0 1170500704 57698432 3456704
06:29:16 63871 8192000000 10072485968 10561908736 1109849008 11.931 99 8 16 0 1166913648 55263136 1801504
06:31:21 41883 8192000000 10077252384 10554458112 1117507920 12.003 99 20 37 2 1164495040 42921072 4066048
06:33:05 28029 8192000000 10056531632 10534039552 1114745776 11.977 99 18 33 1 1168515232 50191680 3577776
06:34:33 42569 8192000000 10129292640 10570035200 1113478288 11.965 98 34 66 0 1170684720 50384960 6821472
06:36:16 43415 8192000000 10076829200 10497302528 1092462128 11.766 96 36 71 0 1156400400 56858224 7080048
06:38:06 19187 8192000000 10141998992 10575765504 1139943056 12.215 100 50 99 1 1223971536 74296432 9732048
06:39:27 72803 8192000000 10067941888 10494152704 1086934240 11.713 95 18 34 0 1115986592 26002160 3050192
06:41:40 45018 8192000000 10117032800 10543534080 1107999280 11.913 97 41 80 0 1173834032 57968576 7866176
06:43:27 41217 8192000000 10154017632 10590461952 1133262672 12.152 99 54 104 0 1253405888 109713152 10430064
06:45:08 118682 8192000000 10102779424 10571001856 1141253360 12.227 99 23 46 0 1223617616 76914288 5449968
06:48:11 20151 8192000000 10052687088 10492891136 1128310704 12.105 99 16 28 1 1165940624 34496240 3133680
06:49:31 49286 8192000000 10079708672 10523549696 1125406592 12.078 98 6 12 0 1155159024 28506656 1245776
06:51:27 80800 8192000000 10115511072 10604298240 1143830320 12.252 100 13 24 0 1252198256 105784736 2583200
06:54:22 41012 8192000000 10062163920 10542690304 1120833840 12.035 98 18 34 0 1166294448 41880608 3580000
06:56:17 9159 8192000000 10066410128 10497191936 1134538128 12.164 100 47 93 1 1168547440 21595728 12413584
06:57:26 21023 8192000000 10153686864 10590248960 1142059824 12.235 100 40 80 0 1246350304 95891776 8398704
06:58:53 27093 8192000000 10183712656 10624446464 1126871072 12.092 98 32 62 1 1263954144 129690048 7393024
07:00:26 29229 8192000000 10179028096 10603454464 1108413712 11.917 97 43 84 0 1246284112 128498976 9371424
07:02:05 27251 8192000000 10167916960 10626969600 1119455648 12.022 99 32 64 0 1266291792 139598640 7237504
07:03:43 42480 8192000000 10137634736 10625388544 1108553024 11.919 98 19 38 0 1239719872 126949872 4216976
07:05:32 74349 8192000000 10078463344 10551681024 1094037712 11.781 97 26 52 0 1153933024 54712672 5182640
07:07:47 91899 8192000000 10085607424 10519277568 1131348112 12.134 100 44 88 0 1187928560 47620112 8960336
07:10:21 51033 8192000000 10081146480 10505388032 1113842000 11.969 99 49 97 0 1184882192 61114288 9925904
07:12:12 62036 8192000000 10072306336 10559303680 1100540160 11.843 98 9 18 0 1153202064 50945904 1716000
07:14:41 64759 8192000000 10099732512 10550325248 1116015264 11.989 98 16 31 0 1173299232 54096704 3187264
07:16:49 26862 8192000000 10148085088 10584076288 1146776624 12.279 100 31 61 0 1251377632 97972336 6628672
07:18:17 58249 8192000000 10149237712 10582020096 1107041632 11.904 96 32 65 0 1225361184 110826208 7493344
07:20:19 43294 8192000000 10161530400 10599411712 1148427632 12.295 100 26 52 0 1264174736 109248000 6499104
07:22:05 70157 8192000000 10228643136 10671661056 1109321328 11.926 96 21 41 0 1257561232 143560336 4679568
07:24:16 38719 8192000000 10217771936 10673565696 1117988032 12.008 97 25 50 0 1289805440 166095984 5721424
07:26:05 57987 8192000000 10051073840 10479890432 1118540912 12.013 99 29 58 0 1141210448 16699040 5970496
07:28:03 105318 8192000000 10127238464 10567184384 1135270000 12.171 100 50 100 0 1219118960 73611344 10237616
07:31:03 81543 8192000000 10105189744 10575998976 1144763344 12.260 100 11 22 0 1229115200 82181648 2170208
07:33:25 52819 8192000000 10130270016 10601168896 1154573568 12.352 100 29 56 1 1236940784 75082784 7284432
07:35:18 43405 8192000000 10121436432 10590064640 1151356144 12.322 100 17 34 0 1219953136 64992736 3604256
07:37:02 39061 8192000000 9168811728 9507758080 309490256 3.640 27 7 14 0 350572512 39390448 1691808

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 07-02-2023 07:39:14 to 07-02-2023 07:39:30.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 166 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:03, from 07-02-2023 07:39:31 to 07-02-2023 07:39:34.

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          218672
MON$OLDEST_ACTIVE               218673
MON$OLDEST_SNAPSHOT             218673
MON$NEXT_TRANSACTION            218673
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                       2033888
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             766
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2895 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2895 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2895 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2895 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 ---
--- end of diff output ---

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

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