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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-12-20 01:37:55
DTS_END                         2021-12-20 01:37:55.9220
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/48046
TRN_ID                          248541
ATT_ID                          700
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: 9616.44 17609 1153973
customer order: creation 183.20 338 21984
customer order: refuse 16.02 330 1922
order to supplier: creation 48.31 1169 5797
order to supplier: removal 3.70 76820 444
invoice (draft): creation 49.13 3229 5896
invoice (draft): removal 55.72 614 6686
invoice accept: apply 44.08 15338 5289
invoice accept: cancel 49.87 670 5984
customer reserve: creation 3997.81 155 479737
customer reserve: removal 4856.28 46 582754
realization accept: apply 88.43 155 10611
realization accept: cancel 87.32 91 10478
payment from customer: creation 62.82 117 7538
payment from customer: removal 5.73 66 688
payment to supplier: creation 51.98 107 6238
payment to supplier: removal 3.70 81 444
service: total inventory turnovers 5.07 1110 608
service: total monetary turnovers 6.50 68 780
service: aggregate perf. data 0.76 21858 91
service: refresh index statistics 0.03 229823 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-12-2021 01:38:33 to 20-12-2021 01:38:35.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 388
TEST_TIME 2 2373
TEST_TIME 3 3773
TEST_TIME 4 4054
TEST_TIME 5 4037
TEST_TIME 6 4225
TEST_TIME 7 4254
TEST_TIME 8 4629
TEST_TIME 9 5029
TEST_TIME 10 5586
TEST_TIME 11 5742
TEST_TIME 12 6060
TEST_TIME 13 6637
TEST_TIME 14 7226
TEST_TIME 15 7091
TEST_TIME 16 7579
TEST_TIME 17 8103
TEST_TIME 18 8390
TEST_TIME 19 8746
TEST_TIME 20 8889
TEST_TIME 21 9301
TEST_TIME 22 9681
TEST_TIME 23 10155
TEST_TIME 24 10215
TEST_TIME 25 10172
TEST_TIME 26 10207
TEST_TIME 27 10501
TEST_TIME 28 10601
TEST_TIME 29 10682
TEST_TIME 30 10500
TEST_TIME 31 10432
TEST_TIME 32 10505
TEST_TIME 33 10502
TEST_TIME 34 10365
TEST_TIME 35 10345
TEST_TIME 36 10407
TEST_TIME 37 10474
TEST_TIME 38 10242
TEST_TIME 39 10149
TEST_TIME 40 10174
TEST_TIME 41 10231
TEST_TIME 42 10030
TEST_TIME 43 9906
TEST_TIME 44 9980
TEST_TIME 45 10037
TEST_TIME 46 10002
TEST_TIME 47 10071
TEST_TIME 48 9927
TEST_TIME 49 9913
TEST_TIME 50 9864
TEST_TIME 51 9839
TEST_TIME 52 9883
TEST_TIME 53 9784
TEST_TIME 54 9724
TEST_TIME 55 9660
TEST_TIME 56 9653
TEST_TIME 57 9544
TEST_TIME 58 9449
TEST_TIME 59 9463
TEST_TIME 60 9554
TEST_TIME 61 9503
TEST_TIME 62 9522
TEST_TIME 63 9534
TEST_TIME 64 9597
TEST_TIME 65 9756
TEST_TIME 66 9744
TEST_TIME 67 9733
TEST_TIME 68 9851
TEST_TIME 69 9942
TEST_TIME 70 10001
TEST_TIME 71 10027
TEST_TIME 72 9972
TEST_TIME 73 9912
TEST_TIME 74 9856
TEST_TIME 75 9819
TEST_TIME 76 9804
TEST_TIME 77 9828
TEST_TIME 78 9767
TEST_TIME 79 9758
TEST_TIME 80 9711
TEST_TIME 81 9624
TEST_TIME 82 9573
TEST_TIME 83 9637
TEST_TIME 84 9591
TEST_TIME 85 9601
TEST_TIME 86 9596
TEST_TIME 87 9633
TEST_TIME 88 9670
TEST_TIME 89 9686
TEST_TIME 90 9640
TEST_TIME 91 9769
TEST_TIME 92 9750
TEST_TIME 93 9738
TEST_TIME 94 9806
TEST_TIME 95 9830
TEST_TIME 96 9836
TEST_TIME 97 9816
TEST_TIME 98 9747
TEST_TIME 99 9753
TEST_TIME 100 9689
TEST_TIME 101 9659
TEST_TIME 102 9657
TEST_TIME 103 9617
TEST_TIME 104 9619
TEST_TIME 105 9601
TEST_TIME 106 9543
TEST_TIME 107 9500
TEST_TIME 108 9522
TEST_TIME 109 9550
TEST_TIME 110 9533
TEST_TIME 111 9585
TEST_TIME 112 9547
TEST_TIME 113 9579
TEST_TIME 114 9572
TEST_TIME 115 9589
TEST_TIME 116 9619
TEST_TIME 117 9588
TEST_TIME 118 9608
TEST_TIME 119 9584
TEST_TIME 120 9623

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 20-12-2021 01:38:36 to 20-12-2021 01:38:40.

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 1600415 1600415 0 0.00 0 21630 5 0 0
doc_list_biud 1600415 1600415 0 0.00 0 34292 22 0 0
sp_add_invoice_to_stock 5359 5289 70 1.30 33 200612 15338 0 70
sp_cancel_adding_invoice 5984 5984 0 0.00 5 96748 671 0 0
sp_cancel_client_order 1922 1922 0 0.00 4 11999 331 0 0
sp_cancel_customer_reserve 582754 582754 0 0.00 1 26359 47 0 0
sp_cancel_pay_from_customer 688 688 0 0.00 3 3957 67 0 0
sp_cancel_pay_to_supplier 444 444 0 0.00 2 5761 81 0 0
sp_cancel_supplier_invoice 6691 6686 5 0.07 2 13798 615 0 5
sp_cancel_supplier_order 444 444 0 0.00 8 883613 76820 0 0
sp_cancel_write_off 10478 10478 0 0.00 1 14055 91 0 0
sp_client_order 21984 21984 0 0.00 3 24806 339 0 0
sp_customer_reserve 479737 479737 0 0.00 0 43041 155 0 0
sp_fill_shopping_cart 33666 33666 0 0.00 1 17574 35 0 0
sp_fill_shopping_cart_clo_res 479730 479730 0 0.00 0 20645 11 0 0
sp_get_clo_for_invoice 5279 5279 0 0.00 0 18455 281 0 0
sp_kill_cost_storno 17594 17594 0 0.00 0 12302 44 0 0
sp_kill_qstorno_ret_qs2qd 1183116 1183116 0 0.00 0 26355 25 0 0
sp_kill_qty_storno 624157 624157 0 0.00 0 34292 56 0 0
sp_lock_selected_doc 22064 22064 0 0.00 0 5914 3 0 0
sp_make_cost_storno 29665 29665 0 0.00 0 17015 46 0 0
sp_multiply_rows_for_pdistr 29665 29665 0 0.00 0 7254 26 0 0
sp_multiply_rows_for_qdistr 21984 21984 0 0.00 0 15723 159 0 0
sp_pay_from_customer 7538 7538 0 0.00 4 19806 117 0 0
sp_pay_to_supplier 6238 6238 0 0.00 5 11337 108 0 0
sp_payment_common 13776 13776 0 0.00 1 18329 26 0 0
sp_qd_handle_on_cancel_clo 1922 1922 0 0.00 0 11988 303 0 0
sp_qd_handle_on_invoice_upd_sts 11262 11262 0 0.00 0 34291 276 0 0
sp_qd_handle_on_reserve_upd_sts 10611 10611 0 0.00 0 14424 57 0 0
sp_reserve_write_off 10611 10611 0 0.00 6 14461 155 0 0
sp_supplier_invoice 5896 5896 0 0.00 5 109159 3230 0 0
sp_supplier_order 5797 5797 0 0.00 5 27782 1170 0 0
srv_aggregate_perf_data 91 91 0 0.00 2950 318612 21858 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1208 144148 48764 0 0
srv_find_qd_qs_mism 1104840 1104840 0 0.00 0 21629 5 0 0
srv_make_invnt_saldo 608 608 0 0.00 43 45090 1110 0 0
srv_make_money_saldo 780 780 0 0.00 0 17985 68 0 0
srv_recalc_idx_stat 4 4 0 0.00 106318 387763 229823 0 0
t$perf-abend:sp_add_invoice_to_stock 70 70 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5289 5289 0 0.00 0 17541 144 0 0
t$perf-norm:sp_cancel_adding_invoice 426 426 0 0.00 0 3124 72 0 0
t$perf-norm:sp_cancel_client_order 1922 1922 0 0.00 0 11646 21 0 0
t$perf-norm:sp_cancel_customer_reserve 734 734 0 0.00 0 10885 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 688 688 0 0.00 0 13872 26 0 0
t$perf-norm:sp_cancel_pay_to_supplier 444 444 0 0.00 0 1194 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 413 413 0 0.00 0 607 12 0 0
t$perf-norm:sp_cancel_supplier_order 444 444 0 0.00 0 18884 1396 0 0
t$perf-norm:sp_cancel_write_off 1104 1104 0 0.00 0 4406 16 0 0
t$perf-norm:sp_client_order 21984 21984 0 0.00 0 15139 19 0 0
t$perf-norm:sp_customer_reserve 1051 1051 0 0.00 0 1220 5 0 0
t$perf-norm:sp_pay_from_customer 7538 7538 0 0.00 0 21495 13 0 0
t$perf-norm:sp_pay_to_supplier 6238 6238 0 0.00 0 21578 15 0 0
t$perf-norm:sp_reserve_write_off 10611 10611 0 0.00 0 13418 10 0 0
t$perf-norm:sp_supplier_invoice 5896 5896 0 0.00 0 4479 15 0 0
t$perf-norm:sp_supplier_order 5797 5797 0 0.00 0 15872 19 0 0
t$perf-norm:srv_aggregate_perf_data 91 91 0 0.00 0 135 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 2761 81 0 0
t$perf-norm:srv_make_invnt_saldo 608 608 0 0.00 0 2067 26 0 0
t$perf-norm:srv_make_money_saldo 780 780 0 0.00 0 1867 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 73 20 0 0
v_add_invoice_to_stock 10696 10556 140 1.30 0 7996 17 0 140
v_all_customers 40130 40130 0 0.00 0 13 0 0 0
v_all_suppliers 32676 32676 0 0.00 0 16 1 0 0
v_all_wares 43968 43968 0 0.00 0 94 1 0 0
v_cancel_adding_invoice 852 852 0 0.00 0 3704 12 0 0
v_cancel_client_order 3844 3844 0 0.00 0 4632 7 0 0
v_cancel_customer_prepayment 1376 1376 0 0.00 0 875 4 0 0
v_cancel_customer_reserve 1468 1468 0 0.00 0 2092 8 0 0
v_cancel_payment_to_supplier 888 888 0 0.00 0 2239 10 0 0
v_cancel_supplier_invoice 836 826 10 1.19 0 4305 24 0 10
v_cancel_supplier_order 888 888 0 0.00 0 2109 5 0 0
v_cancel_write_off 2208 2208 0 0.00 0 3140 13 0 0
v_max_id_clo_ord 5797 5797 0 0.00 0 524 1 0 0
v_max_id_clo_res 1051 1051 0 0.00 0 5259 20 0 0
v_max_id_ord_sup 5885 5885 0 0.00 0 6262 5 0 0
v_max_non_paid_invoice 1582 1582 0 0.00 0 6249 6 0 0
v_max_non_paid_realizn 4004 4004 0 0.00 0 672 3 0 0
v_min_id_clo_ord 5797 5797 0 0.00 0 3018 8 0 0
v_min_id_clo_res 1051 1051 0 0.00 0 5109 10 0 0
v_min_id_ord_sup 5885 5885 0 0.00 0 6342 4 0 0
v_min_non_paid_invoice 6238 6238 0 0.00 0 11084 15 0 0
v_min_non_paid_realizn 7538 7538 0 0.00 0 8436 19 0 0
v_our_firm 10906 10906 0 0.00 0 23 1 0 0
v_reserve_write_off 21222 21222 0 0.00 0 6359 7 0 0
x_lock_depdocs_on_canc_invoice 12257 12257 0 0.00 0 18834 86 0 0
x_lock_depdocs_on_canc_sup_ord 444 444 0 0.00 0 4772 276 0 0
x_make_qstorno_1000_1200 5797 5797 0 0.00 2 27766 1097 0 0
x_make_qstorno_1200_2000 5896 5896 0 0.00 2 109136 3099 0 0
x_make_qstorno_2100_3300 479737 479737 0 0.00 0 43039 141 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-12-2021 01:38:41 to 20-12-2021 01:38:45.

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:07:59 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:00 20779 8192000000 9901265664 10210529280 983992848 10.723 100 90 180 0 1014132416 6362784 23776784
23:10:30 15133 8192000000 9933652240 10254323712 1017118000 11.044 100 90 180 0 1049592800 8734368 23740432
23:11:56 13992 8192000000 9972658096 10305892352 1057557712 11.433 100 85 170 0 1088962496 9690496 21714288
23:13:15 8802 8192000000 10035118800 10379825152 1111476624 11.946 100 79 158 0 1141687168 10940336 19270208
23:14:30 9232 8192000000 10067765600 10428801024 1158307792 12.387 100 79 157 1 1188888752 12334480 18246480
23:15:41 5812 8192000000 10111280096 10481102848 1204716384 12.820 100 73 146 1 1235087984 13942192 16429408
23:16:49 3400 8192000000 10157684464 10536890368 1246105840 13.202 100 86 171 1 1278166704 13188672 18872192
23:17:55 2762 8192000000 10186070112 10568818688 1276515792 13.481 100 91 182 1 1311228144 14681872 20030480
23:18:59 2088 8192000000 10221101504 10606862336 1300508384 13.700 100 93 184 4 1334132272 13982240 19641648
23:20:03 1582 8192000000 10227635568 10616160256 1309912240 13.785 100 87 171 1 1341137600 12815728 18409632
23:21:07 5839 8192000000 10215942624 10618986496 1317350000 13.853 100 52 103 1 1336382752 8303664 10729088
23:22:13 10973 8192000000 10208317696 10626363392 1323680752 13.910 100 31 62 0 1337156224 6135008 7340464
23:23:31 35309 8192000000 10214370112 10641522688 1330006832 13.967 100 29 58 1 1342401072 5767920 6626320
23:25:10 5568 8192000000 10230863120 10651561984 1336602464 14.027 100 23 46 0 1347890976 6532320 4756192
23:26:16 8266 8192000000 10228013392 10647810048 1333900592 14.002 100 31 61 1 1346817360 5886704 7030064
23:27:26 19650 8192000000 10231124688 10638913536 1315367168 13.835 99 42 84 2 1330864304 5865600 9631536
23:29:18 7370 8192000000 10188977632 10595221504 1246793392 13.209 95 29 58 0 1259707776 6622352 6292032
23:30:26 6596 8192000000 10173845280 10572230656 1233573552 13.087 96 31 60 0 1247171568 6663984 6934032
23:31:35 9932 8192000000 10125913152 10519654400 1188993824 12.674 96 28 56 0 1201636096 4669936 7972336
23:32:46 67861 8192000000 10165081232 10571051008 1260870080 13.338 99 41 82 1 1277423200 6240272 10312848
23:34:54 9365 8192000000 10171811456 10581467136 1282357584 13.535 100 29 58 0 1295242512 6226256 6658672
23:36:07 11810 8192000000 10196286160 10608254976 1299583968 13.691 100 18 36 0 1310239936 5170736 5485232
23:37:20 9084 8192000000 10200853760 10614775808 1308403024 13.772 100 21 42 2 1318873024 5420864 5049136
23:38:32 84993 8192000000 10230267376 10648018944 1334861408 14.011 100 33 65 1 1350405424 7785088 7758928
23:41:01 16998 8192000000 10274942224 10686509056 1362396304 14.259 100 59 117 0 1387605840 11779072 13430464
23:42:25 26492 8192000000 10276271536 10697326592 1358104896 14.220 99 40 80 1 1382752576 14889216 9758464
23:44:14 7437 8192000000 10299870064 10716315648 1383757008 14.450 100 52 104 0 1413148800 16650512 12741280
23:45:28 4946 8192000000 10310454272 10729209856 1388727472 14.495 100 66 132 1 1424850096 19686544 16436080
23:46:41 4781 8192000000 10336969888 10755563520 1408026704 14.666 100 66 128 0 1447840672 24431872 15382096
23:47:57 4261 8192000000 10329840176 10747211776 1402671264 14.619 100 62 120 1 1439313856 21606432 15036160
23:49:07 10013 8192000000 10345180272 10763612160 1412314112 14.704 100 70 140 0 1456122576 26901216 16907248
23:50:22 1605 8192000000 10331802816 10739576832 1377549312 14.395 99 88 175 1 1428951760 29848288 21554160
23:51:26 11784 8192000000 10312895232 10734809088 1378536928 14.403 100 45 89 1 1419624192 29706448 11380816
23:53:03 1205 8192000000 10316425408 10716856320 1357069760 14.211 99 82 164 1 1414786496 37520704 20196032
23:54:06 12129 8192000000 10306013840 10734018560 1365005184 14.282 99 29 58 2 1405668592 32380064 8283344
23:55:24 16358 8192000000 10280262080 10708508672 1326970144 13.940 97 33 66 3 1365839168 29841200 9027824
23:56:44 2681 8192000000 10295443088 10710130688 1356277760 14.204 100 64 127 2 1398289184 26247200 15764224
23:57:52 7552 8192000000 10299302512 10719293440 1360253856 14.240 100 47 93 1 1401919696 29529872 12135968
23:59:04 20958 8192000000 10289017968 10726469632 1362695856 14.262 100 32 63 3 1401593408 30165312 8732240
00:00:27 27472 8192000000 10297926352 10733555712 1358228864 14.221 99 23 46 1 1388531504 24003696 6298944
00:01:55 6284 8192000000 10268156752 10701791232 1341640784 14.072 99 43 86 1 1369309264 16983984 10684496
00:03:04 73118 8192000000 10253208800 10708504576 1284684112 13.556 95 10 20 0 1320881040 33720816 2476112
00:05:19 80750 8192000000 10252535472 10696810496 1320195744 13.878 98 7 14 0 1338459168 16487312 1776112
00:07:41 109125 8192000000 10279624592 10718416896 1324626272 13.919 97 19 38 0 1341519824 12956272 3937280
00:10:55 59388 8192000000 10295604496 10737401856 1369287168 14.321 99 18 37 0 1385121200 11771808 4062224
00:13:38 77127 8192000000 10293139328 10753323008 1363087584 14.265 98 7 14 0 1378316768 13571744 1657440
00:15:58 88428 8192000000 10312046608 10765238272 1399939664 14.594 100 30 59 1 1417979344 10671872 7367808
00:18:34 107660 8192000000 10311638800 10765041664 1392425536 14.528 99 24 48 1 1410863312 12303600 6134176
00:21:27 51963 8192000000 10321736896 10770337792 1403841840 14.629 100 27 53 0 1423243104 12757584 6643680
00:23:26 23733 8192000000 10307942480 10753044480 1380076832 14.417 98 35 70 1 1404024592 15613920 8333840
00:25:06 11668 8192000000 10310078720 10772283392 1397193504 14.570 100 28 56 3 1418159920 14037664 6928752
00:26:19 47374 8192000000 10311596624 10777182208 1393766432 14.539 99 28 56 0 1420212128 19303552 7142144
00:28:07 46658 8192000000 10297232768 10766901248 1380187856 14.418 99 7 14 0 1398886928 16638576 2060496
00:30:05 69419 8192000000 10293751408 10753912832 1380958800 14.425 99 22 44 0 1391740128 6011616 4769712
00:32:25 25509 8192000000 10296436480 10751954944 1388133424 14.489 100 35 69 1 1405695536 8815792 8746320
00:33:51 40173 8192000000 10274724944 10736033792 1360954272 14.246 98 8 16 0 1372581216 9615440 2011504
00:36:21 50165 8192000000 10262434064 10729324544 1305854112 13.748 95 11 22 1 1321041280 12288224 2898944
00:38:40 22168 8192000000 10286287424 10728194048 1357217408 14.212 99 54 108 2 1379835696 11023440 11594848
00:40:06 72424 8192000000 10280030864 10740211712 1326509264 13.936 97 10 20 0 1352469520 23176240 2784016
00:42:25 78600 8192000000 10261422384 10695364608 1245728160 13.199 91 18 36 2 1265953552 16301456 3923936
00:44:57 78582 8192000000 10288010368 10746724352 1315467152 13.836 96 14 28 1 1348585568 29666000 3452416
00:47:16 144148 8192000000 10269618208 10712899584 1287032784 13.577 94 15 30 0 1305410208 15304672 3072752
00:50:41 130360 8192000000 10279051088 10733998080 1351721808 14.163 99 23 46 2 1371489504 14044048 5723648
00:53:52 29853 8192000000 10283580464 10739216384 1379210528 14.409 100 25 50 4 1394418432 9887552 5320352
00:55:32 42652 8192000000 10274196032 10751164416 1357912992 14.219 98 5 10 0 1373282032 14259008 1110032
00:57:16 64254 8192000000 10269080096 10731700224 1327493952 13.945 96 11 22 0 1339468752 9288400 2686400
00:59:57 75839 8192000000 10261975168 10732765184 1345548304 14.107 98 10 20 1 1360656448 12919488 2188656
01:02:16 38090 8192000000 10278432592 10728243200 1360034656 14.238 99 32 62 0 1377549696 10257120 7257920
01:03:55 75705 8192000000 10282300848 10742689792 1352478832 14.170 98 16 32 0 1369799680 13487616 3833232
01:06:14 91173 8192000000 10297535600 10746834944 1364064112 14.274 98 16 32 1 1380921696 13133712 3723872
01:08:58 60559 8192000000 10299091424 10754674688 1384363552 14.456 99 17 34 1 1399511584 11450768 3697264
01:11:08 29827 8192000000 10305761104 10766802944 1380528352 14.421 99 21 42 0 1401552592 16465696 4558544
01:12:44 135998 8192000000 10296393088 10745184256 1347938880 14.129 96 12 24 0 1365730720 15022064 2769776
01:16:25 46150 8192000000 10301339552 10753609728 1384432016 14.456 99 26 52 0 1397636800 7819600 5385184
01:18:18 28091 8192000000 10296610416 10744745984 1374620352 14.368 99 25 49 3 1394473952 14622448 5231152
01:19:47 84896 8192000000 10284006736 10761781248 1377998784 14.399 99 14 27 1 1397126448 15691664 3436000
01:22:13 80422 8192000000 10273466384 10734379008 1333560224 13.999 96 17 33 0 1347545232 10219120 3765888
01:24:34 68143 8192000000 10291544560 10747228160 1348285840 14.132 98 17 33 0 1363034272 11055952 3692480
01:26:43 70147 8192000000 10263991856 10720387072 1282968768 13.540 93 17 32 2 1302323104 15745600 3608736
01:28:53 61897 8192000000 10273173600 10714288128 1277122848 13.487 93 26 52 0 1290833936 7742320 5968768
01:30:56 35319 8192000000 10280211264 10736652288 1360982560 14.246 100 21 42 0 1377389456 11709136 4697760
01:32:34 54416 8192000000 10262465456 10732384256 1339430096 14.052 98 12 24 0 1358756304 16542672 2783536
01:34:29 17351 8192000000 10235424080 10665668608 1303057504 13.723 98 36 73 3 1320442064 9613952 7770608
01:35:46 41356 8192000000 10254548784 10688688128 1326428272 13.935 99 29 58 1 1343323008 10923872 5970864
01:37:28 34697 8192000000 9071532304 9291304960 234225664 2.779 18 5 10 0 237825200 2679600 919936

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 20-12-2021 01:38:47 to 20-12-2021 01:39:01.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 70 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 20-12-2021 01:39:02 to 20-12-2021 01:39:04.

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          248815
MON$OLDEST_ACTIVE               248816
MON$OLDEST_SNAPSHOT             248816
MON$NEXT_TRANSACTION            248816
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                       2053984
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33546 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33546 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33546 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33546 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:149: 
:150: mscs-test-161.budzdorov.ru    Mon Dec 20 01:38:05 2021
:151:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:152: 
--- end of diff output ---

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

20.12.21 01:39:05. 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-20 01:39:07 No severe PSQL-related problems occured
20.12.21 01:39:09. End of report.