Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.04.2022 22: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 16570388 kB
MemAvailable 31529236 kB
Buffers 0 kB
Cached 15122256 kB
SwapCached 3216 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.10
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-04-27 22:38:14
DTS_END                         2022-04-27 22:38:14.3390
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/57064
TRN_ID                          242043
ATT_ID                          654
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: 9255.72 28564 1110686
customer order: creation 171.68 347 20602
customer order: refuse 14.78 356 1773
order to supplier: creation 46.45 1290 5574
order to supplier: removal 3.18 109907 381
invoice (draft): creation 46.79 3419 5615
invoice (draft): removal 51.51 784 6181
invoice accept: apply 43.13 18443 5175
invoice accept: cancel 47.27 739 5672
customer reserve: creation 3911.90 186 469428
customer reserve: removal 4618.52 59 554223
realization accept: apply 83.51 179 10021
realization accept: cancel 85.21 101 10225
payment from customer: creation 60.31 149 7237
payment from customer: removal 6.09 116 731
payment to supplier: creation 49.64 132 5957
payment to supplier: removal 3.62 147 434
service: total inventory turnovers 4.79 971 575
service: total monetary turnovers 6.56 25 787
service: aggregate perf. data 0.76 23319 91
service: refresh index statistics 0.03 410607 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 27-04-2022 22:39:03 to 27-04-2022 22:39:05.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9849
TEST_TIME 2 6024
TEST_TIME 3 5766
TEST_TIME 4 6005
TEST_TIME 5 6198
TEST_TIME 6 6105
TEST_TIME 7 6104
TEST_TIME 8 6203
TEST_TIME 9 5929
TEST_TIME 10 6144
TEST_TIME 11 5986
TEST_TIME 12 5986
TEST_TIME 13 6317
TEST_TIME 14 6267
TEST_TIME 15 6248
TEST_TIME 16 6524
TEST_TIME 17 6894
TEST_TIME 18 7203
TEST_TIME 19 7584
TEST_TIME 20 7721
TEST_TIME 21 7874
TEST_TIME 22 8071
TEST_TIME 23 8452
TEST_TIME 24 8599
TEST_TIME 25 9241
TEST_TIME 26 9413
TEST_TIME 27 9689
TEST_TIME 28 10219
TEST_TIME 29 10750
TEST_TIME 30 10680
TEST_TIME 31 10423
TEST_TIME 32 10472
TEST_TIME 33 10814
TEST_TIME 34 10680
TEST_TIME 35 10815
TEST_TIME 36 10708
TEST_TIME 37 10680
TEST_TIME 38 10579
TEST_TIME 39 10355
TEST_TIME 40 10212
TEST_TIME 41 10129
TEST_TIME 42 10020
TEST_TIME 43 10062
TEST_TIME 44 10027
TEST_TIME 45 9865
TEST_TIME 46 9861
TEST_TIME 47 9849
TEST_TIME 48 9822
TEST_TIME 49 9782
TEST_TIME 50 9776
TEST_TIME 51 9701
TEST_TIME 52 9730
TEST_TIME 53 9737
TEST_TIME 54 9617
TEST_TIME 55 9532
TEST_TIME 56 9590
TEST_TIME 57 9479
TEST_TIME 58 9511
TEST_TIME 59 9460
TEST_TIME 60 9396
TEST_TIME 61 9310
TEST_TIME 62 9265
TEST_TIME 63 9244
TEST_TIME 64 9231
TEST_TIME 65 9217
TEST_TIME 66 9267
TEST_TIME 67 9250
TEST_TIME 68 9367
TEST_TIME 69 9397
TEST_TIME 70 9401
TEST_TIME 71 9409
TEST_TIME 72 9493
TEST_TIME 73 9446
TEST_TIME 74 9545
TEST_TIME 75 9521
TEST_TIME 76 9544
TEST_TIME 77 9494
TEST_TIME 78 9436
TEST_TIME 79 9418
TEST_TIME 80 9331
TEST_TIME 81 9370
TEST_TIME 82 9333
TEST_TIME 83 9285
TEST_TIME 84 9269
TEST_TIME 85 9176
TEST_TIME 86 9259
TEST_TIME 87 9217
TEST_TIME 88 9186
TEST_TIME 89 9247
TEST_TIME 90 9222
TEST_TIME 91 9222
TEST_TIME 92 9311
TEST_TIME 93 9316
TEST_TIME 94 9388
TEST_TIME 95 9420
TEST_TIME 96 9422
TEST_TIME 97 9413
TEST_TIME 98 9373
TEST_TIME 99 9403
TEST_TIME 100 9491
TEST_TIME 101 9525
TEST_TIME 102 9449
TEST_TIME 103 9382
TEST_TIME 104 9389
TEST_TIME 105 9335
TEST_TIME 106 9276
TEST_TIME 107 9241
TEST_TIME 108 9218
TEST_TIME 109 9186
TEST_TIME 110 9207
TEST_TIME 111 9204
TEST_TIME 112 9211
TEST_TIME 113 9222
TEST_TIME 114 9232
TEST_TIME 115 9233
TEST_TIME 116 9241
TEST_TIME 117 9205
TEST_TIME 118 9256
TEST_TIME 119 9323
TEST_TIME 120 9262

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 27-04-2022 22:39:06 to 27-04-2022 22:39:10.

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 1545668 1545668 0 0.00 0 24970 6 0 0
doc_list_biud 1545667 1545667 0 0.00 0 39289 27 0 0
sp_add_invoice_to_stock 5300 5175 125 2.35 38 149980 18444 0 125
sp_cancel_adding_invoice 5672 5672 0 0.00 5 46687 740 0 0
sp_cancel_client_order 1773 1773 0 0.00 4 8409 356 0 0
sp_cancel_customer_reserve 554223 554223 0 0.00 1 15346 60 0 0
sp_cancel_pay_from_customer 731 731 0 0.00 2 15371 117 0 0
sp_cancel_pay_to_supplier 434 434 0 0.00 2 10605 147 0 0
sp_cancel_supplier_invoice 6190 6181 9 0.14 1 16299 784 0 9
sp_cancel_supplier_order 381 381 0 0.00 12 1017844 109908 0 0
sp_cancel_write_off 10225 10225 0 0.00 1 14751 101 0 0
sp_client_order 20602 20602 0 0.00 3 31849 347 0 0
sp_customer_reserve 469428 469428 0 0.00 0 52323 186 0 0
sp_fill_shopping_cart 31788 31788 0 0.00 1 19504 43 0 0
sp_fill_shopping_cart_clo_res 469414 469414 0 0.00 0 19876 14 0 0
sp_get_clo_for_invoice 5161 5161 0 0.00 0 31317 334 0 0
sp_kill_cost_storno 17062 17062 0 0.00 0 24961 57 0 0
sp_kill_qstorno_ret_qs2qd 1125233 1125233 0 0.00 0 39287 32 0 0
sp_kill_qty_storno 593637 593637 0 0.00 0 39288 69 0 0
sp_lock_selected_doc 20967 20967 0 0.00 0 9291 4 0 0
sp_make_cost_storno 28376 28376 0 0.00 0 20358 52 0 0
sp_multiply_rows_for_pdistr 28376 28376 0 0.00 0 12744 32 0 0
sp_multiply_rows_for_qdistr 20602 20602 0 0.00 0 14116 162 0 0
sp_pay_from_customer 7237 7237 0 0.00 4 30895 150 0 0
sp_pay_to_supplier 5957 5957 0 0.00 5 16381 132 0 0
sp_payment_common 13194 13194 0 0.00 2 9677 27 0 0
sp_qd_handle_on_cancel_clo 1773 1773 0 0.00 0 6583 319 0 0
sp_qd_handle_on_invoice_upd_sts 10833 10833 0 0.00 0 16629 315 0 0
sp_qd_handle_on_reserve_upd_sts 10021 10021 0 0.00 0 15705 55 0 0
sp_reserve_write_off 10021 10021 0 0.00 6 15728 179 0 0
sp_supplier_invoice 5615 5615 0 0.00 6 74465 3419 0 0
sp_supplier_order 5574 5574 0 0.00 5 37126 1291 0 0
srv_aggregate_perf_data 91 91 0 0.00 2620 279716 23320 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1630 129468 43643 0 0
srv_find_qd_qs_mism 1062481 1062481 0 0.00 0 11461 6 0 0
srv_make_invnt_saldo 575 575 0 0.00 46 30692 971 0 0
srv_make_money_saldo 787 787 0 0.00 0 1659 25 0 0
srv_recalc_idx_stat 4 4 0 0.00 196235 681680 410608 0 0
t$perf-abend:sp_add_invoice_to_stock 125 125 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5175 5175 0 0.00 0 17514 212 0 0
t$perf-norm:sp_cancel_adding_invoice 419 419 0 0.00 0 3846 92 0 0
t$perf-norm:sp_cancel_client_order 1773 1773 0 0.00 0 7043 18 0 0
t$perf-norm:sp_cancel_customer_reserve 689 689 0 0.00 0 2926 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 731 731 0 0.00 0 2887 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 434 434 0 0.00 0 3704 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 404 404 0 0.00 0 4100 25 0 0
t$perf-norm:sp_cancel_supplier_order 381 381 0 0.00 0 29335 1759 0 0
t$perf-norm:sp_cancel_write_off 954 954 0 0.00 0 4340 11 0 0
t$perf-norm:sp_client_order 20602 20602 0 0.00 0 15597 20 0 0
t$perf-norm:sp_customer_reserve 1010 1010 0 0.00 0 1764 9 0 0
t$perf-norm:sp_pay_from_customer 7237 7237 0 0.00 0 5253 10 0 0
t$perf-norm:sp_pay_to_supplier 5957 5957 0 0.00 0 3989 8 0 0
t$perf-norm:sp_reserve_write_off 10021 10021 0 0.00 0 15344 17 0 0
t$perf-norm:sp_supplier_invoice 5615 5615 0 0.00 0 7274 18 0 0
t$perf-norm:sp_supplier_order 5574 5574 0 0.00 0 20549 22 0 0
t$perf-norm:srv_aggregate_perf_data 91 91 0 0.00 0 863 22 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 115 3 0 0
t$perf-norm:srv_make_invnt_saldo 575 575 0 0.00 0 4523 30 0 0
t$perf-norm:srv_make_money_saldo 787 787 0 0.00 0 4992 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 3 0 0
v_add_invoice_to_stock 10572 10322 250 2.36 0 31383 25 0 250
v_all_customers 37588 37588 0 0.00 0 28 0 0 0
v_all_suppliers 30612 30612 0 0.00 0 23 1 0 0
v_all_wares 41204 41204 0 0.00 0 14 1 0 0
v_cancel_adding_invoice 838 838 0 0.00 0 1697 7 0 0
v_cancel_client_order 3546 3546 0 0.00 0 4588 9 0 0
v_cancel_customer_prepayment 1462 1462 0 0.00 0 15359 27 0 0
v_cancel_customer_reserve 1378 1378 0 0.00 0 1979 7 0 0
v_cancel_payment_to_supplier 868 868 0 0.00 0 10590 28 0 0
v_cancel_supplier_invoice 826 808 18 2.17 0 4725 22 0 18
v_cancel_supplier_order 762 762 0 0.00 0 7580 20 0 0
v_cancel_write_off 1908 1908 0 0.00 0 4610 17 0 0
v_max_id_clo_ord 5574 5574 0 0.00 0 6579 4 0 0
v_max_id_clo_res 1009 1009 0 0.00 0 7102 24 0 0
v_max_id_ord_sup 5612 5612 0 0.00 0 6184 8 0 0
v_max_non_paid_invoice 1837 1837 0 0.00 0 1044 3 0 0
v_max_non_paid_realizn 3934 3934 0 0.00 0 2006 4 0 0
v_min_id_clo_ord 5574 5574 0 0.00 0 6148 6 0 0
v_min_id_clo_res 1009 1009 0 0.00 0 2308 9 0 0
v_min_id_ord_sup 5612 5612 0 0.00 0 6296 8 0 0
v_min_non_paid_invoice 5957 5957 0 0.00 0 7996 18 0 0
v_min_non_paid_realizn 7237 7237 0 0.00 0 9674 19 0 0
v_our_firm 10222 10222 0 0.00 0 31 1 0 0
v_reserve_write_off 20042 20042 0 0.00 0 8249 9 0 0
x_lock_depdocs_on_canc_invoice 11449 11449 0 0.00 0 31131 117 0 0
x_lock_depdocs_on_canc_sup_ord 381 381 0 0.00 0 3251 348 0 0
x_make_qstorno_1000_1200 5574 5574 0 0.00 2 37119 1201 0 0
x_make_qstorno_1200_2000 5615 5615 0 0.00 2 74458 3266 0 0
x_make_qstorno_2100_3300 469428 469428 0 0.00 0 52320 169 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-04-2022 22:39:11 to 27-04-2022 22:39:15.

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
20:08:18 5 8192000000 8803492848 8816332800 9097456 0.110 3 2 3 0 9239872 67472 74944
20:09:19 34005 8192000000 9917863424 10229260288 997522976 10.855 100 86 172 0 1025265728 5725424 22017328
20:10:56 23661 8192000000 9956196160 10275135488 1035652320 11.223 100 96 191 1 1068884320 9024800 24207200
20:12:25 19705 8192000000 9974754080 10307354624 1059742704 11.454 100 81 162 0 1090045824 10053312 20249808
20:14:00 13803 8192000000 10053220944 10403127296 1133829280 12.157 100 81 162 1 1164693792 11212864 19651648
20:15:21 9017 8192000000 10103103664 10468175872 1188623088 12.671 100 75 150 0 1218849872 13528448 16698336
20:16:32 3432 8192000000 10143025840 10516512768 1229919040 13.053 100 75 151 2 1259627232 12697120 17011072
20:17:37 3974 8192000000 10163690432 10545446912 1255278928 13.287 100 76 150 1 1284019536 12638960 16101648
20:18:43 2393 8192000000 10206768592 10588786688 1290524720 13.609 100 96 192 2 1327039168 14991440 21523008
20:19:49 2062 8192000000 10222118816 10605346816 1300057104 13.696 100 89 178 1 1332655392 12664208 19934080
20:20:53 14468 8192000000 10220200752 10610593792 1304533760 13.736 100 67 128 1 1327808496 8972112 14302624
20:22:09 4155 8192000000 10205684928 10614071296 1312194784 13.806 100 30 60 0 1326207440 6371968 7640688
20:23:16 7591 8192000000 10212860304 10625896448 1319671440 13.874 100 33 66 1 1335366256 8519616 7175200
20:24:29 8749 8192000000 10215433888 10639257600 1332748944 13.992 100 21 42 1 1347627568 9780480 5098144
20:25:40 43064 8192000000 10226980656 10649894912 1336659376 14.027 100 32 64 0 1349866528 5831648 7375504
20:27:27 7109 8192000000 10236165456 10655748096 1340433968 14.061 100 31 62 1 1354869616 6645472 7790176
20:28:35 6593 8192000000 10208535488 10630807552 1300980080 13.704 98 19 38 1 1311127216 5769504 4377632
20:29:42 10318 8192000000 10164027152 10570285056 1252988032 13.266 97 15 29 1 1261114496 4615040 3511424
20:30:53 21655 8192000000 10147899504 10555752448 1224906352 13.007 96 24 46 1 1239944240 9713968 5323920
20:32:21 5103 8192000000 10129138256 10530951168 1220997280 12.971 98 34 68 0 1235137104 6279744 7860080
20:33:31 9737 8192000000 10153519088 10557706240 1243524544 13.179 98 26 52 0 1255702160 6229504 5948112
20:34:44 55450 8192000000 10183955104 10586914816 1285029888 13.559 100 20 41 0 1295128224 4862112 5236224
20:36:50 7674 8192000000 10189850896 10602123264 1298793040 13.684 100 38 76 0 1314289264 6699024 8797200
20:38:00 10752 8192000000 10209996624 10622472192 1314466224 13.827 100 27 55 1 1325967152 5386992 6113936
20:39:20 59263 8192000000 10244551008 10662522880 1339566656 14.054 100 37 74 1 1354621568 7047248 8007664
20:41:46 17643 8192000000 10276534656 10692546560 1364091136 14.274 100 52 104 0 1387565424 12066480 11407808
20:43:09 28127 8192000000 10291417680 10713825280 1379513984 14.412 100 49 97 1 1406491872 16092736 10885152
20:44:43 6677 8192000000 10311147296 10732281856 1395494608 14.555 100 45 90 0 1421949328 15794672 10660048
20:45:59 13170 8192000000 10316122704 10739224576 1400634736 14.601 100 54 108 0 1431432080 18574624 12222720
20:47:16 9429 8192000000 10334356128 10755932160 1407337984 14.660 100 61 120 3 1440186496 17729968 15118544
20:48:34 6813 8192000000 10349540608 10772406272 1404821216 14.638 99 55 110 1 1448622560 30211024 13590320
20:49:44 6030 8192000000 10355121488 10777055232 1407991360 14.666 99 54 108 1 1455189248 33767344 13430544
20:50:54 2572 8192000000 10352976608 10766471168 1394339984 14.545 99 67 132 2 1448620880 37653936 16626960
20:52:01 8792 8192000000 10344969552 10768896000 1411104736 14.694 100 49 98 1 1456160896 32740752 12315408
20:53:19 11758 8192000000 10373790144 10793787392 1420918784 14.781 100 68 137 1 1476694448 38057408 17718256
20:54:38 4502 8192000000 10354336544 10769575936 1388078640 14.489 99 74 148 1 1450406480 42816624 19511216
20:55:50 1628 8192000000 10364032528 10772877312 1399470480 14.590 100 86 173 3 1462584240 40739648 22374112
20:56:58 2246 8192000000 10354253984 10760593408 1378368032 14.402 98 78 157 2 1441556784 43139168 20049584
20:58:06 2069 8192000000 10345308640 10756034560 1380134864 14.418 99 67 133 2 1436262160 38642944 17484352
20:59:11 22636 8192000000 10364176768 10786746368 1394711872 14.548 99 36 72 1 1458137296 52932208 10493216
21:00:37 8187 8192000000 10327941408 10758053888 1373317424 14.357 100 41 82 1 1439183072 53767488 12098160
21:01:51 11287 8192000000 10307512784 10738221056 1360309808 14.240 100 29 58 0 1422656912 54123840 8223264
21:03:09 9759 8192000000 10315033952 10739736576 1353980480 14.183 100 43 86 1 1422533904 56264032 12289392
21:04:26 20496 8192000000 10292260352 10727653376 1337271936 14.033 98 20 40 3 1387210880 44275456 5663488
21:05:51 26052 8192000000 10275960016 10707103744 1321535808 13.891 99 18 35 2 1370818544 42715840 6566896
21:07:22 37168 8192000000 10250942192 10689552384 1327926544 13.948 99 20 40 1 1344158416 11380240 4851632
21:09:00 63672 8192000000 10288910800 10714456064 1332948400 13.994 97 30 60 0 1354663200 14801680 6913120
21:11:21 80312 8192000000 10294231552 10728353792 1357972176 14.219 99 16 31 0 1382318272 19536912 4809184
21:13:43 83985 8192000000 10277210608 10717716480 1308119504 13.769 95 7 14 1 1321466640 11765232 1581904
21:16:17 120061 8192000000 10288337488 10727522304 1353660992 14.180 99 38 72 2 1370482688 7465184 9356512
21:19:20 30720 8192000000 10283175776 10733264896 1373456144 14.358 100 15 30 0 1388456608 11651376 3349088
21:20:53 41757 8192000000 10286275360 10745827328 1368924624 14.317 99 36 71 0 1388545024 11721888 7898512
21:23:04 58292 8192000000 10308858128 10765295616 1397155408 14.570 100 23 46 1 1416305472 14244720 4905344
21:25:08 60621 8192000000 10303304704 10778718208 1385566352 14.466 99 14 28 0 1408647168 19762368 3318448
21:27:12 33877 8192000000 10310191664 10766422016 1385172416 14.463 99 21 42 0 1404015456 13668032 5175008
21:29:56 81607 8192000000 10297711136 10765844480 1378915056 14.407 99 20 40 1 1399269600 15741696 4612848
21:32:31 46188 8192000000 10307576432 10774429696 1398081392 14.578 100 19 38 0 1420669584 17626928 4961264
21:34:37 66228 8192000000 10309239152 10780844032 1403227376 14.624 100 9 19 0 1419707168 13990336 2489456
21:36:45 82129 8192000000 10300824784 10771226624 1399176704 14.588 100 22 44 1 1413980032 9710816 5092512
21:39:50 54364 8192000000 10288410928 10760863744 1384199760 14.454 99 18 34 0 1393727120 5736848 3790512
21:41:46 52301 8192000000 10286775360 10749091840 1338256768 14.042 96 13 26 0 1346663728 5922640 2484320
21:44:06 36442 8192000000 10288029376 10742337536 1361505824 14.251 98 32 64 2 1385558880 16496624 7556432
21:45:50 58861 8192000000 10271456768 10734952448 1325950048 13.931 96 26 52 1 1350134032 17570496 6613488
21:47:50 42482 8192000000 10262929920 10714959872 1338943056 14.048 98 25 50 0 1359127888 14502736 5682096
21:49:32 34323 8192000000 10270580960 10719035392 1345348864 14.106 98 23 46 3 1364202272 13455776 5397632
21:51:07 57225 8192000000 10263898944 10706599936 1283557296 13.545 94 21 42 0 1299658880 11709696 4391888
21:53:16 29369 8192000000 10264467328 10707763200 1337551552 14.035 99 28 56 1 1356647328 12297184 6798592
21:54:45 21332 8192000000 10290590704 10724433920 1353372864 14.178 99 37 74 1 1371428160 9546928 8508368
21:56:13 110506 8192000000 10248040752 10697949184 1317664608 13.856 97 38 76 0 1336072560 10512944 7895008
21:59:09 19713 8192000000 10255870800 10696294400 1336562128 14.026 99 50 100 0 1359359024 12050112 10746784
22:00:31 71733 8192000000 10248325184 10707005440 1340187664 14.059 99 10 20 1 1354379424 12160032 2031728
22:02:45 75109 8192000000 10252763376 10702946304 1315484400 13.836 97 24 49 0 1330293024 9193888 5614736
22:05:16 44950 8192000000 10246129088 10700480512 1326858384 13.939 98 20 38 1 1336043728 5223136 3962208
22:07:04 64530 8192000000 10278163440 10744123392 1367679216 14.306 100 25 50 2 1388177600 14904736 5593648
22:09:13 48751 8192000000 10298069616 10747125760 1359875264 14.236 98 23 45 1 1380561344 15624256 5061824
22:11:11 56397 8192000000 10296070400 10764509184 1383292720 14.446 100 9 18 0 1404580736 19361808 1926208
22:13:12 90711 8192000000 10299136176 10754830336 1352661024 14.171 97 15 30 1 1363816688 8192832 2962832
22:15:47 17349 8192000000 10316124032 10761809920 1397820608 14.576 100 40 79 0 1416257616 9487632 8949376
22:17:05 14901 8192000000 10343248016 10776330240 1401143136 14.605 100 62 122 3 1428210768 12684336 14383296
22:18:24 129468 8192000000 10299416544 10782216192 1358945664 14.228 97 12 24 0 1381355824 19552032 2858128
22:21:55 39713 8192000000 10290271856 10757885952 1395583888 14.556 100 27 54 1 1409331456 7494112 6253456
22:25:12 83459 8192000000 10282991920 10753417216 1328635728 13.955 95 13 26 0 1346780144 14691888 3452528
22:27:37 68898 8192000000 10293042496 10759000064 1369701728 14.324 99 16 32 1 1390815344 17482688 3630928
22:29:48 57128 8192000000 10292139488 10746728448 1358320848 14.222 98 13 26 1 1375142896 13608624 3213424
22:31:49 55466 8192000000 10272959712 10723074048 1312026560 13.804 95 21 42 0 1324726128 7564160 5135408
22:33:49 75505 8192000000 10287213840 10739208192 1335104208 14.013 97 31 62 2 1354354224 12652832 6597184
22:36:05 72636 8192000000 10283336352 10745593856 1349100896 14.139 98 27 54 0 1372316000 16504192 6710912

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 27-04-2022 22:39:17 to 27-04-2022 22:39:32.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 125 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 27-04-2022 22:39:32 to 27-04-2022 22:39:34.

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          241785
MON$OLDEST_ACTIVE               242171
MON$OLDEST_SNAPSHOT             242171
MON$NEXT_TRANSACTION            242171
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                       2037920
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33578 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33578 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33578 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33578 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 ---
:80: 
:81: mscs-test-161.budzdorov.ru    Wed Apr 27 22:38:15 2022
:82:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:83: 
:84: 
:85: mscs-test-161.budzdorov.ru    Wed Apr 27 22:38:17 2022
:86:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:87: 
:88: 
:89: mscs-test-161.budzdorov.ru    Wed Apr 27 22:38:31 2022
:90:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:91: 
:92: 
:93: mscs-test-161.budzdorov.ru    Wed Apr 27 22:38:31 2022
:94:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:95: 
:96: 
:97: mscs-test-161.budzdorov.ru    Wed Apr 27 22:38:31 2022
:98:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:99: 
:100: 
:101: mscs-test-161.budzdorov.ru    Wed Apr 27 22:38:31 2022
:102:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:103: 
--- end of diff output ---

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

27.04.22 22:39:35. 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-04-27 22:39:38 No severe PSQL-related problems occured
27.04.22 22:39:39. End of report.