Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.12.2021 19:40
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 16549948 kB
MemAvailable 31396964 kB
Buffers 0 kB
Cached 15151076 kB
SwapCached 440 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-26 19:37:56
DTS_END                         2021-12-26 19:37:56.7550
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/40192
TRN_ID                          247927
ATT_ID                          650
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: 9200.54 23475 1104065
customer order: creation 176.95 312 21234
customer order: refuse 14.73 321 1767
order to supplier: creation 47.36 1204 5683
order to supplier: removal 3.38 95420 406
invoice (draft): creation 48.28 3143 5794
invoice (draft): removal 50.03 757 6003
invoice accept: apply 44.11 16819 5293
invoice accept: cancel 44.83 914 5379
customer reserve: creation 4040.98 167 484918
customer reserve: removal 4428.25 57 531390
realization accept: apply 86.68 209 10402
realization accept: cancel 82.49 109 9899
payment from customer: creation 60.82 122 7298
payment from customer: removal 5.90 57 708
payment to supplier: creation 49.42 157 5930
payment to supplier: removal 3.48 80 417
service: total inventory turnovers 4.82 1749 578
service: total monetary turnovers 7.08 29 850
service: aggregate perf. data 0.93 16638 112
service: refresh index statistics 0.03 331226 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 26-12-2021 19:40:36 to 26-12-2021 19:40:38.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1973
TEST_TIME 2 3240
TEST_TIME 3 4144
TEST_TIME 4 4384
TEST_TIME 5 4935
TEST_TIME 6 5173
TEST_TIME 7 4958
TEST_TIME 8 4915
TEST_TIME 9 5299
TEST_TIME 10 5176
TEST_TIME 11 5057
TEST_TIME 12 5207
TEST_TIME 13 5977
TEST_TIME 14 6408
TEST_TIME 15 6404
TEST_TIME 16 6424
TEST_TIME 17 6602
TEST_TIME 18 6832
TEST_TIME 19 7120
TEST_TIME 20 7324
TEST_TIME 21 7665
TEST_TIME 22 7874
TEST_TIME 23 8093
TEST_TIME 24 8142
TEST_TIME 25 8587
TEST_TIME 26 8737
TEST_TIME 27 9379
TEST_TIME 28 9742
TEST_TIME 29 10083
TEST_TIME 30 10258
TEST_TIME 31 10127
TEST_TIME 32 10041
TEST_TIME 33 10046
TEST_TIME 34 10079
TEST_TIME 35 10009
TEST_TIME 36 10072
TEST_TIME 37 10047
TEST_TIME 38 9945
TEST_TIME 39 9887
TEST_TIME 40 9754
TEST_TIME 41 9747
TEST_TIME 42 9733
TEST_TIME 43 9706
TEST_TIME 44 9609
TEST_TIME 45 9568
TEST_TIME 46 9441
TEST_TIME 47 9465
TEST_TIME 48 9489
TEST_TIME 49 9502
TEST_TIME 50 9557
TEST_TIME 51 9430
TEST_TIME 52 9435
TEST_TIME 53 9426
TEST_TIME 54 9414
TEST_TIME 55 9483
TEST_TIME 56 9502
TEST_TIME 57 9565
TEST_TIME 58 9586
TEST_TIME 59 9618
TEST_TIME 60 9659
TEST_TIME 61 9610
TEST_TIME 62 9634
TEST_TIME 63 9625
TEST_TIME 64 9658
TEST_TIME 65 9736
TEST_TIME 66 9717
TEST_TIME 67 9788
TEST_TIME 68 9675
TEST_TIME 69 9612
TEST_TIME 70 9546
TEST_TIME 71 9556
TEST_TIME 72 9496
TEST_TIME 73 9506
TEST_TIME 74 9444
TEST_TIME 75 9428
TEST_TIME 76 9451
TEST_TIME 77 9428
TEST_TIME 78 9351
TEST_TIME 79 9334
TEST_TIME 80 9375
TEST_TIME 81 9353
TEST_TIME 82 9299
TEST_TIME 83 9335
TEST_TIME 84 9347
TEST_TIME 85 9344
TEST_TIME 86 9302
TEST_TIME 87 9365
TEST_TIME 88 9372
TEST_TIME 89 9399
TEST_TIME 90 9357
TEST_TIME 91 9471
TEST_TIME 92 9473
TEST_TIME 93 9478
TEST_TIME 94 9504
TEST_TIME 95 9482
TEST_TIME 96 9413
TEST_TIME 97 9371
TEST_TIME 98 9345
TEST_TIME 99 9313
TEST_TIME 100 9320
TEST_TIME 101 9304
TEST_TIME 102 9263
TEST_TIME 103 9247
TEST_TIME 104 9229
TEST_TIME 105 9264
TEST_TIME 106 9258
TEST_TIME 107 9272
TEST_TIME 108 9248
TEST_TIME 109 9260
TEST_TIME 110 9289
TEST_TIME 111 9314
TEST_TIME 112 9301
TEST_TIME 113 9319
TEST_TIME 114 9341
TEST_TIME 115 9323
TEST_TIME 116 9368
TEST_TIME 117 9312
TEST_TIME 118 9241
TEST_TIME 119 9230
TEST_TIME 120 9205

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 26-12-2021 19:40:40 to 26-12-2021 19:40:43.

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 1552389 1552389 0 0.00 0 24417 6 0 0
doc_list_biud 1552388 1552388 0 0.00 0 28109 25 0 0
sp_add_invoice_to_stock 5381 5293 88 1.63 35 189465 16819 0 88
sp_cancel_adding_invoice 5379 5379 0 0.00 4 92714 914 0 0
sp_cancel_client_order 1767 1767 0 0.00 4 7888 322 0 0
sp_cancel_customer_reserve 531390 531390 0 0.00 1 22028 58 0 0
sp_cancel_pay_from_customer 708 708 0 0.00 2 2246 58 0 0
sp_cancel_pay_to_supplier 417 417 0 0.00 3 9545 80 0 0
sp_cancel_supplier_invoice 6009 6003 6 0.09 2 13718 758 0 6
sp_cancel_supplier_order 406 406 0 0.00 6 1149976 95421 0 0
sp_cancel_write_off 9899 9899 0 0.00 1 13426 110 0 0
sp_client_order 21234 21234 0 0.00 3 14793 313 0 0
sp_customer_reserve 484918 484918 0 0.00 0 38827 167 0 0
sp_fill_shopping_cart 32709 32709 0 0.00 1 21622 37 0 0
sp_fill_shopping_cart_clo_res 484915 484915 0 0.00 0 12537 12 0 0
sp_get_clo_for_invoice 5290 5290 0 0.00 0 17594 265 0 0
sp_kill_cost_storno 16403 16403 0 0.00 0 10078 52 0 0
sp_kill_qstorno_ret_qs2qd 1079088 1079088 0 0.00 0 22016 31 0 0
sp_kill_qty_storno 570535 570535 0 0.00 0 28107 68 0 0
sp_lock_selected_doc 21584 21584 0 0.00 0 11764 8 0 0
sp_make_cost_storno 28920 28920 0 0.00 0 23999 68 0 0
sp_multiply_rows_for_pdistr 28919 28919 0 0.00 0 18135 26 0 0
sp_multiply_rows_for_qdistr 21234 21234 0 0.00 0 12677 129 0 0
sp_pay_from_customer 7298 7298 0 0.00 4 21045 122 0 0
sp_pay_to_supplier 5930 5930 0 0.00 5 30883 158 0 0
sp_payment_common 13228 13228 0 0.00 2 30749 42 0 0
sp_qd_handle_on_cancel_clo 1767 1767 0 0.00 0 6717 287 0 0
sp_qd_handle_on_invoice_upd_sts 10668 10668 0 0.00 0 28105 344 0 0
sp_qd_handle_on_reserve_upd_sts 10402 10402 0 0.00 0 16768 68 0 0
sp_reserve_write_off 10402 10402 0 0.00 6 24522 209 0 0
sp_supplier_invoice 5794 5794 0 0.00 7 87622 3143 0 0
sp_supplier_order 5683 5683 0 0.00 4 58637 1205 0 0
srv_aggregate_perf_data 112 112 0 0.00 2801 121245 16638 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1541 157082 50955 0 0
srv_find_qd_qs_mism 1054287 1054287 0 0.00 0 8906 6 0 0
srv_make_invnt_saldo 578 578 0 0.00 31 406962 1749 0 0
srv_make_money_saldo 850 850 0 0.00 1 1962 30 0 0
srv_recalc_idx_stat 4 4 0 0.00 156642 494303 331226 0 0
t$perf-abend:sp_add_invoice_to_stock 88 88 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 5295 5295 0 0.00 0 13114 147 0 0
t$perf-norm:sp_cancel_adding_invoice 441 441 0 0.00 0 3345 115 0 0
t$perf-norm:sp_cancel_client_order 1767 1767 0 0.00 0 1879 8 0 0
t$perf-norm:sp_cancel_customer_reserve 690 690 0 0.00 0 1924 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 708 708 0 0.00 0 2237 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 417 417 0 0.00 0 1224 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 444 444 0 0.00 0 2104 17 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 19548 1465 0 0
t$perf-norm:sp_cancel_write_off 1020 1020 0 0.00 0 4693 18 0 0
t$perf-norm:sp_client_order 21234 21234 0 0.00 0 23821 18 0 0
t$perf-norm:sp_customer_reserve 1069 1069 0 0.00 0 4521 13 0 0
t$perf-norm:sp_pay_from_customer 7298 7298 0 0.00 0 6701 8 0 0
t$perf-norm:sp_pay_to_supplier 5930 5930 0 0.00 0 8890 11 0 0
t$perf-norm:sp_reserve_write_off 10402 10402 0 0.00 0 13506 14 0 0
t$perf-norm:sp_supplier_invoice 5794 5794 0 0.00 0 9266 15 0 0
t$perf-norm:sp_supplier_order 5683 5683 0 0.00 0 11429 23 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 499 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 990 21 0 0
t$perf-norm:srv_make_invnt_saldo 578 578 0 0.00 0 1941 21 0 0
t$perf-norm:srv_make_money_saldo 850 850 0 0.00 0 4990 21 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 52 15 0 0
v_add_invoice_to_stock 10752 10576 176 1.63 0 7221 21 0 176
v_all_customers 38806 38806 0 0.00 0 25 0 0 0
v_all_suppliers 31388 31388 0 0.00 0 29 1 0 0
v_all_wares 42468 42468 0 0.00 0 14 1 0 0
v_cancel_adding_invoice 882 882 0 0.00 0 517 6 0 0
v_cancel_client_order 3534 3534 0 0.00 0 6095 10 0 0
v_cancel_customer_prepayment 1416 1416 0 0.00 0 781 4 0 0
v_cancel_customer_reserve 1380 1380 0 0.00 0 1252 4 0 0
v_cancel_payment_to_supplier 834 834 0 0.00 0 1055 6 0 0
v_cancel_supplier_invoice 900 888 12 1.33 0 3321 21 0 12
v_cancel_supplier_order 812 812 0 0.00 0 6080 16 0 0
v_cancel_write_off 2040 2040 0 0.00 0 6332 17 0 0
v_max_id_clo_ord 5683 5683 0 0.00 0 3703 2 0 0
v_max_id_clo_res 1069 1069 0 0.00 0 2475 13 0 0
v_max_id_ord_sup 5792 5792 0 0.00 0 6141 7 0 0
v_max_non_paid_invoice 1711 1711 0 0.00 0 1307 3 0 0
v_max_non_paid_realizn 3782 3782 0 0.00 0 4351 4 0 0
v_min_id_clo_ord 5683 5683 0 0.00 0 3004 7 0 0
v_min_id_clo_res 1069 1069 0 0.00 0 560 2 0 0
v_min_id_ord_sup 5792 5792 0 0.00 0 7626 8 0 0
v_min_non_paid_invoice 5930 5930 0 0.00 0 30744 39 0 0
v_min_non_paid_realizn 7298 7298 0 0.00 0 17470 29 0 0
v_our_firm 10694 10694 0 0.00 0 22 1 0 0
v_reserve_write_off 20804 20804 0 0.00 0 6306 6 0 0
x_lock_depdocs_on_canc_invoice 10938 10938 0 0.00 0 24784 112 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 6093 377 0 0
x_make_qstorno_1000_1200 5683 5683 0 0.00 1 58626 1125 0 0
x_make_qstorno_1200_2000 5794 5794 0 0.00 2 87576 3013 0 0
x_make_qstorno_2100_3300 484918 484918 0 0.00 0 38826 153 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-12-2021 19:40:44 to 26-12-2021 19:40:48.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
17:08:00 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:01 22918 8192000000 9900086528 10213482496 976551888 10.651 100 77 154 0 1006754112 10081280 20120944
17:10:32 18189 8192000000 9944209520 10267623424 1028342736 11.152 100 90 180 1 1059406384 8181856 22881792
17:12:07 17228 8192000000 9975801984 10308366336 1062737904 11.483 100 85 170 0 1093553536 9791104 21024528
17:13:32 11079 8192000000 10032844736 10380640256 1118481664 12.013 100 76 152 1 1147498384 10296496 18720224
17:14:47 8137 8192000000 10076388144 10432745472 1163306064 12.434 100 78 155 3 1194788048 13437536 18044448
17:15:58 4935 8192000000 10126553712 10491478016 1205734352 12.830 100 86 170 0 1238279056 13802256 18742448
17:17:06 2477 8192000000 10155208352 10530967552 1244004464 13.183 100 82 162 0 1274198800 12027072 18167264
17:18:10 1710 8192000000 10199388896 10579472384 1276508800 13.481 100 91 183 1 1315768336 18294416 20965120
17:19:12 1102 8192000000 10221103520 10604908544 1297627808 13.674 100 93 184 0 1333729248 14438176 21663264
17:20:14 8455 8192000000 10193304928 10605375488 1304660880 13.738 100 37 73 3 1319690416 7038128 7991408
17:21:23 8369 8192000000 10198629664 10616090624 1313873920 13.821 100 17 34 0 1324082672 6378256 3830496
17:22:32 11730 8192000000 10210086224 10624122880 1321913264 13.894 100 16 32 0 1331424496 5451280 4059952
17:23:45 60800 8192000000 10232089488 10647339008 1330424912 13.971 100 40 77 3 1345560704 5117024 10018768
17:25:47 10535 8192000000 10227369520 10647171072 1333468416 13.998 100 23 43 2 1344899376 6566256 4864704
17:27:01 10537 8192000000 10184850992 10602061824 1248995264 13.229 94 28 56 1 1261231728 5780752 6455712
17:28:13 20634 8192000000 10159294832 10567589888 1240490832 13.151 97 17 34 0 1249213904 4870768 3852304
17:29:58 7981 8192000000 10141256000 10537058304 1212887024 12.896 96 29 58 0 1224836320 5302560 6646736
17:31:06 11531 8192000000 10130825136 10534240256 1234739360 13.098 99 16 32 1 1243862448 5723344 3399744
17:32:19 12250 8192000000 10177663248 10574503936 1274011536 13.458 100 25 49 0 1289300496 9394944 5894016
17:33:33 65285 8192000000 10172259840 10588745728 1284530048 13.554 100 39 76 2 1299351936 5587056 9234832
17:35:42 7771 8192000000 10190531264 10606006272 1299775520 13.693 100 23 46 2 1311209904 5404688 6029696
17:36:52 10947 8192000000 10204888096 10615472128 1306273984 13.752 100 19 38 0 1315856624 5211840 4370800
17:38:09 157082 8192000000 10251355904 10669760512 1337455520 14.034 100 34 68 0 1352239600 7675600 7108480
17:42:15 124819 8192000000 10292655264 10722979840 1387441120 14.483 100 32 65 0 1409823312 14625056 7757136
17:45:27 51269 8192000000 10305529744 10745139200 1394014320 14.542 100 39 76 4 1426214496 22159824 10040352
17:47:23 17738 8192000000 10310977872 10744700928 1393275984 14.535 100 34 68 0 1422175136 20527088 8372064
17:48:49 7969 8192000000 10324204720 10745430016 1387121584 14.480 99 60 119 6 1426831888 24562624 15147680
17:50:05 7470 8192000000 10342709168 10757320704 1396643536 14.565 100 65 130 0 1444630176 32237040 15749600
17:51:17 14629 8192000000 10308219376 10738565120 1375553296 14.377 99 31 61 0 1407620048 24439088 7627664
17:52:38 2114 8192000000 10336968240 10749501440 1391774800 14.522 100 90 182 2 1443634912 30206832 21653280
17:53:47 1541 8192000000 10327438592 10736713728 1375407360 14.375 99 77 149 1 1430975136 36453344 19114432
17:54:51 1796 8192000000 10351319040 10754449408 1388870144 14.496 100 89 178 1 1452162736 39874112 23418480
17:56:03 1800 8192000000 10344240416 10744938496 1376268880 14.383 99 90 180 0 1439966576 41260640 22437056
17:57:12 2500 8192000000 10343941968 10745655296 1378652144 14.404 100 82 165 0 1442855200 43297424 20905632
17:58:20 10463 8192000000 10324253008 10744864768 1371944160 14.344 99 41 82 1 1426373392 43349424 11079808
17:59:38 15726 8192000000 10324538368 10747961344 1363321808 14.267 99 43 86 1 1426189760 51160256 11707696
18:00:58 15349 8192000000 10325776400 10750214144 1356488608 14.206 99 41 82 2 1419543168 51337760 11716800
18:02:17 23709 8192000000 10311203888 10740428800 1341347808 14.070 98 34 66 0 1403616016 52949536 9318672
18:03:48 6901 8192000000 10322966480 10752663552 1367581584 14.305 100 48 94 1 1430155600 49093024 13480992
18:05:04 9511 8192000000 10306610992 10738569216 1370799200 14.334 100 29 57 1 1410445440 32275168 7371072
18:06:17 23964 8192000000 10290014768 10747846656 1357636208 14.216 100 12 24 0 1397037440 35724464 3676768
18:07:41 76562 8192000000 10295846960 10742456320 1365434896 14.286 99 24 47 0 1383067984 12561504 5071584
18:10:09 121659 8192000000 10303033712 10757898240 1378156736 14.400 99 24 48 0 1400293200 16666576 5469888
18:13:18 145565 8192000000 10306805872 10760617984 1332738752 13.992 95 12 24 1 1355570080 20644480 2186848
18:16:44 129912 8192000000 10306425952 10745778176 1353380096 14.178 97 34 68 1 1373061872 11703488 7978288
18:19:58 20663 8192000000 10303558928 10746953728 1375720400 14.378 99 48 95 2 1396851424 10189648 10941376
18:21:22 25791 8192000000 10321837888 10768543744 1399507904 14.591 100 36 68 0 1422545728 15154528 7883296
18:22:50 29704 8192000000 10325814128 10773549056 1394204656 14.543 99 36 71 0 1416166752 12941760 9020336
18:24:24 67364 8192000000 10323278336 10786545664 1396951632 14.568 99 23 46 1 1429735488 26920976 5862880
18:26:32 34353 8192000000 10309375648 10779344896 1400460032 14.599 100 6 12 1 1421875152 19841680 1573440
18:28:07 67985 8192000000 10305357616 10771214336 1389794288 14.504 99 10 20 1 1405601856 13687936 2119632
18:30:20 41774 8192000000 10312508752 10767757312 1398885440 14.585 100 14 28 2 1412755600 10987168 2882992
18:32:11 76603 8192000000 10306496608 10751922176 1367552160 14.305 98 14 28 0 1388556112 17531184 3472768
18:34:35 42835 8192000000 10281732576 10731257856 1342607648 14.081 96 24 48 1 1361329840 13400608 5321584
18:36:37 75537 8192000000 10295270784 10755923968 1378942928 14.407 99 12 24 0 1393219712 11586432 2690352
18:38:54 21982 8192000000 10297194256 10730557440 1325699680 13.928 95 33 67 0 1340050304 7855888 6494736
18:40:16 72928 8192000000 10280352176 10734477312 1334044192 14.004 97 11 22 2 1349116848 12755776 2316880
18:42:30 90157 8192000000 10281280400 10729553920 1271390768 13.434 92 14 28 0 1297728752 23051824 3286160
18:45:03 86595 8192000000 10257868992 10698702848 1315938768 13.840 97 18 36 0 1326182176 5999600 4243808
18:47:31 69997 8192000000 10259262080 10695585792 1315106912 13.832 97 45 90 0 1344873824 19450320 10316592
18:49:43 80926 8192000000 10253693456 10707914752 1344372128 14.097 98 22 44 1 1366696464 17026448 5297888
18:52:04 57491 8192000000 10277029984 10728951808 1344301152 14.096 98 20 40 1 1363056944 13986416 4769376
18:54:03 40608 8192000000 10279402688 10743877632 1372508176 14.350 100 19 38 1 1397252144 20229104 4514864
18:55:44 85410 8192000000 10257750896 10723483648 1355488352 14.197 99 13 26 1 1372673792 13330528 3854912
18:59:14 48867 8192000000 10282818416 10729848832 1349899232 14.147 98 27 54 0 1365606992 9505872 6201888
19:01:09 40533 8192000000 10282858768 10740473856 1380437408 14.420 100 26 52 2 1394202416 8003296 5761712
19:03:01 61273 8192000000 10289352832 10740498432 1357458304 14.215 98 20 39 0 1375477520 13822992 4196224
19:05:31 70251 8192000000 10267507168 10731999232 1331895936 13.984 97 21 42 1 1351964992 15445264 4623792
19:07:51 44179 8192000000 10284172976 10755375104 1369638544 14.324 99 24 48 1 1391363520 16525488 5199488
19:10:04 23302 8192000000 10288312016 10729930752 1354504112 14.188 98 30 60 0 1374202160 13225760 6472288
19:11:31 46142 8192000000 10295136160 10744614912 1386348800 14.473 100 22 44 1 1400059728 8069952 5640976
19:13:19 85946 8192000000 10303782464 10753892352 1363052944 14.265 98 21 42 0 1375118912 7658192 4407776
19:15:56 38896 8192000000 10281207392 10737881088 1367433872 14.304 99 22 44 0 1383239744 10749216 5056656
19:17:35 53498 8192000000 10270722384 10732277760 1364786960 14.280 99 3 6 0 1374790272 9644640 358672
19:19:32 32784 8192000000 10278106384 10734706688 1370264656 14.329 99 27 55 1 1384840864 7633104 6943104
19:21:14 90684 8192000000 10292235328 10763481088 1374050176 14.363 99 19 38 3 1392412416 13851680 4510560
19:23:45 55164 8192000000 10289211104 10735673344 1334357888 14.007 96 38 77 0 1354756832 11395504 9003440
19:25:41 18127 8192000000 10268292912 10712559616 1336054416 14.022 98 43 85 0 1355918096 10194224 9669456
19:27:04 56476 8192000000 10267885568 10723377152 1336424176 14.025 98 22 43 0 1356674336 15524400 4725760
19:29:34 23180 8192000000 10264462384 10691993600 1287603712 13.582 95 37 74 1 1307447520 11559728 8284080
19:31:01 100906 8192000000 10265390720 10710323200 1300481072 13.700 96 15 30 1 1314306576 10827152 2998352
19:33:44 116179 8192000000 10221447376 10664181760 1293315552 13.634 96 18 35 0 1308829520 11230912 4283056
19:36:40 43044 8192000000 10248798256 10705977344 1353214352 14.176 100 10 20 0 1363589312 8178864 2196096

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 26-12-2021 19:40:50 to 26-12-2021 19:41:05.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 88 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 26-12-2021 19:41:06 to 26-12-2021 19:41:08.

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          247037
MON$OLDEST_ACTIVE               248049
MON$OLDEST_SNAPSHOT             248049
MON$NEXT_TRANSACTION            248049
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                       2036640
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.33547 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33547 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33547 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33547 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 ---
:195: 
:196: mscs-test-161.budzdorov.ru    Sun Dec 26 19:38:00 2021
:197:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:198: 
:199: 
:200: mscs-test-161.budzdorov.ru    Sun Dec 26 19:38:29 2021
:201:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:202: 
--- end of diff output ---

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

26.12.21 19:41:09. 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-26 19:41:11 No severe PSQL-related problems occured
26.12.21 19:41:12. End of report.