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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16721668 kB
MemAvailable 31484520 kB
Buffers 0 kB
Cached 14921552 kB
SwapCached 0 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.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-12-01 01:37:56
DTS_END                         2021-12-01 01:37:56.6350
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/33604
TRN_ID                          239733
ATT_ID                          708
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: 9065.65 24842 1087878
customer order: creation 171.66 345 20599
customer order: refuse 15.41 359 1849
order to supplier: creation 45.73 1125 5487
order to supplier: removal 3.36 96316 403
invoice (draft): creation 47.04 2963 5645
invoice (draft): removal 50.53 775 6063
invoice accept: apply 41.67 18366 5000
invoice accept: cancel 45.36 770 5443
customer reserve: creation 3889.03 180 466684
customer reserve: removal 4465.62 57 535874
realization accept: apply 84.69 175 10163
realization accept: cancel 76.16 105 9139
payment from customer: creation 58.70 104 7044
payment from customer: removal 5.86 62 703
payment to supplier: creation 48.80 97 5856
payment to supplier: removal 3.72 69 446
service: total inventory turnovers 4.89 1106 587
service: total monetary turnovers 6.58 26 790
service: aggregate perf. data 0.83 20744 99
service: refresh index statistics 0.03 353105 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 01-12-2021 01:39:04 to 01-12-2021 01:39:06.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2861
TEST_TIME 2 2815
TEST_TIME 3 3903
TEST_TIME 4 4663
TEST_TIME 5 4840
TEST_TIME 6 5085
TEST_TIME 7 4931
TEST_TIME 8 4806
TEST_TIME 9 4957
TEST_TIME 10 5028
TEST_TIME 11 5301
TEST_TIME 12 5891
TEST_TIME 13 5999
TEST_TIME 14 6044
TEST_TIME 15 6527
TEST_TIME 16 6641
TEST_TIME 17 7094
TEST_TIME 18 7410
TEST_TIME 19 7768
TEST_TIME 20 8136
TEST_TIME 21 8470
TEST_TIME 22 8361
TEST_TIME 23 8539
TEST_TIME 24 8928
TEST_TIME 25 9122
TEST_TIME 26 9251
TEST_TIME 27 9654
TEST_TIME 28 10339
TEST_TIME 29 10816
TEST_TIME 30 10566
TEST_TIME 31 10346
TEST_TIME 32 10369
TEST_TIME 33 10400
TEST_TIME 34 10152
TEST_TIME 35 10039
TEST_TIME 36 9864
TEST_TIME 37 10018
TEST_TIME 38 10000
TEST_TIME 39 9913
TEST_TIME 40 9840
TEST_TIME 41 9741
TEST_TIME 42 9629
TEST_TIME 43 9468
TEST_TIME 44 9623
TEST_TIME 45 9552
TEST_TIME 46 9519
TEST_TIME 47 9603
TEST_TIME 48 9558
TEST_TIME 49 9617
TEST_TIME 50 9741
TEST_TIME 51 9649
TEST_TIME 52 9664
TEST_TIME 53 9625
TEST_TIME 54 9724
TEST_TIME 55 9766
TEST_TIME 56 9694
TEST_TIME 57 9657
TEST_TIME 58 9537
TEST_TIME 59 9437
TEST_TIME 60 9535
TEST_TIME 61 9430
TEST_TIME 62 9391
TEST_TIME 63 9366
TEST_TIME 64 9272
TEST_TIME 65 9224
TEST_TIME 66 9199
TEST_TIME 67 9227
TEST_TIME 68 9213
TEST_TIME 69 9287
TEST_TIME 70 9229
TEST_TIME 71 9200
TEST_TIME 72 9225
TEST_TIME 73 9349
TEST_TIME 74 9417
TEST_TIME 75 9354
TEST_TIME 76 9385
TEST_TIME 77 9453
TEST_TIME 78 9449
TEST_TIME 79 9485
TEST_TIME 80 9472
TEST_TIME 81 9423
TEST_TIME 82 9355
TEST_TIME 83 9374
TEST_TIME 84 9306
TEST_TIME 85 9275
TEST_TIME 86 9234
TEST_TIME 87 9205
TEST_TIME 88 9170
TEST_TIME 89 9144
TEST_TIME 90 9165
TEST_TIME 91 9090
TEST_TIME 92 9050
TEST_TIME 93 9082
TEST_TIME 94 9140
TEST_TIME 95 9124
TEST_TIME 96 9121
TEST_TIME 97 9162
TEST_TIME 98 9238
TEST_TIME 99 9221
TEST_TIME 100 9232
TEST_TIME 101 9185
TEST_TIME 102 9245
TEST_TIME 103 9278
TEST_TIME 104 9293
TEST_TIME 105 9306
TEST_TIME 106 9334
TEST_TIME 107 9298
TEST_TIME 108 9241
TEST_TIME 109 9233
TEST_TIME 110 9198
TEST_TIME 111 9168
TEST_TIME 112 9176
TEST_TIME 113 9140
TEST_TIME 114 9128
TEST_TIME 115 9081
TEST_TIME 116 9073
TEST_TIME 117 9089
TEST_TIME 118 9083
TEST_TIME 119 9088
TEST_TIME 120 9072

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 01-12-2021 01:39:08 to 01-12-2021 01:39:11.

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 1519201 1519201 0 0.00 0 16315 6 0 0
doc_list_biud 1519199 1519199 0 0.00 0 47191 26 0 0
sp_add_invoice_to_stock 5104 5000 104 2.03 45 220107 18367 0 104
sp_cancel_adding_invoice 5443 5443 0 0.00 6 68990 771 0 0
sp_cancel_client_order 1849 1849 0 0.00 4 13716 359 0 0
sp_cancel_customer_reserve 535874 535874 0 0.00 1 47193 58 0 0
sp_cancel_pay_from_customer 703 703 0 0.00 2 3617 63 0 0
sp_cancel_pay_to_supplier 446 446 0 0.00 3 4688 69 0 0
sp_cancel_supplier_invoice 6072 6063 9 0.14 1 14383 776 0 9
sp_cancel_supplier_order 403 403 0 0.00 6 1094762 96316 0 0
sp_cancel_write_off 9139 9139 0 0.00 1 7334 106 0 0
sp_client_order 20599 20599 0 0.00 3 58904 345 0 0
sp_customer_reserve 466684 466684 0 0.00 0 91776 180 0 0
sp_fill_shopping_cart 31723 31723 0 0.00 1 10498 36 0 0
sp_fill_shopping_cart_clo_res 466666 466666 0 0.00 0 16260 14 0 0
sp_get_clo_for_invoice 4982 4982 0 0.00 0 50543 330 0 0
sp_kill_cost_storno 15731 15731 0 0.00 0 16301 52 0 0
sp_kill_qstorno_ret_qs2qd 1087353 1087353 0 0.00 0 47184 31 0 0
sp_kill_qty_storno 573916 573916 0 0.00 0 47191 68 0 0
sp_lock_selected_doc 20993 20993 0 0.00 0 9408 5 0 0
sp_make_cost_storno 28045 28045 0 0.00 0 14350 48 0 0
sp_multiply_rows_for_pdistr 28045 28045 0 0.00 0 6307 26 0 0
sp_multiply_rows_for_qdistr 20599 20599 0 0.00 0 58887 164 0 0
sp_pay_from_customer 7044 7044 0 0.00 4 19838 105 0 0
sp_pay_to_supplier 5856 5856 0 0.00 5 12527 97 0 0
sp_payment_common 12900 12900 0 0.00 1 19833 29 0 0
sp_qd_handle_on_cancel_clo 1849 1849 0 0.00 0 13706 335 0 0
sp_qd_handle_on_invoice_upd_sts 10425 10425 0 0.00 0 34926 313 0 0
sp_qd_handle_on_reserve_upd_sts 10163 10163 0 0.00 0 18267 63 0 0
sp_reserve_write_off 10163 10163 0 0.00 6 18281 176 0 0
sp_supplier_invoice 5645 5645 0 0.00 5 62384 2963 0 0
sp_supplier_order 5487 5487 0 0.00 5 33665 1125 0 0
srv_aggregate_perf_data 99 99 0 0.00 2298 198396 20744 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1554 138467 45874 0 0
srv_find_qd_qs_mism 1039541 1039541 0 0.00 0 15640 6 0 0
srv_make_invnt_saldo 587 587 0 0.00 40 111369 1107 0 0
srv_make_money_saldo 790 790 0 0.00 1 2618 27 0 0
srv_recalc_idx_stat 4 4 0 0.00 208483 577326 353106 0 0
t$perf-abend:sp_add_invoice_to_stock 104 104 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 5000 5000 0 0.00 0 10567 191 0 0
t$perf-norm:sp_cancel_adding_invoice 401 401 0 0.00 0 14774 181 0 0
t$perf-norm:sp_cancel_client_order 1849 1849 0 0.00 0 5635 13 0 0
t$perf-norm:sp_cancel_customer_reserve 656 656 0 0.00 0 2296 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 703 703 0 0.00 0 2286 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 446 446 0 0.00 0 471 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 397 397 0 0.00 0 633 10 0 0
t$perf-norm:sp_cancel_supplier_order 403 403 0 0.00 0 17536 1368 0 0
t$perf-norm:sp_cancel_write_off 993 993 0 0.00 0 11617 23 0 0
t$perf-norm:sp_client_order 20599 20599 0 0.00 0 12982 19 0 0
t$perf-norm:sp_customer_reserve 997 997 0 0.00 0 3713 12 0 0
t$perf-norm:sp_pay_from_customer 7044 7044 0 0.00 0 5860 10 0 0
t$perf-norm:sp_pay_to_supplier 5856 5856 0 0.00 0 9105 14 0 0
t$perf-norm:sp_reserve_write_off 10163 10163 0 0.00 0 8058 12 0 0
t$perf-norm:sp_supplier_invoice 5645 5645 0 0.00 0 7378 26 0 0
t$perf-norm:sp_supplier_order 5487 5487 0 0.00 0 13013 16 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 275 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 7 1 0 0
t$perf-norm:srv_make_invnt_saldo 587 587 0 0.00 0 798 21 0 0
t$perf-norm:srv_make_money_saldo 790 790 0 0.00 0 2232 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10172 9964 208 2.04 0 8842 19 0 208
v_all_customers 37476 37476 0 0.00 0 28 0 0 0
v_all_suppliers 30628 30628 0 0.00 0 17 1 0 0
v_all_wares 41190 41190 0 0.00 0 35 1 0 0
v_cancel_adding_invoice 802 802 0 0.00 0 580 6 0 0
v_cancel_client_order 3698 3698 0 0.00 0 2076 4 0 0
v_cancel_customer_prepayment 1406 1406 0 0.00 0 325 3 0 0
v_cancel_customer_reserve 1312 1312 0 0.00 0 619 4 0 0
v_cancel_payment_to_supplier 892 892 0 0.00 0 2231 5 0 0
v_cancel_supplier_invoice 812 794 18 2.21 0 2351 17 0 18
v_cancel_supplier_order 806 806 0 0.00 0 384 3 0 0
v_cancel_write_off 1986 1986 0 0.00 0 3716 13 0 0
v_max_id_clo_ord 5487 5487 0 0.00 0 8066 3 0 0
v_max_id_clo_res 997 997 0 0.00 0 5105 17 0 0
v_max_id_ord_sup 5641 5641 0 0.00 0 3254 4 0 0
v_max_non_paid_invoice 1667 1667 0 0.00 0 522 2 0 0
v_max_non_paid_realizn 3829 3829 0 0.00 0 2039 4 0 0
v_min_id_clo_ord 5487 5487 0 0.00 0 5288 7 0 0
v_min_id_clo_res 997 997 0 0.00 0 2692 10 0 0
v_min_id_ord_sup 5641 5641 0 0.00 0 2740 5 0 0
v_min_non_paid_invoice 5856 5856 0 0.00 0 8617 20 0 0
v_min_non_paid_realizn 7044 7044 0 0.00 0 7980 19 0 0
v_our_firm 10144 10144 0 0.00 0 15 1 0 0
v_reserve_write_off 20326 20326 0 0.00 0 1631 3 0 0
x_lock_depdocs_on_canc_invoice 11109 11109 0 0.00 0 19441 111 0 0
x_lock_depdocs_on_canc_sup_ord 403 403 0 0.00 0 7004 324 0 0
x_make_qstorno_1000_1200 5487 5487 0 0.00 2 32854 1051 0 0
x_make_qstorno_1200_2000 5645 5645 0 0.00 2 60879 2836 0 0
x_make_qstorno_2100_3300 466684 466684 0 0.00 0 91776 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 01-12-2021 01:39:12 to 01-12-2021 01:39:16.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
23:08:00 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:01 34234 8192000000 9911969216 10226249728 992486016 10.806 100 89 178 0 1021680816 6173376 23021424
23:10:38 8635 8192000000 9950520160 10274729984 1034859856 11.215 100 89 178 0 1065756240 8186800 22709584
23:11:53 20015 8192000000 9980634080 10315669504 1063713232 11.492 100 75 150 0 1091677072 9106832 18857008
23:13:31 10517 8192000000 10058027392 10406424576 1141123872 12.226 100 85 170 2 1172688144 11425248 20139024
23:14:47 9980 8192000000 10093506224 10459148288 1183817232 12.626 100 77 154 0 1212786224 11776672 17192320
23:16:00 6594 8192000000 10136531152 10511446016 1227470256 13.031 100 75 150 2 1260638560 16413344 16754960
23:17:10 2638 8192000000 10172642640 10554814464 1258643664 13.318 100 80 161 0 1290023392 13480176 17899552
23:18:15 2105 8192000000 10206205616 10586112000 1284871296 13.557 100 96 192 0 1321371504 14967360 21532848
23:19:19 1536 8192000000 10216316320 10602426368 1299691232 13.692 100 88 174 0 1332117520 13122528 19303760
23:20:23 3992 8192000000 10221156096 10608971776 1308007552 13.768 100 75 151 1 1337341712 13047120 16287040
23:21:29 8736 8192000000 10209821696 10617860096 1314081936 13.823 100 48 95 1 1334811280 10080000 10649344
23:22:42 6090 8192000000 10199636816 10615816192 1317309312 13.852 100 21 42 0 1327881120 6161904 4409904
23:23:49 6748 8192000000 10206075040 10625232896 1323298080 13.907 100 25 51 0 1335149520 5646224 6205216
23:24:56 14925 8192000000 10219620736 10629476352 1329718112 13.965 100 17 34 2 1339684704 6080896 3885696
23:26:13 28422 8192000000 10254917120 10656702464 1334564400 14.008 100 71 142 1 1357258944 5774272 16920272
23:27:42 5180 8192000000 10232413168 10649972736 1326259120 13.933 99 36 72 0 1340921744 6907552 7755072
23:28:50 44150 8192000000 10204335056 10612359168 1273499328 13.454 96 28 56 3 1284872480 4709088 6664064
23:30:34 5850 8192000000 10136331568 10533814272 1214096672 12.907 96 31 60 1 1226793776 5546864 7150240
23:31:43 6630 8192000000 10101063872 10492858368 1199743472 12.774 96 23 46 1 1211747840 5947376 6056992
23:32:51 13204 8192000000 10140648224 10544410624 1250123392 13.239 99 25 49 3 1261395216 5299824 5972000
23:34:07 6845 8192000000 10146812512 10558644224 1265575600 13.381 100 31 62 2 1278704688 5963552 7165536
23:35:15 7048 8192000000 10179157776 10587512832 1284881600 13.558 100 41 81 1 1301205984 6422976 9901408
23:36:33 9018 8192000000 10190389840 10607063040 1298905168 13.685 100 19 38 2 1309376768 5842896 4628704
23:37:42 20121 8192000000 10207251056 10618294272 1307220624 13.761 100 27 54 1 1319334608 6378064 5735920
23:39:08 11933 8192000000 10232344736 10641600512 1326202528 13.933 100 55 109 1 1345903488 7507648 12193312
23:40:24 12575 8192000000 10253781680 10669633536 1345527552 14.107 100 43 86 1 1365113680 10487616 9098512
23:41:39 9704 8192000000 10274352912 10688630784 1359564832 14.233 100 50 100 1 1383493824 12289488 11639504
23:42:57 4159 8192000000 10299528480 10715295744 1382308240 14.437 100 69 136 4 1413854192 15223360 16322592
23:44:14 15983 8192000000 10311866816 10737446912 1395678064 14.556 100 46 92 0 1426709424 19930496 11100864
23:45:37 9279 8192000000 10320170096 10741997568 1401129536 14.605 100 51 101 1 1431039424 18242944 11666944
23:46:52 11103 8192000000 10327259376 10752319488 1408539008 14.671 100 61 122 0 1443653024 20302528 14811488
23:48:12 6080 8192000000 10330700000 10755862528 1407823056 14.665 100 60 118 1 1445395120 22856272 14715792
23:49:23 13448 8192000000 10350876368 10775539712 1413245472 14.713 100 68 136 5 1461832912 31524592 17062848
23:50:44 4472 8192000000 10357371008 10777149440 1409158064 14.676 99 80 160 2 1453957552 25914448 18885040
23:51:55 1575 8192000000 10371295536 10783477760 1419199232 14.766 100 85 169 0 1473406560 33828048 20379280
23:53:02 1554 8192000000 10361450768 10771922944 1408833936 14.674 100 85 167 0 1462246448 32873424 20539088
23:54:08 4979 8192000000 10352597680 10765647872 1401040096 14.604 100 81 163 3 1460360592 39341712 19978784
23:55:17 2102 8192000000 10334588176 10748997632 1382073600 14.435 100 73 143 2 1442841968 41907088 18861280
23:56:24 2416 8192000000 10340698528 10747174912 1376545392 14.386 99 70 137 0 1439483632 45363552 17574688
23:57:29 14084 8192000000 10324369248 10750115840 1373795536 14.361 100 37 74 2 1433384976 49481360 10108080
23:58:52 17618 8192000000 10301089632 10732711936 1360954288 14.246 99 33 66 2 1404975056 34451200 9569568
00:00:15 17559 8192000000 10321467152 10755551232 1377335568 14.393 100 32 64 1 1432164928 45582912 9246448
00:02:01 12939 8192000000 10315952320 10748526592 1345209872 14.104 97 32 64 2 1396764576 43250576 8304128
00:03:19 30219 8192000000 10303157952 10743357440 1341036928 14.067 99 23 44 1 1397041632 49570752 6433952
00:04:51 44056 8192000000 10294460672 10744573952 1352328032 14.168 100 19 38 2 1401568608 44018048 5222528
00:06:36 106254 8192000000 10256250096 10686103552 1296047472 13.659 96 20 39 1 1318387648 17715680 4624496
00:09:28 138467 8192000000 10257299280 10709573632 1318418272 13.862 97 7 14 0 1331169584 11683072 1068240
00:12:48 41040 8192000000 10266672784 10702336000 1340499168 14.062 98 15 29 3 1353776592 10223104 3054320
00:14:30 30918 8192000000 10277615744 10724503552 1361868896 14.254 100 24 47 0 1383506800 16531232 5106672
00:16:04 95250 8192000000 10272176320 10731122688 1372362784 14.348 100 8 16 0 1381963552 7948480 1652288
00:19:24 71601 8192000000 10279567504 10735964160 1379800272 14.415 100 14 28 0 1396340592 13001984 3538336
00:23:10 46870 8192000000 10288776160 10741452800 1368199360 14.311 99 17 34 1 1384035632 11723664 4112608
00:25:01 67728 8192000000 10309370336 10761101312 1391280544 14.517 100 16 32 1 1410468656 15467456 3720656
00:27:09 90271 8192000000 10281981296 10730684416 1336146336 14.023 96 12 24 0 1349532880 10968944 2417600
00:29:46 84510 8192000000 10297097440 10753908736 1353754192 14.181 97 20 40 0 1372259136 14081280 4423664
00:32:11 136224 8192000000 10288374752 10748715008 1326599344 13.936 95 11 23 1 1344837856 15790464 2448048
00:35:28 79842 8192000000 10294108800 10744926208 1378169264 14.400 99 25 50 1 1394273376 10067744 6036368
00:38:10 57143 8192000000 10308244000 10769162240 1395112656 14.551 100 18 36 0 1413085824 14301440 3671728
00:40:08 59031 8192000000 10267731424 10723975168 1349248000 14.141 97 13 26 0 1361696944 9716048 2732896
00:42:08 58634 8192000000 10289825552 10755436544 1390103392 14.507 100 12 22 1 1403872560 11223136 2546032
00:44:07 43084 8192000000 10283864816 10739941376 1342685376 14.082 97 18 33 1 1361517232 15084784 3747072
00:46:06 75312 8192000000 10239861904 10688139264 1247162864 13.212 92 13 26 0 1260460384 10564256 2733264
00:48:28 51543 8192000000 10282673232 10740887552 1370917200 14.335 100 23 46 0 1395807744 19220832 5669712
00:50:37 83476 8192000000 10269725920 10726998016 1321889328 13.894 97 14 28 0 1340238368 15053696 3295344
00:53:01 73975 8192000000 10270071664 10719170560 1317541072 13.854 96 26 51 0 1338245872 14967024 5737776
00:55:15 29743 8192000000 10282973248 10720153600 1334353248 14.006 98 28 56 1 1356147712 15776416 6018048
00:56:50 82763 8192000000 10276217632 10709741568 1338145072 14.041 98 27 54 1 1354623504 10867360 5611072
00:59:14 81190 8192000000 10260792560 10713321472 1333766896 14.001 98 19 38 0 1346422656 7630640 5025120
01:01:40 9388 8192000000 10266099664 10709262336 1358835664 14.227 100 27 53 0 1374259280 9339072 6084544
01:02:49 37259 8192000000 10258134656 10705858560 1341123632 14.068 98 19 38 0 1353519792 8136800 4259360
01:04:41 62395 8192000000 10280115136 10735910912 1379434576 14.411 100 16 33 0 1395253792 11812864 4006352
01:06:44 68013 8192000000 10266201696 10728898560 1362962912 14.264 99 9 18 1 1377573008 12418688 2191408
01:09:01 51257 8192000000 10293217024 10753212416 1392164032 14.525 100 18 36 0 1410541760 14542432 3835296
01:10:56 81645 8192000000 10310201184 10776121344 1397576608 14.573 100 13 26 0 1419657632 19125152 2955872
01:13:26 60201 8192000000 10303401936 10775281664 1395632208 14.556 99 22 42 1 1412125504 11541712 4951584
01:15:27 66265 8192000000 10299927648 10756206592 1373026784 14.354 98 12 24 0 1385047392 9671040 2349568
01:17:34 29474 8192000000 10324787696 10782191616 1406717632 14.655 100 22 44 2 1424917184 13516848 4682704
01:19:11 45397 8192000000 10326224000 10773692416 1377830096 14.397 98 30 60 0 1402630304 17563200 7237008
01:21:07 29328 8192000000 10321999376 10769276928 1388773232 14.495 99 36 72 0 1408369232 11161680 8434320
01:22:42 107001 8192000000 10298232448 10758160384 1368616880 14.315 98 28 56 0 1387660496 13003904 6039712
01:25:29 56771 8192000000 10295949232 10742476800 1370440912 14.331 99 27 54 0 1382428288 6261648 5725728
01:27:27 29644 8192000000 10299616688 10755604480 1392685664 14.530 100 23 46 1 1409458864 11522544 5250656
01:29:04 50616 8192000000 10268680896 10737737728 1353319104 14.177 98 6 12 0 1364781792 10158848 1303840
01:30:57 80235 8192000000 10270445936 10729123840 1327809040 13.947 96 14 28 0 1340480512 9262144 3409328
01:34:07 60239 8192000000 10279635056 10736246784 1354128720 14.185 98 10 20 1 1369297152 12774752 2393680
01:36:07 36147 8192000000 10287147120 10734747648 1356099568 14.202 98 29 56 1 1369159216 7233680 5825968
01:37:45 21836 8192000000 9183778528 9442754560 345881712 4.051 26 6 10 1 355688032 8496288 1310032

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 01-12-2021 01:39:18 to 01-12-2021 01:39:33.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 104 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 01-12-2021 01:39:34 to 01-12-2021 01:39:36.

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          240177
MON$OLDEST_ACTIVE               240178
MON$OLDEST_SNAPSHOT             240178
MON$NEXT_TRANSACTION            240178
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                       2026400
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.33540 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33540 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33540 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33540 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 ---
:637: 
:638: mscs-test-161.budzdorov.ru    Wed Dec  1 01:38:11 2021
:639:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:640: 
:641: 
:642: mscs-test-161.budzdorov.ru    Wed Dec  1 01:38:14 2021
:643:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:644: 
--- end of diff output ---

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

01.12.21 01:39:37. 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-01 01:39:39 No severe PSQL-related problems occured
01.12.21 01:39:40. End of report.