Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.10.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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 16061692 kB
MemAvailable 30637168 kB
Buffers 0 kB
Cached 15601032 kB
SwapCached 3876 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.8
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-10-12 22:37:50
DTS_END                         2021-10-12 22:37:50.6530
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/40914
TRN_ID                          235078
ATT_ID                          698
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: 8880.35 20405 1065642
customer order: creation 169.15 372 20298
customer order: refuse 13.97 355 1676
order to supplier: creation 45.18 1288 5422
order to supplier: removal 3.13 117334 375
invoice (draft): creation 45.38 3728 5446
invoice (draft): removal 50.65 817 6078
invoice accept: apply 40.80 19232 4896
invoice accept: cancel 45.72 829 5486
customer reserve: creation 3754.04 191 450485
customer reserve: removal 4423.81 65 530857
realization accept: apply 80.11 215 9613
realization accept: cancel 80.16 120 9619
payment from customer: creation 58.59 147 7031
payment from customer: removal 5.86 91 703
payment to supplier: creation 47.94 167 5753
payment to supplier: removal 3.53 73 423
service: total inventory turnovers 4.55 1203 546
service: total monetary turnovers 6.95 32 834
service: aggregate perf. data 0.81 17131 97
service: refresh index statistics 0.03 244703 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-10-2021 22:38:35 to 12-10-2021 22:38:37.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1813
TEST_TIME 2 3185
TEST_TIME 3 2966
TEST_TIME 4 3018
TEST_TIME 5 3969
TEST_TIME 6 4322
TEST_TIME 7 4516
TEST_TIME 8 4949
TEST_TIME 9 4790
TEST_TIME 10 5696
TEST_TIME 11 6048
TEST_TIME 12 6249
TEST_TIME 13 6634
TEST_TIME 14 6661
TEST_TIME 15 7049
TEST_TIME 16 7101
TEST_TIME 17 7096
TEST_TIME 18 7532
TEST_TIME 19 7743
TEST_TIME 20 7847
TEST_TIME 21 7970
TEST_TIME 22 7830
TEST_TIME 23 8123
TEST_TIME 24 8489
TEST_TIME 25 8600
TEST_TIME 26 8894
TEST_TIME 27 9125
TEST_TIME 28 9594
TEST_TIME 29 10226
TEST_TIME 30 10637
TEST_TIME 31 10472
TEST_TIME 32 10350
TEST_TIME 33 10182
TEST_TIME 34 10096
TEST_TIME 35 9879
TEST_TIME 36 9777
TEST_TIME 37 9693
TEST_TIME 38 9706
TEST_TIME 39 9560
TEST_TIME 40 9416
TEST_TIME 41 9394
TEST_TIME 42 9337
TEST_TIME 43 9210
TEST_TIME 44 9194
TEST_TIME 45 9043
TEST_TIME 46 9049
TEST_TIME 47 8973
TEST_TIME 48 9001
TEST_TIME 49 8916
TEST_TIME 50 8824
TEST_TIME 51 8784
TEST_TIME 52 8783
TEST_TIME 53 8762
TEST_TIME 54 8770
TEST_TIME 55 8660
TEST_TIME 56 8712
TEST_TIME 57 8723
TEST_TIME 58 8726
TEST_TIME 59 8711
TEST_TIME 60 8765
TEST_TIME 61 8806
TEST_TIME 62 8807
TEST_TIME 63 8896
TEST_TIME 64 8907
TEST_TIME 65 8928
TEST_TIME 66 8964
TEST_TIME 67 9063
TEST_TIME 68 9099
TEST_TIME 69 9026
TEST_TIME 70 9053
TEST_TIME 71 9226
TEST_TIME 72 9162
TEST_TIME 73 9059
TEST_TIME 74 9020
TEST_TIME 75 9035
TEST_TIME 76 8982
TEST_TIME 77 8963
TEST_TIME 78 8908
TEST_TIME 79 8901
TEST_TIME 80 8867
TEST_TIME 81 8837
TEST_TIME 82 8878
TEST_TIME 83 9004
TEST_TIME 84 8951
TEST_TIME 85 8991
TEST_TIME 86 8971
TEST_TIME 87 9019
TEST_TIME 88 9070
TEST_TIME 89 9049
TEST_TIME 90 9121
TEST_TIME 91 9127
TEST_TIME 92 9174
TEST_TIME 93 9197
TEST_TIME 94 9153
TEST_TIME 95 9189
TEST_TIME 96 9181
TEST_TIME 97 9112
TEST_TIME 98 9073
TEST_TIME 99 9072
TEST_TIME 100 9089
TEST_TIME 101 9045
TEST_TIME 102 9012
TEST_TIME 103 8999
TEST_TIME 104 8941
TEST_TIME 105 8966
TEST_TIME 106 8939
TEST_TIME 107 8902
TEST_TIME 108 8881
TEST_TIME 109 8836
TEST_TIME 110 8798
TEST_TIME 111 8814
TEST_TIME 112 8795
TEST_TIME 113 8805
TEST_TIME 114 8776
TEST_TIME 115 8817
TEST_TIME 116 8826
TEST_TIME 117 8835
TEST_TIME 118 8853
TEST_TIME 119 8833
TEST_TIME 120 8880

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 12-10-2021 22:38:39 to 12-10-2021 22:38:42.

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 1482268 1482268 0 0.00 0 55379 7 0 0
doc_list_biud 1482268 1482268 0 0.00 0 31672 30 0 0
sp_add_invoice_to_stock 5023 4896 127 2.52 40 218147 19232 0 127
sp_cancel_adding_invoice 5486 5486 0 0.00 5 49094 829 0 0
sp_cancel_client_order 1676 1676 0 0.00 4 11398 355 0 0
sp_cancel_customer_reserve 530857 530857 0 0.00 1 29147 66 0 0
sp_cancel_pay_from_customer 703 703 0 0.00 3 8203 92 0 0
sp_cancel_pay_to_supplier 423 423 0 0.00 2 4004 74 0 0
sp_cancel_supplier_invoice 6087 6078 9 0.14 1 18507 817 0 9
sp_cancel_supplier_order 375 375 0 0.00 8 1124224 117334 0 0
sp_cancel_write_off 9619 9619 0 0.00 1 15343 120 0 0
sp_client_order 20298 20298 0 0.00 3 21539 373 0 0
sp_customer_reserve 450485 450485 0 0.00 0 42186 191 0 0
sp_fill_shopping_cart 31164 31164 0 0.00 1 16260 47 0 0
sp_fill_shopping_cart_clo_res 450484 450484 0 0.00 0 24833 15 0 0
sp_get_clo_for_invoice 4895 4895 0 0.00 0 26199 325 0 0
sp_kill_cost_storno 16231 16231 0 0.00 0 15339 64 0 0
sp_kill_qstorno_ret_qs2qd 1077786 1077786 0 0.00 0 29141 35 0 0
sp_kill_qty_storno 568599 568599 0 0.00 0 31672 76 0 0
sp_lock_selected_doc 20139 20139 0 0.00 0 9771 5 0 0
sp_make_cost_storno 27292 27292 0 0.00 0 55366 64 0 0
sp_multiply_rows_for_pdistr 27292 27292 0 0.00 0 15942 33 0 0
sp_multiply_rows_for_qdistr 20298 20298 0 0.00 0 21163 160 0 0
sp_pay_from_customer 7031 7031 0 0.00 4 20433 147 0 0
sp_pay_to_supplier 5753 5753 0 0.00 4 17168 167 0 0
sp_payment_common 12784 12784 0 0.00 1 20427 48 0 0
sp_qd_handle_on_cancel_clo 1676 1676 0 0.00 0 11392 325 0 0
sp_qd_handle_on_invoice_upd_sts 10381 10381 0 0.00 0 31671 349 0 0
sp_qd_handle_on_reserve_upd_sts 9613 9613 0 0.00 0 21404 72 0 0
sp_reserve_write_off 9613 9613 0 0.00 6 21426 215 0 0
sp_supplier_invoice 5446 5446 0 0.00 7 88971 3729 0 0
sp_supplier_order 5422 5422 0 0.00 5 66238 1288 0 0
srv_aggregate_perf_data 97 97 0 0.00 2482 132551 17131 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 2088 148720 49648 0 0
srv_find_qd_qs_mism 1018480 1018480 0 0.00 0 14337 6 0 0
srv_make_invnt_saldo 546 546 0 0.00 38 88006 1204 0 0
srv_make_money_saldo 834 834 0 0.00 1 6138 33 0 0
srv_recalc_idx_stat 4 4 0 0.00 52601 346024 244704 0 0
t$perf-abend:sp_add_invoice_to_stock 127 127 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4896 4896 0 0.00 0 16852 212 0 0
t$perf-norm:sp_cancel_adding_invoice 387 387 0 0.00 0 5629 141 0 0
t$perf-norm:sp_cancel_client_order 1676 1676 0 0.00 0 2551 14 0 0
t$perf-norm:sp_cancel_customer_reserve 668 668 0 0.00 0 4326 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 703 703 0 0.00 0 9680 29 0 0
t$perf-norm:sp_cancel_pay_to_supplier 423 423 0 0.00 0 4823 19 0 0
t$perf-norm:sp_cancel_supplier_invoice 392 392 0 0.00 0 4694 22 0 0
t$perf-norm:sp_cancel_supplier_order 375 375 0 0.00 0 21721 1703 0 0
t$perf-norm:sp_cancel_write_off 1007 1007 0 0.00 0 473 3 0 0
t$perf-norm:sp_client_order 20298 20298 0 0.00 0 16418 22 0 0
t$perf-norm:sp_customer_reserve 1006 1006 0 0.00 0 3448 7 0 0
t$perf-norm:sp_pay_from_customer 7031 7031 0 0.00 0 6655 12 0 0
t$perf-norm:sp_pay_to_supplier 5753 5753 0 0.00 0 6043 10 0 0
t$perf-norm:sp_reserve_write_off 9613 9613 0 0.00 0 12428 14 0 0
t$perf-norm:sp_supplier_invoice 5446 5446 0 0.00 0 7790 23 0 0
t$perf-norm:sp_supplier_order 5422 5422 0 0.00 0 7641 18 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 253 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 2347 39 0 0
t$perf-norm:srv_make_invnt_saldo 546 546 0 0.00 0 4591 30 0 0
t$perf-norm:srv_make_money_saldo 834 834 0 0.00 0 2091 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 8 4 0 0
v_add_invoice_to_stock 10044 9790 254 2.52 0 15580 20 0 254
v_all_customers 36986 36986 0 0.00 0 117 1 0 0
v_all_suppliers 29870 29870 0 0.00 0 97 1 0 0
v_all_wares 40596 40596 0 0.00 0 24 1 0 0
v_cancel_adding_invoice 774 774 0 0.00 0 899 7 0 0
v_cancel_client_order 3352 3352 0 0.00 0 6439 7 0 0
v_cancel_customer_prepayment 1406 1406 0 0.00 0 4359 8 0 0
v_cancel_customer_reserve 1336 1336 0 0.00 0 1213 6 0 0
v_cancel_payment_to_supplier 846 846 0 0.00 0 289 3 0 0
v_cancel_supplier_invoice 802 784 18 2.24 0 1798 22 0 18
v_cancel_supplier_order 750 750 0 0.00 0 146 3 0 0
v_cancel_write_off 2014 2014 0 0.00 0 5052 20 0 0
v_max_id_clo_ord 5422 5422 0 0.00 0 953 2 0 0
v_max_id_clo_res 1006 1006 0 0.00 0 12725 22 0 0
v_max_id_ord_sup 5444 5444 0 0.00 0 4063 5 0 0
v_max_non_paid_invoice 1684 1684 0 0.00 0 1003 3 0 0
v_max_non_paid_realizn 3683 3683 0 0.00 0 6018 6 0 0
v_min_id_clo_ord 5422 5422 0 0.00 0 5131 11 0 0
v_min_id_clo_res 1006 1006 0 0.00 0 5089 17 0 0
v_min_id_ord_sup 5444 5444 0 0.00 0 7281 9 0 0
v_min_non_paid_invoice 5753 5753 0 0.00 0 15900 33 0 0
v_min_non_paid_realizn 7031 7031 0 0.00 0 20422 45 0 0
v_our_firm 10306 10306 0 0.00 0 20 1 0 0
v_reserve_write_off 19226 19226 0 0.00 0 13997 8 0 0
x_lock_depdocs_on_canc_invoice 11172 11172 0 0.00 0 11248 127 0 0
x_lock_depdocs_on_canc_sup_ord 375 375 0 0.00 0 5849 381 0 0
x_make_qstorno_1000_1200 5422 5422 0 0.00 1 66223 1196 0 0
x_make_qstorno_1200_2000 5446 5446 0 0.00 3 88934 3559 0 0
x_make_qstorno_2100_3300 450485 450485 0 0.00 0 42179 173 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-10-2021 22:38:43 to 12-10-2021 22:38:47.

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:07:54 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:08:55 31332 8192000000 9920707696 10236506112 997038112 10.850 100 90 180 1 1027674944 7113856 23522976
20:10:32 30002 8192000000 9960138400 10287661056 1038521424 11.250 100 88 176 0 1071012736 10089184 22402128
20:12:07 18074 8192000000 9990500416 10326802432 1074697056 11.597 100 86 172 0 1107056320 10935952 21423312
20:13:32 14651 8192000000 10037405376 10387869696 1120381840 12.031 100 76 152 0 1149601296 10614320 18605136
20:14:52 7349 8192000000 10097432960 10463698944 1185568112 12.642 100 80 160 1 1215717200 11516768 18632320
20:16:01 6365 8192000000 10136108848 10509307904 1227645136 13.032 100 77 154 0 1257494320 13198848 16650336
20:17:11 4454 8192000000 10173771552 10561802240 1265705696 13.382 100 74 148 0 1294748624 11986080 17056848
20:18:17 1915 8192000000 10212193168 10596913152 1294788432 13.648 100 94 187 1 1328536784 12891744 20856608
20:19:21 26977 8192000000 10213553328 10608549888 1308572192 13.773 100 61 119 1 1331015184 8071232 14371760
20:20:49 4808 8192000000 10213293584 10625490944 1318079248 13.859 100 41 83 2 1335365792 8420128 8866416
20:21:56 10155 8192000000 10206813472 10628702208 1324022352 13.913 100 34 68 0 1340623280 9205744 7395184
20:23:09 8161 8192000000 10215585520 10633375744 1326509296 13.936 100 34 67 1 1341528448 6988448 8030704
20:24:20 5318 8192000000 10220766928 10638266368 1332256304 13.988 100 30 62 0 1345766768 6693856 6816608
20:25:25 44996 8192000000 10250443616 10653196288 1333803392 14.002 100 44 86 0 1349448928 5096784 10548752
20:27:11 5832 8192000000 10208234576 10626240512 1316061536 13.841 99 28 55 2 1328912944 5876736 6974672
20:28:20 5695 8192000000 10211548576 10628788224 1307487312 13.763 99 27 55 1 1319645712 5554800 6603600
20:29:29 8095 8192000000 10176590544 10576392192 1250406624 13.242 97 40 80 0 1266533024 6807088 9319312
20:30:39 26548 8192000000 10154332416 10557267968 1199005312 12.767 94 20 38 0 1207546352 4500800 4040240
20:32:06 7443 8192000000 10153533376 10548178944 1247740064 13.217 99 28 57 1 1259809376 5058832 7010480
20:33:16 4982 8192000000 10160885296 10560610304 1248535504 13.225 98 29 58 1 1261176880 5584160 7057216
20:34:22 52606 8192000000 10189254960 10580410368 1279618096 13.510 100 59 120 2 1299585024 4659312 15307616
20:36:14 5704 8192000000 10183926736 10590994432 1290189392 13.606 100 41 82 0 1305961248 6244032 9527824
20:37:23 9906 8192000000 10211470480 10621329408 1314961136 13.831 100 25 50 1 1327102192 5208496 6932560
20:38:40 25146 8192000000 10243035664 10650742784 1335039088 14.013 100 48 96 0 1354390384 7784080 11567216
20:40:09 24410 8192000000 10251781280 10669056000 1346533312 14.116 100 33 64 1 1361045024 6865888 7645824
20:41:40 73400 8192000000 10260168032 10693824512 1361272784 14.249 100 37 74 0 1385026240 15015072 8738384
20:43:59 8645 8192000000 10296096864 10706001920 1374125504 14.364 100 64 125 3 1402017328 13734528 14157296
20:45:10 35321 8192000000 10300344512 10722914304 1374524240 14.368 99 49 98 3 1404422416 18182544 11715632
20:46:57 12380 8192000000 10318835136 10742476800 1394361824 14.545 100 59 117 0 1431201808 22781968 14058016
20:48:19 3279 8192000000 10330301472 10755657728 1405339152 14.643 100 60 120 2 1442859776 22686336 14834288
20:49:29 3689 8192000000 10335938832 10752540672 1394869568 14.549 99 80 157 3 1437600304 23741728 18989008
20:50:36 4031 8192000000 10346347104 10766675968 1409172240 14.677 100 76 151 1 1455947136 28367664 18407232
20:51:47 23213 8192000000 10311032656 10727346176 1361767936 14.253 97 57 113 1 1407966208 32146704 14051568
20:53:17 5382 8192000000 10357253136 10772463616 1407523712 14.662 100 76 152 0 1461106992 35198240 18385040
20:54:25 2087 8192000000 10373652256 10793295872 1418179056 14.757 100 66 132 1 1479079456 43931568 16968832
20:55:39 2652 8192000000 10370803568 10782605312 1406401312 14.652 100 85 170 1 1478166272 50365616 21399344
20:56:45 4033 8192000000 10362702272 10774343680 1399099232 14.587 100 71 142 0 1465369664 48340928 17929504
20:57:55 9102 8192000000 10356708912 10781335552 1405798112 14.647 100 51 102 2 1467186640 47736176 13652352
20:59:08 5848 8192000000 10330938496 10749689856 1358934240 14.228 98 49 98 0 1419741584 47624448 13182896
21:00:20 7928 8192000000 10343728864 10756837376 1377996368 14.399 100 59 118 2 1443799312 50621584 15181360
21:01:40 9024 8192000000 10332464208 10759979008 1371836544 14.343 99 34 68 1 1429218864 47782160 9600160
21:03:17 10621 8192000000 10341578080 10766938112 1373167440 14.355 100 35 70 4 1442809664 59409872 10232352
21:05:36 31455 8192000000 10304581488 10746384384 1351352272 14.160 99 19 38 4 1408108416 51130672 5625472
21:07:09 68828 8192000000 10263583328 10695409664 1296993328 13.668 96 17 35 1 1332915664 32143056 3779280
21:09:22 54412 8192000000 10266516576 10705219584 1340432848 14.061 98 32 63 0 1359301648 12374768 6494032
21:11:17 32452 8192000000 10265212512 10718556160 1359591104 14.234 100 8 16 0 1375342448 13872784 1878560
21:12:54 108868 8192000000 10272893632 10723897344 1352586640 14.171 98 21 43 0 1370653776 13244192 4822944
21:15:44 59333 8192000000 10264325568 10715774976 1335724784 14.019 98 12 24 2 1349626608 11012384 2889440
21:17:45 72418 8192000000 10268463040 10723074048 1367516448 14.305 100 23 46 1 1382737680 9866704 5354528
21:19:58 89959 8192000000 10282654368 10736975872 1369097104 14.319 99 23 45 1 1383898608 9689200 5112304
21:22:52 76839 8192000000 10277047792 10733195264 1364123088 14.274 99 16 30 0 1382271920 14692576 3456256
21:25:12 58218 8192000000 10267613456 10727010304 1366766704 14.298 99 22 44 0 1380050608 8528656 4755248
21:27:12 14485 8192000000 10280588992 10731040768 1380349520 14.420 100 18 36 2 1390151248 5677056 4124672
21:28:30 34767 8192000000 10310128624 10749759488 1389156848 14.498 100 49 98 0 1413145424 11987968 12000608
21:30:07 69586 8192000000 10292589536 10752548864 1383878000 14.451 99 22 44 0 1402740288 13364224 5498064
21:32:31 82033 8192000000 10293359376 10759536640 1395324192 14.553 100 17 34 0 1411329200 11922800 4082208
21:34:53 56706 8192000000 10286038784 10748882944 1368245584 14.311 98 13 26 2 1379386432 8651664 2489184
21:36:50 89468 8192000000 10295563152 10747379712 1366728608 14.298 98 21 42 0 1390910144 19003840 5177696
21:39:21 42858 8192000000 10282110560 10736140288 1354597600 14.189 98 21 42 0 1368234896 9000368 4636928
21:41:56 72662 8192000000 10259548896 10707968000 1270249696 13.424 92 19 38 0 1286945552 12529456 4166400
21:44:09 90272 8192000000 10279086656 10734669824 1344565392 14.099 98 21 42 1 1366247936 17311952 4370592
21:46:47 38218 8192000000 10280714624 10722721792 1348205600 14.131 99 38 76 1 1372664576 15431376 9027600
21:48:31 148720 8192000000 10259085792 10704855040 1317991424 13.859 96 15 28 2 1338261792 16764880 3505488
21:52:47 11871 8192000000 10227720528 10673246208 1307351648 13.762 97 23 46 1 1321851120 9551552 4947920
21:53:59 77786 8192000000 10245959184 10696970240 1307137552 13.760 97 8 16 0 1321127904 12354304 1636048
21:56:35 47541 8192000000 10266115424 10717229056 1322352608 13.898 97 14 28 0 1346793152 20656672 3783872
21:58:39 82993 8192000000 10265118368 10722140160 1348890656 14.137 99 19 38 0 1374953120 20988512 5073952
22:01:12 98513 8192000000 10272175984 10721382400 1341911648 14.075 99 9 18 0 1358784144 15104896 1767600
22:03:51 47699 8192000000 10281689472 10727698432 1359915872 14.237 99 20 40 0 1374787728 10168336 4703520
22:05:41 57356 8192000000 10274855232 10713702400 1342052000 14.076 98 14 28 2 1353684832 8827056 2805776
22:07:39 32383 8192000000 10286725344 10735718400 1359220416 14.230 99 39 78 2 1382550880 14984224 8346240
22:09:14 141069 8192000000 10306651536 10750074880 1361708768 14.253 98 14 28 0 1377169392 12874704 2585920
22:12:35 28213 8192000000 10303684736 10744242176 1362067792 14.256 98 37 74 0 1384323392 14184160 8071440
22:14:06 115613 8192000000 10298101920 10753298432 1381173584 14.427 99 42 84 1 1401439136 10969520 9296032
22:17:16 39261 8192000000 10277551904 10741481472 1367083504 14.301 98 8 16 1 1382694208 13864976 1745728
22:18:57 49935 8192000000 10286361936 10746032128 1377153168 14.391 99 13 26 1 1387395824 7672528 2570128
22:21:14 125208 8192000000 10302999136 10779410432 1407671936 14.663 100 13 27 0 1422288704 11370640 3246128
22:24:31 41247 8192000000 10294401392 10756730880 1384505024 14.457 98 18 34 0 1396350656 8042752 3802880
22:26:15 60074 8192000000 10275409696 10729467904 1361695856 14.253 98 15 30 0 1370701920 6046688 2959376
22:28:18 89745 8192000000 10297360800 10768470016 1397088272 14.569 100 5 10 0 1408436224 10580336 767616
22:30:51 37694 8192000000 10277902816 10728349696 1354149056 14.185 98 36 72 0 1373924992 10748656 9027280
22:32:29 42566 8192000000 10292432720 10744639488 1367876176 14.308 99 31 62 1 1392710480 18279616 6554688
22:34:13 70660 8192000000 10249380176 10702229504 1306709248 13.756 96 22 44 1 1326712048 14868048 5134752
22:36:26 68286 8192000000 10254639008 10711261184 1317330272 13.853 97 20 40 0 1340388240 18437696 4620272

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-10-2021 22:39:05 to 12-10-2021 22:39:07.

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          234101
MON$OLDEST_ACTIVE               235679
MON$OLDEST_SNAPSHOT             235679
MON$NEXT_TRANSACTION            235679
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                       2016512
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33520 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33520 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33520 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33520 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 ---
:228: 
:229: mscs-test-161.budzdorov.ru    Tue Oct 12 22:37:52 2021
:230:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:231: 
:232: 
:233: mscs-test-161.budzdorov.ru    Tue Oct 12 22:38:00 2021
:234:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:235: 
--- end of diff output ---

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

12.10.21 22:39:08. 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-10-12 22:39:10 No severe PSQL-related problems occured
12.10.21 22:39:11. End of report.