Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.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 17501492 kB
MemAvailable 31466456 kB
Buffers 0 kB
Cached 14198112 kB
SwapCached 2712 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-18 22:38:16
DTS_END                         2022-04-18 22:38:16.5040
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/33534
TRN_ID                          242944
ATT_ID                          695
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: 9162.99 20936 1099559
customer order: creation 178.43 342 21411
customer order: refuse 15.42 360 1850
order to supplier: creation 47.90 1280 5748
order to supplier: removal 3.55 95027 426
invoice (draft): creation 47.48 3158 5697
invoice (draft): removal 50.33 856 6039
invoice accept: apply 42.78 18511 5134
invoice accept: cancel 45.89 826 5507
customer reserve: creation 3944.99 183 473399
customer reserve: removal 4481.95 59 537834
realization accept: apply 87.73 162 10527
realization accept: cancel 81.49 117 9779
payment from customer: creation 62.29 108 7475
payment from customer: removal 5.79 90 695
payment to supplier: creation 50.73 139 6087
payment to supplier: removal 3.66 65 439
service: total inventory turnovers 5.05 881 606
service: total monetary turnovers 6.49 34 779
service: aggregate perf. data 1.03 13280 123
service: refresh index statistics 0.03 283233 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 18-04-2022 22:38:50 to 18-04-2022 22:38:53.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8086
TEST_TIME 2 6647
TEST_TIME 3 6424
TEST_TIME 4 6022
TEST_TIME 5 6500
TEST_TIME 6 6328
TEST_TIME 7 5950
TEST_TIME 8 6198
TEST_TIME 9 6318
TEST_TIME 10 6425
TEST_TIME 11 6830
TEST_TIME 12 7008
TEST_TIME 13 6941
TEST_TIME 14 7192
TEST_TIME 15 7559
TEST_TIME 16 7727
TEST_TIME 17 7915
TEST_TIME 18 8047
TEST_TIME 19 8533
TEST_TIME 20 8626
TEST_TIME 21 8440
TEST_TIME 22 9016
TEST_TIME 23 9320
TEST_TIME 24 10130
TEST_TIME 25 11053
TEST_TIME 26 10991
TEST_TIME 27 10695
TEST_TIME 28 10530
TEST_TIME 29 10337
TEST_TIME 30 10400
TEST_TIME 31 10189
TEST_TIME 32 9996
TEST_TIME 33 10033
TEST_TIME 34 9988
TEST_TIME 35 9867
TEST_TIME 36 9813
TEST_TIME 37 9721
TEST_TIME 38 9724
TEST_TIME 39 9627
TEST_TIME 40 9518
TEST_TIME 41 9320
TEST_TIME 42 9427
TEST_TIME 43 9330
TEST_TIME 44 9234
TEST_TIME 45 9161
TEST_TIME 46 9210
TEST_TIME 47 9530
TEST_TIME 48 9458
TEST_TIME 49 9599
TEST_TIME 50 9563
TEST_TIME 51 9531
TEST_TIME 52 9643
TEST_TIME 53 9601
TEST_TIME 54 9695
TEST_TIME 55 9720
TEST_TIME 56 9618
TEST_TIME 57 9518
TEST_TIME 58 9488
TEST_TIME 59 9413
TEST_TIME 60 9406
TEST_TIME 61 9368
TEST_TIME 62 9260
TEST_TIME 63 9248
TEST_TIME 64 9280
TEST_TIME 65 9296
TEST_TIME 66 9282
TEST_TIME 67 9318
TEST_TIME 68 9313
TEST_TIME 69 9278
TEST_TIME 70 9356
TEST_TIME 71 9366
TEST_TIME 72 9345
TEST_TIME 73 9305
TEST_TIME 74 9385
TEST_TIME 75 9424
TEST_TIME 76 9473
TEST_TIME 77 9451
TEST_TIME 78 9435
TEST_TIME 79 9344
TEST_TIME 80 9296
TEST_TIME 81 9296
TEST_TIME 82 9278
TEST_TIME 83 9230
TEST_TIME 84 9169
TEST_TIME 85 9204
TEST_TIME 86 9176
TEST_TIME 87 9176
TEST_TIME 88 9204
TEST_TIME 89 9207
TEST_TIME 90 9170
TEST_TIME 91 9131
TEST_TIME 92 9188
TEST_TIME 93 9357
TEST_TIME 94 9378
TEST_TIME 95 9335
TEST_TIME 96 9373
TEST_TIME 97 9386
TEST_TIME 98 9438
TEST_TIME 99 9373
TEST_TIME 100 9353
TEST_TIME 101 9382
TEST_TIME 102 9353
TEST_TIME 103 9301
TEST_TIME 104 9249
TEST_TIME 105 9215
TEST_TIME 106 9163
TEST_TIME 107 9190
TEST_TIME 108 9211
TEST_TIME 109 9193
TEST_TIME 110 9179
TEST_TIME 111 9162
TEST_TIME 112 9146
TEST_TIME 113 9178
TEST_TIME 114 9196
TEST_TIME 115 9208
TEST_TIME 116 9206
TEST_TIME 117 9199
TEST_TIME 118 9237
TEST_TIME 119 9234
TEST_TIME 120 9178

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 18-04-2022 22:38:54 to 18-04-2022 22:38:58.

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 1537243 1537243 0 0.00 0 25465 7 0 0
doc_list_biud 1537243 1537243 0 0.00 0 84482 27 0 0
sp_add_invoice_to_stock 5202 5134 68 1.30 37 183743 18512 0 68
sp_cancel_adding_invoice 5507 5507 0 0.00 5 90406 827 0 0
sp_cancel_client_order 1850 1850 0 0.00 4 11732 361 0 0
sp_cancel_customer_reserve 537834 537834 0 0.00 1 39180 59 0 0
sp_cancel_pay_from_customer 695 695 0 0.00 2 8396 91 0 0
sp_cancel_pay_to_supplier 439 439 0 0.00 3 4600 66 0 0
sp_cancel_supplier_invoice 6041 6039 2 0.03 1 84484 856 0 2
sp_cancel_supplier_order 426 426 0 0.00 5 1061283 95028 0 0
sp_cancel_write_off 9779 9779 0 0.00 1 25467 118 0 0
sp_client_order 21411 21411 0 0.00 3 41299 342 0 0
sp_customer_reserve 473399 473399 0 0.00 0 44159 184 0 0
sp_fill_shopping_cart 32856 32856 0 0.00 1 8052 37 0 0
sp_fill_shopping_cart_clo_res 473392 473392 0 0.00 0 12801 14 0 0
sp_get_clo_for_invoice 5108 5108 0 0.00 0 18467 320 0 0
sp_kill_cost_storno 16420 16420 0 0.00 0 25463 60 0 0
sp_kill_qstorno_ret_qs2qd 1091912 1091912 0 0.00 0 84480 33 0 0
sp_kill_qty_storno 577069 577069 0 0.00 0 84482 70 0 0
sp_lock_selected_doc 21649 21649 0 0.00 0 7491 5 0 0
sp_make_cost_storno 29196 29196 0 0.00 0 16498 54 0 0
sp_multiply_rows_for_pdistr 29196 29196 0 0.00 0 9722 29 0 0
sp_multiply_rows_for_qdistr 21411 21411 0 0.00 0 41166 167 0 0
sp_pay_from_customer 7475 7475 0 0.00 4 13587 108 0 0
sp_pay_to_supplier 6087 6087 0 0.00 5 16503 140 0 0
sp_payment_common 13562 13562 0 0.00 1 10275 24 0 0
sp_qd_handle_on_cancel_clo 1850 1850 0 0.00 0 11725 336 0 0
sp_qd_handle_on_invoice_upd_sts 10614 10614 0 0.00 0 42049 319 0 0
sp_qd_handle_on_reserve_upd_sts 10527 10527 0 0.00 0 14257 51 0 0
sp_reserve_write_off 10527 10527 0 0.00 6 17914 162 0 0
sp_supplier_invoice 5697 5697 0 0.00 6 75644 3158 0 0
sp_supplier_order 5748 5748 0 0.00 4 49100 1281 0 0
srv_aggregate_perf_data 123 123 0 0.00 2808 104479 13281 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 2348 180534 44607 0 0
srv_find_qd_qs_mism 1049811 1049811 0 0.00 0 13229 6 0 0
srv_make_invnt_saldo 606 606 0 0.00 42 49850 881 0 0
srv_make_money_saldo 779 779 0 0.00 1 4036 35 0 0
srv_recalc_idx_stat 4 4 0 0.00 93508 393447 283234 0 0
t$perf-abend:sp_add_invoice_to_stock 68 68 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 2 2 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5134 5134 0 0.00 0 24305 163 0 0
t$perf-norm:sp_cancel_adding_invoice 475 475 0 0.00 0 5932 89 0 0
t$perf-norm:sp_cancel_client_order 1850 1850 0 0.00 0 4872 21 0 0
t$perf-norm:sp_cancel_customer_reserve 673 673 0 0.00 0 6395 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 695 695 0 0.00 0 833 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 439 439 0 0.00 0 4872 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 414 414 0 0.00 0 433 6 0 0
t$perf-norm:sp_cancel_supplier_order 426 426 0 0.00 0 23103 1397 0 0
t$perf-norm:sp_cancel_write_off 1043 1043 0 0.00 0 1499 6 0 0
t$perf-norm:sp_client_order 21411 21411 0 0.00 0 16543 21 0 0
t$perf-norm:sp_customer_reserve 1054 1054 0 0.00 0 2866 6 0 0
t$perf-norm:sp_pay_from_customer 7475 7475 0 0.00 0 10292 11 0 0
t$perf-norm:sp_pay_to_supplier 6087 6087 0 0.00 0 6250 9 0 0
t$perf-norm:sp_reserve_write_off 10527 10527 0 0.00 0 27250 14 0 0
t$perf-norm:sp_supplier_invoice 5697 5697 0 0.00 0 16623 21 0 0
t$perf-norm:sp_supplier_order 5748 5748 0 0.00 0 16630 20 0 0
t$perf-norm:srv_aggregate_perf_data 123 123 0 0.00 0 202 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 5 0 0 0
t$perf-norm:srv_make_invnt_saldo 606 606 0 0.00 0 1654 18 0 0
t$perf-norm:srv_make_money_saldo 779 779 0 0.00 0 1446 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 3 0 0
v_add_invoice_to_stock 10350 10214 136 1.31 0 5622 16 0 136
v_all_customers 39110 39110 0 0.00 0 17 1 0 0
v_all_suppliers 31802 31802 0 0.00 0 57 1 0 0
v_all_wares 42822 42822 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 950 950 0 0.00 0 263 5 0 0
v_cancel_client_order 3700 3700 0 0.00 0 3289 6 0 0
v_cancel_customer_prepayment 1390 1390 0 0.00 0 6112 15 0 0
v_cancel_customer_reserve 1346 1346 0 0.00 0 1468 5 0 0
v_cancel_payment_to_supplier 878 878 0 0.00 0 2450 5 0 0
v_cancel_supplier_invoice 832 828 4 0.48 0 2779 14 0 4
v_cancel_supplier_order 852 852 0 0.00 0 4426 8 0 0
v_cancel_write_off 2086 2086 0 0.00 0 2707 15 0 0
v_max_id_clo_ord 5748 5748 0 0.00 0 3229 2 0 0
v_max_id_clo_res 1054 1054 0 0.00 0 2112 13 0 0
v_max_id_ord_sup 5697 5697 0 0.00 0 1816 2 0 0
v_max_non_paid_invoice 1631 1631 0 0.00 0 390 2 0 0
v_max_non_paid_realizn 3873 3873 0 0.00 0 4727 6 0 0
v_min_id_clo_ord 5748 5748 0 0.00 0 1493 4 0 0
v_min_id_clo_res 1054 1054 0 0.00 0 3727 6 0 0
v_min_id_ord_sup 5697 5697 0 0.00 0 3997 6 0 0
v_min_non_paid_invoice 6087 6087 0 0.00 0 7437 18 0 0
v_min_non_paid_realizn 7475 7475 0 0.00 0 10271 16 0 0
v_our_firm 10916 10916 0 0.00 0 19 1 0 0
v_reserve_write_off 21054 21054 0 0.00 0 4284 6 0 0
x_lock_depdocs_on_canc_invoice 11132 11132 0 0.00 0 19551 114 0 0
x_lock_depdocs_on_canc_sup_ord 426 426 0 0.00 0 7868 331 0 0
x_make_qstorno_1000_1200 5748 5748 0 0.00 1 49041 1205 0 0
x_make_qstorno_1200_2000 5697 5697 0 0.00 3 72561 3025 0 0
x_make_qstorno_2100_3300 473399 473399 0 0.00 0 44057 167 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-04-2022 22:38:59 to 18-04-2022 22:39:03.

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:20 16 8192000000 8810389680 8825507840 14433488 0.175 4 3 5 0 14646224 80976 131760
20:09:21 28827 8192000000 9907809120 10219589632 992758304 10.808 100 91 182 0 1022269936 5706720 23804912
20:10:58 24141 8192000000 9952849152 10274885632 1032577616 11.193 100 90 180 1 1064415104 8412976 23424512
20:12:27 23944 8192000000 10007754144 10342203392 1080394768 11.651 100 77 154 0 1108954880 9647632 18912480
20:13:58 16032 8192000000 10064100544 10415480832 1141809984 12.233 100 77 154 0 1171163744 11092304 18261456
20:15:18 8963 8192000000 10092574928 10455961600 1178571184 12.577 100 70 140 0 1205665152 11321344 15772624
20:16:32 7144 8192000000 10134099984 10507288576 1225915488 13.016 100 81 161 1 1257072144 12864928 18291728
20:17:43 3325 8192000000 10168432384 10545467392 1249261152 13.231 100 71 143 0 1278707488 14032064 15414272
20:18:49 3728 8192000000 10202036512 10579787776 1284514896 13.554 100 88 176 0 1318454960 13548000 20392064
20:19:56 2458 8192000000 10208712864 10593808384 1296411888 13.663 100 88 175 0 1329386720 12971696 20003136
20:21:00 8712 8192000000 10212404288 10610139136 1306194560 13.752 100 62 122 2 1328210736 6563392 15452784
20:22:09 5209 8192000000 10203505280 10612412416 1315145712 13.833 100 30 60 2 1328560400 5772416 7642272
20:23:16 7752 8192000000 10206213104 10615525376 1317062608 13.850 100 21 42 2 1328470336 6048384 5359344
20:24:25 37122 8192000000 10244729488 10638696448 1324069104 13.914 100 75 150 0 1350049328 6078816 19901408
20:26:02 6280 8192000000 10220294768 10639978496 1330428912 13.971 100 24 48 0 1342272528 6511600 5332016
20:27:10 11640 8192000000 10232468880 10641424384 1332972912 13.994 100 33 66 0 1347128848 6349104 7806832
20:28:22 27838 8192000000 10225434528 10637234176 1319362688 13.871 99 41 82 1 1335318256 5466256 10489312
20:29:52 8550 8192000000 10145082320 10546556928 1210365584 12.872 94 11 22 1 1218448032 5069392 3013056
20:31:01 5344 8192000000 10132936432 10523066368 1216689392 12.931 98 24 50 1 1227317136 5075360 5552384
20:32:08 44798 8192000000 10153001280 10545004544 1232427072 13.076 97 41 79 3 1246468416 5229168 8812176
20:34:12 14859 8192000000 10153219008 10560036864 1255534752 13.289 99 24 49 0 1267616032 6354288 5726992
20:35:29 9855 8192000000 10167464688 10580828160 1284856208 13.557 100 19 38 1 1294628720 5508192 4264320
20:36:40 8280 8192000000 10190201200 10604023808 1300715056 13.702 100 19 38 0 1311130000 5572464 4842480
20:37:49 21367 8192000000 10233403472 10625695744 1314617680 13.828 100 70 142 3 1337476480 6456720 16402080
20:39:14 24881 8192000000 10239998128 10654937088 1341408128 14.070 100 45 90 1 1358745520 7431376 9906016
20:40:46 9736 8192000000 10267481248 10683748352 1363830960 14.272 100 55 108 1 1388725680 12085312 12809408
20:42:06 12921 8192000000 10278598272 10702172160 1376207520 14.383 100 27 53 2 1394819584 12563824 6048240
20:43:22 24908 8192000000 10278300288 10707791872 1377584560 14.395 100 35 70 1 1399498880 13620720 8293600
20:45:05 29964 8192000000 10310621136 10734837760 1391012896 14.515 100 49 97 2 1421253328 18799440 11440992
20:46:39 3235 8192000000 10330821888 10751094784 1403411760 14.625 100 61 121 2 1437689520 19431408 14846352
20:47:48 5284 8192000000 10351790928 10772975616 1416522000 14.742 100 64 130 1 1459529680 27851120 15156560
20:48:56 7207 8192000000 10360903232 10785918976 1418880416 14.763 100 61 122 0 1467595616 33068832 15646368
20:50:07 4565 8192000000 10373146368 10789539840 1419610832 14.769 100 81 161 2 1476889744 38529408 18749504
20:51:17 3587 8192000000 10368079168 10787323904 1421007392 14.782 100 63 126 0 1476951536 39824704 16119440
20:52:32 2785 8192000000 10377634640 10798080000 1427644080 14.840 100 71 143 0 1488296080 42546384 18105616
20:53:39 2510 8192000000 10368936208 10776981504 1398175920 14.579 99 90 180 0 1464956784 43647136 23133728
20:54:48 3099 8192000000 10368567248 10776444928 1399679888 14.592 100 81 161 2 1468172304 48228640 20263776
20:55:58 2424 8192000000 10377777424 10781241344 1403257296 14.624 100 89 175 2 1475789888 51208832 21323760
20:57:04 5406 8192000000 10357206752 10785173504 1399993792 14.595 100 49 98 1 1468344848 55234672 13116384
20:58:13 19284 8192000000 10333144192 10764750848 1347319504 14.123 96 36 70 0 1403904784 46351040 10234240
20:59:39 2348 8192000000 10357098080 10773422080 1385309680 14.464 100 69 139 0 1456736336 53103920 18322736
21:00:50 17409 8192000000 10329800992 10768547840 1380084640 14.417 100 34 68 0 1446872368 56573408 10214320
21:02:19 29214 8192000000 10286020656 10732494848 1336748432 14.028 99 19 38 0 1392270880 49343088 6179360
21:03:52 64785 8192000000 10274898160 10726699008 1365782656 14.289 100 7 14 0 1383510112 16006336 1721120
21:06:05 22140 8192000000 10276923408 10712641536 1361098560 14.247 100 55 107 1 1384764752 10566640 13099552
21:07:38 28160 8192000000 10269416336 10704490496 1336036112 14.022 98 47 93 1 1355135968 8828304 10271552
21:09:07 44687 8192000000 10257982320 10700320768 1346150624 14.113 98 12 24 1 1357276848 8370880 2755344
21:10:54 51508 8192000000 10254658336 10708938752 1329013664 13.958 97 14 28 0 1344492288 12361248 3117376
21:12:46 53737 8192000000 10261439168 10702376960 1340704640 14.064 98 20 40 0 1352875968 7514288 4657040
21:14:40 81275 8192000000 10269528192 10728554496 1361527328 14.251 99 10 20 0 1375971296 11985280 2458688
21:17:25 93340 8192000000 10277463072 10739851264 1348362592 14.133 98 7 14 0 1364807568 14812160 1632816
21:20:00 59417 8192000000 10265721536 10725457920 1330995840 13.976 97 9 17 1 1346292784 13623328 1673616
21:22:00 34381 8192000000 10288726192 10739372032 1369711888 14.324 99 14 28 1 1386686928 13700800 3274240
21:23:35 31127 8192000000 10305918336 10751778816 1388206096 14.490 99 23 47 1 1411811792 17632896 5972800
21:25:12 109193 8192000000 10313611312 10789965824 1402216896 14.615 100 13 26 1 1429177696 23838368 3122432
21:28:06 71109 8192000000 10309372272 10761355264 1383328160 14.446 99 18 36 0 1399516528 12339952 3848416
21:30:28 37414 8192000000 10298256128 10746101760 1369363056 14.321 98 18 36 0 1380427184 7368464 3695664
21:32:06 47303 8192000000 10291359904 10749272064 1372253088 14.347 99 13 26 1 1388545232 13482032 2810112
21:33:55 33817 8192000000 10307591488 10742988800 1370848192 14.335 98 26 52 0 1385531472 8990784 5692496
21:35:33 83355 8192000000 10277259184 10737905664 1372892912 14.353 99 36 72 1 1393156672 11647056 8616704
21:38:01 53312 8192000000 10280935072 10738839552 1370345344 14.330 98 21 42 0 1384056256 8632224 5078688
21:40:02 52726 8192000000 10275991664 10733334528 1352835472 14.173 97 10 20 0 1362192928 7125440 2232016
21:41:55 63928 8192000000 10284697312 10752626688 1359346784 14.231 98 8 16 1 1373859712 13115408 1397520
21:43:59 58598 8192000000 10288086560 10742845440 1363231600 14.266 98 25 49 1 1377561360 8509440 5820320
21:46:20 47765 8192000000 10279996416 10724749312 1331572096 13.981 96 22 44 0 1346037904 9333328 5132480
21:48:10 66736 8192000000 10264478240 10702450688 1284339072 13.553 94 18 36 0 1296079104 7920128 3819904
21:50:23 53116 8192000000 10277084368 10711654400 1311591296 13.801 96 32 64 0 1328371664 10235296 6545072
21:52:24 96970 8192000000 10259068864 10709725184 1315143328 13.833 97 15 30 0 1334371920 16002288 3226304
21:55:03 53280 8192000000 10282715120 10721832960 1358692128 14.226 99 27 54 0 1374096224 9400384 6003712
21:56:59 62202 8192000000 10290248352 10733871104 1376936752 14.389 100 44 87 1 1395392704 8016832 10439120
21:59:05 26468 8192000000 10294489552 10739351552 1378760848 14.405 99 39 79 2 1396685904 8879808 9045248
22:00:34 64219 8192000000 10257115840 10717192192 1356166512 14.203 98 8 16 1 1364618752 6800464 1651776
22:02:50 66322 8192000000 10270523680 10736054272 1344038704 14.094 97 13 26 0 1358183872 11089792 3055376
22:04:57 47182 8192000000 10275544704 10726371328 1339019392 14.049 97 20 40 0 1352221472 8694624 4507456
22:06:44 45865 8192000000 10281458560 10729414656 1339932176 14.057 97 29 59 0 1357760384 11060624 6767584
22:08:39 55811 8192000000 10317791008 10761662464 1379993264 14.416 99 24 48 0 1401429568 15702896 5733408
22:10:42 76146 8192000000 10297388800 10764390400 1390556848 14.511 100 12 23 1 1410494800 17159152 2778800
22:13:01 51731 8192000000 10292761296 10735431680 1353815680 14.182 97 20 40 1 1365851648 7793552 4242416
22:14:54 86226 8192000000 10301362048 10750160896 1370189136 14.329 98 25 50 2 1387521760 11892960 5439664
22:17:26 180534 8192000000 10292804912 10764865536 1374668144 14.369 99 17 34 0 1395305056 16506912 4130000
22:21:27 65338 8192000000 10282914608 10745962496 1386938736 14.479 100 10 20 0 1397302672 8018128 2345808
22:23:49 75500 8192000000 10284505936 10748682240 1377543744 14.395 99 14 28 1 1397856144 16791504 3520896
22:26:32 69688 8192000000 10264002416 10721722368 1318714992 13.865 95 12 25 0 1333167712 11732624 2720096
22:28:45 20012 8192000000 10284313984 10719956992 1324326368 13.916 96 50 100 2 1341395136 6560672 10508096
22:30:12 28087 8192000000 10286175328 10729508864 1341914016 14.075 97 30 60 0 1360902848 12512048 6476784
22:31:44 55601 8192000000 10265108240 10713419776 1314536000 13.827 96 23 45 0 1331920528 12668000 4716528
22:33:42 50909 8192000000 10277032464 10720428032 1352105040 14.166 99 29 57 2 1367678304 9591248 5982016
22:35:46 21393 8192000000 10265814400 10695991296 1310969488 13.795 96 45 90 0 1332155584 11363552 9822544
22:37:22 46240 8192000000 10273264032 10701393920 1313113824 13.814 97 21 42 0 1326876784 9028160 4734800

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 68 v_add_invoice_to_stock 335544517
except 2 v_cancel_supplier_invoice 335544517

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

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          240831
MON$OLDEST_ACTIVE               243086
MON$OLDEST_SNAPSHOT             243086
MON$NEXT_TRANSACTION            243086
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                       1997184
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.33577 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33577 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33577 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33577 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 ---
:628: 
:629: mscs-test-161.budzdorov.ru    Mon Apr 18 22:38:17 2022
:630:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:631: 
:632: 
:633: mscs-test-161.budzdorov.ru    Mon Apr 18 22:38:18 2022
:634:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:635: 
:636: 
:637: mscs-test-161.budzdorov.ru    Mon Apr 18 22:38:23 2022
:638:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:639: 
--- end of diff output ---

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

18.04.22 22:39:23. 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-18 22:39:26 No severe PSQL-related problems occured
18.04.22 22:39:27. End of report.