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

Hardware and OS info

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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2023-01-19 13:37:25
DTS_END                         2023-01-19 13:37:25.4060
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/48894
TRN_ID                          223882
ATT_ID                          610
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: 8881.67 20990 1065800
customer order: creation 176.52 447 21182
customer order: refuse 15.33 496 1840
order to supplier: creation 45.78 1350 5494
order to supplier: removal 3.42 172528 410
invoice (draft): creation 46.93 3126 5632
invoice (draft): removal 48.76 1123 5851
invoice accept: apply 42.53 27335 5104
invoice accept: cancel 44.03 1405 5284
customer reserve: creation 3907.55 261 468906
customer reserve: removal 4252.20 106 510264
realization accept: apply 85.67 312 10280
realization accept: cancel 78.46 187 9415
payment from customer: creation 61.63 197 7395
payment from customer: removal 5.54 181 665
payment to supplier: creation 51.31 203 6157
payment to supplier: removal 3.27 176 392
service: total inventory turnovers 3.89 4692 467
service: total monetary turnovers 7.85 358 942
service: aggregate perf. data 0.96 15767 115
service: refresh index statistics 0.04 189541 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 19-01-2023 13:38:47 to 19-01-2023 13:38:49.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2752
TEST_TIME 2 3281
TEST_TIME 3 3013
TEST_TIME 4 3518
TEST_TIME 5 3938
TEST_TIME 6 4175
TEST_TIME 7 4347
TEST_TIME 8 4880
TEST_TIME 9 5135
TEST_TIME 10 5389
TEST_TIME 11 5443
TEST_TIME 12 5734
TEST_TIME 13 5873
TEST_TIME 14 6184
TEST_TIME 15 6263
TEST_TIME 16 6465
TEST_TIME 17 6546
TEST_TIME 18 6768
TEST_TIME 19 6989
TEST_TIME 20 7115
TEST_TIME 21 7203
TEST_TIME 22 7250
TEST_TIME 23 7294
TEST_TIME 24 7260
TEST_TIME 25 7647
TEST_TIME 26 7903
TEST_TIME 27 7964
TEST_TIME 28 7892
TEST_TIME 29 7831
TEST_TIME 30 8120
TEST_TIME 31 8102
TEST_TIME 32 8568
TEST_TIME 33 8966
TEST_TIME 34 9500
TEST_TIME 35 9880
TEST_TIME 36 10183
TEST_TIME 37 10433
TEST_TIME 38 10478
TEST_TIME 39 10603
TEST_TIME 40 10475
TEST_TIME 41 10364
TEST_TIME 42 10321
TEST_TIME 43 10235
TEST_TIME 44 10234
TEST_TIME 45 10100
TEST_TIME 46 9938
TEST_TIME 47 9868
TEST_TIME 48 9751
TEST_TIME 49 9753
TEST_TIME 50 9684
TEST_TIME 51 9710
TEST_TIME 52 9617
TEST_TIME 53 9552
TEST_TIME 54 9500
TEST_TIME 55 9465
TEST_TIME 56 9366
TEST_TIME 57 9429
TEST_TIME 58 9344
TEST_TIME 59 9331
TEST_TIME 60 9309
TEST_TIME 61 9281
TEST_TIME 62 9281
TEST_TIME 63 9361
TEST_TIME 64 9302
TEST_TIME 65 9295
TEST_TIME 66 9257
TEST_TIME 67 9408
TEST_TIME 68 9396
TEST_TIME 69 9362
TEST_TIME 70 9532
TEST_TIME 71 9496
TEST_TIME 72 9484
TEST_TIME 73 9525
TEST_TIME 74 9532
TEST_TIME 75 9514
TEST_TIME 76 9415
TEST_TIME 77 9332
TEST_TIME 78 9352
TEST_TIME 79 9285
TEST_TIME 80 9274
TEST_TIME 81 9248
TEST_TIME 82 9232
TEST_TIME 83 9191
TEST_TIME 84 9102
TEST_TIME 85 9107
TEST_TIME 86 9060
TEST_TIME 87 9093
TEST_TIME 88 9117
TEST_TIME 89 9123
TEST_TIME 90 9112
TEST_TIME 91 9070
TEST_TIME 92 9091
TEST_TIME 93 9121
TEST_TIME 94 9129
TEST_TIME 95 9142
TEST_TIME 96 9120
TEST_TIME 97 9163
TEST_TIME 98 9151
TEST_TIME 99 9181
TEST_TIME 100 9182
TEST_TIME 101 9224
TEST_TIME 102 9186
TEST_TIME 103 9122
TEST_TIME 104 9094
TEST_TIME 105 9088
TEST_TIME 106 9090
TEST_TIME 107 9054
TEST_TIME 108 9037
TEST_TIME 109 8997
TEST_TIME 110 8968
TEST_TIME 111 8938
TEST_TIME 112 8936
TEST_TIME 113 8916
TEST_TIME 114 8897
TEST_TIME 115 8908
TEST_TIME 116 8924
TEST_TIME 117 8917
TEST_TIME 118 8942
TEST_TIME 119 8949
TEST_TIME 120 8889

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 19-01-2023 13:38:51 to 19-01-2023 13:38:54.

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 1498295 1498295 0 0.00 0 25877 15 0 0
doc_list_biud 1498291 1498291 0 0.00 0 40861 43 0 0
sp_add_invoice_to_stock 5207 5104 103 1.97 53 201428 27335 0 103
sp_cancel_adding_invoice 5284 5284 0 0.00 7 84426 1406 0 0
sp_cancel_client_order 1840 1840 0 0.00 5 20333 497 0 0
sp_cancel_customer_reserve 510264 510264 0 0.00 1 31301 106 0 0
sp_cancel_pay_from_customer 665 665 0 0.00 3 20899 182 0 0
sp_cancel_pay_to_supplier 392 392 0 0.00 4 9145 177 0 0
sp_cancel_supplier_invoice 5861 5851 10 0.17 3 40869 1123 0 10
sp_cancel_supplier_order 410 410 0 0.00 10 1685866 172529 0 0
sp_cancel_write_off 9415 9415 0 0.00 2 11648 188 0 0
sp_client_order 21182 21182 0 0.00 6 22471 448 0 0
sp_customer_reserve 468906 468906 0 0.00 1 117952 262 0 0
sp_fill_shopping_cart 32303 32303 0 0.00 1 22666 80 0 0
sp_fill_shopping_cart_clo_res 468887 468887 0 0.00 0 16627 19 0 0
sp_get_clo_for_invoice 5078 5078 0 0.00 0 18562 265 0 0
sp_kill_cost_storno 15756 15756 0 0.00 0 25873 89 0 0
sp_kill_qstorno_ret_qs2qd 1036204 1036204 0 0.00 0 40860 46 0 0
sp_kill_qty_storno 548420 548420 0 0.00 0 40861 107 0 0
sp_lock_selected_doc 21281 21281 0 0.00 0 8563 9 0 0
sp_make_cost_storno 28910 28910 0 0.00 0 11211 79 0 0
sp_multiply_rows_for_pdistr 28909 28909 0 0.00 0 8368 40 0 0
sp_multiply_rows_for_qdistr 21182 21182 0 0.00 0 12987 159 0 0
sp_pay_from_customer 7395 7395 0 0.00 6 22656 198 0 0
sp_pay_to_supplier 6157 6157 0 0.00 7 20209 204 0 0
sp_payment_common 13552 13552 0 0.00 2 11824 49 0 0
sp_qd_handle_on_cancel_clo 1840 1840 0 0.00 1 11560 401 0 0
sp_qd_handle_on_invoice_upd_sts 10361 10361 0 0.00 0 25511 616 0 0
sp_qd_handle_on_reserve_upd_sts 10279 10279 0 0.00 0 34239 70 0 0
sp_reserve_write_off 10280 10280 0 0.00 8 34394 313 0 0
sp_supplier_invoice 5632 5632 0 0.00 10 93450 3127 0 0
sp_supplier_order 5494 5494 0 0.00 6 44870 1350 0 0
srv_aggregate_perf_data 115 115 0 0.00 2324 230198 15768 0 0
srv_fill_mon_cache_memory 73 73 0 0.00 696 130827 33177 0 0
srv_find_qd_qs_mism 1016058 1016058 0 0.00 0 15596 12 0 0
srv_make_invnt_saldo 467 467 0 0.00 40 187505 4693 0 0
srv_make_money_saldo 942 942 0 0.00 1 19895 359 0 0
srv_recalc_idx_stat 5 5 0 0.00 109875 274092 189541 0 0
t$perf-abend:sp_add_invoice_to_stock 103 103 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5104 5104 0 0.00 1 15608 1020 0 0
t$perf-norm:sp_cancel_adding_invoice 436 436 0 0.00 2 6096 470 0 0
t$perf-norm:sp_cancel_client_order 1840 1840 0 0.00 0 6139 36 0 0
t$perf-norm:sp_cancel_customer_reserve 710 710 0 0.00 0 1057 32 0 0
t$perf-norm:sp_cancel_pay_from_customer 665 665 0 0.00 0 914 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 392 392 0 0.00 0 1381 21 0 0
t$perf-norm:sp_cancel_supplier_invoice 396 396 0 0.00 0 771 30 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 128770 8087 0 0
t$perf-norm:sp_cancel_write_off 1077 1077 0 0.00 0 1722 21 0 0
t$perf-norm:sp_client_order 21182 21182 0 0.00 0 8919 39 0 0
t$perf-norm:sp_customer_reserve 1035 1035 0 0.00 0 2449 16 0 0
t$perf-norm:sp_pay_from_customer 7395 7395 0 0.00 0 4680 21 0 0
t$perf-norm:sp_pay_to_supplier 6157 6157 0 0.00 0 3451 20 0 0
t$perf-norm:sp_reserve_write_off 10280 10280 0 0.00 0 6303 22 0 0
t$perf-norm:sp_supplier_invoice 5632 5632 0 0.00 0 7326 31 0 0
t$perf-norm:sp_supplier_order 5494 5494 0 0.00 0 12494 36 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 530 23 0 0
t$perf-norm:srv_fill_mon_cache_memory 73 73 0 0.00 0 48 4 0 0
t$perf-norm:srv_make_invnt_saldo 467 467 0 0.00 0 2626 22 0 0
t$perf-norm:srv_make_money_saldo 942 942 0 0.00 0 1387 8 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 5 173 75 0 0
v_add_invoice_to_stock 10358 10152 206 1.98 0 27213 27 0 206
v_all_customers 39256 39256 0 0.00 0 101 4 0 0
v_all_suppliers 31512 31512 0 0.00 0 137 4 0 0
v_all_wares 42364 42364 0 0.00 0 89 4 0 0
v_cancel_adding_invoice 872 872 0 0.00 0 19787 51 0 0
v_cancel_client_order 3680 3680 0 0.00 0 20122 16 0 0
v_cancel_customer_prepayment 1330 1330 0 0.00 0 20808 35 0 0
v_cancel_customer_reserve 1420 1420 0 0.00 1 31168 38 0 0
v_cancel_payment_to_supplier 784 784 0 0.00 0 9073 21 0 0
v_cancel_supplier_invoice 812 792 20 2.46 0 448 21 0 20
v_cancel_supplier_order 820 820 0 0.00 0 2663 16 0 0
v_cancel_write_off 2154 2154 0 0.00 0 3069 26 0 0
v_max_id_clo_ord 5493 5493 0 0.00 0 1504 5 0 0
v_max_id_clo_res 1035 1035 0 0.00 0 3241 19 0 0
v_max_id_ord_sup 5626 5626 0 0.00 0 3813 6 0 0
v_max_non_paid_invoice 1520 1520 0 0.00 0 1301 9 0 0
v_max_non_paid_realizn 3658 3658 0 0.00 0 2052 8 0 0
v_min_id_clo_ord 5493 5493 0 0.00 0 3068 9 0 0
v_min_id_clo_res 1035 1035 0 0.00 0 7891 20 0 0
v_min_id_ord_sup 5626 5626 0 0.00 0 3166 7 0 0
v_min_non_paid_invoice 6157 6157 0 0.00 0 7154 18 0 0
v_min_non_paid_realizn 7395 7395 0 0.00 0 11817 21 0 0
v_our_firm 10582 10582 0 0.00 0 79 5 0 0
v_reserve_write_off 20558 20558 0 0.00 0 31100 19 0 0
x_lock_depdocs_on_canc_invoice 10739 10739 0 0.00 0 14621 129 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 2883 353 0 0
x_make_qstorno_1000_1200 5494 5494 0 0.00 2 44847 1182 0 0
x_make_qstorno_1200_2000 5632 5632 0 0.00 4 92071 2906 0 0
x_make_qstorno_2100_3300 468904 468904 0 0.00 0 117948 229 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-01-2023 13:38:55 to 19-01-2023 13:38:59.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
11:07:29 905 8192000000 8924896352 8981344256 17736128 0.216 4 3 4 0 18549504 219040 594336
11:08:30 30551 8192000000 9733059680 10034720768 812816944 9.026 100 90 180 0 843063408 8064336 22182128
11:10:07 14030 8192000000 9777153360 10087456768 849357600 9.394 100 89 178 0 883256800 12777072 21122128
11:11:28 7413 8192000000 9799605360 10118483968 870249216 9.603 100 79 158 0 907416880 18828768 18338896
11:12:43 11645 8192000000 9865434064 10199035904 914500896 10.042 100 85 168 0 975180928 42444080 18235952
11:13:57 5680 8192000000 9917411600 10257043456 945158432 10.344 100 92 176 1 1029775328 66094256 18522640
11:15:08 2916 8192000000 9959405760 10309386240 978338432 10.668 100 89 174 0 1076161936 79889296 17934208
11:16:15 2039 8192000000 9983455952 10341892096 1006391072 10.940 100 87 161 1 1098224960 75509056 16324832
11:17:20 829 8192000000 9995505840 10361573376 1033837568 11.205 100 94 173 1 1115343488 63486864 18019056
11:18:21 987 8192000000 10036099920 10412503040 1050734240 11.368 100 96 184 2 1160314704 91660128 17920336
11:19:24 1067 8192000000 10037677280 10418663424 1065182272 11.506 100 89 173 1 1162939488 80774048 16983168
11:20:27 1222 8192000000 10055198672 10439671808 1068498592 11.538 100 98 184 3 1171926912 85376832 18051488
11:21:29 990 8192000000 10043115984 10427387904 1078120464 11.630 100 97 184 1 1165939568 68721008 19098096
11:22:31 1142 8192000000 10036563568 10420051968 1079857440 11.646 100 82 155 2 1155012640 59800528 15354672
11:23:33 2972 8192000000 10026014288 10411864064 1082675216 11.673 100 72 130 0 1150278944 55794544 11809184
11:24:37 4710 8192000000 10046543488 10431049728 1088445280 11.728 100 89 165 3 1164620096 60032736 16142080
11:25:43 1437 8192000000 10071246320 10469322752 1094919424 11.789 100 85 169 1 1196718608 84651328 17147856
11:26:45 1181 8192000000 10092402208 10476765184 1088439776 11.728 100 94 179 1 1212821344 104614336 19767232
11:27:47 1311 8192000000 10051699648 10442461184 1094563472 11.786 100 97 189 1 1174448016 59884176 20000368
11:28:50 1215 8192000000 10046182832 10433445888 1088249072 11.726 100 98 179 1 1169028176 62684976 18094128
11:29:52 991 8192000000 10064737472 10460815360 1097258400 11.812 100 85 166 0 1188834928 74284992 17291536
11:30:54 1528 8192000000 10020371280 10400284672 1054229920 11.401 98 86 161 3 1146902480 75969424 16703136
11:31:57 1327 8192000000 10016709408 10397073408 1057140400 11.429 100 91 161 1 1136454560 62338448 16975712
11:32:59 1177 8192000000 10016267696 10396553216 1045931456 11.322 100 89 166 2 1138106544 74448160 17726928
11:34:02 993 8192000000 10026902512 10407784448 1057502160 11.433 100 99 184 0 1147148000 70351040 19294800
11:35:04 1859 8192000000 9987498048 10376564736 1049320704 11.354 100 58 116 1 1113978704 52277584 12380416
11:36:07 6112 8192000000 9984438960 10367885312 1039972944 11.264 100 59 116 1 1103349680 49897600 13479136
11:37:18 3290 8192000000 9949470560 10340085760 1048347504 11.345 100 54 92 1 1079848256 22762464 8738288
11:38:31 6195 8192000000 10000529872 10381795328 1066677680 11.520 100 77 135 5 1117809104 38721088 12410336
11:39:43 11142 8192000000 10040117552 10424233984 1084624560 11.692 100 85 156 2 1168566112 68798832 15142720
11:41:00 11601 8192000000 10080091776 10475511808 1095674784 11.797 100 64 124 1 1200306992 92244336 12387872
11:42:17 10504 8192000000 10107994640 10497683456 1099301408 11.831 100 70 133 2 1228571888 115044144 14226336
11:43:32 4595 8192000000 10142739776 10536624128 1106302560 11.897 100 68 128 1 1260450032 139991552 14155920
11:44:40 9977 8192000000 10173347040 10572451840 1111208416 11.944 100 72 144 1 1300175552 173459984 15507152
11:45:56 2313 8192000000 10155957536 10559295488 1119152256 12.019 100 84 155 5 1273292128 139082704 15057168
11:47:06 3876 8192000000 10207706400 10622586880 1130436000 12.125 100 73 145 0 1311161024 165331024 15394000
11:48:17 1891 8192000000 10247881888 10642100224 1147838176 12.289 100 89 168 1 1364521392 198607312 18075904
11:49:24 2105 8192000000 10276404864 10687541248 1164821248 12.448 100 86 162 0 1382011568 198707440 18482880
11:50:29 1441 8192000000 10319352304 10724421632 1160622640 12.409 100 92 182 1 1427493616 244806304 22064672
11:51:34 5351 8192000000 10313155776 10732376064 1172595344 12.521 100 89 175 2 1419464320 228092192 18776784
11:52:43 1393 8192000000 10375896464 10781564928 1181079360 12.600 100 93 178 0 1486530752 284714432 20736960
11:53:47 999 8192000000 10410416880 10825433088 1196877056 12.747 100 98 189 0 1518466928 299509168 22080704
11:54:50 905 8192000000 10445510496 10863325184 1193770176 12.718 100 100 198 0 1552872704 336413312 22689216
11:55:56 759 8192000000 10441285600 10862776320 1195812432 12.737 100 97 190 0 1548527680 331408320 21306928
11:57:00 843 8192000000 10461388752 10883235840 1196894560 12.747 100 100 196 1 1569442528 349316832 23231136
11:58:05 2218 8192000000 10506100912 10926084096 1198379376 12.761 100 96 183 3 1603700224 382770944 22549904
11:59:13 1127 8192000000 10545957648 10955370496 1208884608 12.859 100 99 191 1 1650085872 418649744 22551520
12:00:16 1669 8192000000 10548991008 10982879232 1203191280 12.806 100 96 190 2 1648572320 423020080 22360960
12:01:20 1287 8192000000 10575878144 11033878528 1205198400 12.825 100 98 195 0 1678738864 450451808 23088656
12:02:25 1129 8192000000 10554447104 10996137984 1196650880 12.745 100 96 192 2 1660373552 441278656 22444016
12:03:29 849 8192000000 10553845312 11003064320 1185069168 12.637 100 73 136 3 1656972432 454907168 16996096
12:04:33 4915 8192000000 10582798656 11019214848 1195900496 12.738 100 62 123 0 1689084512 477503136 15680880
12:05:42 688 8192000000 10620871136 11041021952 1184901296 12.636 100 89 174 1 1723005424 516624304 21479824
12:06:47 3282 8192000000 10657815296 11111358464 1181713360 12.606 100 71 142 0 1761392000 562157344 17521296
12:07:54 7436 8192000000 10609198192 11064655872 1165242592 12.452 100 53 103 0 1718035520 538949776 13843152
12:09:13 7347 8192000000 10550921888 11014950912 1155589136 12.362 99 73 137 2 1643791008 470895728 17306144
12:10:23 6025 8192000000 10454227680 10942656512 1147058288 12.282 100 46 89 0 1557450864 398808288 11584288
12:11:32 13754 8192000000 10301329440 10865573888 1147788368 12.289 100 48 97 1 1400929104 241816800 11323936
12:12:49 19538 8192000000 10280366208 10736594944 1143840288 12.252 100 16 31 0 1385594544 237783120 3971136
12:14:12 58483 8192000000 10137266864 10598039552 1103291696 11.869 97 15 30 0 1207075296 100404784 3378816
12:16:12 31940 8192000000 10088236640 10507726848 1137110272 12.188 100 35 61 0 1190162976 46481936 6570768
12:17:46 94648 8192000000 10137576736 10607177728 1117651872 12.005 98 19 36 0 1212779472 91326064 3801536
12:21:02 94918 8192000000 10092594544 10577944576 1119300384 12.020 98 24 46 0 1175195136 52008640 3886112
12:23:47 116715 8192000000 10103383872 10538917888 1082927312 11.675 95 42 85 0 1128155264 36063376 9164576
12:26:49 45807 8192000000 10089330704 10549387264 1129199728 12.114 99 21 41 0 1215489616 82197312 4092576
12:28:43 26093 8192000000 10060481712 10530209792 1132233200 12.142 100 12 22 0 1171160592 36529952 2397440
12:30:10 129751 8192000000 10072427840 10559311872 1067652944 11.530 94 15 30 0 1143560112 72586608 3320560
12:33:21 29420 8192000000 10080780928 10527014912 1112902848 11.960 99 33 67 0 1184022944 64200176 6919920
12:34:53 74698 8192000000 10053527024 10512957440 1112950864 11.960 99 16 32 0 1166165392 49883328 3331200
12:37:12 95308 8192000000 10077006784 10540072960 1111225712 11.944 98 16 32 0 1149235392 34683248 3326432
12:40:18 45767 8192000000 10068571360 10552176640 1118594336 12.014 98 23 47 0 1187008704 63768816 4645552
12:42:05 44944 8192000000 10122315584 10563801088 1128452544 12.107 98 24 47 0 1206415248 73157168 4805536
12:43:53 15884 8192000000 10160782800 10614865920 1150495648 12.314 100 55 107 0 1274587424 112861504 11230272
12:45:17 25589 8192000000 10123072976 10557509632 1148290448 12.293 100 35 65 1 1219305552 64353920 6661184
12:46:43 32705 8192000000 10172664720 10624577536 1119392208 12.021 97 52 101 2 1253540416 123164064 10984144
12:48:25 30470 8192000000 10083129408 10511740928 1106658256 11.901 97 34 66 0 1148728640 35015984 7054400
12:49:56 28930 8192000000 10151517904 10611228672 1131240960 12.133 99 43 86 0 1241137744 100981888 8914896
12:51:33 76724 8192000000 10115031968 10593214464 1132891744 12.149 99 35 70 1 1231940944 91673504 7375696
12:54:41 23087 8192000000 10058392688 10501689344 1115094112 11.981 98 42 81 1 1152740576 30273184 7373280
12:56:06 69382 8192000000 10100111872 10563166208 1132513344 12.145 99 16 32 0 1217890144 81828544 3548256
12:58:24 54838 8192000000 10060630352 10528358400 1122835744 12.054 99 16 32 0 1165692144 39114576 3741824
13:00:19 72285 8192000000 10105709616 10557624320 1124308224 12.068 98 24 48 0 1200828032 71369584 5150224
13:02:46 66108 8192000000 10127878304 10592239616 1097485920 11.814 96 23 46 0 1198747392 96532736 4728736
13:04:58 60170 8192000000 10070544336 10565259264 1114614944 11.976 99 14 28 0 1175155408 57435888 3104576
13:07:01 37058 8192000000 10062656592 10514690048 1113129024 11.962 99 26 52 0 1161988736 43437264 5422448
13:08:38 35950 8192000000 10089621296 10535923712 1114885776 11.979 99 36 72 0 1180776384 58584032 7306576
13:10:15 68603 8192000000 10084825408 10554662912 1085279984 11.698 96 24 46 1 1155195520 64824048 5091488
13:12:25 25214 8192000000 10066540848 10490109952 1112295104 11.954 99 47 94 1 1174170352 52770032 9105216
13:13:55 9175 8192000000 10118750848 10536316928 1124791152 12.072 100 56 112 0 1199790304 63749632 11249520
13:15:06 10826 8192000000 10090058240 10523852800 1114224064 11.972 99 38 76 1 1181304400 59784000 7296336
13:16:22 130825 8192000000 10101330176 10578817024 1079096256 11.639 96 21 42 0 1138272320 55026560 4149504
13:19:34 97474 8192000000 10104813824 10555310080 1128643872 12.109 100 34 62 3 1200559584 64722880 7192832
13:22:20 16742 8192000000 10120992224 10588549120 1134122576 12.160 100 20 36 0 1229253072 90885120 4245376
13:23:37 49766 8192000000 10062665856 10552012800 1127092320 12.094 99 8 15 0 1171855552 43622912 1140320
13:25:27 68910 8192000000 10049953840 10504941568 1107131136 11.905 97 13 24 0 1136560304 26667056 2762112
13:27:41 78864 8192000000 10045373248 10504773632 1098436224 11.823 97 7 14 0 1128083568 28123680 1523664
13:30:05 23018 8192000000 10085622912 10518360064 1109547104 11.928 97 57 104 1 1166070016 45375648 11147264
13:31:29 75738 8192000000 10098883872 10545836032 1115475344 11.984 98 17 34 1 1198962528 79477616 4009568
13:33:49 12906 8192000000 10132298288 10583891968 1127602016 12.099 98 37 74 0 1214655152 79168784 7884352
13:35:04 68303 8192000000 10136299280 10582835200 1140022960 12.216 100 32 63 0 1240723328 94312048 6388320
13:37:21 20434 8192000000 9166914928 9424871424 251784464 2.981 23 5 10 0 335716000 82636912 1294624

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 19-01-2023 13:39:01 to 19-01-2023 13:39:18.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 103 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 19-01-2023 13:39:19 to 19-01-2023 13:39:21.

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          217760
MON$OLDEST_ACTIVE               224269
MON$OLDEST_SNAPSHOT             224269
MON$NEXT_TRANSACTION            224269
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                       2085472
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             779
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2890 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2890 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2890 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2890 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
--- end of diff output ---

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

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