Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 30.12.2021 22:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16397768 kB
MemAvailable 31307428 kB
Buffers 0 kB
Cached 15236156 kB
SwapCached 2272 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-30 22:37:58
DTS_END                         2021-12-30 22:37:58.2640
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/48868
TRN_ID                          238078
ATT_ID                          658
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: 9285.42 24434 1114251
customer order: creation 167.93 378 20152
customer order: refuse 14.58 370 1750
order to supplier: creation 47.17 1303 5660
order to supplier: removal 3.63 101310 436
invoice (draft): creation 46.31 3605 5557
invoice (draft): removal 54.62 816 6554
invoice accept: apply 40.73 17023 4887
invoice accept: cancel 48.84 739 5861
customer reserve: creation 3804.27 166 456512
customer reserve: removal 4762.64 61 571517
realization accept: apply 84.55 191 10146
realization accept: cancel 81.22 109 9746
payment from customer: creation 59.04 125 7085
payment from customer: removal 6.00 65 720
payment to supplier: creation 48.68 144 5841
payment to supplier: removal 3.45 93 414
service: total inventory turnovers 4.25 1233 510
service: total monetary turnovers 6.68 33 802
service: aggregate perf. data 0.81 20411 97
service: refresh index statistics 0.03 340509 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 30-12-2021 22:39:09 to 30-12-2021 22:39:12.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 833
TEST_TIME 2 1100
TEST_TIME 3 2007
TEST_TIME 4 3369
TEST_TIME 5 3338
TEST_TIME 6 3695
TEST_TIME 7 3899
TEST_TIME 8 4422
TEST_TIME 9 4543
TEST_TIME 10 4879
TEST_TIME 11 5003
TEST_TIME 12 6256
TEST_TIME 13 7087
TEST_TIME 14 7136
TEST_TIME 15 7946
TEST_TIME 16 7911
TEST_TIME 17 7844
TEST_TIME 18 8289
TEST_TIME 19 8429
TEST_TIME 20 8494
TEST_TIME 21 8717
TEST_TIME 22 8743
TEST_TIME 23 8650
TEST_TIME 24 8432
TEST_TIME 25 8504
TEST_TIME 26 8611
TEST_TIME 27 8915
TEST_TIME 28 8778
TEST_TIME 29 9033
TEST_TIME 30 9364
TEST_TIME 31 10237
TEST_TIME 32 10046
TEST_TIME 33 10143
TEST_TIME 34 10041
TEST_TIME 35 10019
TEST_TIME 36 10064
TEST_TIME 37 9902
TEST_TIME 38 9761
TEST_TIME 39 9839
TEST_TIME 40 9805
TEST_TIME 41 9993
TEST_TIME 42 9968
TEST_TIME 43 9905
TEST_TIME 44 9753
TEST_TIME 45 9644
TEST_TIME 46 9647
TEST_TIME 47 9540
TEST_TIME 48 9499
TEST_TIME 49 9579
TEST_TIME 50 9569
TEST_TIME 51 9476
TEST_TIME 52 9351
TEST_TIME 53 9279
TEST_TIME 54 9270
TEST_TIME 55 9186
TEST_TIME 56 9395
TEST_TIME 57 9518
TEST_TIME 58 9494
TEST_TIME 59 9450
TEST_TIME 60 9650
TEST_TIME 61 9723
TEST_TIME 62 9723
TEST_TIME 63 9706
TEST_TIME 64 9703
TEST_TIME 65 9704
TEST_TIME 66 9741
TEST_TIME 67 9816
TEST_TIME 68 9842
TEST_TIME 69 9881
TEST_TIME 70 9772
TEST_TIME 71 9737
TEST_TIME 72 9663
TEST_TIME 73 9587
TEST_TIME 74 9562
TEST_TIME 75 9589
TEST_TIME 76 9524
TEST_TIME 77 9505
TEST_TIME 78 9429
TEST_TIME 79 9478
TEST_TIME 80 9453
TEST_TIME 81 9421
TEST_TIME 82 9351
TEST_TIME 83 9319
TEST_TIME 84 9248
TEST_TIME 85 9320
TEST_TIME 86 9277
TEST_TIME 87 9317
TEST_TIME 88 9294
TEST_TIME 89 9267
TEST_TIME 90 9246
TEST_TIME 91 9327
TEST_TIME 92 9385
TEST_TIME 93 9456
TEST_TIME 94 9509
TEST_TIME 95 9532
TEST_TIME 96 9543
TEST_TIME 97 9586
TEST_TIME 98 9613
TEST_TIME 99 9640
TEST_TIME 100 9551
TEST_TIME 101 9518
TEST_TIME 102 9462
TEST_TIME 103 9445
TEST_TIME 104 9413
TEST_TIME 105 9383
TEST_TIME 106 9337
TEST_TIME 107 9306
TEST_TIME 108 9279
TEST_TIME 109 9302
TEST_TIME 110 9286
TEST_TIME 111 9233
TEST_TIME 112 9224
TEST_TIME 113 9219
TEST_TIME 114 9234
TEST_TIME 115 9208
TEST_TIME 116 9207
TEST_TIME 117 9276
TEST_TIME 118 9302
TEST_TIME 119 9310
TEST_TIME 120 9287

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 30-12-2021 22:39:13 to 30-12-2021 22:39:16.

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 1537557 1537557 0 0.00 0 15129 6 0 0
doc_list_biud 1537557 1537557 0 0.00 0 65701 29 0 0
sp_add_invoice_to_stock 5031 4887 144 2.86 26 176397 17023 0 144
sp_cancel_adding_invoice 5861 5861 0 0.00 3 78257 740 0 0
sp_cancel_client_order 1750 1750 0 0.00 4 10311 371 0 0
sp_cancel_customer_reserve 571517 571517 0 0.00 1 19547 61 0 0
sp_cancel_pay_from_customer 720 720 0 0.00 3 5725 66 0 0
sp_cancel_pay_to_supplier 414 414 0 0.00 3 7762 93 0 0
sp_cancel_supplier_invoice 6569 6554 15 0.22 2 65704 816 0 15
sp_cancel_supplier_order 436 436 0 0.00 6 757989 101310 0 0
sp_cancel_write_off 9746 9746 0 0.00 1 9756 110 0 0
sp_client_order 20152 20152 0 0.00 3 42806 378 0 0
sp_customer_reserve 456512 456512 0 0.00 0 36804 167 0 0
sp_fill_shopping_cart 31341 31341 0 0.00 1 38719 41 0 0
sp_fill_shopping_cart_clo_res 456510 456510 0 0.00 0 29704 13 0 0
sp_get_clo_for_invoice 4885 4885 0 0.00 0 30760 299 0 0
sp_kill_cost_storno 16741 16741 0 0.00 0 9753 55 0 0
sp_kill_qstorno_ret_qs2qd 1159770 1159770 0 0.00 0 65699 33 0 0
sp_kill_qty_storno 610895 610895 0 0.00 0 65700 72 0 0
sp_lock_selected_doc 20968 20968 0 0.00 0 8056 6 0 0
sp_make_cost_storno 27957 27957 0 0.00 0 15127 62 0 0
sp_multiply_rows_for_pdistr 27957 27957 0 0.00 0 7205 29 0 0
sp_multiply_rows_for_qdistr 20152 20152 0 0.00 0 42794 159 0 0
sp_pay_from_customer 7085 7085 0 0.00 4 15688 126 0 0
sp_pay_to_supplier 5841 5841 0 0.00 5 14064 144 0 0
sp_payment_common 12926 12926 0 0.00 1 7243 29 0 0
sp_qd_handle_on_cancel_clo 1750 1750 0 0.00 0 10303 331 0 0
sp_qd_handle_on_invoice_upd_sts 10746 10746 0 0.00 0 35615 328 0 0
sp_qd_handle_on_reserve_upd_sts 10146 10146 0 0.00 0 16037 68 0 0
sp_reserve_write_off 10146 10146 0 0.00 6 16379 192 0 0
sp_supplier_invoice 5557 5557 0 0.00 6 113040 3605 0 0
sp_supplier_order 5660 5660 0 0.00 5 37236 1304 0 0
srv_aggregate_perf_data 97 97 0 0.00 2940 212415 20412 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1505 153624 46170 0 0
srv_find_qd_qs_mism 1067209 1067209 0 0.00 0 10707 5 0 0
srv_make_invnt_saldo 510 510 0 0.00 40 66308 1234 0 0
srv_make_money_saldo 802 802 0 0.00 1 6633 34 0 0
srv_recalc_idx_stat 4 4 0 0.00 256099 411172 340509 0 0
t$perf-abend:sp_add_invoice_to_stock 144 144 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4887 4887 0 0.00 0 12537 162 0 0
t$perf-norm:sp_cancel_adding_invoice 421 421 0 0.00 0 8604 123 0 0
t$perf-norm:sp_cancel_client_order 1750 1750 0 0.00 0 5578 20 0 0
t$perf-norm:sp_cancel_customer_reserve 741 741 0 0.00 0 5792 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 720 720 0 0.00 0 4580 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 414 414 0 0.00 0 2770 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 433 433 0 0.00 0 606 13 0 0
t$perf-norm:sp_cancel_supplier_order 436 436 0 0.00 0 34588 1815 0 0
t$perf-norm:sp_cancel_write_off 1022 1022 0 0.00 0 10388 13 0 0
t$perf-norm:sp_client_order 20152 20152 0 0.00 0 13878 16 0 0
t$perf-norm:sp_customer_reserve 1014 1014 0 0.00 0 1175 5 0 0
t$perf-norm:sp_pay_from_customer 7085 7085 0 0.00 0 12885 15 0 0
t$perf-norm:sp_pay_to_supplier 5841 5841 0 0.00 0 11953 14 0 0
t$perf-norm:sp_reserve_write_off 10146 10146 0 0.00 0 5870 10 0 0
t$perf-norm:sp_supplier_invoice 5557 5557 0 0.00 0 11802 17 0 0
t$perf-norm:sp_supplier_order 5660 5660 0 0.00 0 4044 16 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 478 18 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 1069 18 0 0
t$perf-norm:srv_make_invnt_saldo 510 510 0 0.00 0 4072 27 0 0
t$perf-norm:srv_make_money_saldo 802 802 0 0.00 0 1712 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 6 4 0 0
v_add_invoice_to_stock 10058 9770 288 2.86 0 4075 17 0 288
v_all_customers 36658 36658 0 0.00 0 77 1 0 0
v_all_suppliers 31134 31134 0 0.00 0 26 1 0 0
v_all_wares 40304 40304 0 0.00 0 32 1 0 0
v_cancel_adding_invoice 842 842 0 0.00 0 2669 10 0 0
v_cancel_client_order 3500 3500 0 0.00 0 3375 9 0 0
v_cancel_customer_prepayment 1440 1440 0 0.00 0 1635 5 0 0
v_cancel_customer_reserve 1482 1482 0 0.00 0 4146 8 0 0
v_cancel_payment_to_supplier 828 828 0 0.00 0 697 5 0 0
v_cancel_supplier_invoice 896 866 30 3.34 0 3968 23 0 30
v_cancel_supplier_order 872 872 0 0.00 0 1638 6 0 0
v_cancel_write_off 2044 2044 0 0.00 0 6200 18 0 0
v_max_id_clo_ord 5656 5656 0 0.00 0 110 1 0 0
v_max_id_clo_res 1014 1014 0 0.00 0 3890 12 0 0
v_max_id_ord_sup 5533 5533 0 0.00 0 4759 3 0 0
v_max_non_paid_invoice 1463 1463 0 0.00 0 1875 4 0 0
v_max_non_paid_realizn 3829 3829 0 0.00 0 5116 6 0 0
v_min_id_clo_ord 5656 5656 0 0.00 0 5106 7 0 0
v_min_id_clo_res 1014 1014 0 0.00 0 4590 14 0 0
v_min_id_ord_sup 5533 5533 0 0.00 0 6142 11 0 0
v_min_non_paid_invoice 5841 5841 0 0.00 0 7238 23 0 0
v_min_non_paid_realizn 7085 7085 0 0.00 0 7122 19 0 0
v_our_firm 10158 10158 0 0.00 0 25 1 0 0
v_reserve_write_off 20292 20292 0 0.00 0 5671 7 0 0
x_lock_depdocs_on_canc_invoice 11982 11982 0 0.00 0 11083 106 0 0
x_lock_depdocs_on_canc_sup_ord 436 436 0 0.00 0 10406 285 0 0
x_make_qstorno_1000_1200 5660 5660 0 0.00 1 37187 1225 0 0
x_make_qstorno_1200_2000 5557 5557 0 0.00 3 112937 3440 0 0
x_make_qstorno_2100_3300 456512 456512 0 0.00 0 36409 151 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 30-12-2021 22:39:17 to 30-12-2021 22:39:21.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
20:08:02 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:02 23885 8192000000 9909176688 10224111616 993754608 10.818 100 86 172 0 1021880032 6222208 21903216
20:10:33 18779 8192000000 9952504032 10278481920 1037075536 11.237 100 88 176 2 1068642416 8789664 22777216
20:12:00 19593 8192000000 10000963264 10335662080 1076468896 11.614 100 85 171 0 1107749984 10325440 20955648
20:13:25 12258 8192000000 10030428976 10380972032 1116154944 11.991 100 79 158 1 1146518144 10765632 19597568
20:14:39 9719 8192000000 10086689664 10446540800 1172911440 12.524 100 90 178 2 1207056928 13768096 20377392
20:15:51 5596 8192000000 10124201680 10496057344 1209226176 12.862 100 85 170 0 1242782432 14445440 19110816
20:17:00 3188 8192000000 10162764624 10535612416 1249779328 13.236 100 93 186 0 1284082128 13858688 20444112
20:18:05 2028 8192000000 10203811680 10586124288 1282297776 13.534 100 95 191 1 1316636528 13044800 21293952
20:19:09 1515 8192000000 10209517088 10596020224 1293118784 13.633 100 91 185 1 1326046512 12230608 20697120
20:20:11 2152 8192000000 10216753168 10608836608 1299082272 13.687 100 79 158 3 1329904736 12956336 17866128
20:21:14 4398 8192000000 10200451920 10612158464 1308216768 13.770 100 40 77 2 1324106384 7245296 8644320
20:22:24 4906 8192000000 10209403952 10624864256 1321357344 13.889 100 31 62 0 1335153344 6778720 7017280
20:23:37 59708 8192000000 10223692480 10641752064 1326949536 13.940 100 35 68 1 1340164144 5517088 7697520
20:25:38 4952 8192000000 10234809792 10656235520 1342943680 14.084 100 28 56 1 1355296384 5902944 6449760
20:26:43 6172 8192000000 10233762144 10651815936 1334969536 14.012 100 30 60 0 1348319984 6596768 6753680
20:27:55 71665 8192000000 10186115456 10595389440 1232528624 13.077 93 32 64 0 1250103552 9670304 7904624
20:30:11 5862 8192000000 10167961392 10568736768 1257261008 13.305 98 25 49 1 1268544896 6277312 5006576
20:31:18 4161 8192000000 10159512224 10560434176 1236007600 13.109 97 35 69 3 1250045760 6081184 7956976
20:32:22 13398 8192000000 10147509056 10551390208 1236846384 13.117 98 14 28 2 1245753168 5855712 3051072
20:33:41 11690 8192000000 10176088304 10576969728 1273799488 13.456 100 62 128 2 1295100320 5281312 16019520
20:34:55 8152 8192000000 10180264272 10589368320 1289056560 13.596 100 25 50 1 1299945504 5139120 5749824
20:36:09 9898 8192000000 10199915136 10613395456 1305116192 13.742 100 30 60 1 1317917424 5211376 7589856
20:37:22 84920 8192000000 10221827776 10633863168 1317514048 13.854 100 34 68 1 1330653360 4973216 8166096
20:39:51 28686 8192000000 10247382112 10662547456 1344942112 14.102 100 33 66 0 1360760832 8040096 7778624
20:41:27 11868 8192000000 10266320496 10690564096 1366746192 14.298 100 30 60 1 1384563472 10171584 7645696
20:42:41 19025 8192000000 10271042960 10691387392 1362868304 14.263 99 40 80 3 1382981792 11439424 8674064
20:44:04 12925 8192000000 10306818192 10728079360 1393356480 14.536 100 53 106 0 1419825952 12791280 13678192
20:45:22 9079 8192000000 10322349760 10751741952 1409786080 14.682 100 40 80 0 1434837712 14301888 10749744
20:46:36 4043 8192000000 10319123120 10746322944 1402423008 14.617 99 50 99 0 1428433696 14272560 11738128
20:47:45 25952 8192000000 10328310656 10760495104 1399657600 14.592 99 46 92 1 1431726464 20407792 11661072
20:49:13 6944 8192000000 10330821376 10766397440 1413478160 14.715 100 50 101 1 1448434928 22078352 12878416
20:50:24 1503 8192000000 10355286864 10776526848 1418449280 14.759 100 93 186 2 1470205456 28830416 22925760
20:51:28 2669 8192000000 10331740048 10746167296 1399371792 14.589 100 87 174 1 1438920128 17986736 21561600
20:52:33 1981 8192000000 10348931696 10770837504 1404917728 14.639 99 90 176 1 1447263456 20979792 21365936
20:53:39 8033 8192000000 10326615600 10755801088 1404915408 14.639 100 46 92 1 1434155616 17536464 11703744
20:54:51 9849 8192000000 10341755648 10767486976 1406872704 14.656 100 53 106 2 1445803152 25046688 13883760
20:56:05 2138 8192000000 10320662128 10745716736 1387089648 14.480 99 64 128 0 1425234032 21476720 16667664
20:57:12 3115 8192000000 10326371408 10740387840 1387980224 14.488 100 73 146 1 1431738464 25593984 18164256
20:58:19 17564 8192000000 10302637552 10723721216 1342620032 14.081 97 39 78 2 1381088112 27493216 10974864
20:59:47 5728 8192000000 10335348640 10756210688 1384872928 14.460 99 53 106 1 1436228368 37742016 13613424
21:01:12 10149 8192000000 10339987904 10764611584 1399386880 14.590 100 41 82 1 1446303136 36062080 10854176
21:02:29 10550 8192000000 10351242064 10769203200 1397540912 14.573 100 63 126 2 1458035776 44128432 16366432
21:03:55 20984 8192000000 10344915168 10779246592 1397347088 14.571 100 34 68 2 1452484832 45847600 9290144
21:05:30 10971 8192000000 10345399648 10773569536 1389793856 14.504 99 45 89 0 1440943328 39299952 11849520
21:06:52 44770 8192000000 10302515024 10748051456 1362640608 14.261 99 27 54 0 1414906720 44571136 7694976
21:08:44 38437 8192000000 10298972512 10726187008 1352803840 14.173 98 33 65 1 1368858336 7857776 8196720
21:10:28 91368 8192000000 10279853584 10719338496 1310214416 13.788 95 9 18 2 1327896112 15735376 1946320
21:12:59 83186 8192000000 10292496720 10725433344 1344200448 14.095 97 19 37 1 1356898608 9083280 3614880
21:15:25 26819 8192000000 10302158064 10731569152 1367799968 14.307 99 62 120 0 1391609248 9086752 14722528
21:16:53 33857 8192000000 10292318656 10740580352 1382384304 14.438 100 15 30 0 1395691008 9969584 3337120
21:18:31 104239 8192000000 10311041600 10762575872 1388755856 14.495 100 9 18 0 1408446400 17383936 2306608
21:22:40 41107 8192000000 10315488560 10766516224 1399918816 14.594 100 41 82 0 1421779552 12914336 8946400
21:24:25 29918 8192000000 10312737536 10760114176 1377849776 14.397 98 40 80 0 1394792752 7146384 9796592
21:25:55 54933 8192000000 10307157040 10772213760 1399574176 14.591 100 41 82 0 1427034064 17146944 10312944
21:28:20 81097 8192000000 10311588976 10770829312 1382538320 14.439 98 16 32 1 1402290928 15897200 3855408
21:31:51 67778 8192000000 10326932816 10776334336 1401006352 14.604 100 19 38 0 1425829264 19346288 5476624
21:34:00 58177 8192000000 10308688688 10779316224 1381886608 14.433 98 19 36 0 1407788032 21824096 4077328
21:36:02 60733 8192000000 10323027136 10783166464 1395792208 14.558 99 31 62 0 1415056464 11802496 7461760
21:38:40 132269 8192000000 10275829936 10726318080 1300818320 13.703 92 12 24 0 1316685312 13271744 2595248
21:42:07 49236 8192000000 10302915440 10742280192 1323671744 13.910 95 41 82 1 1340802048 7179632 9950672
21:43:57 99318 8192000000 10308084688 10773364736 1373031840 14.354 99 12 24 0 1389157328 13583344 2542144
21:46:40 141707 8192000000 10274633344 10733072384 1286259936 13.570 92 19 38 0 1303611456 13167712 4183808
21:50:09 57977 8192000000 10273993872 10722689024 1340573872 14.063 98 40 80 1 1363728288 14372928 8781488
21:52:07 22326 8192000000 10253879664 10704510976 1316481616 13.845 96 38 76 0 1344705552 19353776 8870160
21:53:45 43364 8192000000 10255965056 10701930496 1334754880 14.010 98 16 31 0 1348200480 9400432 4045168
21:55:32 61167 8192000000 10272313184 10733133824 1376768160 14.388 100 23 46 0 1397723904 15760496 5195248
21:57:35 58888 8192000000 10259414016 10719318016 1336886704 14.029 97 18 36 1 1348234400 6527056 4820640
21:59:35 26201 8192000000 10243727712 10696007680 1329473456 13.962 99 12 24 1 1348596192 16458384 2664352
22:01:02 78376 8192000000 10245623504 10711412736 1336852288 14.029 99 11 22 0 1354483984 15185648 2446048
22:03:31 63881 8192000000 10258816160 10717462528 1353757904 14.181 99 15 30 1 1368636544 11559232 3319408
22:05:39 40352 8192000000 10280368912 10726043648 1347134416 14.122 99 34 68 1 1367244416 12582224 7527776
22:07:24 29486 8192000000 10290974944 10734419968 1361578560 14.252 99 32 64 1 1388269680 19260144 7430976
22:08:54 81639 8192000000 10294985088 10766036992 1338348592 14.043 97 8 16 1 1359447040 19533296 1565152
22:11:35 64327 8192000000 10298603488 10753949696 1344089136 14.094 97 18 36 1 1363579088 15901456 3588496
22:13:41 61773 8192000000 10296367424 10742857728 1348267680 14.132 97 26 52 1 1366758768 13291648 5199440
22:15:43 153621 8192000000 10295609728 10756050944 1367037648 14.300 98 25 50 0 1384407776 12118912 5251216
22:19:31 55121 8192000000 10287861648 10753507328 1382832704 14.442 99 9 18 0 1392560272 7892304 1835264
22:21:38 55973 8192000000 10286113504 10746908672 1364957360 14.282 98 14 27 0 1376845648 8520176 3368112
22:23:59 98805 8192000000 10287543616 10743746560 1350750496 14.154 97 20 40 0 1366207296 10473808 4982992
22:26:50 11831 8192000000 10272972112 10720804864 1364013232 14.273 99 38 75 3 1376955264 5007600 7934432
22:28:04 86230 8192000000 10265466352 10740670464 1364049504 14.274 99 14 29 0 1376610288 9586976 2973808
22:30:30 50101 8192000000 10272850288 10732548096 1331123376 13.977 97 16 32 0 1342149344 7398720 3627248
22:33:12 47666 8192000000 10292826112 10749771776 1369416368 14.322 100 30 59 1 1396273344 19906560 6950416
22:35:12 56052 8192000000 10276519616 10728644608 1347111536 14.121 98 39 78 1 1370781488 14903888 8766064
22:37:15 55798 8192000000 9141304640 9384747008 298533664 3.516 22 9 18 0 309376176 8793440 2049072

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 30-12-2021 22:39:23 to 30-12-2021 22:39:38.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 144 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 30-12-2021 22:39:39 to 30-12-2021 22: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          238779
MON$OLDEST_ACTIVE               239154
MON$OLDEST_SNAPSHOT             239154
MON$NEXT_TRANSACTION            239154
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                       2040224
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.33548 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33548 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33548 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33548 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 ---
:140: 
:141: mscs-test-161.budzdorov.ru    Thu Dec 30 22:38:28 2021
:142:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:143: 
:144: 
:145: mscs-test-161.budzdorov.ru    Thu Dec 30 22:38:28 2021
:146:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:147: 
:148: 
:149: mscs-test-161.budzdorov.ru    Thu Dec 30 22:38:30 2021
:150:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:151: 
--- end of diff output ---

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

30.12.21 22: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
2021-12-30 22:39:44 No severe PSQL-related problems occured
30.12.21 22:39:45. End of report.