Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.11.2021 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 17213112 kB
MemAvailable 31640928 kB
Buffers 0 kB
Cached 14494952 kB
SwapCached 0 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.8
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 2021-11-15 19:37:52
DTS_END                         2021-11-15 19:37:52.5100
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/48078
TRN_ID                          243780
ATT_ID                          673
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: 9378.03 20774 1125363
customer order: creation 177.88 367 21346
customer order: refuse 15.03 357 1804
order to supplier: creation 45.72 1310 5486
order to supplier: removal 3.46 103938 415
invoice (draft): creation 46.57 3452 5588
invoice (draft): removal 54.72 735 6566
invoice accept: apply 42.26 19595 5071
invoice accept: cancel 49.43 777 5932
customer reserve: creation 3846.26 196 461551
customer reserve: removal 4782.28 59 573874
realization accept: apply 86.18 239 10342
realization accept: cancel 95.03 112 11404
payment from customer: creation 60.96 142 7315
payment from customer: removal 5.77 79 692
payment to supplier: creation 50.20 170 6024
payment to supplier: removal 3.48 194 418
service: total inventory turnovers 4.35 1326 522
service: total monetary turnovers 7.39 31 887
service: aggregate perf. data 1.02 14986 122
service: refresh index statistics 0.03 267418 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-11-2021 19:40:05 to 15-11-2021 19:40:07.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3115
TEST_TIME 2 3359
TEST_TIME 3 2756
TEST_TIME 4 3421
TEST_TIME 5 4322
TEST_TIME 6 4518
TEST_TIME 7 4658
TEST_TIME 8 5218
TEST_TIME 9 5379
TEST_TIME 10 6211
TEST_TIME 11 6470
TEST_TIME 12 6738
TEST_TIME 13 6883
TEST_TIME 14 6926
TEST_TIME 15 7166
TEST_TIME 16 7463
TEST_TIME 17 7540
TEST_TIME 18 7683
TEST_TIME 19 7823
TEST_TIME 20 8211
TEST_TIME 21 8280
TEST_TIME 22 8560
TEST_TIME 23 8466
TEST_TIME 24 8597
TEST_TIME 25 8520
TEST_TIME 26 8955
TEST_TIME 27 9177
TEST_TIME 28 9147
TEST_TIME 29 9166
TEST_TIME 30 9353
TEST_TIME 31 9482
TEST_TIME 32 10031
TEST_TIME 33 10611
TEST_TIME 34 10559
TEST_TIME 35 10455
TEST_TIME 36 10488
TEST_TIME 37 10522
TEST_TIME 38 10389
TEST_TIME 39 10271
TEST_TIME 40 10168
TEST_TIME 41 10106
TEST_TIME 42 9938
TEST_TIME 43 9926
TEST_TIME 44 9943
TEST_TIME 45 10026
TEST_TIME 46 9889
TEST_TIME 47 9882
TEST_TIME 48 9783
TEST_TIME 49 9730
TEST_TIME 50 9845
TEST_TIME 51 9784
TEST_TIME 52 9786
TEST_TIME 53 9687
TEST_TIME 54 9541
TEST_TIME 55 9518
TEST_TIME 56 9528
TEST_TIME 57 9502
TEST_TIME 58 9428
TEST_TIME 59 9313
TEST_TIME 60 9289
TEST_TIME 61 9270
TEST_TIME 62 9330
TEST_TIME 63 9354
TEST_TIME 64 9347
TEST_TIME 65 9415
TEST_TIME 66 9398
TEST_TIME 67 9465
TEST_TIME 68 9442
TEST_TIME 69 9585
TEST_TIME 70 9587
TEST_TIME 71 9565
TEST_TIME 72 9606
TEST_TIME 73 9610
TEST_TIME 74 9613
TEST_TIME 75 9535
TEST_TIME 76 9502
TEST_TIME 77 9507
TEST_TIME 78 9495
TEST_TIME 79 9463
TEST_TIME 80 9408
TEST_TIME 81 9362
TEST_TIME 82 9266
TEST_TIME 83 9259
TEST_TIME 84 9296
TEST_TIME 85 9265
TEST_TIME 86 9316
TEST_TIME 87 9301
TEST_TIME 88 9301
TEST_TIME 89 9301
TEST_TIME 90 9331
TEST_TIME 91 9317
TEST_TIME 92 9383
TEST_TIME 93 9515
TEST_TIME 94 9486
TEST_TIME 95 9540
TEST_TIME 96 9549
TEST_TIME 97 9561
TEST_TIME 98 9502
TEST_TIME 99 9448
TEST_TIME 100 9466
TEST_TIME 101 9423
TEST_TIME 102 9438
TEST_TIME 103 9381
TEST_TIME 104 9346
TEST_TIME 105 9322
TEST_TIME 106 9318
TEST_TIME 107 9332
TEST_TIME 108 9316
TEST_TIME 109 9298
TEST_TIME 110 9300
TEST_TIME 111 9345
TEST_TIME 112 9346
TEST_TIME 113 9377
TEST_TIME 114 9424
TEST_TIME 115 9404
TEST_TIME 116 9419
TEST_TIME 117 9452
TEST_TIME 118 9428
TEST_TIME 119 9451
TEST_TIME 120 9380

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 15-11-2021 19:40:09 to 15-11-2021 19:40:12.

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 1555136 1555136 0 0.00 0 23825 7 0 0
doc_list_biud 1555136 1555136 0 0.00 0 43012 28 0 0
sp_add_invoice_to_stock 5172 5071 101 1.95 45 265496 19595 0 101
sp_cancel_adding_invoice 5932 5932 0 0.00 3 60255 777 0 0
sp_cancel_client_order 1804 1804 0 0.00 4 8438 358 0 0
sp_cancel_customer_reserve 573874 573874 0 0.00 1 40575 59 0 0
sp_cancel_pay_from_customer 692 692 0 0.00 3 7051 80 0 0
sp_cancel_pay_to_supplier 418 418 0 0.00 3 22170 194 0 0
sp_cancel_supplier_invoice 6568 6566 2 0.03 1 43074 735 0 2
sp_cancel_supplier_order 415 415 0 0.00 6 980093 103938 0 0
sp_cancel_write_off 11404 11404 0 0.00 1 23835 113 0 0
sp_client_order 21346 21346 0 0.00 3 35996 368 0 0
sp_customer_reserve 461551 461551 0 0.00 0 43350 196 0 0
sp_fill_shopping_cart 32408 32408 0 0.00 1 14313 36 0 0
sp_fill_shopping_cart_clo_res 461544 461544 0 0.00 0 15340 13 0 0
sp_get_clo_for_invoice 5064 5064 0 0.00 0 27439 271 0 0
sp_kill_cost_storno 18446 18446 0 0.00 0 22164 58 0 0
sp_kill_qstorno_ret_qs2qd 1166133 1166133 0 0.00 0 43011 32 0 0
sp_kill_qty_storno 615401 615401 0 0.00 0 43012 69 0 0
sp_lock_selected_doc 21234 21234 0 0.00 0 10483 7 0 0
sp_make_cost_storno 28745 28745 0 0.00 0 23519 84 0 0
sp_multiply_rows_for_pdistr 28745 28745 0 0.00 0 21378 34 0 0
sp_multiply_rows_for_qdistr 21346 21346 0 0.00 0 12510 158 0 0
sp_pay_from_customer 7315 7315 0 0.00 4 23933 142 0 0
sp_pay_to_supplier 6024 6024 0 0.00 5 23171 171 0 0
sp_payment_common 13339 13339 0 0.00 2 21945 48 0 0
sp_qd_handle_on_cancel_clo 1804 1804 0 0.00 0 8432 334 0 0
sp_qd_handle_on_invoice_upd_sts 10996 10996 0 0.00 0 18466 349 0 0
sp_qd_handle_on_reserve_upd_sts 10342 10342 0 0.00 0 20976 62 0 0
sp_reserve_write_off 10342 10342 0 0.00 6 25542 240 0 0
sp_supplier_invoice 5588 5588 0 0.00 5 74734 3452 0 0
sp_supplier_order 5486 5486 0 0.00 5 46786 1311 0 0
srv_aggregate_perf_data 122 122 0 0.00 2219 108281 14987 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 997 138791 45519 0 0
srv_find_qd_qs_mism 1078053 1078053 0 0.00 0 9954 6 0 0
srv_make_invnt_saldo 522 522 0 0.00 39 80580 1326 0 0
srv_make_money_saldo 887 887 0 0.00 1 3746 32 0 0
srv_recalc_idx_stat 4 4 0 0.00 111315 441104 267418 0 0
t$perf-abend:sp_add_invoice_to_stock 101 101 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 5071 5071 0 0.00 0 10886 180 0 0
t$perf-norm:sp_cancel_adding_invoice 431 431 0 0.00 0 11415 134 0 0
t$perf-norm:sp_cancel_client_order 1804 1804 0 0.00 0 1754 14 0 0
t$perf-norm:sp_cancel_customer_reserve 680 680 0 0.00 0 2708 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 692 692 0 0.00 0 4050 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 418 418 0 0.00 0 3790 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 372 372 0 0.00 0 929 17 0 0
t$perf-norm:sp_cancel_supplier_order 415 415 0 0.00 0 24413 1714 0 0
t$perf-norm:sp_cancel_write_off 1016 1016 0 0.00 0 5195 13 0 0
t$perf-norm:sp_client_order 21346 21346 0 0.00 0 6788 20 0 0
t$perf-norm:sp_customer_reserve 1062 1062 0 0.00 0 3371 11 0 0
t$perf-norm:sp_pay_from_customer 7315 7315 0 0.00 0 7942 10 0 0
t$perf-norm:sp_pay_to_supplier 6024 6024 0 0.00 0 3804 8 0 0
t$perf-norm:sp_reserve_write_off 10342 10342 0 0.00 0 5596 9 0 0
t$perf-norm:sp_supplier_invoice 5588 5588 0 0.00 0 5052 19 0 0
t$perf-norm:sp_supplier_order 5486 5486 0 0.00 0 6170 20 0 0
t$perf-norm:srv_aggregate_perf_data 122 122 0 0.00 0 321 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 40 1 0 0
t$perf-norm:srv_make_invnt_saldo 522 522 0 0.00 0 1305 38 0 0
t$perf-norm:srv_make_money_saldo 887 887 0 0.00 0 1353 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 4 3 0 0
v_add_invoice_to_stock 10330 10128 202 1.95 0 6561 18 0 202
v_all_customers 38854 38854 0 0.00 0 17 1 0 0
v_all_suppliers 31022 31022 0 0.00 0 177 1 0 0
v_all_wares 42692 42692 0 0.00 0 177 1 0 0
v_cancel_adding_invoice 862 862 0 0.00 0 101 5 0 0
v_cancel_client_order 3608 3608 0 0.00 0 5908 6 0 0
v_cancel_customer_prepayment 1384 1384 0 0.00 0 580 3 0 0
v_cancel_customer_reserve 1360 1360 0 0.00 0 243 3 0 0
v_cancel_payment_to_supplier 836 836 0 0.00 0 6011 19 0 0
v_cancel_supplier_invoice 748 744 4 0.53 0 1353 16 0 4
v_cancel_supplier_order 830 830 0 0.00 0 3521 10 0 0
v_cancel_write_off 2032 2032 0 0.00 0 4809 22 0 0
v_max_id_clo_ord 5484 5484 0 0.00 0 1513 2 0 0
v_max_id_clo_res 1062 1062 0 0.00 0 2907 14 0 0
v_max_id_ord_sup 5578 5578 0 0.00 0 6184 3 0 0
v_max_non_paid_invoice 1575 1575 0 0.00 0 435 3 0 0
v_max_non_paid_realizn 3902 3902 0 0.00 0 3016 5 0 0
v_min_id_clo_ord 5484 5484 0 0.00 0 3139 7 0 0
v_min_id_clo_res 1062 1062 0 0.00 0 9126 24 0 0
v_min_id_ord_sup 5578 5578 0 0.00 0 4918 5 0 0
v_min_non_paid_invoice 6024 6024 0 0.00 0 21798 50 0 0
v_min_non_paid_realizn 7315 7315 0 0.00 0 21305 32 0 0
v_our_firm 10664 10664 0 0.00 0 170 1 0 0
v_reserve_write_off 20684 20684 0 0.00 0 6088 5 0 0
x_lock_depdocs_on_canc_invoice 12126 12126 0 0.00 0 19056 121 0 0
x_lock_depdocs_on_canc_sup_ord 415 415 0 0.00 0 17816 485 0 0
x_make_qstorno_1000_1200 5486 5486 0 0.00 1 46727 1225 0 0
x_make_qstorno_1200_2000 5588 5588 0 0.00 3 74666 3331 0 0
x_make_qstorno_2100_3300 461551 461551 0 0.00 0 43350 180 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-11-2021 19:40:13 to 15-11-2021 19:40:17.

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:07:55 13 8192000000 8808967360 8823345152 12953840 0.157 3 3 4 0 13137760 76800 107120
17:08:56 10616 8192000000 9891357696 10199683072 978294368 10.668 100 87 174 0 1006932384 5474720 23163296
17:10:13 34611 8192000000 9947779264 10268139520 1025950720 11.129 100 86 172 1 1060105584 12174736 21980128
17:12:00 23955 8192000000 9989449552 10325721088 1069626512 11.549 100 81 162 1 1099224032 9512720 20084800
17:13:25 17760 8192000000 10034498400 10384793600 1117830256 12.006 100 79 159 2 1147020800 10842640 18347904
17:14:54 9359 8192000000 10088311024 10451296256 1178814208 12.579 100 74 148 3 1206945600 11271648 16859744
17:16:08 6454 8192000000 10134213120 10501758976 1216961680 12.934 100 84 168 0 1249579424 14296864 18320880
17:17:16 5314 8192000000 10150086496 10528153600 1241598752 13.161 100 75 150 2 1272455728 14270096 16586880
17:18:23 2455 8192000000 10190126688 10572435456 1277421824 13.489 100 88 176 0 1309177792 11949456 19806512
17:19:27 1154 8192000000 10218577264 10600112128 1292094096 13.623 100 94 188 1 1327436848 13574976 21767776
17:20:29 4666 8192000000 10201970992 10602373120 1299292912 13.689 100 65 130 1 1322173408 7687648 15192848
17:21:36 11408 8192000000 10185918352 10604044288 1305691760 13.747 100 22 44 1 1316775920 6682128 4402032
17:22:54 10415 8192000000 10203115344 10624585728 1317816704 13.857 100 18 37 1 1328675728 6367312 4491712
17:24:08 53314 8192000000 10220338928 10636349440 1322809776 13.902 100 28 53 1 1334740176 5497552 6432848
17:26:11 7836 8192000000 10221790240 10639536128 1327074448 13.941 100 37 73 2 1346681872 10244288 9363136
17:27:20 7238 8192000000 10217895472 10635149312 1316585264 13.846 99 10 20 0 1324191104 4986896 2618944
17:28:28 9074 8192000000 10194036384 10609627136 1257582720 13.308 95 16 33 0 1266793040 5485840 3724480
17:29:41 49652 8192000000 10155925776 10541424640 1203917984 12.813 94 57 113 2 1222382016 5258144 13205888
17:31:31 3664 8192000000 10134376928 10531864576 1240564304 13.151 100 26 50 3 1253469200 7141536 5763360
17:32:35 8453 8192000000 10162294480 10558791680 1248795920 13.227 99 33 66 0 1262148608 4996704 8355984
17:33:45 48508 8192000000 10164051648 10574811136 1275431296 13.471 100 23 45 1 1285486688 4422224 5633168
17:35:34 7088 8192000000 10190477200 10601840640 1293651488 13.637 100 34 67 2 1308048592 6435568 7961536
17:36:41 11277 8192000000 10190389008 10605604864 1298530896 13.682 100 9 18 1 1305815120 5256928 2027296
17:37:58 20919 8192000000 10224491168 10634141696 1321979488 13.895 100 37 74 1 1339990656 8717952 9293216
17:39:26 93639 8192000000 10245051104 10669268992 1345759584 14.109 100 41 82 1 1363945760 7975568 10210608
17:42:04 8233 8192000000 10268658352 10686509056 1358958352 14.228 100 56 113 2 1382581648 10286496 13336800
17:43:20 9483 8192000000 10288706400 10704637952 1371580848 14.341 100 62 125 2 1398456064 12733552 14141664
17:44:32 34724 8192000000 10293810928 10719256576 1372637888 14.351 100 40 79 1 1395801072 13964976 9198208
17:46:15 3445 8192000000 10311287856 10731368448 1384686784 14.458 100 52 103 0 1414760112 17887216 12186112
17:47:21 13403 8192000000 10315704416 10743980032 1396149648 14.561 100 61 121 0 1428356128 17388304 14818176
17:48:40 12598 8192000000 10324179312 10748112896 1386208608 14.472 99 69 138 2 1421263792 19457216 15597968
17:50:17 5162 8192000000 10301891824 10718834688 1365338976 14.285 99 65 129 0 1401231312 21439552 14452784
17:51:34 6335 8192000000 10331994960 10751680512 1396513856 14.564 100 68 136 0 1436714000 24276560 15923584
17:52:46 7170 8192000000 10318044160 10729529344 1372527264 14.350 99 76 151 0 1415032432 24768656 17736512
17:53:58 1191 8192000000 10333799312 10742001664 1386765968 14.477 100 88 174 1 1435583968 27826160 20991840
17:55:06 6149 8192000000 10295386496 10699694080 1342597072 14.081 99 72 144 1 1388356736 27346304 18413360
17:56:17 1202 8192000000 10316193360 10718064640 1346084416 14.112 98 91 180 0 1402540400 33725408 22730576
17:57:23 5415 8192000000 10316424928 10725224448 1357488240 14.215 100 81 160 2 1419610592 41779408 20342944
17:58:36 4120 8192000000 10308653728 10711162880 1351741856 14.163 100 70 138 2 1403899344 34476576 17680912
17:59:45 994 8192000000 10317310432 10726350848 1369352000 14.321 100 68 135 2 1418051344 31351904 17347440
18:00:54 2470 8192000000 10341190624 10751442944 1379788672 14.415 100 80 159 1 1439408992 38394928 21225392
18:02:03 4772 8192000000 10345672960 10763362304 1380851776 14.424 99 51 102 0 1439940960 45291456 13797728
18:03:14 7022 8192000000 10369140128 10790268928 1404873952 14.638 100 53 106 3 1469689184 50711392 14103840
18:04:25 13323 8192000000 10365798864 10799919104 1413264592 14.713 100 51 102 1 1474575776 47621232 13689952
18:05:50 16003 8192000000 10350342784 10791931904 1398767728 14.584 100 23 46 0 1455093184 49969536 6355920
18:07:12 16679 8192000000 10308831504 10749607936 1362276016 14.258 99 25 50 2 1409615360 40380240 6959104
18:08:31 28349 8192000000 10332600048 10772127744 1376472032 14.385 100 23 46 2 1433889216 50965488 6451696
18:10:03 57527 8192000000 10304089488 10739482624 1356140704 14.203 98 17 34 0 1380619696 20926384 3552608
18:12:03 99492 8192000000 10315448448 10772799488 1377112592 14.391 99 23 45 0 1392911152 10855552 4943008
18:14:46 75690 8192000000 10314778544 10769620992 1391055664 14.515 99 22 44 1 1406650480 9387152 6207664
18:17:06 65725 8192000000 10317820352 10772893696 1406884176 14.656 100 30 58 0 1425748224 12102112 6761936
18:19:52 85452 8192000000 10336366832 10795106304 1413970720 14.719 100 30 60 1 1445025216 24319424 6735072
18:22:18 82956 8192000000 10319433504 10797273088 1398412352 14.581 99 9 18 0 1418296368 17785136 2098880
18:24:43 37543 8192000000 10323591824 10771697664 1380727744 14.423 98 28 54 2 1397532432 10239248 6565440
18:26:22 20642 8192000000 10319904128 10763517952 1392116240 14.525 100 51 97 3 1421283056 17714832 11451984
18:27:44 28671 8192000000 10297819968 10771034112 1397906528 14.576 100 8 16 0 1415481120 15508864 2065728
18:29:13 48116 8192000000 10293090704 10766229504 1383347024 14.446 99 5 8 0 1400808224 16549968 911232
18:31:02 54585 8192000000 10302562464 10763870208 1389502432 14.501 99 15 30 0 1404518016 11572768 3442816
18:34:15 41707 8192000000 10281693632 10743214080 1360260688 14.240 97 18 36 1 1373640720 9262272 4117760
18:36:00 93494 8192000000 10279264256 10755133440 1376960832 14.389 99 9 18 0 1387063536 8180176 1922528
18:38:34 43868 8192000000 10274649616 10738827264 1347249440 14.123 97 14 28 0 1361740000 11794160 2696400
18:40:19 44364 8192000000 10293278832 10750062592 1360139392 14.239 98 24 48 0 1374974288 9528752 5306144
18:42:10 55291 8192000000 10275658528 10734759936 1357563728 14.215 98 11 22 0 1372464864 11927200 2973936
18:44:05 39309 8192000000 10280463776 10749833216 1363638160 14.270 99 14 28 1 1377823952 10593152 3592640
18:45:45 117634 8192000000 10263719344 10710417408 1248534256 13.225 91 20 40 0 1270172032 17327232 4310544
18:48:44 38268 8192000000 10258075312 10696081408 1300971824 13.704 96 33 66 0 1319248064 11343936 6932304
18:50:28 39323 8192000000 10240942464 10680619008 1304190784 13.733 98 37 74 0 1322364288 9896992 8276512
18:52:09 96167 8192000000 10262978528 10707058688 1348637584 14.135 99 21 42 0 1364560784 11181024 4742176
18:54:45 39214 8192000000 10275615776 10720276480 1364684832 14.279 100 45 90 0 1386896144 12616688 9594624
18:56:29 41098 8192000000 10231225264 10683138048 1300229920 13.697 96 12 24 0 1308593408 5953264 2410224
18:58:26 42910 8192000000 10254789712 10704973824 1358160144 14.221 100 11 20 0 1369865584 9294320 2411120
19:00:09 93362 8192000000 10278135760 10737168384 1358396720 14.223 99 13 26 1 1372974576 11210960 3366896
19:02:43 42117 8192000000 10262467520 10721931264 1338872016 14.047 98 17 34 0 1359441120 16176912 4392192
19:04:29 56770 8192000000 10273472464 10725330944 1367986144 14.309 100 12 24 1 1381577824 10612496 2979184
19:06:26 104153 8192000000 10305709344 10765332480 1380616752 14.422 99 17 34 0 1400454160 15547648 4289760
19:09:11 67408 8192000000 10293389968 10762240000 1384905120 14.460 100 18 36 1 1411999568 22857696 4236752
19:11:37 33525 8192000000 10305157088 10758799360 1397138976 14.570 100 22 44 1 1411102736 9501392 4462368
19:13:23 72542 8192000000 10320846560 10787827712 1377901648 14.398 98 7 14 0 1398427216 19130016 1395552
19:15:52 138791 8192000000 10300865632 10770280448 1375900400 14.380 98 26 52 0 1399091632 17368544 5822688
19:19:12 57816 8192000000 10294135312 10770628608 1398766688 14.584 100 11 22 0 1411002208 9873520 2362000
19:21:10 57225 8192000000 10273398256 10725986304 1352728128 14.172 96 17 33 0 1366857984 10466784 3663072
19:23:16 92596 8192000000 10284477456 10760347648 1381578768 14.431 99 10 20 0 1396648096 12700784 2368544
19:25:52 21902 8192000000 10265507280 10719756288 1342320288 14.078 97 19 37 1 1353915120 7296992 4297840
19:27:20 75349 8192000000 10294473616 10750377984 1354425760 14.187 97 23 46 0 1371127568 11396912 5304896
19:29:39 52804 8192000000 10291424528 10758397952 1358681104 14.226 98 10 20 0 1377828080 17302848 1844128
19:31:33 102880 8192000000 10292814064 10749308928 1319989280 13.877 95 19 38 2 1333503344 9428064 4086000
19:34:21 68343 8192000000 10263085056 10719318016 1291612528 13.619 94 29 58 5 1308676560 10947728 6116304
19:36:29 109135 8192000000 9703787472 10122043392 798403888 8.880 58 18 36 0 809776112 7433536 3938688

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 15-11-2021 19:40:19 to 15-11-2021 19:40:34.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 101 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 15-11-2021 19:40:35 to 15-11-2021 19:40:37.

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          243063
MON$OLDEST_ACTIVE               244093
MON$OLDEST_SNAPSHOT             244093
MON$NEXT_TRANSACTION            244093
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                       2050144
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33533 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33533 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33533 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33533 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 ---
:367: 
:368: mscs-test-161.budzdorov.ru    Mon Nov 15 19:38:27 2021
:369:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:370: 
:371: 
:372: mscs-test-161.budzdorov.ru    Mon Nov 15 19:38:27 2021
:373:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:374: 
:375: 
:376: mscs-test-161.budzdorov.ru    Mon Nov 15 19:38:55 2021
:377:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:378: 
:379: 
:380: mscs-test-161.budzdorov.ru    Mon Nov 15 19:38:55 2021
:381:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:382: 
--- end of diff output ---

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

15.11.21 19:40:38. 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
2021-11-15 19:40:40 No severe PSQL-related problems occured
15.11.21 19:40:41. End of report.