Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.07.2022 16: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 16149708 kB
MemAvailable 31089092 kB
Buffers 0 kB
Cached 15546796 kB
SwapCached 2516 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-13 16:38:24
DTS_END                         2022-07-13 16:38:24.4510
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/40734
TRN_ID                          240490
ATT_ID                          686
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: 9241.20 24074 1108944
customer order: creation 168.25 353 20190
customer order: refuse 14.96 365 1795
order to supplier: creation 46.13 1298 5535
order to supplier: removal 3.47 96846 416
invoice (draft): creation 45.88 3373 5505
invoice (draft): removal 53.15 719 6378
invoice accept: apply 41.45 17363 4974
invoice accept: cancel 47.76 790 5731
customer reserve: creation 3775.34 174 453041
customer reserve: removal 4752.07 56 570248
realization accept: apply 82.52 209 9902
realization accept: cancel 82.88 103 9946
payment from customer: creation 59.73 135 7167
payment from customer: removal 5.56 118 667
payment to supplier: creation 47.77 145 5732
payment to supplier: removal 3.08 105 370
service: total inventory turnovers 4.49 1127 539
service: total monetary turnovers 5.90 61 708
service: aggregate perf. data 0.80 24410 96
service: refresh index statistics 0.03 333731 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-07-2022 16:39:26 to 13-07-2022 16:39:28.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6036
TEST_TIME 2 5133
TEST_TIME 3 5115
TEST_TIME 4 4722
TEST_TIME 5 4462
TEST_TIME 6 5263
TEST_TIME 7 5632
TEST_TIME 8 6019
TEST_TIME 9 6071
TEST_TIME 10 6311
TEST_TIME 11 6461
TEST_TIME 12 6425
TEST_TIME 13 6352
TEST_TIME 14 6818
TEST_TIME 15 6975
TEST_TIME 16 7081
TEST_TIME 17 7415
TEST_TIME 18 7747
TEST_TIME 19 8121
TEST_TIME 20 8428
TEST_TIME 21 9031
TEST_TIME 22 9435
TEST_TIME 23 9887
TEST_TIME 24 9948
TEST_TIME 25 10262
TEST_TIME 26 10770
TEST_TIME 27 10587
TEST_TIME 28 10406
TEST_TIME 29 10299
TEST_TIME 30 10158
TEST_TIME 31 10267
TEST_TIME 32 10105
TEST_TIME 33 9984
TEST_TIME 34 9864
TEST_TIME 35 9748
TEST_TIME 36 9608
TEST_TIME 37 9508
TEST_TIME 38 9377
TEST_TIME 39 9446
TEST_TIME 40 9403
TEST_TIME 41 9283
TEST_TIME 42 9242
TEST_TIME 43 9117
TEST_TIME 44 9117
TEST_TIME 45 9056
TEST_TIME 46 9040
TEST_TIME 47 9012
TEST_TIME 48 9304
TEST_TIME 49 9306
TEST_TIME 50 9521
TEST_TIME 51 9470
TEST_TIME 52 9483
TEST_TIME 53 9493
TEST_TIME 54 9448
TEST_TIME 55 9406
TEST_TIME 56 9360
TEST_TIME 57 9654
TEST_TIME 58 9651
TEST_TIME 59 9636
TEST_TIME 60 9546
TEST_TIME 61 9544
TEST_TIME 62 9494
TEST_TIME 63 9453
TEST_TIME 64 9428
TEST_TIME 65 9394
TEST_TIME 66 9337
TEST_TIME 67 9358
TEST_TIME 68 9507
TEST_TIME 69 9520
TEST_TIME 70 9575
TEST_TIME 71 9539
TEST_TIME 72 9691
TEST_TIME 73 9649
TEST_TIME 74 9624
TEST_TIME 75 9642
TEST_TIME 76 9693
TEST_TIME 77 9714
TEST_TIME 78 9722
TEST_TIME 79 9755
TEST_TIME 80 9686
TEST_TIME 81 9587
TEST_TIME 82 9612
TEST_TIME 83 9561
TEST_TIME 84 9522
TEST_TIME 85 9531
TEST_TIME 86 9479
TEST_TIME 87 9436
TEST_TIME 88 9370
TEST_TIME 89 9339
TEST_TIME 90 9316
TEST_TIME 91 9474
TEST_TIME 92 9434
TEST_TIME 93 9436
TEST_TIME 94 9451
TEST_TIME 95 9490
TEST_TIME 96 9534
TEST_TIME 97 9586
TEST_TIME 98 9527
TEST_TIME 99 9581
TEST_TIME 100 9626
TEST_TIME 101 9663
TEST_TIME 102 9658
TEST_TIME 103 9617
TEST_TIME 104 9569
TEST_TIME 105 9553
TEST_TIME 106 9524
TEST_TIME 107 9507
TEST_TIME 108 9465
TEST_TIME 109 9431
TEST_TIME 110 9415
TEST_TIME 111 9358
TEST_TIME 112 9358
TEST_TIME 113 9336
TEST_TIME 114 9312
TEST_TIME 115 9285
TEST_TIME 116 9273
TEST_TIME 117 9268
TEST_TIME 118 9280
TEST_TIME 119 9300
TEST_TIME 120 9243

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 13-07-2022 16:39:29 to 13-07-2022 16:39:33.

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 1530907 1530907 0 0.00 0 24892 6 0 0
doc_list_biud 1530907 1530907 0 0.00 0 41231 26 0 0
sp_add_invoice_to_stock 5051 4974 77 1.52 14 213820 17364 0 77
sp_cancel_adding_invoice 5731 5731 0 0.00 4 52476 791 0 0
sp_cancel_client_order 1795 1795 0 0.00 4 11248 366 0 0
sp_cancel_customer_reserve 570248 570248 0 0.00 0 41232 56 0 0
sp_cancel_pay_from_customer 667 667 0 0.00 3 7112 118 0 0
sp_cancel_pay_to_supplier 370 370 0 0.00 3 8798 105 0 0
sp_cancel_supplier_invoice 6384 6378 6 0.09 1 11451 719 0 6
sp_cancel_supplier_order 416 416 0 0.00 8 967427 96846 0 0
sp_cancel_write_off 9946 9946 0 0.00 1 11731 104 0 0
sp_client_order 20190 20190 0 0.00 3 26680 353 0 0
sp_customer_reserve 453041 453041 0 0.00 0 69785 175 0 0
sp_fill_shopping_cart 31228 31228 0 0.00 1 19477 40 0 0
sp_fill_shopping_cart_clo_res 453036 453036 0 0.00 0 11671 13 0 0
sp_get_clo_for_invoice 4969 4969 0 0.00 0 24455 281 0 0
sp_kill_cost_storno 16714 16714 0 0.00 0 11680 51 0 0
sp_kill_qstorno_ret_qs2qd 1157236 1157236 0 0.00 0 41230 30 0 0
sp_kill_qty_storno 609384 609384 0 0.00 0 41231 66 0 0
sp_lock_selected_doc 20563 20563 0 0.00 0 9265 6 0 0
sp_make_cost_storno 27769 27769 0 0.00 0 24886 68 0 0
sp_multiply_rows_for_pdistr 27769 27769 0 0.00 0 11971 29 0 0
sp_multiply_rows_for_qdistr 20190 20190 0 0.00 0 18208 161 0 0
sp_pay_from_customer 7167 7167 0 0.00 4 24900 135 0 0
sp_pay_to_supplier 5732 5732 0 0.00 5 13498 146 0 0
sp_payment_common 12899 12899 0 0.00 2 16786 44 0 0
sp_qd_handle_on_cancel_clo 1795 1795 0 0.00 0 11242 330 0 0
sp_qd_handle_on_invoice_upd_sts 10699 10699 0 0.00 0 28890 323 0 0
sp_qd_handle_on_reserve_upd_sts 9902 9902 0 0.00 0 15320 57 0 0
sp_reserve_write_off 9902 9902 0 0.00 6 35295 210 0 0
sp_supplier_invoice 5505 5505 0 0.00 5 87910 3373 0 0
sp_supplier_order 5535 5535 0 0.00 5 32677 1299 0 0
srv_aggregate_perf_data 96 96 0 0.00 2320 460089 24411 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2114 115766 43466 0 0
srv_find_qd_qs_mism 1063181 1063181 0 0.00 0 18002 5 0 0
srv_make_invnt_saldo 539 539 0 0.00 43 71903 1127 0 0
srv_make_money_saldo 708 708 0 0.00 1 17081 62 0 0
srv_recalc_idx_stat 4 4 0 0.00 96406 529224 333732 0 0
t$perf-abend:sp_add_invoice_to_stock 77 77 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 4975 4975 0 0.00 0 25346 184 0 0
t$perf-norm:sp_cancel_adding_invoice 413 413 0 0.00 0 4422 103 0 0
t$perf-norm:sp_cancel_client_order 1795 1795 0 0.00 0 8192 14 0 0
t$perf-norm:sp_cancel_customer_reserve 677 677 0 0.00 0 2759 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 667 667 0 0.00 0 3624 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 370 370 0 0.00 0 2723 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 392 392 0 0.00 0 638 13 0 0
t$perf-norm:sp_cancel_supplier_order 416 416 0 0.00 0 28826 1577 0 0
t$perf-norm:sp_cancel_write_off 963 963 0 0.00 0 4960 21 0 0
t$perf-norm:sp_client_order 20190 20190 0 0.00 0 13958 18 0 0
t$perf-norm:sp_customer_reserve 977 977 0 0.00 0 20399 24 0 0
t$perf-norm:sp_pay_from_customer 7167 7167 0 0.00 0 15331 14 0 0
t$perf-norm:sp_pay_to_supplier 5732 5732 0 0.00 0 11926 14 0 0
t$perf-norm:sp_reserve_write_off 9902 9902 0 0.00 0 24837 17 0 0
t$perf-norm:sp_supplier_invoice 5505 5505 0 0.00 0 10607 21 0 0
t$perf-norm:sp_supplier_order 5535 5535 0 0.00 0 9233 23 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 222 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 270 10 0 0
t$perf-norm:srv_make_invnt_saldo 539 539 0 0.00 0 2406 19 0 0
t$perf-norm:srv_make_money_saldo 708 708 0 0.00 0 2301 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10090 9936 154 1.52 0 6626 18 0 154
v_all_customers 36672 36672 0 0.00 0 19 0 0 0
v_all_suppliers 30448 30448 0 0.00 0 25 1 0 0
v_all_wares 40380 40380 0 0.00 0 24 1 0 0
v_cancel_adding_invoice 826 826 0 0.00 0 1596 8 0 0
v_cancel_client_order 3590 3590 0 0.00 0 2934 7 0 0
v_cancel_customer_prepayment 1334 1334 0 0.00 0 4571 14 0 0
v_cancel_customer_reserve 1354 1354 0 0.00 0 1499 5 0 0
v_cancel_payment_to_supplier 740 740 0 0.00 0 6644 21 0 0
v_cancel_supplier_invoice 796 784 12 1.50 0 2670 20 0 12
v_cancel_supplier_order 832 832 0 0.00 0 5005 16 0 0
v_cancel_write_off 1926 1926 0 0.00 0 4497 17 0 0
v_max_id_clo_ord 5535 5535 0 0.00 0 2386 2 0 0
v_max_id_clo_res 977 977 0 0.00 0 2167 12 0 0
v_max_id_ord_sup 5503 5503 0 0.00 0 6084 4 0 0
v_max_non_paid_invoice 1546 1546 0 0.00 0 12914 12 0 0
v_max_non_paid_realizn 3929 3929 0 0.00 0 7033 5 0 0
v_min_id_clo_ord 5535 5535 0 0.00 0 2482 6 0 0
v_min_id_clo_res 977 977 0 0.00 0 1045 3 0 0
v_min_id_ord_sup 5503 5503 0 0.00 0 4317 5 0 0
v_min_non_paid_invoice 5732 5732 0 0.00 0 13461 26 0 0
v_min_non_paid_realizn 7167 7167 0 0.00 0 16781 39 0 0
v_our_firm 10184 10184 0 0.00 0 14 1 0 0
v_reserve_write_off 19804 19804 0 0.00 0 6643 8 0 0
x_lock_depdocs_on_canc_invoice 11717 11717 0 0.00 0 30547 125 0 0
x_lock_depdocs_on_canc_sup_ord 416 416 0 0.00 0 11977 397 0 0
x_make_qstorno_1000_1200 5535 5535 0 0.00 1 32664 1224 0 0
x_make_qstorno_1200_2000 5505 5505 0 0.00 2 87875 3223 0 0
x_make_qstorno_2100_3300 453041 453041 0 0.00 0 69785 159 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-07-2022 16:39:34 to 13-07-2022 16:39:38.

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
14:08:28 6 8192000000 8798879696 8810233856 5307904 0.064 2 1 2 0 5407504 56816 42784
14:09:29 31646 8192000000 9910199344 10221678592 984363440 10.727 100 87 174 0 1014418928 6512944 23542544
14:11:10 18289 8192000000 9955892928 10281504768 1036241808 11.229 100 93 186 0 1068997424 8994144 23761472
14:12:34 9149 8192000000 9985449504 10324459520 1075344032 11.603 100 74 148 1 1103828304 10260624 18223648
14:13:46 8533 8192000000 10045007616 10393722880 1127447744 12.097 100 80 158 1 1157975856 11161392 19366720
14:15:00 6169 8192000000 10090002608 10449342464 1171142016 12.508 100 81 162 0 1202650080 12445552 19062512
14:16:08 3420 8192000000 10119151056 10484822016 1206551568 12.837 100 80 158 0 1237883264 13583264 17748432
14:17:15 1058 8192000000 10155045840 10529259520 1245442016 13.196 100 79 158 0 1276025744 13103136 17480592
14:18:19 1117 8192000000 10195792368 10579890176 1285274048 13.561 100 87 175 3 1317286112 12564640 19447424
14:19:20 21127 8192000000 10205343248 10597031936 1300878064 13.703 100 69 139 1 1325853408 9225456 15749888
14:20:42 5856 8192000000 10207277408 10616524800 1316705136 13.847 100 37 77 1 1332951296 7461136 8785024
14:21:49 8360 8192000000 10210745152 10627981312 1329681600 13.964 100 18 32 0 1339319520 6454640 3183280
14:22:58 24866 8192000000 10245915472 10644819968 1333669984 14.000 100 68 136 1 1355838496 6789088 15379424
14:24:23 8093 8192000000 10228652208 10645426176 1337837728 14.038 100 23 46 2 1348311728 5185792 5288208
14:25:32 3360 8192000000 10233463024 10649911296 1340517744 14.062 100 31 57 1 1353239568 5765872 6955952
14:26:38 52965 8192000000 10216174608 10638872576 1314403552 13.826 98 31 62 0 1327035904 5233088 7399264
14:28:31 3085 8192000000 10211836752 10618732544 1312226656 13.806 100 44 89 1 1331244304 8812592 10205056
14:29:34 14221 8192000000 10153886048 10554150912 1163972896 12.440 91 24 47 0 1174278800 4815552 5490352
14:30:49 6532 8192000000 10131552528 10532392960 1242761968 13.172 100 21 43 1 1253029360 5401216 4866176
14:31:55 25184 8192000000 10107428112 10503409664 1144641984 12.259 91 13 27 1 1152733040 5015760 3075296
14:33:22 5156 8192000000 10166550032 10561265664 1258019488 13.312 99 37 74 0 1272850064 6148144 8682432
14:34:28 8674 8192000000 10185562208 10590011392 1289334656 13.598 100 33 64 0 1302446864 5301952 7810256
14:35:40 20310 8192000000 10197339248 10607869952 1303480960 13.727 100 33 65 0 1318437648 5571696 9384992
14:37:02 11975 8192000000 10191136144 10606129152 1304338576 13.735 100 21 42 0 1314675536 5316400 5020560
14:38:15 8357 8192000000 10209882432 10628386816 1324567472 13.918 100 15 30 0 1333255136 5070320 3617344
14:39:33 7523 8192000000 10255472912 10666905600 1346391072 14.115 100 42 84 0 1365795328 9832304 9571952
14:40:44 5428 8192000000 10264499376 10679398400 1358280336 14.222 100 52 105 1 1381864208 11728832 11855040
14:42:05 38805 8192000000 10266720224 10693951488 1366400320 14.295 100 32 64 0 1388599424 14228496 7970608
14:43:48 23540 8192000000 10281614560 10713591808 1373458432 14.358 100 33 64 0 1398963552 17540192 7964928
14:45:18 5552 8192000000 10300602800 10718375936 1380500224 14.421 100 62 121 1 1415408416 20854720 14053472
14:46:29 4628 8192000000 10283775280 10702811136 1364536800 14.278 99 50 99 0 1394714080 18246912 11930368
14:47:38 2113 8192000000 10320465504 10736611328 1384161968 14.454 99 70 140 0 1421766384 20633056 16971360
14:48:48 3172 8192000000 10315045792 10730946560 1384709392 14.459 99 57 112 1 1421987888 23444800 13833696
14:49:55 3885 8192000000 10328456768 10745180160 1395561584 14.555 100 68 136 1 1438723072 25634240 17527248
14:51:02 5487 8192000000 10331547264 10751905792 1394726960 14.548 100 60 120 0 1442279232 32531632 15020640
14:52:14 6624 8192000000 10319331408 10728235008 1373477344 14.358 100 65 127 0 1425792976 36733504 15582128
14:53:24 7263 8192000000 10317486336 10727424000 1360942480 14.246 99 61 122 0 1418239584 41849520 15447584
14:54:39 5431 8192000000 10328894128 10743099392 1371490992 14.340 99 51 102 1 1426002064 41238272 13272800
14:55:50 8241 8192000000 10328895168 10746834944 1381171440 14.427 100 51 102 1 1434057872 39907488 12978944
14:57:02 7927 8192000000 10326224640 10747195392 1368863440 14.317 99 39 78 0 1423525680 43534768 11127472
14:58:17 14364 8192000000 10287341680 10711527424 1348957616 14.138 98 40 80 0 1393639312 33631824 11049872
14:59:33 12504 8192000000 10314184224 10748186624 1375483472 14.376 100 41 82 0 1425612704 38480640 11648592
15:01:12 33319 8192000000 10283350400 10726592512 1359600880 14.234 100 20 40 0 1396259184 31054704 5603600
15:02:51 22925 8192000000 10272634992 10703544320 1324583392 13.918 98 23 46 0 1357897952 27429760 5884800
15:04:18 16761 8192000000 10280286560 10718056448 1350071440 14.148 100 29 58 0 1381971136 24417472 7482224
15:05:36 59714 8192000000 10274066448 10704412672 1345430448 14.106 99 40 78 2 1368022928 12593040 9999440
15:07:45 16938 8192000000 10278904832 10713391104 1354251936 14.186 99 34 64 0 1371240752 9492896 7495920
15:09:02 6932 8192000000 10276040624 10702864384 1361153152 14.248 100 56 110 0 1384461744 11168880 12139712
15:10:32 72834 8192000000 10254950480 10704408576 1354259824 14.186 99 12 24 0 1363161536 6240464 2661248
15:12:45 41982 8192000000 10271828496 10725167104 1372866592 14.353 100 12 22 0 1381173136 6352192 1954352
15:14:28 77546 8192000000 10282843520 10741624832 1389488080 14.501 100 28 56 1 1403846736 8159472 6199184
15:16:57 73649 8192000000 10302103408 10739859456 1365217312 14.284 98 44 86 1 1384448816 9137248 10094256
15:19:12 24738 8192000000 10288438656 10738466816 1378204704 14.400 99 18 36 0 1388492976 6389872 3898400
15:20:51 29784 8192000000 10295604704 10747174912 1383945920 14.452 99 23 46 0 1403748576 13641104 6161552
15:22:30 34694 8192000000 10299279648 10754994176 1391424736 14.519 99 26 52 1 1416254928 18626336 6203856
15:24:29 50422 8192000000 10309962336 10761289728 1382341360 14.437 99 23 46 0 1407523600 19030464 6151776
15:26:20 41025 8192000000 10305456432 10772393984 1401469008 14.608 100 12 24 0 1431882048 27369728 3043312
15:28:03 65781 8192000000 10269229760 10730708992 1325199840 13.924 95 10 21 0 1352137792 24581920 2356032
15:30:09 15586 8192000000 10292832352 10726215680 1361318816 14.249 99 47 94 2 1382062752 9567248 11176688
15:31:26 51195 8192000000 10279648720 10731573248 1371843440 14.344 100 26 52 0 1390298224 12689824 5764960
15:33:20 29471 8192000000 10322524656 10763108352 1391452384 14.519 100 39 78 0 1424897712 24832464 8612864
15:35:55 12603 8192000000 10312671264 10748403712 1390912160 14.514 100 50 100 1 1409316608 5766096 12638352
15:37:13 100946 8192000000 10275403808 10726227968 1359621360 14.234 99 20 40 0 1378853920 14512176 4720384
15:40:53 18708 8192000000 10282570944 10716856320 1345371360 14.106 97 33 62 0 1361707184 9858512 6477312
15:42:14 83287 8192000000 10258218928 10714890240 1340403520 14.061 97 15 30 0 1361221024 17240624 3576880
15:44:38 76610 8192000000 10300736432 10757853184 1385189936 14.463 100 18 36 0 1411474288 21714448 4569904
15:46:57 77586 8192000000 10283360960 10739830784 1341829952 14.074 97 14 28 0 1364966192 19622240 3514000
15:49:15 94021 8192000000 10256022960 10716033024 1293880480 13.640 95 12 24 0 1311980560 15695440 2404640
15:51:54 32203 8192000000 10275406320 10702532608 1328382976 13.953 98 20 39 0 1341515872 8494064 4638832
15:53:40 57646 8192000000 10276333744 10725003264 1321843552 13.893 96 26 52 0 1340068336 12500064 5724720
15:55:44 112900 8192000000 10276818752 10718924800 1266131312 13.386 92 22 44 0 1282731808 11637872 4962624
15:58:48 115766 8192000000 10269680432 10705809408 1319092752 13.868 96 26 52 0 1332577472 7642976 5841744
16:02:04 46646 8192000000 10274058688 10721079296 1357549408 14.215 99 26 49 2 1373067376 9065904 6452064
16:03:58 60084 8192000000 10281167968 10734665728 1370831920 14.334 99 21 42 0 1387785712 11818272 5135520
16:05:58 71678 8192000000 10297882816 10751414272 1387428976 14.483 100 20 40 1 1410810528 18404384 4977168
16:08:11 70588 8192000000 10311280432 10773901312 1392430688 14.528 100 21 42 1 1419568688 22364688 4773312
16:11:20 41274 8192000000 10299974416 10755788800 1383406672 14.447 99 17 34 0 1398173600 11092784 3674144
16:13:07 56298 8192000000 10289113984 10746494976 1352471808 14.170 97 15 30 0 1368135664 12162768 3501088
16:15:03 72551 8192000000 10312279008 10768683008 1365584928 14.287 98 9 18 0 1389147584 21686848 1875808
16:17:19 38707 8192000000 10300867472 10753187840 1369301216 14.321 99 46 92 0 1397839440 18037440 10500784
16:19:25 94892 8192000000 10273969680 10723680256 1311635104 13.801 93 24 47 0 1327210128 10635504 4939520
16:22:01 105693 8192000000 10290756400 10742923264 1360777984 14.244 98 34 68 1 1381458192 12933472 7746736
16:24:50 54737 8192000000 10279840704 10756132864 1374601920 14.368 99 20 38 1 1392239072 13705312 3931840
16:26:45 92996 8192000000 10280009168 10745163776 1375601552 14.377 99 10 20 0 1384505296 6906160 1997584
16:29:21 54656 8192000000 10293635680 10758422528 1394485952 14.546 100 18 36 0 1413599776 14940960 4172864
16:31:23 37827 8192000000 10282250704 10743783424 1369673488 14.324 98 14 28 0 1378711456 5915248 3122720
16:34:13 75110 8192000000 10275846960 10735947776 1332425152 13.989 95 16 32 0 1345665088 9545008 3694928
16:36:29 54366 8192000000 10290552256 10752327680 1375043824 14.372 99 18 36 0 1390167856 11256784 3867248

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 13-07-2022 16:39:40 to 13-07-2022 16:39:54.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 77 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 13-07-2022 16:39:55 to 13-07-2022 16:39:57.

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          238938
MON$OLDEST_ACTIVE               241048
MON$OLDEST_SNAPSHOT             241048
MON$NEXT_TRANSACTION            241048
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                       2025056
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.33608 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33608 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33608 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33608 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 ---
--- end of diff output ---

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

13.07.22 16:39:58. 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-13 16:40:01 No severe PSQL-related problems occured
13.07.22 16:40:02. End of report.