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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-07-28 22:38:27
DTS_END                         2022-07-28 22:38:27.0550
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/49828
TRN_ID                          247180
ATT_ID                          659
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: 9271.33 15899 1112559
customer order: creation 181.77 351 21812
customer order: refuse 15.43 356 1851
order to supplier: creation 46.99 1300 5639
order to supplier: removal 3.54 88576 425
invoice (draft): creation 48.83 3254 5860
invoice (draft): removal 52.23 745 6267
invoice accept: apply 44.33 18163 5319
invoice accept: cancel 46.33 766 5560
customer reserve: creation 4005.48 184 480658
customer reserve: removal 4522.52 54 542703
realization accept: apply 88.10 162 10572
realization accept: cancel 80.51 113 9661
payment from customer: creation 62.13 95 7455
payment from customer: removal 5.90 86 708
payment to supplier: creation 50.63 107 6076
payment to supplier: removal 3.45 72 414
service: total inventory turnovers 5.24 817 629
service: total monetary turnovers 6.90 24 828
service: aggregate perf. data 0.98 15347 118
service: refresh index statistics 0.03 187403 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-07-2022 22:39:18 to 28-07-2022 22:39:20.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5801
TEST_TIME 2 4799
TEST_TIME 3 5147
TEST_TIME 4 4978
TEST_TIME 5 5047
TEST_TIME 6 5111
TEST_TIME 7 5240
TEST_TIME 8 5802
TEST_TIME 9 5622
TEST_TIME 10 5756
TEST_TIME 11 5804
TEST_TIME 12 5593
TEST_TIME 13 5870
TEST_TIME 14 5976
TEST_TIME 15 6142
TEST_TIME 16 6571
TEST_TIME 17 7095
TEST_TIME 18 7320
TEST_TIME 19 7845
TEST_TIME 20 8449
TEST_TIME 21 8657
TEST_TIME 22 9092
TEST_TIME 23 9118
TEST_TIME 24 9782
TEST_TIME 25 9741
TEST_TIME 26 10001
TEST_TIME 27 10347
TEST_TIME 28 10247
TEST_TIME 29 9905
TEST_TIME 30 9976
TEST_TIME 31 9852
TEST_TIME 32 9964
TEST_TIME 33 9760
TEST_TIME 34 9727
TEST_TIME 35 9778
TEST_TIME 36 9685
TEST_TIME 37 9739
TEST_TIME 38 9667
TEST_TIME 39 9633
TEST_TIME 40 9562
TEST_TIME 41 9535
TEST_TIME 42 9392
TEST_TIME 43 9269
TEST_TIME 44 9323
TEST_TIME 45 9260
TEST_TIME 46 9436
TEST_TIME 47 9381
TEST_TIME 48 9431
TEST_TIME 49 9378
TEST_TIME 50 9376
TEST_TIME 51 9502
TEST_TIME 52 9473
TEST_TIME 53 9572
TEST_TIME 54 9583
TEST_TIME 55 9576
TEST_TIME 56 9588
TEST_TIME 57 9655
TEST_TIME 58 9653
TEST_TIME 59 9612
TEST_TIME 60 9475
TEST_TIME 61 9469
TEST_TIME 62 9442
TEST_TIME 63 9406
TEST_TIME 64 9330
TEST_TIME 65 9290
TEST_TIME 66 9286
TEST_TIME 67 9315
TEST_TIME 68 9273
TEST_TIME 69 9269
TEST_TIME 70 9324
TEST_TIME 71 9303
TEST_TIME 72 9312
TEST_TIME 73 9312
TEST_TIME 74 9309
TEST_TIME 75 9300
TEST_TIME 76 9513
TEST_TIME 77 9541
TEST_TIME 78 9581
TEST_TIME 79 9562
TEST_TIME 80 9502
TEST_TIME 81 9443
TEST_TIME 82 9417
TEST_TIME 83 9392
TEST_TIME 84 9367
TEST_TIME 85 9357
TEST_TIME 86 9342
TEST_TIME 87 9299
TEST_TIME 88 9250
TEST_TIME 89 9236
TEST_TIME 90 9200
TEST_TIME 91 9216
TEST_TIME 92 9206
TEST_TIME 93 9214
TEST_TIME 94 9244
TEST_TIME 95 9310
TEST_TIME 96 9300
TEST_TIME 97 9339
TEST_TIME 98 9359
TEST_TIME 99 9394
TEST_TIME 100 9407
TEST_TIME 101 9379
TEST_TIME 102 9414
TEST_TIME 103 9414
TEST_TIME 104 9366
TEST_TIME 105 9327
TEST_TIME 106 9313
TEST_TIME 107 9252
TEST_TIME 108 9226
TEST_TIME 109 9215
TEST_TIME 110 9226
TEST_TIME 111 9222
TEST_TIME 112 9286
TEST_TIME 113 9263
TEST_TIME 114 9267
TEST_TIME 115 9267
TEST_TIME 116 9260
TEST_TIME 117 9251
TEST_TIME 118 9281
TEST_TIME 119 9289
TEST_TIME 120 9274

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 28-07-2022 22:39:21 to 28-07-2022 22:39:25.

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 1556978 1556978 0 0.00 0 16707 6 0 0
doc_list_biud 1556976 1556976 0 0.00 0 39296 24 0 0
sp_add_invoice_to_stock 5383 5319 64 1.18 34 201264 18164 0 64
sp_cancel_adding_invoice 5560 5560 0 0.00 5 63783 766 0 0
sp_cancel_client_order 1851 1851 0 0.00 4 10326 356 0 0
sp_cancel_customer_reserve 542703 542703 0 0.00 0 39297 55 0 0
sp_cancel_pay_from_customer 708 708 0 0.00 3 4753 87 0 0
sp_cancel_pay_to_supplier 414 414 0 0.00 2 1805 72 0 0
sp_cancel_supplier_invoice 6273 6267 6 0.09 1 26580 746 0 6
sp_cancel_supplier_order 425 425 0 0.00 5 1075642 88577 0 0
sp_cancel_write_off 9661 9661 0 0.00 1 10992 114 0 0
sp_client_order 21812 21812 0 0.00 3 24636 351 0 0
sp_customer_reserve 480658 480658 0 0.00 0 109480 185 0 0
sp_fill_shopping_cart 33304 33304 0 0.00 0 14091 36 0 0
sp_fill_shopping_cart_clo_res 480649 480649 0 0.00 0 16866 17 0 0
sp_get_clo_for_invoice 5309 5309 0 0.00 0 24374 282 0 0
sp_kill_cost_storno 16343 16343 0 0.00 0 13796 51 0 0
sp_kill_qstorno_ret_qs2qd 1101759 1101759 0 0.00 0 39294 30 0 0
sp_kill_qty_storno 582347 582347 0 0.00 0 39296 65 0 0
sp_lock_selected_doc 21892 21892 0 0.00 0 8522 6 0 0
sp_make_cost_storno 29412 29412 0 0.00 0 9557 44 0 0
sp_multiply_rows_for_pdistr 29411 29411 0 0.00 0 6768 23 0 0
sp_multiply_rows_for_qdistr 21812 21812 0 0.00 0 19780 167 0 0
sp_pay_from_customer 7455 7455 0 0.00 4 7475 95 0 0
sp_pay_to_supplier 6076 6076 0 0.00 4 9579 108 0 0
sp_payment_common 13531 13531 0 0.00 1 7011 20 0 0
sp_qd_handle_on_cancel_clo 1851 1851 0 0.00 0 9529 313 0 0
sp_qd_handle_on_invoice_upd_sts 10869 10869 0 0.00 0 12111 290 0 0
sp_qd_handle_on_reserve_upd_sts 10571 10571 0 0.00 0 23525 58 0 0
sp_reserve_write_off 10572 10572 0 0.00 6 23562 163 0 0
sp_supplier_invoice 5860 5860 0 0.00 6 82758 3254 0 0
sp_supplier_order 5639 5639 0 0.00 4 33983 1300 0 0
srv_aggregate_perf_data 118 118 0 0.00 2474 202317 15348 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1455 128583 44524 0 0
srv_find_qd_qs_mism 1062342 1062342 0 0.00 0 16706 6 0 0
srv_make_invnt_saldo 629 629 0 0.00 37 14026 817 0 0
srv_make_money_saldo 828 828 0 0.00 0 574 24 0 0
srv_recalc_idx_stat 4 4 0 0.00 87980 231263 187403 0 0
t$perf-abend:sp_add_invoice_to_stock 64 64 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5319 5319 0 0.00 0 10299 140 0 0
t$perf-norm:sp_cancel_adding_invoice 402 402 0 0.00 0 4504 97 0 0
t$perf-norm:sp_cancel_client_order 1851 1851 0 0.00 0 9791 21 0 0
t$perf-norm:sp_cancel_customer_reserve 723 723 0 0.00 0 1491 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 708 708 0 0.00 0 475 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 414 414 0 0.00 0 278 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 429 429 0 0.00 0 3595 15 0 0
t$perf-norm:sp_cancel_supplier_order 425 425 0 0.00 0 23064 1457 0 0
t$perf-norm:sp_cancel_write_off 1060 1060 0 0.00 0 3899 11 0 0
t$perf-norm:sp_client_order 21812 21812 0 0.00 0 9485 18 0 0
t$perf-norm:sp_customer_reserve 1051 1051 0 0.00 0 6717 13 0 0
t$perf-norm:sp_pay_from_customer 7455 7455 0 0.00 0 7800 9 0 0
t$perf-norm:sp_pay_to_supplier 6076 6076 0 0.00 0 2819 8 0 0
t$perf-norm:sp_reserve_write_off 10572 10572 0 0.00 0 7740 9 0 0
t$perf-norm:sp_supplier_invoice 5860 5860 0 0.00 0 7194 17 0 0
t$perf-norm:sp_supplier_order 5639 5639 0 0.00 0 3851 13 0 0
t$perf-norm:srv_aggregate_perf_data 118 118 0 0.00 0 210 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 628 20 0 0
t$perf-norm:srv_make_invnt_saldo 629 629 0 0.00 0 1315 18 0 0
t$perf-norm:srv_make_money_saldo 828 828 0 0.00 0 1068 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 163 42 0 0
v_add_invoice_to_stock 10746 10618 128 1.19 0 6151 18 0 128
v_all_customers 39456 39456 0 0.00 0 72 1 0 0
v_all_suppliers 31440 31440 0 0.00 0 33 1 0 0
v_all_wares 43624 43624 0 0.00 0 23 1 0 0
v_cancel_adding_invoice 804 804 0 0.00 0 2755 14 0 0
v_cancel_client_order 3702 3702 0 0.00 0 3890 8 0 0
v_cancel_customer_prepayment 1416 1416 0 0.00 0 4325 12 0 0
v_cancel_customer_reserve 1446 1446 0 0.00 0 1933 9 0 0
v_cancel_payment_to_supplier 828 828 0 0.00 0 1138 7 0 0
v_cancel_supplier_invoice 870 858 12 1.37 0 5481 30 0 12
v_cancel_supplier_order 850 850 0 0.00 0 1059 8 0 0
v_cancel_write_off 2120 2120 0 0.00 0 2607 18 0 0
v_max_id_clo_ord 5638 5638 0 0.00 0 1412 2 0 0
v_max_id_clo_res 1051 1051 0 0.00 0 1772 12 0 0
v_max_id_ord_sup 5854 5854 0 0.00 0 3172 2 0 0
v_max_non_paid_invoice 1848 1848 0 0.00 0 109 2 0 0
v_max_non_paid_realizn 4073 4073 0 0.00 0 716 3 0 0
v_min_id_clo_ord 5638 5638 0 0.00 0 1889 6 0 0
v_min_id_clo_res 1051 1051 0 0.00 0 645 4 0 0
v_min_id_ord_sup 5854 5854 0 0.00 0 6796 6 0 0
v_min_non_paid_invoice 6076 6076 0 0.00 0 5493 13 0 0
v_min_non_paid_realizn 7455 7455 0 0.00 0 6986 14 0 0
v_our_firm 10932 10932 0 0.00 0 19 1 0 0
v_reserve_write_off 21142 21142 0 0.00 0 4098 8 0 0
x_lock_depdocs_on_canc_invoice 11398 11398 0 0.00 0 21648 116 0 0
x_lock_depdocs_on_canc_sup_ord 425 425 0 0.00 0 12362 336 0 0
x_make_qstorno_1000_1200 5639 5639 0 0.00 2 33977 1226 0 0
x_make_qstorno_1200_2000 5860 5860 0 0.00 3 75150 3123 0 0
x_make_qstorno_2100_3300 480658 480658 0 0.00 0 109088 166 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-07-2022 22:39:26 to 28-07-2022 22:39:30.

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:30 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:31 30231 8192000000 9896542272 10209030144 977056880 10.656 100 87 174 0 1005481872 5388544 23036448
20:11:11 6793 8192000000 9949628448 10270306304 1035595120 11.222 100 92 184 1 1066753296 7680800 23477376
20:12:26 12012 8192000000 9988742448 10321985536 1066974976 11.523 100 78 156 0 1095191776 8617760 19599040
20:13:46 10300 8192000000 10033857536 10379649024 1120373360 12.031 100 78 156 0 1148740240 9451280 18915600
20:15:10 4918 8192000000 10094338672 10456698880 1186639984 12.652 100 83 164 1 1217724640 12583664 18500992
20:16:16 3391 8192000000 10143540000 10515685376 1230388176 13.058 100 81 158 1 1261160848 13082528 17690144
20:17:23 2218 8192000000 10174104080 10555342848 1255181792 13.286 100 77 154 0 1285530192 13125056 17223344
20:18:27 1011 8192000000 10209886272 10592321536 1293329520 13.635 100 88 174 0 1326912016 14376352 19206144
20:19:31 1659 8192000000 10212370000 10598285312 1299495216 13.691 100 81 161 1 1330703712 12654016 18554480
20:20:35 30005 8192000000 10220390976 10613276672 1309576400 13.782 100 68 136 0 1336048080 10751248 15720432
20:22:06 2232 8192000000 10204780272 10617417728 1316819280 13.848 100 34 68 0 1331644128 6970640 7854208
20:23:10 4961 8192000000 10220968304 10635440128 1329104304 13.959 100 52 107 0 1348782784 8202928 11475552
20:24:29 4792 8192000000 10224448672 10640302080 1330169504 13.969 100 40 78 0 1347378160 8931824 8276832
20:25:38 4369 8192000000 10240697232 10652782592 1338232864 14.041 100 30 60 0 1352345984 6601104 7512016
20:26:45 9551 8192000000 10226874160 10645688320 1337242272 14.033 100 23 46 1 1347899280 5381792 5275216
20:27:58 12312 8192000000 10217050768 10643599360 1325493760 13.926 99 29 51 4 1335785152 4503968 5787424
20:29:12 4273 8192000000 10201040208 10613579776 1289007904 13.595 98 20 37 0 1298503344 5316336 4179104
20:30:17 3660 8192000000 10170300656 10574118912 1252903856 13.265 98 34 67 1 1267719056 7279792 7535408
20:31:22 76460 8192000000 10127340448 10526789632 1122240768 12.048 88 31 60 0 1134241888 5287152 6713968
20:33:41 5355 8192000000 10171735008 10575904768 1266456240 13.389 99 34 68 0 1279542464 4832416 8253808
20:34:49 7719 8192000000 10193059664 10598584320 1286728112 13.574 99 34 65 0 1299336304 4381600 8226592
20:35:59 47353 8192000000 10217180688 10613690368 1294291936 13.643 99 48 96 1 1310959568 5175536 11492096
20:37:47 6046 8192000000 10203269120 10621575168 1313229536 13.815 100 43 87 1 1329717824 6368608 10119680
20:38:55 24705 8192000000 10251394672 10660253696 1340094000 14.058 100 64 125 0 1367384992 13881280 13409712
20:40:28 15802 8192000000 10260233952 10678956032 1354823264 14.191 100 38 74 0 1373379760 9756320 8800176
20:42:03 30194 8192000000 10295104240 10714132480 1378770592 14.406 100 54 107 0 1405342272 14376944 12194736
20:43:39 5346 8192000000 10300926944 10719870976 1386294608 14.473 100 57 114 1 1415432640 15292224 13845808
20:44:51 15335 8192000000 10306777168 10731786240 1391007536 14.515 100 50 100 0 1421964800 19201840 11755424
20:46:13 24788 8192000000 10325178720 10750476288 1402448160 14.617 100 54 107 0 1438331536 22644208 13239168
20:47:43 10857 8192000000 10322924608 10743640064 1386255696 14.472 98 53 105 0 1422713936 23339984 13118256
20:49:00 9847 8192000000 10346503984 10772443136 1409320592 14.678 99 46 93 0 1448893984 27665936 11907456
20:50:16 9046 8192000000 10332235840 10757488640 1406191776 14.650 100 42 82 2 1443867616 27051136 10624704
20:51:31 4288 8192000000 10349014000 10768318464 1406594464 14.654 100 65 127 1 1456559088 34217744 15746880
20:52:42 7385 8192000000 10360109520 10771292160 1406978192 14.657 100 69 138 0 1461221952 37263968 16979792
20:53:53 1455 8192000000 10341832160 10750578688 1390955072 14.514 100 84 166 2 1450707504 39575920 20176512
20:55:10 5118 8192000000 10344175552 10753028096 1375187024 14.373 99 79 156 1 1440558416 45860544 19510848
20:56:17 2273 8192000000 10339342400 10746286080 1374308240 14.366 100 83 164 1 1440689552 45304352 21076960
20:57:24 4093 8192000000 10335773040 10743369728 1372892576 14.353 100 78 154 0 1439506992 47533024 19081392
20:58:33 19608 8192000000 10286173856 10712711168 1326968944 13.940 97 27 52 0 1367393568 32894880 7529744
21:00:02 27624 8192000000 10274242128 10710110208 1342849264 14.083 99 24 48 0 1385544736 36063824 6631648
21:01:35 6942 8192000000 10287162672 10716876800 1353597344 14.180 100 39 77 1 1395268352 32137328 9533680
21:02:43 29761 8192000000 10259204096 10694856704 1315847712 13.839 97 22 44 1 1343641264 21867008 5926544
21:04:14 28932 8192000000 10261373264 10702057472 1340022496 14.058 99 17 34 0 1366463360 21663856 4777008
21:06:43 67222 8192000000 10261032480 10699776000 1336160896 14.023 98 32 64 1 1351361184 7210240 7990048
21:09:12 28616 8192000000 10265167920 10687320064 1323456176 13.908 97 39 74 1 1336592944 5762496 7374272
21:10:45 49391 8192000000 10262777808 10711199744 1354682192 14.190 99 12 24 0 1368839872 11413296 2744384
21:12:41 38789 8192000000 10291215536 10723250176 1363367184 14.268 99 33 64 1 1381992080 11005968 7618928
21:14:23 16476 8192000000 10298635392 10739847168 1391394688 14.518 100 39 73 1 1406928352 7784800 7748864
21:15:40 73357 8192000000 10291824192 10746441728 1392283984 14.526 100 20 40 0 1406249584 9581856 4383744
21:17:54 39012 8192000000 10294308496 10744078336 1384620704 14.458 99 13 26 0 1396894576 8660880 3612992
21:19:34 33218 8192000000 10285217936 10741788672 1385574800 14.466 99 19 36 0 1401962272 11873504 4513968
21:21:10 43840 8192000000 10302086176 10749276160 1390782000 14.513 100 39 77 1 1415615040 15586352 9246688
21:23:01 62815 8192000000 10288897296 10749280256 1382346144 14.438 99 23 44 0 1400852912 13194080 5312688
21:25:22 118251 8192000000 10295292464 10748735488 1368649184 14.315 98 18 36 0 1383671360 10753408 4268768
21:28:27 50435 8192000000 10316707680 10773504000 1391770288 14.522 99 19 38 1 1415346176 18977888 4598000
21:30:30 70358 8192000000 10287488512 10742599680 1331011600 13.976 95 17 34 0 1354667120 19849440 3806080
21:32:40 49532 8192000000 10287589104 10733355008 1332298816 13.988 96 30 60 0 1351330256 12327152 6704288
21:34:32 15243 8192000000 10277155728 10715942912 1344968704 14.102 98 40 79 0 1361570208 8024688 8576816
21:35:49 52283 8192000000 10280115184 10725343232 1335456912 14.016 97 23 44 0 1349901216 9893120 4551184
21:37:49 103992 8192000000 10271757392 10717589504 1354268144 14.186 99 22 42 1 1371751296 12881344 4601808
21:40:33 15494 8192000000 10285179968 10734538752 1364820464 14.281 99 35 70 0 1386847728 14865232 7162032
21:41:52 64462 8192000000 10265456288 10721284096 1335736608 14.019 97 14 28 0 1350754944 11765696 3252640
21:44:08 39271 8192000000 10243410768 10694430720 1312276144 13.807 96 12 25 0 1329274816 14025840 2972832
21:46:31 31434 8192000000 10240422304 10677690368 1287124688 13.578 95 27 55 1 1301256080 8173488 5957904
21:48:02 74962 8192000000 10255484064 10697904128 1341969376 14.075 100 21 40 1 1357053760 10077696 5006688
21:50:24 54029 8192000000 10277206496 10723524608 1351631088 14.162 99 24 48 0 1366133488 9489808 5012592
21:52:19 69324 8192000000 10303785088 10751381504 1374629808 14.369 99 17 34 0 1400019280 21106160 4283312
21:54:33 68639 8192000000 10272918256 10728271872 1331260528 13.979 97 6 12 0 1343540912 11132864 1147520
21:56:42 79144 8192000000 10300764192 10737594368 1357994992 14.219 98 26 51 0 1374520736 11286640 5239104
21:59:09 76936 8192000000 10266825136 10722209792 1340012544 14.058 97 23 46 0 1352388288 7256128 5119616
22:01:44 32642 8192000000 10276197424 10735042560 1378488208 14.403 100 11 18 0 1390037216 9647232 1901776
22:03:24 74105 8192000000 10279811760 10748043264 1389094800 14.498 100 10 20 0 1399965536 8647648 2223088
22:05:52 119711 8192000000 10282246000 10747596800 1363462864 14.268 98 16 32 0 1375367344 7855712 4048768
22:08:59 73795 8192000000 10286394656 10736148480 1329392400 13.962 95 20 40 0 1340905248 6861616 4651232
22:11:13 52874 8192000000 10301903712 10757578752 1359000304 14.228 97 20 38 0 1379829040 16659456 4169280
22:13:06 48386 8192000000 10312464416 10760097792 1396604496 14.565 100 22 44 1 1412609024 11020256 4984272
22:14:55 84893 8192000000 10289807936 10751180800 1336398000 14.025 95 15 30 0 1352803392 13007152 3398240
22:17:21 20667 8192000000 10317040128 10758901760 1382280960 14.437 99 47 90 0 1405278912 13104160 9893792
22:19:06 17624 8192000000 10292871104 10737827840 1356761552 14.208 98 23 47 0 1370100272 8419568 4919152
22:20:24 58031 8192000000 10307372720 10748915712 1372378640 14.348 99 22 44 0 1385626864 8451952 4796272
22:22:23 128583 8192000000 10252180272 10695704576 1304172960 13.733 95 35 70 0 1323594640 11836624 7585056
22:27:08 57506 8192000000 10281465680 10750865408 1362044544 14.256 99 9 18 0 1379810528 15947088 1818896
22:29:05 95664 8192000000 10266326320 10739134464 1293861312 13.639 94 7 14 0 1315840864 20316976 1662576
22:31:52 17566 8192000000 10275973136 10711101440 1349178752 14.140 99 43 84 1 1369758976 10588240 9991984
22:33:09 53540 8192000000 10254503952 10696269824 1289737488 13.602 95 32 66 0 1307291584 10675168 6878928
22:35:03 87742 8192000000 10241396016 10687590400 1295062352 13.650 96 11 21 0 1309024384 11495744 2466288
22:37:32 56166 8192000000 10148739344 10579771392 1234195232 13.093 92 15 30 0 1249559136 12091072 3272832

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 28-07-2022 22:39:32 to 28-07-2022 22:39:47.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 64 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 28-07-2022 22:39:47 to 28-07-2022 22:39:49.

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          246549
MON$OLDEST_ACTIVE               247481
MON$OLDEST_SNAPSHOT             247481
MON$NEXT_TRANSACTION            247481
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                       2018368
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.33613 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33613 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33613 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33613 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 ---
:412: 
:413: mscs-test-161.budzdorov.ru    Thu Jul 28 22:38:42 2022
:414:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:415: 
--- end of diff output ---

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

28.07.22 22:39:51. 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-28 22:39:53 No severe PSQL-related problems occured
28.07.22 22:39:54. End of report.