Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.01.2023 16:43
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 15413696 kB
MemAvailable 30390752 kB
Buffers 0 kB
Cached 16274660 kB
SwapCached 1632 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-24 16:42:19
DTS_END                         2023-01-24 16:42:19.8060
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/57300
TRN_ID                          236012
ATT_ID                          666
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: 9200.61 19709 1104073
customer order: creation 169.68 354 20361
customer order: refuse 14.69 382 1763
order to supplier: creation 43.53 1250 5224
order to supplier: removal 3.37 113219 404
invoice (draft): creation 45.16 3269 5419
invoice (draft): removal 53.93 898 6471
invoice accept: apply 40.42 18980 4851
invoice accept: cancel 48.19 816 5783
customer reserve: creation 3711.53 187 445383
customer reserve: removal 4777.69 61 573323
realization accept: apply 82.18 212 9862
realization accept: cancel 84.60 109 10152
payment from customer: creation 56.86 129 6823
payment from customer: removal 5.33 118 639
payment to supplier: creation 48.04 164 5765
payment to supplier: removal 3.30 232 396
service: total inventory turnovers 4.58 1159 550
service: total monetary turnovers 6.70 28 804
service: aggregate perf. data 0.80 21622 96
service: refresh index statistics 0.03 230994 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 24-01-2023 16:43:25 to 24-01-2023 16:43:27.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1902
TEST_TIME 2 3392
TEST_TIME 3 3644
TEST_TIME 4 3910
TEST_TIME 5 4305
TEST_TIME 6 5171
TEST_TIME 7 5234
TEST_TIME 8 5454
TEST_TIME 9 5830
TEST_TIME 10 6116
TEST_TIME 11 6168
TEST_TIME 12 6175
TEST_TIME 13 6314
TEST_TIME 14 6336
TEST_TIME 15 6336
TEST_TIME 16 6596
TEST_TIME 17 6930
TEST_TIME 18 6867
TEST_TIME 19 7370
TEST_TIME 20 7758
TEST_TIME 21 7913
TEST_TIME 22 8015
TEST_TIME 23 8059
TEST_TIME 24 8769
TEST_TIME 25 8925
TEST_TIME 26 9231
TEST_TIME 27 9533
TEST_TIME 28 9916
TEST_TIME 29 10291
TEST_TIME 30 10737
TEST_TIME 31 10523
TEST_TIME 32 10406
TEST_TIME 33 10411
TEST_TIME 34 10405
TEST_TIME 35 10316
TEST_TIME 36 10162
TEST_TIME 37 9998
TEST_TIME 38 10011
TEST_TIME 39 9977
TEST_TIME 40 9806
TEST_TIME 41 9779
TEST_TIME 42 9719
TEST_TIME 43 9654
TEST_TIME 44 9540
TEST_TIME 45 9455
TEST_TIME 46 9391
TEST_TIME 47 9318
TEST_TIME 48 9292
TEST_TIME 49 9228
TEST_TIME 50 9172
TEST_TIME 51 9129
TEST_TIME 52 9073
TEST_TIME 53 9110
TEST_TIME 54 9145
TEST_TIME 55 9065
TEST_TIME 56 9128
TEST_TIME 57 9198
TEST_TIME 58 9139
TEST_TIME 59 9164
TEST_TIME 60 9185
TEST_TIME 61 9200
TEST_TIME 62 9201
TEST_TIME 63 9141
TEST_TIME 64 9325
TEST_TIME 65 9266
TEST_TIME 66 9340
TEST_TIME 67 9385
TEST_TIME 68 9393
TEST_TIME 69 9405
TEST_TIME 70 9387
TEST_TIME 71 9391
TEST_TIME 72 9448
TEST_TIME 73 9327
TEST_TIME 74 9303
TEST_TIME 75 9288
TEST_TIME 76 9230
TEST_TIME 77 9240
TEST_TIME 78 9203
TEST_TIME 79 9205
TEST_TIME 80 9188
TEST_TIME 81 9112
TEST_TIME 82 9045
TEST_TIME 83 9022
TEST_TIME 84 9023
TEST_TIME 85 8979
TEST_TIME 86 9022
TEST_TIME 87 9045
TEST_TIME 88 9217
TEST_TIME 89 9236
TEST_TIME 90 9256
TEST_TIME 91 9253
TEST_TIME 92 9256
TEST_TIME 93 9341
TEST_TIME 94 9333
TEST_TIME 95 9372
TEST_TIME 96 9360
TEST_TIME 97 9383
TEST_TIME 98 9439
TEST_TIME 99 9424
TEST_TIME 100 9352
TEST_TIME 101 9356
TEST_TIME 102 9347
TEST_TIME 103 9315
TEST_TIME 104 9295
TEST_TIME 105 9302
TEST_TIME 106 9277
TEST_TIME 107 9256
TEST_TIME 108 9226
TEST_TIME 109 9160
TEST_TIME 110 9172
TEST_TIME 111 9165
TEST_TIME 112 9211
TEST_TIME 113 9242
TEST_TIME 114 9232
TEST_TIME 115 9256
TEST_TIME 116 9236
TEST_TIME 117 9254
TEST_TIME 118 9245
TEST_TIME 119 9265
TEST_TIME 120 9201

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 24-01-2023 16:43:29 to 24-01-2023 16:43:32.

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 1517930 1517930 0 0.00 0 37464 7 0 0
doc_list_biud 1517929 1517929 0 0.00 0 28329 30 0 0
sp_add_invoice_to_stock 4935 4851 84 1.70 53 189195 18980 0 84
sp_cancel_adding_invoice 5783 5783 0 0.00 5 89448 816 0 0
sp_cancel_client_order 1763 1763 0 0.00 5 20040 383 0 0
sp_cancel_customer_reserve 573323 573323 0 0.00 1 25745 62 0 0
sp_cancel_pay_from_customer 639 639 0 0.00 2 15613 118 0 0
sp_cancel_pay_to_supplier 396 396 0 0.00 3 19456 232 0 0
sp_cancel_supplier_invoice 6483 6471 12 0.18 2 28335 899 0 12
sp_cancel_supplier_order 404 404 0 0.00 5 1055737 113220 0 0
sp_cancel_write_off 10152 10152 0 0.00 1 10804 110 0 0
sp_client_order 20361 20361 0 0.00 3 23804 355 0 0
sp_customer_reserve 445383 445383 0 0.00 0 71125 188 0 0
sp_fill_shopping_cart 31004 31004 0 0.00 1 32819 44 0 0
sp_fill_shopping_cart_clo_res 445382 445382 0 0.00 0 28631 13 0 0
sp_get_clo_for_invoice 4850 4850 0 0.00 0 27631 326 0 0
sp_kill_cost_storno 16970 16970 0 0.00 0 15815 60 0 0
sp_kill_qstorno_ret_qs2qd 1163673 1163673 0 0.00 0 28325 34 0 0
sp_kill_qty_storno 612608 612608 0 0.00 0 28328 73 0 0
sp_lock_selected_doc 20431 20431 0 0.00 0 8178 6 0 0
sp_make_cost_storno 27300 27300 0 0.00 0 14934 58 0 0
sp_multiply_rows_for_pdistr 27300 27300 0 0.00 0 16187 38 0 0
sp_multiply_rows_for_qdistr 20361 20361 0 0.00 0 23778 153 0 0
sp_pay_from_customer 6823 6823 0 0.00 4 23353 130 0 0
sp_pay_to_supplier 5765 5765 0 0.00 4 24010 164 0 0
sp_payment_common 12588 12588 0 0.00 1 23347 49 0 0
sp_qd_handle_on_cancel_clo 1763 1763 0 0.00 0 10039 323 0 0
sp_qd_handle_on_invoice_upd_sts 10633 10633 0 0.00 0 24960 365 0 0
sp_qd_handle_on_reserve_upd_sts 9862 9862 0 0.00 0 11234 69 0 0
sp_reserve_write_off 9862 9862 0 0.00 6 22360 212 0 0
sp_supplier_invoice 5419 5419 0 0.00 5 75404 3269 0 0
sp_supplier_order 5224 5224 0 0.00 4 35422 1251 0 0
srv_aggregate_perf_data 96 96 0 0.00 2298 457719 21622 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1414 119792 43165 0 0
srv_find_qd_qs_mism 1058458 1058458 0 0.00 0 37461 5 0 0
srv_make_invnt_saldo 550 550 0 0.00 35 63782 1159 0 0
srv_make_money_saldo 804 804 0 0.00 0 582 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 155838 401723 230995 0 0
t$perf-abend:sp_add_invoice_to_stock 84 84 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4851 4851 0 0.00 0 8882 177 0 0
t$perf-norm:sp_cancel_adding_invoice 411 411 0 0.00 0 4348 98 0 0
t$perf-norm:sp_cancel_client_order 1763 1763 0 0.00 0 12102 19 0 0
t$perf-norm:sp_cancel_customer_reserve 676 676 0 0.00 0 2977 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 639 639 0 0.00 0 654 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 396 396 0 0.00 0 2866 18 0 0
t$perf-norm:sp_cancel_supplier_invoice 424 424 0 0.00 0 1159 12 0 0
t$perf-norm:sp_cancel_supplier_order 404 404 0 0.00 0 26995 1746 0 0
t$perf-norm:sp_cancel_write_off 1006 1006 0 0.00 0 1252 7 0 0
t$perf-norm:sp_client_order 20361 20361 0 0.00 0 12788 16 0 0
t$perf-norm:sp_customer_reserve 947 947 0 0.00 0 2074 5 0 0
t$perf-norm:sp_pay_from_customer 6823 6823 0 0.00 0 11470 10 0 0
t$perf-norm:sp_pay_to_supplier 5765 5765 0 0.00 0 16376 13 0 0
t$perf-norm:sp_reserve_write_off 9862 9862 0 0.00 0 18736 14 0 0
t$perf-norm:sp_supplier_invoice 5419 5419 0 0.00 0 7129 20 0 0
t$perf-norm:sp_supplier_order 5224 5224 0 0.00 0 6873 17 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 662 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 466 10 0 0
t$perf-norm:srv_make_invnt_saldo 550 550 0 0.00 0 761 26 0 0
t$perf-norm:srv_make_money_saldo 804 804 0 0.00 0 2711 21 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 40 11 0 0
v_add_invoice_to_stock 9868 9700 168 1.70 0 21356 27 0 168
v_all_customers 36948 36948 0 0.00 0 219 1 0 0
v_all_suppliers 29832 29832 0 0.00 0 98 1 0 0
v_all_wares 40722 40722 0 0.00 0 43 1 0 0
v_cancel_adding_invoice 822 822 0 0.00 0 837 9 0 0
v_cancel_client_order 3526 3526 0 0.00 0 19875 14 0 0
v_cancel_customer_prepayment 1278 1278 0 0.00 0 2291 6 0 0
v_cancel_customer_reserve 1352 1352 0 0.00 0 3718 8 0 0
v_cancel_payment_to_supplier 792 792 0 0.00 0 19283 54 0 0
v_cancel_supplier_invoice 872 848 24 2.75 0 19876 70 0 24
v_cancel_supplier_order 808 808 0 0.00 0 1286 6 0 0
v_cancel_write_off 2012 2012 0 0.00 0 4569 17 0 0
v_max_id_clo_ord 5224 5224 0 0.00 0 7105 3 0 0
v_max_id_clo_res 947 947 0 0.00 0 5461 16 0 0
v_max_id_ord_sup 5419 5419 0 0.00 0 4632 4 0 0
v_max_non_paid_invoice 1492 1492 0 0.00 0 822 4 0 0
v_max_non_paid_realizn 3645 3645 0 0.00 0 2999 4 0 0
v_min_id_clo_ord 5224 5224 0 0.00 0 2836 8 0 0
v_min_id_clo_res 947 947 0 0.00 0 3784 8 0 0
v_min_id_ord_sup 5419 5419 0 0.00 0 3068 6 0 0
v_min_non_paid_invoice 5765 5765 0 0.00 0 18777 46 0 0
v_min_non_paid_realizn 6823 6823 0 0.00 0 23345 40 0 0
v_our_firm 10130 10130 0 0.00 0 20 1 0 0
v_reserve_write_off 19724 19724 0 0.00 0 22183 10 0 0
x_lock_depdocs_on_canc_invoice 11830 11830 0 0.00 0 17215 129 0 0
x_lock_depdocs_on_canc_sup_ord 404 404 0 0.00 0 9148 453 0 0
x_make_qstorno_1000_1200 5224 5224 0 0.00 1 35386 1169 0 0
x_make_qstorno_1200_2000 5419 5419 0 0.00 2 74128 3100 0 0
x_make_qstorno_2100_3300 445383 445383 0 0.00 0 71124 172 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-01-2023 16:43:33 to 24-01-2023 16:43:37.

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:12:23 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:13:25 29396 8192000000 9909564128 10220605440 985691488 10.740 100 93 186 0 1016339600 6414048 24234064
14:15:02 12567 8192000000 9943424976 10260795392 1024146704 11.112 100 91 182 0 1056161568 8502416 23512448
14:16:23 15156 8192000000 9966512368 10299047936 1054198176 11.401 100 80 160 0 1084155872 9686112 20271584
14:17:48 5153 8192000000 10023297392 10364334080 1106791664 11.902 100 81 162 0 1136940976 10914048 19235264
14:19:00 4850 8192000000 10072020768 10428276736 1160584128 12.409 100 72 144 0 1189114672 11838816 16691728
14:20:07 3196 8192000000 10118961344 10484416512 1204860256 12.821 100 79 156 1 1235421248 13432096 17128896
14:21:11 1715 8192000000 10157364128 10530684928 1243331120 13.177 100 84 168 1 1275596448 13448192 18817136
14:22:15 1435 8192000000 10187666800 10566180864 1272084192 13.441 100 87 175 0 1305652896 14905216 18663488
14:23:18 925 8192000000 10221935712 10605453312 1302182240 13.715 100 88 177 0 1333852768 11988752 19681776
14:24:21 25771 8192000000 10207362464 10613129216 1313350992 13.816 100 40 80 1 1330189168 7195072 9643104
14:25:48 5061 8192000000 10220697248 10630901760 1321888768 13.894 100 34 66 1 1340813072 11412784 7511520
14:26:55 8018 8192000000 10206915264 10626973696 1327409552 13.944 100 11 22 0 1335130224 5303680 2416992
14:28:04 66498 8192000000 10226179968 10644127744 1328552128 13.954 100 26 52 1 1347834032 13673728 5608176
14:30:11 7280 8192000000 10224164592 10645110784 1333983824 14.003 100 22 44 0 1345050688 6251520 4815344
14:31:19 7791 8192000000 10224161664 10648014848 1337103488 14.031 100 17 34 0 1346417904 5145120 4169296
14:32:29 15971 8192000000 10207437456 10609758208 1242613008 13.170 94 14 28 0 1251430272 4966736 3850528
14:33:47 30421 8192000000 10172730768 10581372928 1246444528 13.206 96 21 40 1 1257126704 5875248 4806928
14:35:18 11399 8192000000 10164473296 10566127616 1246771312 13.209 98 17 35 1 1256201024 5181408 4248304
14:36:29 7955 8192000000 10130468656 10532716544 1236195936 13.111 99 20 41 1 1247134128 4784832 6153360
14:37:40 73605 8192000000 10160781776 10572476416 1263368208 13.361 99 24 48 0 1274429936 5415856 5645872
14:39:54 5650 8192000000 10181454688 10587127808 1285535136 13.564 100 37 74 1 1299915520 6065984 8314400
14:41:01 11245 8192000000 10192486720 10599362560 1298314320 13.680 100 23 45 1 1308187760 4481840 5391600
14:42:12 29764 8192000000 10222230960 10631929856 1320645024 13.883 100 23 46 0 1331158752 5031632 5482096
14:43:49 61392 8192000000 10250428832 10664710144 1340604176 14.063 100 47 94 0 1361839856 11059664 10176016
14:45:58 8154 8192000000 10266961040 10680303616 1356519376 14.206 100 63 124 2 1383177040 12129952 14527712
14:47:14 8469 8192000000 10278212720 10694017024 1366294928 14.294 100 52 102 1 1392869008 15174928 11399152
14:48:24 9190 8192000000 10285675296 10708643840 1373863600 14.362 100 44 88 0 1400750032 16823808 10062624
14:49:47 7516 8192000000 10302702624 10726543360 1386901680 14.478 100 47 93 1 1417397488 18769040 11726768
14:51:00 20096 8192000000 10305730304 10731237376 1389877072 14.505 100 54 108 2 1419097872 16060240 13160560
14:52:25 8553 8192000000 10338515856 10755784704 1400118720 14.596 100 56 108 0 1440622656 26977424 13526512
14:53:37 9826 8192000000 10341184944 10769641472 1412129200 14.703 100 43 86 0 1455174400 32107248 10937952
14:54:50 15299 8192000000 10345295296 10775080960 1407935520 14.666 99 44 88 0 1450099280 31088928 11074832
14:56:13 1549 8192000000 10355376320 10771267584 1414819904 14.727 100 69 137 1 1465196528 34495808 15880816
14:57:20 4171 8192000000 10358472688 10776928256 1410985824 14.693 100 58 115 0 1463679920 38507440 14186656
14:58:31 5105 8192000000 10346269680 10758234112 1389010944 14.497 99 76 153 1 1450756592 43357248 18388400
14:59:42 4529 8192000000 10356958384 10767392768 1389583248 14.502 99 71 140 0 1451024144 44022368 17418528
15:00:52 3845 8192000000 10350995952 10753167360 1378703488 14.405 100 86 172 1 1452848400 52827152 21317760
15:02:01 2440 8192000000 10354651120 10762788864 1391340224 14.518 100 83 166 2 1459701536 47843328 20517984
15:03:07 1877 8192000000 10368376512 10773336064 1397393360 14.572 100 86 167 2 1468701424 49491232 21816832
15:04:16 1404 8192000000 10370765888 10769866752 1388633472 14.494 100 90 181 0 1466368976 54871584 22863920
15:05:23 2387 8192000000 10347877264 10762182656 1380084848 14.417 100 60 118 0 1453455840 57571984 15799008
15:06:29 5243 8192000000 10371177408 10795302912 1402361552 14.616 100 47 91 0 1475223168 60278336 12583280
15:07:44 18097 8192000000 10364443984 10797035520 1411542288 14.698 100 32 64 0 1470888640 49958976 9387376
15:09:21 27932 8192000000 10323632192 10766184448 1390320624 14.509 100 28 56 0 1451812544 53190896 8301024
15:11:33 7689 8192000000 10334233360 10763509760 1356912480 14.210 98 49 99 0 1409445392 39753408 12779504
15:12:43 41128 8192000000 10283342480 10717462528 1344527120 14.098 97 21 40 0 1366912896 17699248 4686528
15:14:28 105333 8192000000 10284237856 10736267264 1358029440 14.220 98 30 60 0 1382807152 17774848 7002864
15:17:53 12998 8192000000 10294881936 10744676352 1365120672 14.283 99 23 42 1 1389801808 20203120 4478016
15:19:07 42989 8192000000 10290221360 10739421184 1381550592 14.430 100 33 64 1 1400206160 11985264 6670304
15:21:08 81787 8192000000 10284701008 10738438144 1363168816 14.266 98 10 20 0 1375504320 9938528 2396976
15:23:44 119768 8192000000 10270088368 10720497664 1356297360 14.204 98 11 20 1 1368852656 10277792 2277504
15:26:44 75517 8192000000 10284320336 10745008128 1386651728 14.476 100 18 37 1 1400215552 9453904 4109920
15:29:10 65102 8192000000 10293709600 10746433536 1379262496 14.410 99 44 86 1 1398047088 9173456 9611136
15:31:25 19103 8192000000 10296611648 10757242880 1394190320 14.543 100 21 40 0 1413367984 14543264 4634400
15:32:49 17902 8192000000 10292338448 10741698560 1382081104 14.435 99 48 92 2 1405671088 12620320 10969664
15:34:15 85928 8192000000 10293146240 10762260480 1398619712 14.583 100 7 14 0 1414228928 13865024 1744192
15:36:45 72853 8192000000 10300211824 10763939840 1403514704 14.626 100 23 45 0 1424408656 14862672 6031280
15:39:23 65974 8192000000 10290957728 10749923328 1347815824 14.128 96 11 22 0 1365460720 15179552 2465344
15:41:32 81340 8192000000 10294791792 10754871296 1371016544 14.336 98 22 44 0 1386797744 10459472 5321728
15:43:54 67607 8192000000 10281818768 10729263104 1290705184 13.611 92 11 22 0 1301086576 8154048 2227344
15:46:03 87285 8192000000 10268053392 10715967488 1314321376 13.825 96 13 26 0 1335927120 19030736 2575008
15:48:36 74286 8192000000 10255373552 10700640256 1292998624 13.632 95 4 8 0 1305805328 12205680 601024
15:50:50 53546 8192000000 10257375456 10688593920 1298035568 13.677 96 36 72 0 1318575888 12654512 7885808
15:52:44 29145 8192000000 10266682336 10701049856 1346687952 14.118 100 37 74 0 1369285664 13830944 8766768
15:54:19 85681 8192000000 10254535136 10702532608 1326191648 13.933 98 10 20 1 1353353152 24927504 2234000
15:57:06 73147 8192000000 10235863504 10680102912 1314206800 13.824 98 22 43 1 1329205904 10077888 4921216
15:59:22 48082 8192000000 10244104256 10692874240 1332803952 13.992 99 19 38 1 1352162192 14496720 4861520
16:01:12 42642 8192000000 10245338320 10696347648 1344817440 14.101 99 14 28 0 1357532880 9093712 3621728
16:02:56 37449 8192000000 10261082448 10708279296 1341705760 14.073 99 10 20 0 1350435200 6157552 2571888
16:04:55 55989 8192000000 10258424448 10719219712 1362183776 14.257 100 7 15 0 1375149120 11252480 1712864
16:07:32 41461 8192000000 10285221280 10726862848 1354551056 14.188 98 24 48 0 1381901632 21598016 5752560
16:09:15 70710 8192000000 10300609872 10757185536 1362834704 14.263 99 26 52 0 1399328544 29806032 6687808
16:11:29 98916 8192000000 10282087392 10723078144 1299719392 13.693 94 13 25 1 1315139072 12926016 2493664
16:14:13 67573 8192000000 10296373440 10751664128 1382163728 14.436 100 11 22 0 1398996960 14608752 2224480
16:16:26 34686 8192000000 10307248688 10750193664 1393477328 14.537 100 31 59 0 1410485904 10366912 6641664
16:18:04 56766 8192000000 10297083824 10737774592 1364476320 14.278 98 36 70 0 1382544416 10283952 7784144
16:20:03 100632 8192000000 10292427312 10747703296 1351670672 14.163 97 9 18 0 1368050144 14436640 1942832
16:22:44 115409 8192000000 10313110864 10767466496 1390908704 14.514 99 31 62 0 1411979248 13747904 7322640
16:25:49 24205 8192000000 10292501920 10747183104 1381399472 14.429 99 33 64 0 1398593296 9688704 7505120
16:27:18 84005 8192000000 10305489552 10774495232 1376272416 14.383 98 7 13 0 1394419072 16796288 1350368
16:29:47 55663 8192000000 10293608016 10753343488 1363569824 14.269 97 13 27 0 1377664048 11054912 3039312
16:32:05 40718 8192000000 10307184720 10777231360 1404933392 14.639 100 11 22 0 1425707712 17909200 2865120
16:33:49 58259 8192000000 10277876672 10743214080 1331464240 13.980 96 11 22 0 1346776688 12706000 2606448
16:35:55 74116 8192000000 10265250000 10722332672 1287305600 13.580 93 15 30 0 1298281696 7733632 3242464
16:38:30 15569 8192000000 10294408912 10728955904 1364876832 14.281 99 54 108 0 1386672032 10034960 11760240
16:39:48 31114 8192000000 10279666928 10717663232 1334792096 14.010 97 31 61 0 1353442544 11323920 7326528
16:42:05 28677 8192000000 9177190432 9427591168 329462544 3.866 24 3 6 0 341776784 11631072 683168

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 24-01-2023 16:43:39 to 24-01-2023 16:43:54.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 84 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 24-01-2023 16:43:55 to 24-01-2023 16:43:57.

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          237285
MON$OLDEST_ACTIVE               237286
MON$OLDEST_SNAPSHOT             237286
MON$NEXT_TRANSACTION            237286
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                       2015296
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.33657 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33657 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33657 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33657 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Tue Jan 24 16:42:48 2023
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Tue Jan 24 16:42:51 2023
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:13: 
--- end of diff output ---

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

24.01.23 16:43:58. 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-24 16:44:01 No severe PSQL-related problems occured
24.01.23 16:44:02. End of report.