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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-06-21 01:38:22
DTS_END                         2022-06-21 01:38:22.8530
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/37282
TRN_ID                          234534
ATT_ID                          649
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: 9213.98 23049 1105677
customer order: creation 163.56 407 19627
customer order: refuse 14.04 377 1685
order to supplier: creation 44.53 1338 5344
order to supplier: removal 3.54 111141 425
invoice (draft): creation 44.29 3966 5315
invoice (draft): removal 53.35 842 6402
invoice accept: apply 40.51 18446 4861
invoice accept: cancel 48.12 818 5774
customer reserve: creation 3758.41 181 451009
customer reserve: removal 4762.24 65 571469
realization accept: apply 79.72 212 9567
realization accept: cancel 78.28 114 9393
payment from customer: creation 55.83 116 6700
payment from customer: removal 5.55 89 666
payment to supplier: creation 47.38 140 5686
payment to supplier: removal 3.35 75 402
service: total inventory turnovers 4.06 1563 487
service: total monetary turnovers 6.26 39 751
service: aggregate perf. data 0.92 19353 110
service: refresh index statistics 0.03 301691 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-06-2022 01:39:10 to 21-06-2022 01:39:12.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3468
TEST_TIME 2 3766
TEST_TIME 3 4672
TEST_TIME 4 4624
TEST_TIME 5 4603
TEST_TIME 6 4540
TEST_TIME 7 4827
TEST_TIME 8 5231
TEST_TIME 9 5299
TEST_TIME 10 5561
TEST_TIME 11 5617
TEST_TIME 12 5991
TEST_TIME 13 6411
TEST_TIME 14 6557
TEST_TIME 15 6601
TEST_TIME 16 6793
TEST_TIME 17 6849
TEST_TIME 18 7098
TEST_TIME 19 7289
TEST_TIME 20 7264
TEST_TIME 21 8167
TEST_TIME 22 8546
TEST_TIME 23 8604
TEST_TIME 24 8557
TEST_TIME 25 8977
TEST_TIME 26 9091
TEST_TIME 27 9341
TEST_TIME 28 9593
TEST_TIME 29 9947
TEST_TIME 30 10416
TEST_TIME 31 10619
TEST_TIME 32 10471
TEST_TIME 33 10272
TEST_TIME 34 10333
TEST_TIME 35 10157
TEST_TIME 36 10060
TEST_TIME 37 9972
TEST_TIME 38 10072
TEST_TIME 39 9938
TEST_TIME 40 9803
TEST_TIME 41 9760
TEST_TIME 42 9674
TEST_TIME 43 9578
TEST_TIME 44 9513
TEST_TIME 45 9565
TEST_TIME 46 9434
TEST_TIME 47 9486
TEST_TIME 48 9377
TEST_TIME 49 9314
TEST_TIME 50 9249
TEST_TIME 51 9177
TEST_TIME 52 9221
TEST_TIME 53 9115
TEST_TIME 54 9052
TEST_TIME 55 9021
TEST_TIME 56 9202
TEST_TIME 57 9189
TEST_TIME 58 9092
TEST_TIME 59 9211
TEST_TIME 60 9268
TEST_TIME 61 9262
TEST_TIME 62 9336
TEST_TIME 63 9286
TEST_TIME 64 9420
TEST_TIME 65 9432
TEST_TIME 66 9479
TEST_TIME 67 9388
TEST_TIME 68 9491
TEST_TIME 69 9491
TEST_TIME 70 9539
TEST_TIME 71 9500
TEST_TIME 72 9434
TEST_TIME 73 9398
TEST_TIME 74 9428
TEST_TIME 75 9380
TEST_TIME 76 9360
TEST_TIME 77 9290
TEST_TIME 78 9306
TEST_TIME 79 9307
TEST_TIME 80 9310
TEST_TIME 81 9225
TEST_TIME 82 9177
TEST_TIME 83 9185
TEST_TIME 84 9215
TEST_TIME 85 9212
TEST_TIME 86 9187
TEST_TIME 87 9232
TEST_TIME 88 9232
TEST_TIME 89 9214
TEST_TIME 90 9237
TEST_TIME 91 9320
TEST_TIME 92 9317
TEST_TIME 93 9318
TEST_TIME 94 9336
TEST_TIME 95 9351
TEST_TIME 96 9345
TEST_TIME 97 9375
TEST_TIME 98 9441
TEST_TIME 99 9353
TEST_TIME 100 9313
TEST_TIME 101 9275
TEST_TIME 102 9232
TEST_TIME 103 9220
TEST_TIME 104 9215
TEST_TIME 105 9203
TEST_TIME 106 9171
TEST_TIME 107 9124
TEST_TIME 108 9135
TEST_TIME 109 9077
TEST_TIME 110 9059
TEST_TIME 111 9121
TEST_TIME 112 9139
TEST_TIME 113 9165
TEST_TIME 114 9135
TEST_TIME 115 9141
TEST_TIME 116 9152
TEST_TIME 117 9174
TEST_TIME 118 9179
TEST_TIME 119 9244
TEST_TIME 120 9215

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 21-06-2022 01:39:13 to 21-06-2022 01:39:17.

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 1524070 1524070 0 0.00 0 35606 6 0 0
doc_list_biud 1524062 1524062 0 0.00 0 36355 31 0 0
sp_add_invoice_to_stock 4977 4861 116 2.33 18 166856 18447 0 116
sp_cancel_adding_invoice 5774 5774 0 0.00 4 62177 819 0 0
sp_cancel_client_order 1685 1685 0 0.00 4 12412 377 0 0
sp_cancel_customer_reserve 571469 571469 0 0.00 1 35161 66 0 0
sp_cancel_pay_from_customer 666 666 0 0.00 2 9268 90 0 0
sp_cancel_pay_to_supplier 402 402 0 0.00 3 2077 75 0 0
sp_cancel_supplier_invoice 6411 6402 9 0.14 1 16492 842 0 9
sp_cancel_supplier_order 425 425 0 0.00 5 968461 111142 0 0
sp_cancel_write_off 9393 9393 0 0.00 1 10525 115 0 0
sp_client_order 19627 19627 0 0.00 4 40387 407 0 0
sp_customer_reserve 451009 451009 0 0.00 0 37414 181 0 0
sp_fill_shopping_cart 30282 30282 0 0.00 1 36637 47 0 0
sp_fill_shopping_cart_clo_res 451004 451004 0 0.00 0 23523 14 0 0
sp_get_clo_for_invoice 4856 4856 0 0.00 0 42103 374 0 0
sp_kill_cost_storno 16235 16235 0 0.00 0 10523 62 0 0
sp_kill_qstorno_ret_qs2qd 1159158 1159158 0 0.00 0 35155 35 0 0
sp_kill_qty_storno 609566 609566 0 0.00 0 36354 76 0 0
sp_lock_selected_doc 20025 20025 0 0.00 0 9052 5 0 0
sp_make_cost_storno 26809 26809 0 0.00 0 35602 67 0 0
sp_multiply_rows_for_pdistr 26804 26804 0 0.00 0 8633 26 0 0
sp_multiply_rows_for_qdistr 19626 19626 0 0.00 0 40375 171 0 0
sp_pay_from_customer 6700 6700 0 0.00 4 17820 117 0 0
sp_pay_to_supplier 5686 5686 0 0.00 4 33867 140 0 0
sp_payment_common 12386 12386 0 0.00 1 14767 28 0 0
sp_qd_handle_on_cancel_clo 1685 1685 0 0.00 0 12405 349 0 0
sp_qd_handle_on_invoice_upd_sts 10627 10627 0 0.00 0 36353 342 0 0
sp_qd_handle_on_reserve_upd_sts 9565 9565 0 0.00 0 15978 77 0 0
sp_reserve_write_off 9567 9567 0 0.00 6 33750 212 0 0
sp_supplier_invoice 5315 5315 0 0.00 7 113503 3966 0 0
sp_supplier_order 5344 5344 0 0.00 5 30578 1338 0 0
srv_aggregate_perf_data 110 110 0 0.00 2826 101607 19354 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1221 130806 47747 0 0
srv_find_qd_qs_mism 1060092 1060092 0 0.00 0 8827 5 0 0
srv_make_invnt_saldo 487 487 0 0.00 51 91774 1563 0 0
srv_make_money_saldo 751 751 0 0.00 0 7241 39 0 0
srv_recalc_idx_stat 4 4 0 0.00 172258 394426 301692 0 0
t$perf-abend:sp_add_invoice_to_stock 116 116 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4861 4861 0 0.00 0 8015 159 0 0
t$perf-norm:sp_cancel_adding_invoice 397 397 0 0.00 1 7889 145 0 0
t$perf-norm:sp_cancel_client_order 1685 1685 0 0.00 0 2734 14 0 0
t$perf-norm:sp_cancel_customer_reserve 649 649 0 0.00 0 1133 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 666 666 0 0.00 0 6398 19 0 0
t$perf-norm:sp_cancel_pay_to_supplier 402 402 0 0.00 0 3280 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 384 384 0 0.00 0 222 6 0 0
t$perf-norm:sp_cancel_supplier_order 425 425 0 0.00 0 28368 1412 0 0
t$perf-norm:sp_cancel_write_off 999 999 0 0.00 0 3367 10 0 0
t$perf-norm:sp_client_order 19627 19627 0 0.00 0 6448 18 0 0
t$perf-norm:sp_customer_reserve 960 960 0 0.00 0 1091 5 0 0
t$perf-norm:sp_pay_from_customer 6700 6700 0 0.00 0 6433 12 0 0
t$perf-norm:sp_pay_to_supplier 5686 5686 0 0.00 0 6442 14 0 0
t$perf-norm:sp_reserve_write_off 9567 9567 0 0.00 0 6609 13 0 0
t$perf-norm:sp_supplier_invoice 5315 5315 0 0.00 0 7807 23 0 0
t$perf-norm:sp_supplier_order 5344 5344 0 0.00 0 3066 16 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 946 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 17 1 0 0
t$perf-norm:srv_make_invnt_saldo 487 487 0 0.00 0 2730 25 0 0
t$perf-norm:srv_make_money_saldo 751 751 0 0.00 0 1820 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 9938 9706 232 2.33 0 10722 19 0 232
v_all_customers 35298 35298 0 0.00 0 14 1 0 0
v_all_suppliers 29440 29440 0 0.00 0 20 1 0 0
v_all_wares 39248 39248 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 794 794 0 0.00 0 298 5 0 0
v_cancel_client_order 3370 3370 0 0.00 0 2295 8 0 0
v_cancel_customer_prepayment 1332 1332 0 0.00 0 4854 14 0 0
v_cancel_customer_reserve 1298 1298 0 0.00 0 2504 7 0 0
v_cancel_payment_to_supplier 804 804 0 0.00 0 1950 7 0 0
v_cancel_supplier_invoice 786 768 18 2.29 0 415 11 0 18
v_cancel_supplier_order 850 850 0 0.00 0 1192 9 0 0
v_cancel_write_off 1998 1998 0 0.00 0 1880 13 0 0
v_max_id_clo_ord 5343 5343 0 0.00 0 4323 3 0 0
v_max_id_clo_res 960 960 0 0.00 0 32335 51 0 0
v_max_id_ord_sup 5315 5315 0 0.00 0 9377 6 0 0
v_max_non_paid_invoice 1624 1624 0 0.00 0 3217 5 0 0
v_max_non_paid_realizn 3662 3662 0 0.00 0 5701 8 0 0
v_min_id_clo_ord 5343 5343 0 0.00 0 5198 12 0 0
v_min_id_clo_res 960 960 0 0.00 0 7048 13 0 0
v_min_id_ord_sup 5315 5315 0 0.00 0 13981 12 0 0
v_min_non_paid_invoice 5686 5686 0 0.00 0 7977 20 0 0
v_min_non_paid_realizn 6700 6700 0 0.00 0 5529 17 0 0
v_our_firm 10026 10026 0 0.00 0 19 1 0 0
v_reserve_write_off 19130 19130 0 0.00 0 12452 8 0 0
x_lock_depdocs_on_canc_invoice 11792 11792 0 0.00 0 20013 114 0 0
x_lock_depdocs_on_canc_sup_ord 425 425 0 0.00 0 11437 427 0 0
x_make_qstorno_1000_1200 5344 5344 0 0.00 1 30537 1243 0 0
x_make_qstorno_1200_2000 5315 5315 0 0.00 2 113371 3796 0 0
x_make_qstorno_2100_3300 451008 451008 0 0.00 0 37412 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-06-2022 01:39:18 to 21-06-2022 01:39:22.

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
23:08:26 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:27 30595 8192000000 9899938992 10206986240 976533248 10.650 100 95 190 0 1008164352 6310912 25320192
23:11:02 24918 8192000000 9948375792 10267238400 1025149968 11.122 100 93 186 1 1057941344 8842192 23949184
23:12:32 13966 8192000000 9971461840 10302881792 1053938448 11.398 100 77 154 0 1083223936 9910112 19375376
23:13:54 16724 8192000000 10026902096 10376310784 1115410624 11.984 100 80 160 0 1145379424 10649360 19319440
23:15:20 10382 8192000000 10095958192 10458726400 1186980192 12.655 100 84 168 0 1218903456 12855344 19067920
23:16:31 4549 8192000000 10138756304 10506432512 1222824992 12.988 100 84 166 2 1255342688 13307312 19210384
23:17:39 3603 8192000000 10169047648 10545491968 1255798144 13.291 100 84 166 1 1286863824 12966880 18098800
23:18:44 1555 8192000000 10205686640 10584367104 1286515520 13.572 100 96 190 0 1322630336 14635728 21479088
23:19:47 15253 8192000000 10211717952 10600968192 1304346992 13.735 100 76 151 1 1332305216 10071664 17886560
23:21:04 14732 8192000000 10218620144 10615160832 1312887392 13.812 100 53 106 4 1331763216 5835408 13040416
23:22:20 7942 8192000000 10206464352 10625609728 1324121296 13.914 100 19 36 1 1334229648 6364368 3743984
23:23:33 10912 8192000000 10222549600 10641649664 1335302976 14.015 100 17 34 2 1344760864 5971264 3486624
23:24:51 9215 8192000000 10229351408 10650152960 1340436560 14.061 100 23 46 0 1355522768 9905168 5181040
23:26:04 53415 8192000000 10261961728 10669228032 1345891520 14.110 100 56 111 0 1365670464 6121616 13657328
23:27:58 4426 8192000000 10240337920 10655993856 1345385296 14.106 100 40 80 0 1360947344 6732416 8829632
23:29:03 8141 8192000000 10177585936 10592120832 1258157168 13.313 96 14 28 1 1267085968 5836928 3091872
23:30:14 9489 8192000000 10169248736 10581078016 1267873344 13.402 98 27 53 4 1280227920 5846064 6508512
23:31:26 7523 8192000000 10168229728 10572513280 1261291088 13.342 99 30 60 0 1275462448 7195520 6975840
23:32:36 8432 8192000000 10142433568 10545242112 1242947008 13.173 99 22 44 1 1253531568 5280528 5304032
23:33:45 63119 8192000000 10152308128 10556624896 1260687888 13.336 100 35 70 2 1274475056 6073264 7713904
23:35:51 5628 8192000000 10180970960 10581147648 1280413712 13.517 100 20 40 1 1289918656 5014576 4490368
23:36:57 10021 8192000000 10193421328 10606555136 1299111376 13.687 100 22 43 1 1309947904 6024592 4811936
23:38:11 23111 8192000000 10236177456 10640609280 1328604656 13.955 100 36 69 1 1342604016 4872704 9126656
23:40:13 37808 8192000000 10251510016 10667724800 1347030496 14.121 100 51 100 3 1371500128 12465312 12004320
23:41:57 52839 8192000000 10272909776 10695827456 1365898192 14.290 100 36 72 1 1388923600 14833600 8191808
23:43:56 17541 8192000000 10290928224 10711920640 1382927200 14.443 100 52 104 1 1409414720 14280800 12206720
23:45:19 6695 8192000000 10304712048 10729709568 1388910720 14.496 100 53 105 1 1418995440 17271632 12813088
23:46:33 4983 8192000000 10320707936 10745536512 1398696896 14.583 100 57 114 0 1432165264 20634944 12833424
23:47:43 14644 8192000000 10325238992 10754793472 1406489600 14.653 100 49 96 0 1438078816 20413472 11175744
23:49:10 7813 8192000000 10350423472 10770132992 1415720352 14.735 100 76 149 4 1459192960 25770032 17702576
23:50:28 8610 8192000000 10349934208 10765848576 1406533568 14.653 100 67 132 1 1457130496 34114032 16482896
23:51:41 9149 8192000000 10342424976 10769608704 1414721440 14.726 100 50 98 3 1454239360 27091904 12426016
23:53:02 6219 8192000000 10341552064 10765066240 1407933984 14.666 100 56 112 1 1448598560 26618816 14045760
23:54:14 4693 8192000000 10331499904 10748694528 1395773792 14.557 99 62 123 0 1440728864 29227584 15727488
23:55:25 5200 8192000000 10335820304 10750500864 1394445376 14.546 100 68 136 2 1446048192 33816320 17786496
23:56:37 7531 8192000000 10337746800 10756976640 1382365328 14.438 98 62 123 0 1434809680 36472176 15972176
23:57:53 2778 8192000000 10355454032 10767351808 1401156928 14.605 100 68 136 2 1462113264 43251424 17704912
23:59:02 1829 8192000000 10359801616 10759958528 1386978672 14.479 100 92 185 2 1462621408 51473568 24169168
00:00:07 6326 8192000000 10327675904 10750058496 1380129536 14.418 100 41 81 2 1436082864 44387072 11566256
00:01:17 12603 8192000000 10351693312 10784251904 1403868240 14.629 100 38 76 0 1458906928 44731696 10306992
00:02:34 1217 8192000000 10366318576 10784501760 1397601648 14.574 100 68 137 1 1470436832 54362704 18472480
00:03:48 19493 8192000000 10338048752 10784444416 1394751152 14.548 100 25 48 3 1454085664 52066784 7267728
00:05:09 22653 8192000000 10347882496 10789474304 1379445776 14.412 99 32 64 2 1446423040 58321888 8655376
00:06:37 29304 8192000000 10334770784 10769567744 1366964320 14.300 99 24 48 1 1429708288 56131296 6612672
00:08:07 42894 8192000000 10314097680 10759761920 1329081872 13.959 97 35 69 0 1382958976 44438832 9438272
00:09:53 23177 8192000000 10274015248 10704347136 1359774880 14.235 100 40 79 1 1375970720 6501728 9694112
00:11:17 49042 8192000000 10275013744 10714546176 1368116720 14.310 100 21 42 0 1384146960 11104320 4925920
00:13:12 75326 8192000000 10277143984 10719223808 1352107520 14.166 98 21 42 0 1369136320 12032592 4996208
00:15:27 62627 8192000000 10285656256 10726223872 1366191536 14.293 100 23 45 2 1385628448 14564736 4872176
00:17:38 92906 8192000000 10288474896 10734657536 1374067184 14.363 99 27 53 2 1392114784 12806144 5241456
00:20:15 61405 8192000000 10289554944 10740502528 1374521152 14.368 99 27 54 2 1395428416 14622352 6284912
00:22:18 21809 8192000000 10295914912 10738024448 1386798096 14.477 100 39 75 2 1401834912 6565376 8471440
00:23:41 58394 8192000000 10300392000 10735984640 1375959408 14.380 99 32 64 1 1391170224 8115280 7095536
00:25:48 74897 8192000000 10295636352 10751197184 1398178800 14.579 100 21 42 0 1412847296 9422704 5245792
00:28:05 31194 8192000000 10301969472 10756833280 1404094000 14.631 100 24 48 0 1420392544 10939504 5359040
00:29:40 24430 8192000000 10322326736 10773946368 1414843824 14.727 100 42 84 4 1440712704 15908624 9960256
00:31:13 50701 8192000000 10322605024 10787061760 1415500960 14.733 100 13 26 0 1435380928 16592256 3287712
00:33:04 73198 8192000000 10298816704 10771705856 1371719360 14.342 97 11 22 0 1390438448 16130864 2588224
00:35:27 45518 8192000000 10287904192 10740879360 1360989296 14.246 98 27 54 0 1376613584 9365264 6259024
00:37:13 69545 8192000000 10291402592 10754883584 1370891632 14.335 98 11 22 1 1387278608 13912176 2474800
00:39:31 130806 8192000000 10305045760 10757832704 1361556240 14.251 97 14 28 1 1374218576 10380080 2282256
00:42:43 127747 8192000000 10273120400 10732425216 1267467264 13.398 91 8 16 0 1276955760 7940432 1548064
00:45:52 52653 8192000000 10287098336 10731565056 1348430848 14.133 98 20 40 0 1375100032 22197040 4472144
00:47:47 66590 8192000000 10277246224 10721095680 1348739872 14.136 98 22 44 0 1363490448 9713664 5036912
00:49:58 115256 8192000000 10269196288 10711764992 1320402720 13.880 96 29 58 0 1335963456 9189360 6371376
00:53:24 20391 8192000000 10293108944 10746417152 1379662240 14.414 100 25 50 0 1399059536 13407696 5989600
00:54:44 45577 8192000000 10284410064 10736955392 1356659728 14.207 98 7 14 0 1371262096 13258544 1343824
00:57:26 72454 8192000000 10262043312 10715320320 1310897136 13.794 95 9 18 0 1330765840 17511904 2356800
00:59:56 68950 8192000000 10254289696 10708217856 1341422976 14.070 99 19 38 0 1356140032 10219024 4498032
01:02:15 82037 8192000000 10238103008 10693816320 1306807760 13.757 96 8 16 0 1319034512 10310000 1916752
01:04:38 11428 8192000000 10275917360 10718769152 1362028752 14.256 100 55 109 1 1387857392 14593632 11235008
01:05:50 43081 8192000000 10278086400 10712739840 1347545056 14.125 99 20 40 0 1361207136 8677664 4984416
01:08:20 86103 8192000000 10283695008 10740211712 1356139184 14.203 98 26 52 1 1374100688 12378816 5582688
01:10:47 73863 8192000000 10294922624 10747318272 1375144912 14.373 99 14 28 0 1387078800 9230336 2703552
01:13:01 96817 8192000000 10310312352 10761338880 1378938496 14.407 99 13 25 1 1395165392 13255712 2971184
01:15:39 123696 8192000000 10304176272 10771603456 1370203824 14.329 98 12 24 0 1392249664 19699872 2345968
01:18:47 97532 8192000000 10296551216 10744909824 1332613840 13.991 95 18 36 0 1345092096 8520656 3957600
01:21:27 67947 8192000000 10291640464 10758578176 1399202144 14.588 100 11 22 0 1410638704 9042944 2393616
01:24:20 91453 8192000000 10324673744 10779308032 1398168416 14.579 99 14 28 0 1412497520 10710000 3619104
01:27:10 66480 8192000000 10313535936 10776666112 1400734256 14.602 99 27 54 1 1425334176 18166192 6433728
01:29:17 68843 8192000000 10294484512 10761814016 1351079568 14.157 96 20 40 1 1367907920 11883200 4945152
01:31:26 69475 8192000000 10277442816 10728767488 1296398176 13.662 93 13 27 0 1308604928 8976768 3229984
01:33:36 90453 8192000000 10281760144 10724298752 1311316080 13.798 95 19 38 1 1327864800 12018160 4530560
01:36:06 16260 8192000000 10302644048 10744496128 1375426960 14.376 99 43 87 1 1397998176 12693424 9877792
01:37:23 61980 8192000000 10105038704 10537971712 1195255056 12.732 86 13 26 0 1212104352 13773472 3075824

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 21-06-2022 01:39:24 to 21-06-2022 01:39:38.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 116 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-06-2022 01:39:39 to 21-06-2022 01:39:41.

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          235410
MON$OLDEST_ACTIVE               235411
MON$OLDEST_SNAPSHOT             235411
MON$NEXT_TRANSACTION            235411
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                       2022592
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33603 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33603 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33603 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33603 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 ---
:217: 
:218: mscs-test-161.budzdorov.ru    Tue Jun 21 01:38:36 2022
:219:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:220: 
--- end of diff output ---

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

21.06.22 01:39:42. 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-06-21 01:39:45 No severe PSQL-related problems occured
21.06.22 01:39:46. End of report.