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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-12-19 16:38:01
DTS_END                         2021-12-19 16:38:01.5410
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/41996
TRN_ID                          235905
ATT_ID                          673
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 9149.23 22593 1097908
customer order: creation 170.18 351 20422
customer order: refuse 14.80 366 1776
order to supplier: creation 45.34 1303 5441
order to supplier: removal 3.36 113293 403
invoice (draft): creation 45.82 3232 5498
invoice (draft): removal 51.42 914 6170
invoice accept: apply 41.61 19680 4993
invoice accept: cancel 46.33 817 5559
customer reserve: creation 3863.38 194 463606
customer reserve: removal 4578.36 64 549403
realization accept: apply 84.72 168 10166
realization accept: cancel 76.79 109 9215
payment from customer: creation 58.12 99 6974
payment from customer: removal 5.74 78 689
payment to supplier: creation 47.65 135 5718
payment to supplier: removal 3.18 70 382
service: total inventory turnovers 4.93 1212 592
service: total monetary turnovers 6.63 33 796
service: aggregate perf. data 0.84 20343 101
service: refresh index statistics 0.03 289402 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 19-12-2021 16:40:08 to 19-12-2021 16:40:10.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5306
TEST_TIME 2 4452
TEST_TIME 3 5016
TEST_TIME 4 4837
TEST_TIME 5 4789
TEST_TIME 6 5028
TEST_TIME 7 5518
TEST_TIME 8 5631
TEST_TIME 9 6282
TEST_TIME 10 6871
TEST_TIME 11 6909
TEST_TIME 12 7029
TEST_TIME 13 7098
TEST_TIME 14 7509
TEST_TIME 15 7488
TEST_TIME 16 7438
TEST_TIME 17 7988
TEST_TIME 18 8051
TEST_TIME 19 8546
TEST_TIME 20 8553
TEST_TIME 21 8575
TEST_TIME 22 8493
TEST_TIME 23 9059
TEST_TIME 24 9480
TEST_TIME 25 9885
TEST_TIME 26 10507
TEST_TIME 27 11041
TEST_TIME 28 11337
TEST_TIME 29 11334
TEST_TIME 30 11159
TEST_TIME 31 11080
TEST_TIME 32 10958
TEST_TIME 33 10800
TEST_TIME 34 10697
TEST_TIME 35 10652
TEST_TIME 36 10489
TEST_TIME 37 10336
TEST_TIME 38 10139
TEST_TIME 39 10188
TEST_TIME 40 10051
TEST_TIME 41 9889
TEST_TIME 42 9775
TEST_TIME 43 9792
TEST_TIME 44 9672
TEST_TIME 45 9636
TEST_TIME 46 9609
TEST_TIME 47 9608
TEST_TIME 48 9457
TEST_TIME 49 9421
TEST_TIME 50 9463
TEST_TIME 51 9485
TEST_TIME 52 9371
TEST_TIME 53 9459
TEST_TIME 54 9413
TEST_TIME 55 9370
TEST_TIME 56 9361
TEST_TIME 57 9305
TEST_TIME 58 9399
TEST_TIME 59 9493
TEST_TIME 60 9480
TEST_TIME 61 9479
TEST_TIME 62 9498
TEST_TIME 63 9478
TEST_TIME 64 9541
TEST_TIME 65 9518
TEST_TIME 66 9607
TEST_TIME 67 9584
TEST_TIME 68 9536
TEST_TIME 69 9478
TEST_TIME 70 9460
TEST_TIME 71 9417
TEST_TIME 72 9390
TEST_TIME 73 9345
TEST_TIME 74 9303
TEST_TIME 75 9286
TEST_TIME 76 9226
TEST_TIME 77 9189
TEST_TIME 78 9236
TEST_TIME 79 9308
TEST_TIME 80 9287
TEST_TIME 81 9270
TEST_TIME 82 9344
TEST_TIME 83 9377
TEST_TIME 84 9377
TEST_TIME 85 9424
TEST_TIME 86 9379
TEST_TIME 87 9520
TEST_TIME 88 9469
TEST_TIME 89 9465
TEST_TIME 90 9557
TEST_TIME 91 9479
TEST_TIME 92 9434
TEST_TIME 93 9365
TEST_TIME 94 9390
TEST_TIME 95 9331
TEST_TIME 96 9284
TEST_TIME 97 9319
TEST_TIME 98 9260
TEST_TIME 99 9193
TEST_TIME 100 9203
TEST_TIME 101 9223
TEST_TIME 102 9259
TEST_TIME 103 9248
TEST_TIME 104 9249
TEST_TIME 105 9238
TEST_TIME 106 9310
TEST_TIME 107 9266
TEST_TIME 108 9333
TEST_TIME 109 9316
TEST_TIME 110 9353
TEST_TIME 111 9351
TEST_TIME 112 9398
TEST_TIME 113 9398
TEST_TIME 114 9325
TEST_TIME 115 9290
TEST_TIME 116 9254
TEST_TIME 117 9272
TEST_TIME 118 9255
TEST_TIME 119 9233
TEST_TIME 120 9159

Performance per minute, chart


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

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 1526779 1526779 0 0.00 0 20863 6 0 0
doc_list_biud 1526779 1526779 0 0.00 0 37807 29 0 0
sp_add_invoice_to_stock 5119 4993 126 2.46 25 202464 19681 0 126
sp_cancel_adding_invoice 5559 5559 0 0.00 3 62068 817 0 0
sp_cancel_client_order 1776 1776 0 0.00 4 10275 367 0 0
sp_cancel_customer_reserve 549403 549403 0 0.00 1 37808 65 0 0
sp_cancel_pay_from_customer 689 689 0 0.00 3 4120 79 0 0
sp_cancel_pay_to_supplier 382 382 0 0.00 3 2663 70 0 0
sp_cancel_supplier_invoice 6178 6170 8 0.12 1 23086 915 0 8
sp_cancel_supplier_order 403 403 0 0.00 8 1067981 113293 0 0
sp_cancel_write_off 9215 9215 0 0.00 1 12522 109 0 0
sp_client_order 20422 20422 0 0.00 3 25439 352 0 0
sp_customer_reserve 463606 463606 0 0.00 0 99844 195 0 0
sp_fill_shopping_cart 31354 31354 0 0.00 1 23349 42 0 0
sp_fill_shopping_cart_clo_res 463592 463592 0 0.00 0 32399 15 0 0
sp_get_clo_for_invoice 4979 4979 0 0.00 0 27434 305 0 0
sp_kill_cost_storno 15845 15845 0 0.00 0 12518 64 0 0
sp_kill_qstorno_ret_qs2qd 1114594 1114594 0 0.00 0 37804 35 0 0
sp_kill_qty_storno 587671 587671 0 0.00 0 37806 76 0 0
sp_lock_selected_doc 20828 20828 0 0.00 0 8937 5 0 0
sp_make_cost_storno 27837 27837 0 0.00 0 19276 51 0 0
sp_multiply_rows_for_pdistr 27837 27837 0 0.00 0 20844 29 0 0
sp_multiply_rows_for_qdistr 20422 20422 0 0.00 0 25429 172 0 0
sp_pay_from_customer 6974 6974 0 0.00 4 10099 100 0 0
sp_pay_to_supplier 5718 5718 0 0.00 5 20870 136 0 0
sp_payment_common 12692 12692 0 0.00 2 8012 20 0 0
sp_qd_handle_on_cancel_clo 1776 1776 0 0.00 0 10268 327 0 0
sp_qd_handle_on_invoice_upd_sts 10538 10538 0 0.00 0 30054 341 0 0
sp_qd_handle_on_reserve_upd_sts 10166 10166 0 0.00 0 22246 55 0 0
sp_reserve_write_off 10166 10166 0 0.00 5 22281 169 0 0
sp_supplier_invoice 5498 5498 0 0.00 5 85794 3232 0 0
sp_supplier_order 5441 5441 0 0.00 5 41724 1303 0 0
srv_aggregate_perf_data 101 101 0 0.00 2501 204566 20343 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1276 180796 47147 0 0
srv_find_qd_qs_mism 1050352 1050352 0 0.00 0 15544 6 0 0
srv_make_invnt_saldo 592 592 0 0.00 32 33681 1212 0 0
srv_make_money_saldo 796 796 0 0.00 1 3701 34 0 0
srv_recalc_idx_stat 4 4 0 0.00 158507 586667 289403 0 0
t$perf-abend:sp_add_invoice_to_stock 126 126 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4993 4993 0 0.00 0 16489 198 0 0
t$perf-norm:sp_cancel_adding_invoice 410 410 0 0.00 1 4590 110 0 0
t$perf-norm:sp_cancel_client_order 1776 1776 0 0.00 0 3670 14 0 0
t$perf-norm:sp_cancel_customer_reserve 662 662 0 0.00 0 4582 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 689 689 0 0.00 0 10985 25 0 0
t$perf-norm:sp_cancel_pay_to_supplier 382 382 0 0.00 0 1745 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 380 380 0 0.00 0 4321 29 0 0
t$perf-norm:sp_cancel_supplier_order 403 403 0 0.00 0 23178 1837 0 0
t$perf-norm:sp_cancel_write_off 981 981 0 0.00 0 13010 23 0 0
t$perf-norm:sp_client_order 20422 20422 0 0.00 0 9831 21 0 0
t$perf-norm:sp_customer_reserve 1022 1022 0 0.00 0 6971 14 0 0
t$perf-norm:sp_pay_from_customer 6974 6974 0 0.00 0 6021 10 0 0
t$perf-norm:sp_pay_to_supplier 5718 5718 0 0.00 0 12343 10 0 0
t$perf-norm:sp_reserve_write_off 10166 10166 0 0.00 0 12256 15 0 0
t$perf-norm:sp_supplier_invoice 5498 5498 0 0.00 0 11815 25 0 0
t$perf-norm:sp_supplier_order 5441 5441 0 0.00 0 6219 22 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 72 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 16 1 0 0
t$perf-norm:srv_make_invnt_saldo 592 592 0 0.00 0 2017 31 0 0
t$perf-norm:srv_make_money_saldo 796 796 0 0.00 0 2795 21 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 10210 9958 252 2.46 0 11581 20 0 252
v_all_customers 36958 36958 0 0.00 0 40 1 0 0
v_all_suppliers 30282 30282 0 0.00 0 28 1 0 0
v_all_wares 40844 40844 0 0.00 0 14 1 0 0
v_cancel_adding_invoice 820 820 0 0.00 0 1695 11 0 0
v_cancel_client_order 3552 3552 0 0.00 0 4656 8 0 0
v_cancel_customer_prepayment 1378 1378 0 0.00 0 1296 7 0 0
v_cancel_customer_reserve 1324 1324 0 0.00 0 1363 5 0 0
v_cancel_payment_to_supplier 764 764 0 0.00 0 2633 12 0 0
v_cancel_supplier_invoice 776 760 16 2.06 0 714 15 0 16
v_cancel_supplier_order 806 806 0 0.00 0 3369 10 0 0
v_cancel_write_off 1962 1962 0 0.00 0 4524 21 0 0
v_max_id_clo_ord 5441 5441 0 0.00 0 564 1 0 0
v_max_id_clo_res 1022 1022 0 0.00 0 2439 15 0 0
v_max_id_ord_sup 5490 5490 0 0.00 0 5907 4 0 0
v_max_non_paid_invoice 1508 1508 0 0.00 0 278 3 0 0
v_max_non_paid_realizn 3832 3832 0 0.00 0 1863 4 0 0
v_min_id_clo_ord 5441 5441 0 0.00 0 4449 7 0 0
v_min_id_clo_res 1022 1022 0 0.00 0 7882 17 0 0
v_min_id_ord_sup 5490 5490 0 0.00 0 6661 7 0 0
v_min_non_paid_invoice 5718 5718 0 0.00 0 8007 15 0 0
v_min_non_paid_realizn 6974 6974 0 0.00 0 4496 12 0 0
v_our_firm 10170 10170 0 0.00 0 15 1 0 0
v_reserve_write_off 20332 20332 0 0.00 0 5195 8 0 0
x_lock_depdocs_on_canc_invoice 11349 11349 0 0.00 0 39485 142 0 0
x_lock_depdocs_on_canc_sup_ord 403 403 0 0.00 0 9043 409 0 0
x_make_qstorno_1000_1200 5441 5441 0 0.00 1 41715 1220 0 0
x_make_qstorno_1200_2000 5498 5498 0 0.00 2 70372 3075 0 0
x_make_qstorno_2100_3300 463606 463606 0 0.00 0 99807 177 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-12-2021 16:40:16 to 19-12-2021 16:40:20.

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:05 4 8192000000 8802008912 8814231552 7576416 0.092 2 2 3 0 7717504 66144 74944
14:09:07 29295 8192000000 9896803040 10203824128 977765872 10.662 100 89 178 0 1007569744 5815600 23988272
14:10:49 20880 8192000000 9943544128 10264432640 1026142976 11.131 100 92 184 0 1057953312 8330848 23479488
14:12:20 14566 8192000000 9991169504 10329505792 1080719760 11.654 100 80 160 0 1110311040 9794832 19796448
14:13:37 9993 8192000000 10040090224 10388746240 1125667168 12.080 100 82 164 1 1156448112 11585856 19195088
14:14:50 10563 8192000000 10080286816 10440531968 1169405040 12.491 100 75 150 0 1198942896 12606208 16931648
14:16:04 4275 8192000000 10126402976 10491523072 1212782432 12.895 100 79 156 0 1243032848 12507728 17742688
14:17:10 5488 8192000000 10154374768 10533552128 1246157968 13.203 100 76 152 1 1276609424 14216080 16235376
14:18:19 1540 8192000000 10190638400 10569928704 1273847296 13.457 100 87 176 1 1306562672 12995312 19720064
14:19:22 35465 8192000000 10188741920 10595876864 1297457424 13.672 100 45 88 1 1315824784 7186128 11181232
14:21:03 6467 8192000000 10195524544 10609115136 1308657664 13.774 100 28 54 3 1320732976 6068928 6006384
14:22:13 11893 8192000000 10199428304 10618220544 1315832752 13.839 100 10 21 0 1323843632 5986448 2024432
14:23:27 54794 8192000000 10209327936 10633994240 1323655760 13.910 100 27 55 0 1335726608 5526496 6544352
14:25:27 4565 8192000000 10220509600 10637692928 1318251744 13.861 99 40 80 1 1334682560 6954400 9476416
14:26:35 8980 8192000000 10220663728 10641874944 1329448496 13.962 100 8 16 1 1337081696 5531408 2101792
14:27:47 40080 8192000000 10226718000 10633404416 1323704464 13.910 100 20 40 2 1333202080 5100800 4396816
14:29:49 7690 8192000000 10161968400 10572128256 1257750080 13.309 97 26 52 2 1269420256 5706256 5963920
14:31:01 6601 8192000000 10126476336 10518835200 1230521680 13.059 99 36 71 2 1246012432 7443520 8047232
14:32:10 8438 8192000000 10125085600 10527997952 1210917088 12.878 97 23 46 0 1221063664 4706384 5440192
14:33:20 34430 8192000000 10151987904 10561110016 1253927904 13.274 99 23 46 1 1265256112 5737888 5590320
14:34:56 8681 8192000000 10175298608 10579886080 1271319552 13.434 99 17 34 0 1281214272 5758160 4136560
14:36:05 9925 8192000000 10190204224 10604855296 1298035312 13.677 100 23 46 0 1309018960 5639680 5343968
14:37:23 45357 8192000000 10195568032 10614456320 1304443856 13.736 100 24 48 0 1315466512 5254224 5768432
14:39:29 21835 8192000000 10235055408 10652348416 1335179152 14.014 100 34 68 1 1353148352 10106016 7863184
14:40:54 31660 8192000000 10259855856 10685284352 1356699184 14.208 100 35 66 3 1380554320 15232320 8622816
14:42:29 13067 8192000000 10282420656 10708340736 1373499216 14.358 100 38 74 0 1398660112 16066080 9094816
14:43:51 13263 8192000000 10302506256 10726744064 1383525440 14.448 100 41 82 2 1412099680 19051072 9523168
14:45:11 7535 8192000000 10313089008 10738749440 1389312688 14.500 100 49 96 1 1424335536 24068720 10954128
14:46:24 8617 8192000000 10328560144 10754826240 1403121392 14.623 100 55 110 2 1441284912 24330480 13833040
14:47:36 2961 8192000000 10358431344 10780106752 1417775712 14.753 100 73 146 0 1465532016 30038480 17717824
14:48:42 6916 8192000000 10362197584 10791698432 1416946944 14.746 99 64 128 1 1461833104 30392352 14493808
14:49:53 3697 8192000000 10378628848 10794913792 1424263488 14.810 100 72 142 2 1478941360 37018128 17659744
14:51:00 6825 8192000000 10384944448 10807160832 1433982912 14.897 100 74 148 0 1491915600 40399840 17532848
14:52:10 4981 8192000000 10391299024 10816856064 1436404400 14.918 100 65 130 1 1496550080 43779008 16366672
14:53:20 5020 8192000000 10370254624 10790547456 1421093888 14.782 100 66 131 1 1476840240 39099392 16646960
14:54:29 2644 8192000000 10360260624 10775896064 1399868000 14.594 100 73 145 2 1464287920 46077696 18342224
14:55:38 3093 8192000000 10367423616 10785517568 1412808064 14.709 100 66 132 1 1473203216 42909088 17486064
14:56:51 1272 8192000000 10373214224 10782937088 1406159648 14.650 99 84 167 1 1475278288 47086032 22032608
14:57:56 5926 8192000000 10341069552 10762903552 1376492576 14.385 98 46 92 0 1431572016 42714736 12364704
14:59:08 8567 8192000000 10346991408 10764926976 1389298784 14.500 100 52 101 2 1452789536 49818352 13672400
15:00:35 4577 8192000000 10366833440 10789298176 1397390048 14.572 100 51 100 1 1467964176 56395952 14178176
15:01:43 12717 8192000000 10349592944 10781507584 1388994016 14.497 100 34 68 1 1456084336 56934000 10156320
15:03:19 17612 8192000000 10323800624 10763997184 1349891312 14.146 98 34 68 2 1416793440 56956992 9945136
15:04:47 19587 8192000000 10300296320 10742497280 1354928432 14.192 100 28 57 1 1407644800 43824032 8892336
15:06:12 93569 8192000000 10256451904 10706956288 1315210048 13.833 96 32 63 1 1343499424 19846064 8443312
15:08:49 25003 8192000000 10278848608 10721026048 1367831136 14.308 100 37 76 0 1386548592 10427968 8289488
15:10:17 180796 8192000000 10279931328 10741018624 1350115504 14.149 98 15 30 0 1369432912 15834400 3483008
15:14:24 116358 8192000000 10271222992 10722615296 1354974944 14.192 98 15 30 0 1367490112 8877936 3637232
15:17:51 30600 8192000000 10266637952 10704211968 1348720224 14.136 98 42 82 2 1365024048 7095648 9208176
15:19:25 45190 8192000000 10293925456 10739093504 1381829840 14.433 100 49 98 1 1411848224 19074576 10943808
15:21:12 57150 8192000000 10285819072 10743357440 1375341888 14.375 99 12 24 0 1390019504 11092496 3585120
15:23:10 46206 8192000000 10291367792 10745405440 1369054880 14.319 99 35 69 0 1395294000 18697408 7541712
15:25:18 56226 8192000000 10288966544 10750345216 1388159168 14.489 100 9 18 0 1407453680 16899728 2394784
15:27:15 82864 8192000000 10277454192 10746433536 1374267776 14.365 99 14 28 0 1395034976 17152832 3614368
15:29:40 83790 8192000000 10282708752 10744344576 1345347648 14.106 97 11 22 0 1359812752 12324096 2141008
15:32:04 55476 8192000000 10291894144 10752774144 1395172112 14.552 100 10 19 2 1408313408 10857376 2283920
15:34:00 49059 8192000000 10302251088 10762547200 1386335136 14.473 99 15 30 0 1403101056 13464416 3301504
15:35:50 54277 8192000000 10303730496 10768707584 1378650544 14.404 98 23 46 0 1402042576 18088064 5303968
15:37:49 50999 8192000000 10310352240 10762649600 1388795520 14.495 99 19 38 0 1402790384 9775104 4219760
15:39:44 84633 8192000000 10296891792 10755952640 1343438656 14.088 96 18 36 1 1358497968 11327344 3731968
15:42:09 51119 8192000000 10297988928 10758737920 1372685712 14.351 99 42 84 0 1395634464 13821360 9127392
15:44:03 99056 8192000000 10288417984 10734342144 1313757104 13.820 94 13 26 0 1334378256 18247824 2373328
15:46:47 71335 8192000000 10277963584 10735828992 1362523008 14.260 99 41 82 1 1380734992 8435360 9776624
15:48:59 43283 8192000000 10260288816 10720817152 1332455856 13.989 97 11 20 0 1346563056 11594160 2513040
15:50:44 25459 8192000000 10254433072 10700947456 1342238464 14.078 99 14 28 0 1351208816 6150688 2819664
15:52:14 60831 8192000000 10266859808 10719395840 1316499920 13.845 96 19 38 2 1333590416 12830288 4260208
15:54:22 64751 8192000000 10275219328 10730459136 1357726288 14.217 99 16 32 2 1377123248 15642352 3754608
15:56:38 86803 8192000000 10258862864 10717208576 1304272496 13.734 95 13 26 0 1318576384 10968768 3335120
15:59:05 42131 8192000000 10268633408 10712104960 1346773616 14.118 99 29 58 2 1363060176 9255168 7031392
16:00:48 81648 8192000000 10271307200 10719305728 1322945888 13.903 97 14 28 0 1340169216 13784864 3438464
16:03:28 35189 8192000000 10283446688 10743369728 1365664432 14.288 100 9 18 1 1387442624 19597104 2181088
16:05:07 50943 8192000000 10283090768 10752045056 1375301168 14.375 100 14 28 1 1400766576 22428944 3036464
16:07:07 14439 8192000000 10299269248 10739613696 1375154752 14.373 100 55 110 1 1400499632 13180304 12164576
16:08:24 125660 8192000000 10282184032 10735755264 1332788144 13.992 96 15 30 0 1349910224 13672944 3449136
16:11:49 23872 8192000000 10281999984 10723315712 1366437072 14.295 99 40 79 0 1385038112 9784496 8816544
16:13:17 111186 8192000000 10279537328 10738106368 1371699328 14.342 99 9 19 0 1384215584 10167120 2349136
16:16:32 99048 8192000000 10273649136 10730549248 1347063056 14.121 98 11 22 0 1358906224 9530080 2313088
16:19:17 54261 8192000000 10270174784 10730815488 1364176544 14.275 99 14 28 0 1384489744 16989328 3323872
16:21:11 50631 8192000000 10291004224 10741153792 1382079792 14.435 100 20 39 2 1394460320 7929504 4451024
16:23:07 59303 8192000000 10304795024 10757931008 1381753616 14.432 99 14 28 2 1400455600 15673152 3028832
16:25:06 62465 8192000000 10304300896 10757410816 1364462368 14.277 97 25 50 0 1381466688 11427840 5576480
16:27:59 54203 8192000000 10301446240 10760130560 1377232992 14.392 99 31 62 1 1395498272 11853760 6411520
16:30:13 28139 8192000000 10310756528 10760769536 1394623536 14.547 100 40 80 1 1414896208 11758416 8514256
16:31:44 139219 8192000000 10281628080 10736111616 1356646816 14.207 98 15 29 0 1374313520 15072400 2594304
16:35:09 71348 8192000000 10257851344 10720636928 1341429680 14.070 98 9 18 0 1354345632 11049952 1866000
16:37:20 51116 8192000000 9364521376 9672032256 522490064 5.995 39 3 6 0 531147056 7943984 713008

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 19-12-2021 16:40:22 to 19-12-2021 16:40:36.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 126 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 19-12-2021 16:40:37 to 19-12-2021 16:40:39.

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          236155
MON$OLDEST_ACTIVE               236156
MON$OLDEST_SNAPSHOT             236156
MON$NEXT_TRANSACTION            236156
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                       2020928
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33546 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33546 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33546 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33546 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 ---
:126: 
:127: mscs-test-161.budzdorov.ru    Sun Dec 19 16:38:26 2021
:128:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:129: 
--- end of diff output ---

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

19.12.21 16:40:40. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2021-12-19 16:40:43 No severe PSQL-related problems occured
19.12.21 16:40:44. End of report.