Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.07.2022 19:39
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 16103040 kB
MemAvailable 31049364 kB
Buffers 0 kB
Cached 15585532 kB
SwapCached 2484 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-07-18 19:38:22
DTS_END                         2022-07-18 19:38:22.8220
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/34966
TRN_ID                          240446
ATT_ID                          697
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: 9311.88 20094 1117426
customer order: creation 171.42 360 20570
customer order: refuse 14.53 365 1743
order to supplier: creation 46.09 1241 5531
order to supplier: removal 3.46 90838 415
invoice (draft): creation 45.95 3336 5514
invoice (draft): removal 52.38 706 6286
invoice accept: apply 42.88 17621 5145
invoice accept: cancel 46.99 759 5639
customer reserve: creation 3941.16 175 472939
customer reserve: removal 4646.52 53 557582
realization accept: apply 85.31 193 10237
realization accept: cancel 84.28 93 10113
payment from customer: creation 60.33 104 7239
payment from customer: removal 5.89 63 707
payment to supplier: creation 49.58 117 5949
payment to supplier: removal 3.15 92 378
service: total inventory turnovers 4.74 1328 569
service: total monetary turnovers 6.40 22 768
service: aggregate perf. data 0.82 20677 98
service: refresh index statistics 0.03 263745 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 18-07-2022 19:39:33 to 18-07-2022 19:39:36.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5581
TEST_TIME 2 3954
TEST_TIME 3 3789
TEST_TIME 4 4436
TEST_TIME 5 4320
TEST_TIME 6 4554
TEST_TIME 7 4700
TEST_TIME 8 4949
TEST_TIME 9 5381
TEST_TIME 10 5226
TEST_TIME 11 5767
TEST_TIME 12 6000
TEST_TIME 13 6207
TEST_TIME 14 6582
TEST_TIME 15 6803
TEST_TIME 16 7387
TEST_TIME 17 7858
TEST_TIME 18 8095
TEST_TIME 19 8385
TEST_TIME 20 8577
TEST_TIME 21 9001
TEST_TIME 22 9449
TEST_TIME 23 9435
TEST_TIME 24 9915
TEST_TIME 25 9882
TEST_TIME 26 10824
TEST_TIME 27 10498
TEST_TIME 28 10335
TEST_TIME 29 10225
TEST_TIME 30 10181
TEST_TIME 31 10141
TEST_TIME 32 10131
TEST_TIME 33 10018
TEST_TIME 34 10039
TEST_TIME 35 9851
TEST_TIME 36 9850
TEST_TIME 37 9774
TEST_TIME 38 9682
TEST_TIME 39 9493
TEST_TIME 40 9535
TEST_TIME 41 9556
TEST_TIME 42 9451
TEST_TIME 43 9383
TEST_TIME 44 9437
TEST_TIME 45 9400
TEST_TIME 46 9312
TEST_TIME 47 9177
TEST_TIME 48 9245
TEST_TIME 49 9225
TEST_TIME 50 9302
TEST_TIME 51 9249
TEST_TIME 52 9213
TEST_TIME 53 9271
TEST_TIME 54 9301
TEST_TIME 55 9394
TEST_TIME 56 9461
TEST_TIME 57 9420
TEST_TIME 58 9417
TEST_TIME 59 9446
TEST_TIME 60 9596
TEST_TIME 61 9600
TEST_TIME 62 9549
TEST_TIME 63 9442
TEST_TIME 64 9341
TEST_TIME 65 9390
TEST_TIME 66 9340
TEST_TIME 67 9434
TEST_TIME 68 9362
TEST_TIME 69 9361
TEST_TIME 70 9356
TEST_TIME 71 9403
TEST_TIME 72 9466
TEST_TIME 73 9423
TEST_TIME 74 9479
TEST_TIME 75 9510
TEST_TIME 76 9567
TEST_TIME 77 9594
TEST_TIME 78 9614
TEST_TIME 79 9578
TEST_TIME 80 9487
TEST_TIME 81 9469
TEST_TIME 82 9475
TEST_TIME 83 9427
TEST_TIME 84 9445
TEST_TIME 85 9369
TEST_TIME 86 9332
TEST_TIME 87 9372
TEST_TIME 88 9378
TEST_TIME 89 9396
TEST_TIME 90 9435
TEST_TIME 91 9412
TEST_TIME 92 9403
TEST_TIME 93 9588
TEST_TIME 94 9590
TEST_TIME 95 9618
TEST_TIME 96 9684
TEST_TIME 97 9659
TEST_TIME 98 9661
TEST_TIME 99 9668
TEST_TIME 100 9652
TEST_TIME 101 9651
TEST_TIME 102 9564
TEST_TIME 103 9548
TEST_TIME 104 9494
TEST_TIME 105 9489
TEST_TIME 106 9445
TEST_TIME 107 9414
TEST_TIME 108 9397
TEST_TIME 109 9340
TEST_TIME 110 9338
TEST_TIME 111 9329
TEST_TIME 112 9287
TEST_TIME 113 9247
TEST_TIME 114 9213
TEST_TIME 115 9315
TEST_TIME 116 9377
TEST_TIME 117 9369
TEST_TIME 118 9358
TEST_TIME 119 9352
TEST_TIME 120 9312

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 18-07-2022 19:39:37 to 18-07-2022 19:39:40.

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 1555073 1555073 0 0.00 0 30555 6 0 0
doc_list_biud 1555071 1555071 0 0.00 0 32572 25 0 0
sp_add_invoice_to_stock 5288 5145 143 2.70 30 381534 17621 0 143
sp_cancel_adding_invoice 5639 5639 0 0.00 4 52653 759 0 0
sp_cancel_client_order 1743 1743 0 0.00 4 14022 366 0 0
sp_cancel_customer_reserve 557582 557582 0 0.00 0 32578 54 0 0
sp_cancel_pay_from_customer 707 707 0 0.00 3 3261 63 0 0
sp_cancel_pay_to_supplier 378 378 0 0.00 2 4037 92 0 0
sp_cancel_supplier_invoice 6292 6286 6 0.09 2 31878 707 0 6
sp_cancel_supplier_order 415 415 0 0.00 7 974850 90838 0 0
sp_cancel_write_off 10113 10113 0 0.00 1 7664 94 0 0
sp_client_order 20570 20570 0 0.00 3 29412 360 0 0
sp_customer_reserve 472939 472939 0 0.00 0 51089 175 0 0
sp_fill_shopping_cart 31609 31609 0 0.00 1 17406 40 0 0
sp_fill_shopping_cart_clo_res 472937 472937 0 0.00 0 15313 15 0 0
sp_get_clo_for_invoice 5143 5143 0 0.00 0 23975 276 0 0
sp_kill_cost_storno 16837 16837 0 0.00 0 5706 46 0 0
sp_kill_qstorno_ret_qs2qd 1131978 1131978 0 0.00 0 32560 29 0 0
sp_kill_qty_storno 597157 597157 0 0.00 0 32570 64 0 0
sp_lock_selected_doc 21084 21084 0 0.00 0 6643 5 0 0
sp_make_cost_storno 28568 28568 0 0.00 0 23558 50 0 0
sp_multiply_rows_for_pdistr 28568 28568 0 0.00 0 8500 25 0 0
sp_multiply_rows_for_qdistr 20570 20570 0 0.00 0 11434 154 0 0
sp_pay_from_customer 7239 7239 0 0.00 3 9092 104 0 0
sp_pay_to_supplier 5949 5949 0 0.00 4 10057 117 0 0
sp_payment_common 13187 13187 0 0.00 1 8296 27 0 0
sp_qd_handle_on_cancel_clo 1743 1743 0 0.00 0 14012 336 0 0
sp_qd_handle_on_invoice_upd_sts 10782 10782 0 0.00 0 29518 332 0 0
sp_qd_handle_on_reserve_upd_sts 10236 10236 0 0.00 0 29463 81 0 0
sp_reserve_write_off 10237 10237 0 0.00 6 29505 194 0 0
sp_supplier_invoice 5514 5514 0 0.00 6 91588 3337 0 0
sp_supplier_order 5531 5531 0 0.00 4 29621 1241 0 0
srv_aggregate_perf_data 98 98 0 0.00 2325 394534 20677 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1321 200149 46272 0 0
srv_find_qd_qs_mism 1068980 1068980 0 0.00 0 30554 5 0 0
srv_make_invnt_saldo 569 569 0 0.00 49 141149 1328 0 0
srv_make_money_saldo 768 768 0 0.00 1 430 22 0 0
srv_recalc_idx_stat 4 4 0 0.00 129875 385871 263746 0 0
t$perf-abend:sp_add_invoice_to_stock 143 143 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5145 5145 0 0.00 0 15136 184 0 0
t$perf-norm:sp_cancel_adding_invoice 391 391 0 0.00 0 2866 124 0 0
t$perf-norm:sp_cancel_client_order 1743 1743 0 0.00 0 6734 18 0 0
t$perf-norm:sp_cancel_customer_reserve 656 656 0 0.00 0 602 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 707 707 0 0.00 0 7688 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 378 378 0 0.00 0 1676 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 386 386 0 0.00 0 1081 13 0 0
t$perf-norm:sp_cancel_supplier_order 415 415 0 0.00 0 32086 1552 0 0
t$perf-norm:sp_cancel_write_off 1029 1029 0 0.00 0 2324 7 0 0
t$perf-norm:sp_client_order 20570 20570 0 0.00 0 15692 19 0 0
t$perf-norm:sp_customer_reserve 948 948 0 0.00 0 6636 19 0 0
t$perf-norm:sp_pay_from_customer 7240 7240 0 0.00 0 10747 15 0 0
t$perf-norm:sp_pay_to_supplier 5950 5950 0 0.00 0 6651 16 0 0
t$perf-norm:sp_reserve_write_off 10237 10237 0 0.00 0 5045 12 0 0
t$perf-norm:sp_supplier_invoice 5514 5514 0 0.00 0 13110 23 0 0
t$perf-norm:sp_supplier_order 5531 5531 0 0.00 0 5400 17 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 135 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 744 22 0 0
t$perf-norm:srv_make_invnt_saldo 570 570 0 0.00 0 848 17 0 0
t$perf-norm:srv_make_money_saldo 768 768 0 0.00 0 3252 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 10572 10286 286 2.70 0 4409 17 0 286
v_all_customers 37500 37500 0 0.00 0 20 0 0 0
v_all_suppliers 30614 30614 0 0.00 0 16 1 0 0
v_all_wares 41138 41138 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 782 782 0 0.00 0 319 6 0 0
v_cancel_client_order 3486 3486 0 0.00 0 4317 6 0 0
v_cancel_customer_prepayment 1414 1414 0 0.00 0 3253 5 0 0
v_cancel_customer_reserve 1312 1312 0 0.00 0 1603 4 0 0
v_cancel_payment_to_supplier 756 756 0 0.00 0 1543 11 0 0
v_cancel_supplier_invoice 784 772 12 1.53 0 3773 17 0 12
v_cancel_supplier_order 830 830 0 0.00 0 1455 5 0 0
v_cancel_write_off 2058 2058 0 0.00 0 4969 15 0 0
v_max_id_clo_ord 5531 5531 0 0.00 0 2050 1 0 0
v_max_id_clo_res 948 948 0 0.00 0 1747 21 0 0
v_max_id_ord_sup 5509 5509 0 0.00 0 4012 5 0 0
v_max_non_paid_invoice 1682 1682 0 0.00 0 1902 3 0 0
v_max_non_paid_realizn 3918 3918 0 0.00 0 1546 4 0 0
v_min_id_clo_ord 5531 5531 0 0.00 0 9718 8 0 0
v_min_id_clo_res 948 948 0 0.00 0 997 3 0 0
v_min_id_ord_sup 5509 5509 0 0.00 0 5276 8 0 0
v_min_non_paid_invoice 5949 5949 0 0.00 0 7475 23 0 0
v_min_non_paid_realizn 7238 7238 0 0.00 0 5931 17 0 0
v_our_firm 10278 10278 0 0.00 0 18 1 0 0
v_reserve_write_off 20472 20472 0 0.00 0 5015 8 0 0
x_lock_depdocs_on_canc_invoice 11539 11539 0 0.00 0 17041 111 0 0
x_lock_depdocs_on_canc_sup_ord 415 415 0 0.00 0 8272 356 0 0
x_make_qstorno_1000_1200 5531 5531 0 0.00 1 29606 1165 0 0
x_make_qstorno_1200_2000 5514 5514 0 0.00 2 91542 3187 0 0
x_make_qstorno_2100_3300 472939 472939 0 0.00 0 46158 158 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-07-2022 19:39:41 to 18-07-2022 19:39:45.

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
17:08:26 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:27 24601 8192000000 9900143808 10207125504 980347904 10.688 100 94 188 0 1010534464 5612320 24574240
17:10:54 5775 8192000000 9940592480 10260393984 1026654640 11.136 100 87 174 0 1056388672 7358272 22375760
17:12:10 19322 8192000000 9981559888 10309230592 1058085712 11.438 100 79 158 0 1086726192 8354096 20286384
17:13:32 11527 8192000000 10023989072 10365898752 1111023344 11.942 100 79 157 0 1139347760 9388032 18936384
17:14:46 8958 8192000000 10064958176 10418982912 1151727344 12.326 100 79 156 0 1181110976 10959248 18424384
17:16:06 4413 8192000000 10114950624 10477125632 1206726464 12.839 100 80 157 0 1236184656 11709792 17748400
17:17:11 3628 8192000000 10146553200 10519281664 1240812288 13.154 100 82 160 3 1270511712 12248576 17450848
17:18:16 1399 8192000000 10181335280 10556289024 1262979104 13.357 100 95 185 0 1297435616 13429056 21027456
17:19:19 1685 8192000000 10196822448 10581938176 1289690464 13.601 100 81 157 0 1318924688 12365808 16868416
17:20:22 34269 8192000000 10216276432 10609762304 1304057792 13.732 100 68 132 0 1329212304 9891664 15262848
17:21:59 4818 8192000000 10207906400 10614484992 1312508448 13.809 100 33 66 1 1328658544 8428336 7721760
17:23:06 8462 8192000000 10196914240 10618609664 1318875904 13.867 100 15 30 0 1328555552 6122400 3557248
17:24:14 16067 8192000000 10211992800 10631815168 1325019968 13.922 100 15 30 0 1334096160 6066576 3009616
17:25:31 62750 8192000000 10223827200 10648219648 1334142816 14.005 100 35 70 2 1347455856 5198672 8114368
17:27:36 6107 8192000000 10215022208 10635411456 1323374016 13.907 99 27 52 0 1335456832 5610800 6472016
17:28:42 6502 8192000000 10205271264 10622271488 1314242048 13.825 100 18 36 0 1323650368 5187728 4220592
17:29:52 11449 8192000000 10171918864 10581528576 1240955856 13.155 96 9 18 0 1248009904 4841504 2212544
17:31:04 22993 8192000000 10130205424 10536779776 1195933648 12.739 95 45 86 0 1212020896 5485760 10601488
17:32:28 6545 8192000000 10120481040 10516819968 1219851648 12.960 99 25 48 0 1231137728 5819264 5466816
17:33:36 9232 8192000000 10167193456 10572845056 1271125696 13.432 100 23 47 0 1282396768 5987600 5283472
17:34:45 13024 8192000000 10192887072 10593837056 1287379408 13.580 100 41 82 0 1301938224 5509680 9049136
17:35:58 29843 8192000000 10175261440 10590662656 1288425312 13.590 100 26 52 0 1299829840 5814640 5589888
17:37:31 8626 8192000000 10200470480 10610421760 1298164416 13.679 100 35 70 1 1312962144 7371696 7426032
17:38:42 22676 8192000000 10225441360 10634964992 1324194608 13.915 100 39 76 0 1338202336 5114512 8893216
17:40:10 75000 8192000000 10267752256 10682568704 1350941552 14.156 100 61 121 1 1377196400 12600736 13654112
17:42:51 31161 8192000000 10275885008 10697420800 1365995120 14.291 100 36 72 0 1389100032 14620352 8484560
17:44:28 6684 8192000000 10291849728 10711998464 1378809360 14.406 100 43 86 1 1406466528 17765424 9891744
17:45:39 8985 8192000000 10311013824 10733465600 1392064224 14.524 100 47 95 0 1422473056 19063712 11345120
17:46:52 4352 8192000000 10329958384 10750500864 1402519808 14.617 100 56 112 0 1441195936 25346496 13329632
17:48:04 3704 8192000000 10333103856 10756259840 1399431776 14.590 99 51 98 0 1435355504 23876992 12046736
17:49:12 6495 8192000000 10328402320 10753028096 1393186320 14.534 99 54 107 1 1434615136 28126224 13302592
17:50:22 1320 8192000000 10355883600 10770259968 1412499632 14.706 100 83 163 0 1463549968 30897088 20153248
17:51:26 10505 8192000000 10340020912 10764361728 1393917008 14.541 99 55 110 0 1444950768 36473296 14560464
17:52:43 7143 8192000000 10347166752 10772316160 1404276640 14.633 100 44 88 1 1451331472 34960704 12094128
17:53:57 1374 8192000000 10373311920 10786402304 1387936560 14.487 98 82 162 0 1453551392 44830144 20784688
17:55:03 1537 8192000000 10348404928 10762645504 1394010448 14.542 100 84 169 0 1454881952 40447792 20423712
17:56:09 7772 8192000000 10302780480 10724872192 1359385552 14.232 98 40 80 1 1403164144 32997376 10781216
17:57:24 8475 8192000000 10329735824 10757709824 1385750304 14.468 100 38 76 0 1438735952 42752112 10233536
17:58:40 10319 8192000000 10290913888 10719105024 1352278048 14.168 100 29 58 2 1399685168 39013376 8393744
17:59:53 12310 8192000000 10282040304 10719973376 1353377760 14.178 100 24 48 0 1397212848 37303760 6531328
18:01:22 3930 8192000000 10281579840 10698313728 1330448688 13.971 98 56 109 2 1375235392 30757232 14029472
18:02:29 39266 8192000000 10284149392 10717081600 1356398544 14.205 100 23 46 0 1399634000 37535312 5700144
18:04:19 29205 8192000000 10275223872 10717761536 1335960288 14.021 98 19 38 0 1369469296 28691648 4817360
18:05:50 82140 8192000000 10285934080 10722807808 1358570496 14.225 99 41 80 1 1386278048 17696672 10010880
18:08:33 7472 8192000000 10289438384 10737156096 1377913504 14.398 100 26 53 0 1397906640 14087536 5905600
18:09:41 38980 8192000000 10286566016 10724970496 1355736464 14.199 98 32 63 0 1373945024 10363872 7844688
18:11:21 42700 8192000000 10275188624 10717339648 1360533392 14.242 99 22 44 1 1377161968 11865216 4763360
18:13:05 68807 8192000000 10276856480 10723819520 1360178368 14.239 98 13 24 1 1373081520 10174720 2728432
18:15:15 37249 8192000000 10274102976 10725896192 1356341600 14.204 98 12 25 1 1369703904 10658416 2703888
18:17:03 73285 8192000000 10298445040 10746236928 1375190976 14.374 99 26 50 0 1399294144 18008080 6095088
18:19:18 56903 8192000000 10293433216 10750570496 1396098368 14.560 100 30 60 1 1410932544 8453360 6380816
18:21:15 13575 8192000000 10304412912 10760142848 1402845232 14.620 100 32 65 1 1419520144 9518832 7156080
18:22:30 39670 8192000000 10304393952 10762592256 1407246480 14.659 100 20 39 0 1421345024 10289696 3808848
18:24:11 81850 8192000000 10313007664 10770792448 1414052080 14.720 100 12 24 0 1426560000 9851904 2656016
18:26:33 88782 8192000000 10312084384 10785058816 1400614240 14.600 99 11 18 0 1413520848 11174688 1731920
18:29:08 34374 8192000000 10291937344 10737963008 1338935632 14.048 95 14 28 0 1352218368 10481600 2801136
18:30:46 19570 8192000000 10321517056 10768347136 1407281152 14.660 100 40 79 0 1426771712 10847392 8643168
18:32:10 8708 8192000000 10319919632 10770354176 1408107664 14.667 100 44 89 0 1427233712 8957792 10168256
18:33:26 31929 8192000000 10302895792 10757275648 1380896784 14.425 99 15 30 2 1398778016 14590336 3290896
18:34:58 48081 8192000000 10291676080 10740293632 1360439504 14.241 97 16 32 0 1371918688 7812288 3666896
18:36:56 49907 8192000000 10290298096 10734358528 1336155408 14.023 97 21 42 0 1361659776 20906032 4598336
18:38:47 200148 8192000000 10287957968 10738655232 1298881632 13.685 94 21 41 0 1313326816 10028544 4416640
18:44:55 50005 8192000000 10277131088 10731728896 1346395520 14.115 98 10 20 0 1363279536 14383136 2500880
18:46:47 17438 8192000000 10275698336 10708729856 1349263440 14.141 99 62 125 0 1376552016 13579648 13708928
18:48:10 48549 8192000000 10269223280 10714177536 1338626800 14.045 98 20 41 0 1352185360 9196432 4362128
18:50:07 87510 8192000000 10302215376 10740563968 1345734192 14.109 98 18 36 0 1364634640 15025296 3875152
18:52:35 58699 8192000000 10292177024 10750619648 1350494816 14.152 98 13 26 0 1375488128 22250624 2742688
18:54:35 112268 8192000000 10291217504 10744160256 1331488320 13.981 97 18 36 0 1353039120 17823856 3726944
18:58:05 123443 8192000000 10296132960 10735816704 1377165296 14.391 100 32 64 1 1396674128 12618816 6890016
19:01:12 39210 8192000000 10286528048 10738294784 1361802160 14.254 98 19 38 0 1379972896 13336672 4834064
19:02:53 85466 8192000000 10293308208 10744717312 1342140272 14.077 96 12 24 0 1362809328 17739424 2929632
19:05:18 60122 8192000000 10295130192 10759602176 1386787072 14.477 100 11 22 0 1415582224 26097696 2697456
19:07:44 63252 8192000000 10310075360 10771083264 1383818192 14.451 99 14 28 0 1410363088 23364880 3180016
19:09:49 72954 8192000000 10322636416 10785689600 1410852560 14.692 100 14 28 0 1435803280 21342208 3608512
19:12:04 66662 8192000000 10318232048 10772938752 1387931280 14.487 98 13 27 0 1400106624 9279504 2895840
19:14:22 75869 8192000000 10309541648 10760523776 1378505728 14.403 98 9 18 0 1390427360 9977760 1943872
19:16:49 19106 8192000000 10329867328 10760069120 1375871664 14.380 98 69 134 0 1400473104 9272080 15329360
19:18:10 72019 8192000000 10323954896 10768097280 1373933472 14.362 98 38 76 0 1393643424 11685136 8024816
19:20:24 67122 8192000000 10324499312 10768334848 1401492800 14.608 100 67 131 1 1425335440 10363280 13479360
19:22:31 72978 8192000000 10282332560 10754805760 1385235840 14.463 99 11 22 0 1396875936 8984448 2655648
19:24:45 46491 8192000000 10272857024 10732814336 1356339872 14.204 98 8 15 1 1364530592 6451584 1739136
19:26:36 81461 8192000000 10277100784 10725650432 1349247760 14.141 98 19 38 0 1365422736 11436448 4738528
19:29:03 27985 8192000000 10258996896 10715496448 1355467024 14.197 100 19 38 1 1374069792 14000672 4602096
19:30:33 59416 8192000000 10288283472 10734563328 1365212624 14.284 99 19 38 0 1383341744 13380752 4748368
19:32:35 71455 8192000000 10287044704 10759340032 1383679920 14.449 100 15 30 0 1411360944 23370768 4310256
19:34:47 97511 8192000000 10266191136 10708287488 1295491984 13.654 94 18 35 0 1314081648 14416096 4173568
19:37:27 67918 8192000000 9119892128 9363517440 263240160 3.113 20 4 8 0 267130192 2805152 1084880

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-07-2022 19:39:47 to 18-07-2022 19:40:02.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 143 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 18-07-2022 19:40:03 to 18-07-2022 19:40:05.

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          240937
MON$OLDEST_ACTIVE               240938
MON$OLDEST_SNAPSHOT             240938
MON$NEXT_TRANSACTION            240938
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                       2026528
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.33609 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33609 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33609 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33609 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 ---
:111: 
:112: mscs-test-161.budzdorov.ru    Mon Jul 18 19:38:37 2022
:113:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:114: 
:115: 
:116: mscs-test-161.budzdorov.ru    Mon Jul 18 19:38:37 2022
:117:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:118: 
:119: 
:120: mscs-test-161.budzdorov.ru    Mon Jul 18 19:38:37 2022
:121:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:122: 
:123: 
:124: mscs-test-161.budzdorov.ru    Mon Jul 18 19:38:38 2022
:125:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:126: 
:127: 
:128: mscs-test-161.budzdorov.ru    Mon Jul 18 19:38:38 2022
:129:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:130: 
:131: 
:132: mscs-test-161.budzdorov.ru    Mon Jul 18 19:38:38 2022
:133:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:134: 
:135: 
:136: mscs-test-161.budzdorov.ru    Mon Jul 18 19:38:38 2022
:137:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:138: 
:139: 
:140: mscs-test-161.budzdorov.ru    Mon Jul 18 19:38:42 2022
:141:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:142: 
:143: 
:144: mscs-test-161.budzdorov.ru    Mon Jul 18 19:38:42 2022
:145:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:146: 
--- end of diff output ---

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

18.07.22 19:40:06. 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-07-18 19:40:08 No severe PSQL-related problems occured
18.07.22 19:40:09. End of report.