Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.08.2022 16:44
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 16038264 kB
MemAvailable 31014972 kB
Buffers 0 kB
Cached 15705568 kB
SwapCached 2512 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-08-02 16:42:25
DTS_END                         2022-08-02 16:42:25.1110
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/38180
TRN_ID                          243650
ATT_ID                          671
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: 9277.80 23277 1113336
customer order: creation 177.63 344 21316
customer order: refuse 15.53 351 1863
order to supplier: creation 47.44 1168 5693
order to supplier: removal 3.33 104628 400
invoice (draft): creation 48.79 3276 5855
invoice (draft): removal 51.65 885 6198
invoice accept: apply 43.23 17478 5188
invoice accept: cancel 46.73 798 5607
customer reserve: creation 3961.62 175 475394
customer reserve: removal 4577.82 59 549338
realization accept: apply 86.18 191 10341
realization accept: cancel 85.66 101 10279
payment from customer: creation 60.55 143 7266
payment from customer: removal 5.68 92 681
payment to supplier: creation 50.29 180 6035
payment to supplier: removal 3.64 64 437
service: total inventory turnovers 4.48 1325 538
service: total monetary turnovers 6.64 41 797
service: aggregate perf. data 0.88 21837 106
service: refresh index statistics 0.03 312397 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 02-08-2022 16:44:28 to 02-08-2022 16:44:30.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4481
TEST_TIME 2 4069
TEST_TIME 3 3941
TEST_TIME 4 3846
TEST_TIME 5 5178
TEST_TIME 6 5482
TEST_TIME 7 5413
TEST_TIME 8 5635
TEST_TIME 9 5664
TEST_TIME 10 5819
TEST_TIME 11 6179
TEST_TIME 12 6402
TEST_TIME 13 6679
TEST_TIME 14 6908
TEST_TIME 15 7338
TEST_TIME 16 7646
TEST_TIME 17 7791
TEST_TIME 18 8273
TEST_TIME 19 8459
TEST_TIME 20 8305
TEST_TIME 21 8197
TEST_TIME 22 8323
TEST_TIME 23 8512
TEST_TIME 24 8721
TEST_TIME 25 8743
TEST_TIME 26 8837
TEST_TIME 27 9380
TEST_TIME 28 9859
TEST_TIME 29 10747
TEST_TIME 30 10784
TEST_TIME 31 10925
TEST_TIME 32 10615
TEST_TIME 33 10561
TEST_TIME 34 10462
TEST_TIME 35 10391
TEST_TIME 36 10248
TEST_TIME 37 10155
TEST_TIME 38 10174
TEST_TIME 39 10118
TEST_TIME 40 10108
TEST_TIME 41 10036
TEST_TIME 42 9914
TEST_TIME 43 10049
TEST_TIME 44 9976
TEST_TIME 45 9840
TEST_TIME 46 9762
TEST_TIME 47 9732
TEST_TIME 48 9747
TEST_TIME 49 9671
TEST_TIME 50 9585
TEST_TIME 51 9551
TEST_TIME 52 9492
TEST_TIME 53 9559
TEST_TIME 54 9519
TEST_TIME 55 9416
TEST_TIME 56 9292
TEST_TIME 57 9212
TEST_TIME 58 9202
TEST_TIME 59 9178
TEST_TIME 60 9165
TEST_TIME 61 9176
TEST_TIME 62 9215
TEST_TIME 63 9267
TEST_TIME 64 9248
TEST_TIME 65 9238
TEST_TIME 66 9282
TEST_TIME 67 9392
TEST_TIME 68 9331
TEST_TIME 69 9444
TEST_TIME 70 9416
TEST_TIME 71 9438
TEST_TIME 72 9452
TEST_TIME 73 9490
TEST_TIME 74 9487
TEST_TIME 75 9394
TEST_TIME 76 9362
TEST_TIME 77 9272
TEST_TIME 78 9269
TEST_TIME 79 9280
TEST_TIME 80 9204
TEST_TIME 81 9143
TEST_TIME 82 9181
TEST_TIME 83 9209
TEST_TIME 84 9180
TEST_TIME 85 9159
TEST_TIME 86 9183
TEST_TIME 87 9191
TEST_TIME 88 9209
TEST_TIME 89 9271
TEST_TIME 90 9248
TEST_TIME 91 9325
TEST_TIME 92 9348
TEST_TIME 93 9335
TEST_TIME 94 9359
TEST_TIME 95 9360
TEST_TIME 96 9417
TEST_TIME 97 9414
TEST_TIME 98 9365
TEST_TIME 99 9338
TEST_TIME 100 9315
TEST_TIME 101 9277
TEST_TIME 102 9275
TEST_TIME 103 9263
TEST_TIME 104 9218
TEST_TIME 105 9178
TEST_TIME 106 9200
TEST_TIME 107 9182
TEST_TIME 108 9203
TEST_TIME 109 9185
TEST_TIME 110 9207
TEST_TIME 111 9194
TEST_TIME 112 9168
TEST_TIME 113 9165
TEST_TIME 114 9319
TEST_TIME 115 9337
TEST_TIME 116 9346
TEST_TIME 117 9333
TEST_TIME 118 9351
TEST_TIME 119 9338
TEST_TIME 120 9279

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 02-08-2022 16:44:32 to 02-08-2022 16:44:35.

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 1553436 1553436 0 0.00 0 28145 7 0 0
doc_list_biud 1553436 1553436 0 0.00 0 58995 27 0 0
sp_add_invoice_to_stock 5252 5188 64 1.21 35 186659 17479 0 64
sp_cancel_adding_invoice 5607 5607 0 0.00 4 50233 799 0 0
sp_cancel_client_order 1863 1863 0 0.00 4 18793 352 0 0
sp_cancel_customer_reserve 549338 549338 0 0.00 0 58996 60 0 0
sp_cancel_pay_from_customer 681 681 0 0.00 3 6134 93 0 0
sp_cancel_pay_to_supplier 437 437 0 0.00 2 3589 64 0 0
sp_cancel_supplier_invoice 6206 6198 8 0.12 1 34350 885 0 8
sp_cancel_supplier_order 400 400 0 0.00 6 1005318 104629 0 0
sp_cancel_write_off 10279 10279 0 0.00 1 11324 102 0 0
sp_client_order 21316 21316 0 0.00 3 58496 345 0 0
sp_customer_reserve 475394 475394 0 0.00 0 54397 175 0 0
sp_fill_shopping_cart 32856 32856 0 0.00 1 20326 43 0 0
sp_fill_shopping_cart_clo_res 475391 475391 0 0.00 0 17351 15 0 0
sp_get_clo_for_invoice 5185 5185 0 0.00 0 34451 309 0 0
sp_kill_cost_storno 17004 17004 0 0.00 0 14604 53 0 0
sp_kill_qstorno_ret_qs2qd 1115553 1115553 0 0.00 0 58993 33 0 0
sp_kill_qty_storno 589210 589210 0 0.00 0 58994 70 0 0
sp_lock_selected_doc 21512 21512 0 0.00 0 6838 6 0 0
sp_make_cost_storno 28826 28826 0 0.00 0 27980 67 0 0
sp_multiply_rows_for_pdistr 28826 28826 0 0.00 0 19979 34 0 0
sp_multiply_rows_for_qdistr 21316 21316 0 0.00 0 58488 156 0 0
sp_pay_from_customer 7266 7266 0 0.00 4 20096 143 0 0
sp_pay_to_supplier 6035 6035 0 0.00 5 28163 181 0 0
sp_payment_common 13301 13301 0 0.00 1 19720 47 0 0
sp_qd_handle_on_cancel_clo 1863 1863 0 0.00 0 18788 325 0 0
sp_qd_handle_on_invoice_upd_sts 10791 10791 0 0.00 0 10000 295 0 0
sp_qd_handle_on_reserve_upd_sts 10341 10341 0 0.00 0 17035 52 0 0
sp_reserve_write_off 10341 10341 0 0.00 6 17603 191 0 0
sp_supplier_invoice 5855 5855 0 0.00 6 84590 3277 0 0
sp_supplier_order 5693 5693 0 0.00 5 26016 1168 0 0
srv_aggregate_perf_data 106 106 0 0.00 2160 346939 21838 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1851 128971 44542 0 0
srv_find_qd_qs_mism 1064119 1064119 0 0.00 0 13761 5 0 0
srv_make_invnt_saldo 538 538 0 0.00 25 80223 1326 0 0
srv_make_money_saldo 797 797 0 0.00 1 6593 41 0 0
srv_recalc_idx_stat 4 4 0 0.00 123759 622527 312397 0 0
t$perf-abend:sp_add_invoice_to_stock 64 64 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5188 5188 0 0.00 0 13886 161 0 0
t$perf-norm:sp_cancel_adding_invoice 446 446 0 0.00 0 7656 92 0 0
t$perf-norm:sp_cancel_client_order 1863 1863 0 0.00 0 1710 9 0 0
t$perf-norm:sp_cancel_customer_reserve 722 722 0 0.00 0 3025 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 681 681 0 0.00 0 2504 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 437 437 0 0.00 0 425 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 425 425 0 0.00 0 3084 16 0 0
t$perf-norm:sp_cancel_supplier_order 400 400 0 0.00 0 24908 1406 0 0
t$perf-norm:sp_cancel_write_off 1013 1013 0 0.00 0 1278 5 0 0
t$perf-norm:sp_client_order 21316 21316 0 0.00 0 9767 14 0 0
t$perf-norm:sp_customer_reserve 1023 1023 0 0.00 0 4968 10 0 0
t$perf-norm:sp_pay_from_customer 7266 7266 0 0.00 0 4416 8 0 0
t$perf-norm:sp_pay_to_supplier 6035 6035 0 0.00 0 3538 8 0 0
t$perf-norm:sp_reserve_write_off 10341 10341 0 0.00 0 14433 12 0 0
t$perf-norm:sp_supplier_invoice 5855 5855 0 0.00 0 14477 19 0 0
t$perf-norm:sp_supplier_order 5693 5693 0 0.00 0 3539 13 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 76 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 9 1 0 0
t$perf-norm:srv_make_invnt_saldo 538 538 0 0.00 0 566 18 0 0
t$perf-norm:srv_make_money_saldo 797 797 0 0.00 0 3120 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 10496 10368 128 1.21 0 7201 21 0 128
v_all_customers 38982 38982 0 0.00 0 99 1 0 0
v_all_suppliers 31890 31890 0 0.00 0 315 1 0 0
v_all_wares 42632 42632 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 892 892 0 0.00 0 831 7 0 0
v_cancel_client_order 3726 3726 0 0.00 0 3257 6 0 0
v_cancel_customer_prepayment 1362 1362 0 0.00 0 4796 15 0 0
v_cancel_customer_reserve 1444 1444 0 0.00 0 3126 8 0 0
v_cancel_payment_to_supplier 874 874 0 0.00 0 2161 6 0 0
v_cancel_supplier_invoice 866 850 16 1.84 0 1297 13 0 16
v_cancel_supplier_order 800 800 0 0.00 0 1395 6 0 0
v_cancel_write_off 2026 2026 0 0.00 0 5224 17 0 0
v_max_id_clo_ord 5690 5690 0 0.00 0 408 1 0 0
v_max_id_clo_res 1023 1023 0 0.00 0 8810 31 0 0
v_max_id_ord_sup 5850 5850 0 0.00 0 4967 7 0 0
v_max_non_paid_invoice 1630 1630 0 0.00 0 2811 5 0 0
v_max_non_paid_realizn 3721 3721 0 0.00 0 3657 5 0 0
v_min_id_clo_ord 5690 5690 0 0.00 0 10781 13 0 0
v_min_id_clo_res 1023 1023 0 0.00 0 4294 10 0 0
v_min_id_ord_sup 5850 5850 0 0.00 0 7665 13 0 0
v_min_non_paid_invoice 6035 6035 0 0.00 0 18334 37 0 0
v_min_non_paid_realizn 7266 7266 0 0.00 0 17412 34 0 0
v_our_firm 10740 10740 0 0.00 0 22 1 0 0
v_reserve_write_off 20682 20682 0 0.00 0 11550 10 0 0
x_lock_depdocs_on_canc_invoice 11380 11380 0 0.00 0 35093 125 0 0
x_lock_depdocs_on_canc_sup_ord 400 400 0 0.00 0 11000 354 0 0
x_make_qstorno_1000_1200 5693 5693 0 0.00 1 26008 1087 0 0
x_make_qstorno_1200_2000 5855 5855 0 0.00 2 76221 3115 0 0
x_make_qstorno_2100_3300 475394 475394 0 0.00 0 54395 157 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-08-2022 16:44:36 to 02-08-2022 16:44:40.

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:28 2 8192000000 8798053728 8809054208 4656880 0.056 2 1 2 0 4756480 56816 42784
14:13:29 23100 8192000000 9887191360 10195808256 968085200 10.568 100 88 176 0 997291840 5924576 23282064
14:14:56 28162 8192000000 9944754720 10267275264 1025552896 11.126 100 91 182 0 1057176464 8030752 23592816
14:16:26 13275 8192000000 9971191696 10303418368 1058304528 11.440 100 82 164 0 1088695952 9929568 20461856
14:17:47 11644 8192000000 10024545872 10368602112 1106992272 11.904 100 74 146 0 1135137072 10271984 17872816
14:19:02 7043 8192000000 10068860352 10427514880 1160347984 12.407 100 73 144 1 1187353984 10784784 16221216
14:20:11 4179 8192000000 10123933472 10489147392 1210699840 12.876 100 79 158 0 1241339888 12944400 17695648
14:21:18 2896 8192000000 10147468848 10523381760 1245617632 13.198 100 86 168 2 1277963024 13541696 18803696
14:22:22 2374 8192000000 10177022448 10554908672 1257396224 13.306 100 87 171 0 1290852976 14259280 19197472
14:23:25 1104 8192000000 10215294848 10597535744 1297009008 13.668 100 95 191 1 1332595312 14767616 20818688
14:24:28 10292 8192000000 10212726320 10615226368 1308294368 13.771 100 50 98 0 1328780656 9537600 10948688
14:25:40 14802 8192000000 10196861952 10615500800 1314039792 13.823 100 36 71 0 1328127536 5293520 8794224
14:27:04 7908 8192000000 10209973008 10625949696 1325697168 13.928 100 27 54 1 1337884944 5841680 6346096
14:28:15 11215 8192000000 10219634416 10638503936 1333204176 13.996 100 22 42 2 1344056448 5977936 4874336
14:29:26 13747 8192000000 10217226816 10643206144 1338869920 14.047 100 13 26 1 1347424368 5786048 2768400
14:30:41 23433 8192000000 10225840720 10652446720 1341353568 14.070 100 22 44 0 1351596944 5232976 5010400
14:32:06 4894 8192000000 10229819920 10650775552 1340263632 14.060 100 32 63 0 1353670816 6074496 7332688
14:33:12 11202 8192000000 10195126064 10600898560 1263508688 13.362 96 28 56 1 1275084992 5181472 6394832
14:34:24 74211 8192000000 10082597136 10464096256 1113731904 11.968 86 25 49 0 1123700304 4127424 5840976
14:36:40 7159 8192000000 10135220368 10534043648 1234046480 13.091 99 24 48 1 1245874192 6191216 5636496
14:37:48 11684 8192000000 10162940064 10564775936 1243771840 13.181 97 19 38 0 1258292304 10116672 4403792
14:39:04 64033 8192000000 10174787520 10589401088 1287821456 13.584 100 28 56 0 1300085040 5131744 7131840
14:41:11 2372 8192000000 10209785888 10615754752 1300039920 13.696 100 36 71 1 1314613792 6014928 8558944
14:42:15 9942 8192000000 10208332928 10628108288 1320018064 13.877 100 19 36 0 1328836576 4930896 3887616
14:43:39 28634 8192000000 10257848944 10672390144 1351067248 14.157 100 47 94 1 1370254048 8474704 10712096
14:45:10 24882 8192000000 10250260576 10677919744 1356156976 14.203 100 28 54 0 1370783344 7959552 6666816
14:46:43 20346 8192000000 10281139568 10704084992 1360168432 14.239 99 46 92 0 1385787520 15050912 10568176
14:48:21 14348 8192000000 10298301184 10723098624 1382969472 14.443 100 39 78 0 1408854656 16423088 9462096
14:49:39 6737 8192000000 10313515104 10738221056 1398104848 14.578 100 52 101 2 1427142944 16284240 12753856
14:50:49 9341 8192000000 10323229568 10750742528 1407958704 14.666 100 48 95 0 1437248704 18331040 10958960
14:52:04 1850 8192000000 10325585872 10751336448 1401489792 14.608 100 49 98 2 1434176816 20574592 12112432
14:53:13 11462 8192000000 10331124384 10764931072 1400429424 14.599 99 45 90 0 1438419712 27173792 10816496
14:54:30 3137 8192000000 10355820208 10774695936 1415439776 14.732 100 67 132 1 1466107056 35034816 15632464
14:55:39 3413 8192000000 10354618864 10769358848 1408372496 14.669 100 81 159 0 1459579712 32901856 18305360
14:56:47 4786 8192000000 10357880768 10773504000 1401319008 14.607 100 70 139 1 1461359568 43611584 16428976
14:58:03 3710 8192000000 10336865376 10754322432 1388735312 14.495 100 68 134 0 1444336464 38385328 17215824
14:59:10 5100 8192000000 10327880352 10742280192 1382566800 14.439 99 56 112 1 1434186688 37885168 13734720
15:00:21 2973 8192000000 10323694688 10732826624 1363968448 14.273 100 72 139 1 1426986512 45931408 17086656
15:01:30 8798 8192000000 10321392704 10742337536 1377535536 14.395 100 40 79 0 1427955568 39851216 10568816
15:02:43 4526 8192000000 10351097904 10775502848 1403443392 14.626 100 47 94 1 1461676192 46011984 12220816
15:03:52 12166 8192000000 10348328768 10766114816 1381377600 14.429 99 51 101 1 1445116288 50376720 13361968
15:05:12 8915 8192000000 10358168160 10774740992 1394551840 14.546 100 51 101 1 1461889680 53454064 13883776
15:06:35 13583 8192000000 10334771536 10763149312 1382602048 14.440 100 38 76 0 1443816848 49869904 11344896
15:07:58 25554 8192000000 10326219424 10760921088 1351937536 14.165 98 30 59 0 1418937024 57976064 9023424
15:09:45 38032 8192000000 10315848768 10752090112 1331401104 13.980 98 23 47 1 1390132480 51558592 7172784
15:11:25 35447 8192000000 10279865712 10716442624 1329485696 13.963 97 16 31 0 1360970576 28063184 3421696
15:13:04 93302 8192000000 10277230880 10710646784 1354646672 14.189 99 41 76 0 1378832512 15862224 8323616
15:15:38 28408 8192000000 10290673392 10732212224 1378260368 14.401 100 38 74 1 1394307248 7748960 8297920
15:17:10 83156 8192000000 10287299440 10739781632 1350576960 14.153 97 15 30 0 1367039504 12951648 3510896
15:19:51 93214 8192000000 10296566048 10754105344 1369500368 14.323 99 13 26 1 1395085728 22580208 3005152
15:22:26 47074 8192000000 10304269776 10748616704 1381110592 14.426 99 30 61 0 1398760032 10634000 7015440
15:24:16 30470 8192000000 10323274512 10763816960 1400399856 14.599 100 33 66 0 1420500384 12448800 7651728
15:25:48 60406 8192000000 10294797440 10743685120 1370896832 14.335 98 26 52 0 1387531984 10565344 6069808
15:27:50 108478 8192000000 10292598544 10752716800 1366597904 14.297 98 24 48 0 1382494464 10583808 5312752
15:31:01 34162 8192000000 10301385088 10757169152 1398609952 14.583 100 31 61 1 1413797088 7912960 7274176
15:33:52 55823 8192000000 10310185776 10781405184 1411120512 14.694 100 12 23 0 1427029344 13208480 2700352
15:35:48 39046 8192000000 10295579120 10761424896 1391691024 14.521 99 10 20 0 1407437136 13580304 2165808
15:38:05 43654 8192000000 10284847376 10752450560 1394994912 14.550 100 16 31 2 1405304896 6397536 3912448
15:39:51 76378 8192000000 10293911680 10770612224 1399787664 14.593 100 16 32 0 1416812896 13063184 3962048
15:42:32 55392 8192000000 10292289392 10765262848 1383403504 14.447 98 19 37 0 1396250912 8240768 4606640
15:44:33 41032 8192000000 10276550336 10730045440 1325636304 13.928 95 14 28 0 1335480656 6706240 3138112
15:46:15 31424 8192000000 10294617248 10735824896 1351195072 14.158 97 42 77 1 1365874384 6302320 8376992
15:47:49 51592 8192000000 10270854368 10728947712 1348993808 14.138 98 24 48 0 1367060416 12580272 5486336
15:49:41 63986 8192000000 10276705344 10730139648 1339956608 14.057 98 14 28 0 1356980528 13625712 3398208
15:51:46 68586 8192000000 10258658800 10696585216 1273686800 13.455 93 22 44 0 1290258544 11927072 4644672
15:53:56 36856 8192000000 10255605200 10686394368 1291054512 13.614 95 31 62 0 1310262304 12205392 7002400
15:55:39 38292 8192000000 10253670560 10689462272 1310570624 13.791 98 20 40 0 1327650480 13056016 4023840
15:57:21 99557 8192000000 10241336464 10690195456 1333165568 13.996 99 23 44 1 1352022464 12946528 5910368
16:00:09 40623 8192000000 10228317088 10676084736 1282549056 13.536 95 33 65 0 1301581216 12058272 6973888
16:01:53 48665 8192000000 10248953632 10704289792 1320915168 13.885 98 13 25 1 1338057168 14413424 2728576
16:03:46 54173 8192000000 10254467456 10702536704 1340752256 14.064 99 12 24 0 1356075632 12277472 3045904
16:05:42 77849 8192000000 10263460880 10712821760 1345000560 14.102 99 18 35 0 1359619648 10475728 4143360
16:08:00 69723 8192000000 10272822880 10735947776 1374109232 14.364 100 18 36 0 1387897648 9661472 4126944
16:10:12 36550 8192000000 10300146800 10748391424 1385379552 14.465 100 24 48 0 1402706192 11524848 5801792
16:12:06 78044 8192000000 10310220896 10755252224 1366390384 14.295 98 18 35 0 1387097440 16952448 3754608
16:14:31 90164 8192000000 10305858016 10757373952 1387425984 14.483 99 13 27 0 1404930544 14588928 2915632
16:17:08 128971 8192000000 10301210064 10752659456 1330036160 13.967 94 22 45 0 1349273968 14041184 5196624
16:20:18 126490 8192000000 10290963840 10750664704 1355702368 14.199 97 25 49 0 1369702768 8933152 5067248
16:24:17 73025 8192000000 10278611696 10737561600 1361090896 14.247 98 14 28 1 1376454736 11784032 3579808
16:26:37 41164 8192000000 10281097104 10739585024 1384608704 14.458 100 5 10 0 1396628240 10508464 1511072
16:28:18 60910 8192000000 10261240688 10730688512 1343685312 14.091 98 8 16 0 1356524240 10836608 2002320
16:30:20 41549 8192000000 10264530592 10712727552 1334228176 14.005 97 11 22 0 1343981008 6944688 2808144
16:32:05 47918 8192000000 10260021600 10701225984 1309601200 13.782 96 25 48 0 1324213280 9375136 5236944
16:33:56 37165 8192000000 10288626352 10729959424 1350399280 14.151 98 29 58 0 1374279232 17239472 6640480
16:35:40 121576 8192000000 10262859408 10730074112 1297725536 13.675 95 10 20 0 1322062560 22397728 1939296
16:38:43 56311 8192000000 10257060816 10693959680 1292373456 13.626 96 34 68 0 1310780896 11099120 7308320
16:40:46 41663 8192000000 10270942496 10706006016 1330224336 13.969 98 21 42 0 1345918480 10995888 4698256

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 02-08-2022 16:44:42 to 02-08-2022 16:44:57.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 64 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 02-08-2022 16:44:58 to 02-08-2022 16:45:00.

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          242382
MON$OLDEST_ACTIVE               244762
MON$OLDEST_SNAPSHOT             244762
MON$NEXT_TRANSACTION            244762
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                       2029344
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.33614 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33614 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33614 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33614 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 Aug  2 16:42:26 2022
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Tue Aug  2 16:42:33 2022
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Tue Aug  2 16:43:04 2022
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:17: 
--- end of diff output ---

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

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