Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.02.2021 10:28
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 e0e7b46250ba4f9795ceb55e0face73f
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 2020-09-25 13:42
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.00
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 16283440 kB
MemAvailable 31161016 kB
Buffers 0 kB
Cached 15418744 kB
SwapCached 3904 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 4.0.0
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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-02-20 10:26:57
DTS_END                         2021-02-20 10:26:57.8290
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(68:17)
IP                              ::1/57282
TRN_ID                          230451
ATT_ID                          663
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: 8902.12 24279 1068254
customer order: creation 168.78 432 20253
customer order: refuse 14.75 392 1770
order to supplier: creation 45.22 1610 5426
order to supplier: removal 3.29 135391 395
invoice (draft): creation 45.68 3996 5481
invoice (draft): removal 50.47 1136 6056
invoice accept: apply 40.75 18949 4890
invoice accept: cancel 45.12 1054 5414
customer reserve: creation 3743.90 185 449268
customer reserve: removal 4463.08 78 535569
realization accept: apply 81.42 191 9770
realization accept: cancel 73.46 129 8815
payment from customer: creation 58.49 121 7019
payment from customer: removal 5.19 124 623
payment to supplier: creation 48.55 127 5826
payment to supplier: removal 3.45 86 414
service: total inventory turnovers 3.55 3968 426
service: total monetary turnovers 6.09 713 731
service: aggregate perf. data 0.87 18697 104
service: refresh index statistics 0.03 298192 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-02-2021 10:29:04 to 20-02-2021 10:29:06.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4282
TEST_TIME 2 3449
TEST_TIME 3 3084
TEST_TIME 4 3830
TEST_TIME 5 4325
TEST_TIME 6 4840
TEST_TIME 7 4887
TEST_TIME 8 4993
TEST_TIME 9 5512
TEST_TIME 10 5583
TEST_TIME 11 5530
TEST_TIME 12 5509
TEST_TIME 13 5745
TEST_TIME 14 5830
TEST_TIME 15 5984
TEST_TIME 16 6054
TEST_TIME 17 6013
TEST_TIME 18 6113
TEST_TIME 19 6407
TEST_TIME 20 6571
TEST_TIME 21 7091
TEST_TIME 22 7392
TEST_TIME 23 7696
TEST_TIME 24 8088
TEST_TIME 25 8009
TEST_TIME 26 8051
TEST_TIME 27 8238
TEST_TIME 28 8806
TEST_TIME 29 9528
TEST_TIME 30 9950
TEST_TIME 31 10245
TEST_TIME 32 10603
TEST_TIME 33 10462
TEST_TIME 34 10368
TEST_TIME 35 10551
TEST_TIME 36 10322
TEST_TIME 37 10188
TEST_TIME 38 9939
TEST_TIME 39 9959
TEST_TIME 40 9755
TEST_TIME 41 9756
TEST_TIME 42 9698
TEST_TIME 43 9615
TEST_TIME 44 9609
TEST_TIME 45 9684
TEST_TIME 46 9678
TEST_TIME 47 9558
TEST_TIME 48 9533
TEST_TIME 49 9480
TEST_TIME 50 9557
TEST_TIME 51 9443
TEST_TIME 52 9497
TEST_TIME 53 9588
TEST_TIME 54 9558
TEST_TIME 55 9575
TEST_TIME 56 9610
TEST_TIME 57 9590
TEST_TIME 58 9642
TEST_TIME 59 9649
TEST_TIME 60 9688
TEST_TIME 61 9644
TEST_TIME 62 9608
TEST_TIME 63 9512
TEST_TIME 64 9425
TEST_TIME 65 9393
TEST_TIME 66 9295
TEST_TIME 67 9221
TEST_TIME 68 9164
TEST_TIME 69 9192
TEST_TIME 70 9167
TEST_TIME 71 9156
TEST_TIME 72 9155
TEST_TIME 73 9111
TEST_TIME 74 9073
TEST_TIME 75 9170
TEST_TIME 76 9206
TEST_TIME 77 9275
TEST_TIME 78 9286
TEST_TIME 79 9325
TEST_TIME 80 9289
TEST_TIME 81 9185
TEST_TIME 82 9167
TEST_TIME 83 9176
TEST_TIME 84 9175
TEST_TIME 85 9144
TEST_TIME 86 9095
TEST_TIME 87 9049
TEST_TIME 88 9068
TEST_TIME 89 9092
TEST_TIME 90 9046
TEST_TIME 91 9043
TEST_TIME 92 9013
TEST_TIME 93 9045
TEST_TIME 94 9090
TEST_TIME 95 9060
TEST_TIME 96 9053
TEST_TIME 97 9152
TEST_TIME 98 9123
TEST_TIME 99 9178
TEST_TIME 100 9124
TEST_TIME 101 9045
TEST_TIME 102 9024
TEST_TIME 103 8974
TEST_TIME 104 8964
TEST_TIME 105 8954
TEST_TIME 106 8915
TEST_TIME 107 8889
TEST_TIME 108 8878
TEST_TIME 109 8849
TEST_TIME 110 8877
TEST_TIME 111 8847
TEST_TIME 112 8916
TEST_TIME 113 9048
TEST_TIME 114 9073
TEST_TIME 115 9064
TEST_TIME 116 9052
TEST_TIME 117 8989
TEST_TIME 118 8940
TEST_TIME 119 8920
TEST_TIME 120 8902

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 20-02-2021 10:29:07 to 20-02-2021 10:29: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 1484044 1484044 0 0.00 0 34819 7 0 0
doc_list_biud 1484044 1484044 0 0.00 0 70192 36 0 0
sp_add_invoice_to_stock 4978 4890 88 1.76 45 192167 18949 0 88
sp_cancel_adding_invoice 5414 5414 0 0.00 5 87094 1055 0 0
sp_cancel_client_order 1770 1770 0 0.00 4 12901 392 0 0
sp_cancel_customer_reserve 535569 535569 0 0.00 1 34273 79 0 0
sp_cancel_pay_from_customer 623 623 0 0.00 3 6689 124 0 0
sp_cancel_pay_to_supplier 414 414 0 0.00 3 3126 86 0 0
sp_cancel_supplier_invoice 6062 6056 6 0.09 2 70196 1137 0 6
sp_cancel_supplier_order 395 395 0 0.00 7 1376717 135392 0 0
sp_cancel_write_off 8815 8815 0 0.00 1 20115 129 0 0
sp_client_order 20253 20253 0 0.00 4 16699 433 0 0
sp_customer_reserve 449268 449268 0 0.00 0 100904 185 0 0
sp_fill_shopping_cart 31153 31153 0 0.00 1 25214 44 0 0
sp_fill_shopping_cart_clo_res 449266 449266 0 0.00 0 31361 17 0 0
sp_get_clo_for_invoice 4888 4888 0 0.00 0 29399 365 0 0
sp_kill_cost_storno 15266 15266 0 0.00 0 4749 63 0 0
sp_kill_qstorno_ret_qs2qd 1086404 1086404 0 0.00 0 70190 43 0 0
sp_kill_qty_storno 572677 572677 0 0.00 0 70192 92 0 0
sp_lock_selected_doc 20292 20292 0 0.00 0 11769 6 0 0
sp_make_cost_storno 27503 27503 0 0.00 0 13873 53 0 0
sp_multiply_rows_for_pdistr 27503 27503 0 0.00 0 9368 35 0 0
sp_multiply_rows_for_qdistr 20253 20253 0 0.00 0 16692 252 0 0
sp_pay_from_customer 7019 7019 0 0.00 4 12738 121 0 0
sp_pay_to_supplier 5826 5826 0 0.00 5 12678 127 0 0
sp_payment_common 12845 12845 0 0.00 1 4756 21 0 0
sp_qd_handle_on_cancel_clo 1770 1770 0 0.00 0 12881 365 0 0
sp_qd_handle_on_invoice_upd_sts 10302 10302 0 0.00 0 48017 473 0 0
sp_qd_handle_on_reserve_upd_sts 9770 9770 0 0.00 0 19821 58 0 0
sp_reserve_write_off 9770 9770 0 0.00 7 22188 192 0 0
sp_supplier_invoice 5481 5481 0 0.00 7 95182 3997 0 0
sp_supplier_order 5426 5426 0 0.00 6 54437 1610 0 0
srv_aggregate_perf_data 104 104 0 0.00 3104 137592 18697 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1106 177666 41765 0 0
srv_find_qd_qs_mism 1021423 1021423 0 0.00 0 34784 6 0 0
srv_make_invnt_saldo 426 426 0 0.00 74 128988 3969 0 0
srv_make_money_saldo 731 731 0 0.00 1 13649 714 0 0
srv_recalc_idx_stat 4 4 0 0.00 196448 388545 298192 0 0
t$perf-abend:sp_add_invoice_to_stock 88 88 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4890 4890 0 0.00 0 27401 220 0 0
t$perf-norm:sp_cancel_adding_invoice 382 382 0 0.00 0 12789 134 0 0
t$perf-norm:sp_cancel_client_order 1770 1770 0 0.00 0 1833 12 0 0
t$perf-norm:sp_cancel_customer_reserve 698 698 0 0.00 0 2769 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 623 623 0 0.00 0 3116 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 414 414 0 0.00 0 1743 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 401 401 0 0.00 0 1983 25 0 0
t$perf-norm:sp_cancel_supplier_order 395 395 0 0.00 0 22227 1772 0 0
t$perf-norm:sp_cancel_write_off 952 952 0 0.00 0 2499 10 0 0
t$perf-norm:sp_client_order 20253 20253 0 0.00 0 20631 20 0 0
t$perf-norm:sp_customer_reserve 981 981 0 0.00 0 3894 8 0 0
t$perf-norm:sp_pay_from_customer 7019 7019 0 0.00 0 9643 11 0 0
t$perf-norm:sp_pay_to_supplier 5826 5826 0 0.00 0 11535 14 0 0
t$perf-norm:sp_reserve_write_off 9770 9770 0 0.00 0 5453 12 0 0
t$perf-norm:sp_supplier_invoice 5481 5481 0 0.00 0 7424 22 0 0
t$perf-norm:sp_supplier_order 5426 5426 0 0.00 0 12601 24 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 379 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 196 7 0 0
t$perf-norm:srv_make_invnt_saldo 426 426 0 0.00 0 1161 14 0 0
t$perf-norm:srv_make_money_saldo 731 731 0 0.00 0 767 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 94 27 0 0
v_add_invoice_to_stock 9950 9774 176 1.76 0 9589 18 0 176
v_all_customers 37146 37146 0 0.00 0 20 1 0 0
v_all_suppliers 29730 29730 0 0.00 0 26 1 0 0
v_all_wares 40506 40506 0 0.00 0 134 1 0 0
v_cancel_adding_invoice 764 764 0 0.00 0 353 7 0 0
v_cancel_client_order 3540 3540 0 0.00 0 3573 5 0 0
v_cancel_customer_prepayment 1246 1246 0 0.00 0 4548 21 0 0
v_cancel_customer_reserve 1396 1396 0 0.00 0 1665 7 0 0
v_cancel_payment_to_supplier 828 828 0 0.00 0 167 3 0 0
v_cancel_supplier_invoice 814 802 12 1.47 0 1721 18 0 12
v_cancel_supplier_order 790 790 0 0.00 0 1484 9 0 0
v_cancel_write_off 1904 1904 0 0.00 0 3110 21 0 0
v_max_id_clo_ord 5425 5425 0 0.00 0 2629 2 0 0
v_max_id_clo_res 981 981 0 0.00 0 6039 22 0 0
v_max_id_ord_sup 5473 5473 0 0.00 0 9376 8 0 0
v_max_non_paid_invoice 1859 1859 0 0.00 0 1122 3 0 0
v_max_non_paid_realizn 3676 3676 0 0.00 0 748 3 0 0
v_min_id_clo_ord 5425 5425 0 0.00 0 2474 7 0 0
v_min_id_clo_res 981 981 0 0.00 0 6966 16 0 0
v_min_id_ord_sup 5473 5473 0 0.00 0 9232 8 0 0
v_min_non_paid_invoice 5826 5826 0 0.00 0 4535 12 0 0
v_min_non_paid_realizn 7019 7019 0 0.00 0 4748 16 0 0
v_our_firm 10046 10046 0 0.00 0 105 1 0 0
v_reserve_write_off 19540 19540 0 0.00 0 4270 7 0 0
x_lock_depdocs_on_canc_invoice 11069 11069 0 0.00 0 23511 128 0 0
x_lock_depdocs_on_canc_sup_ord 395 395 0 0.00 0 22086 455 0 0
x_make_qstorno_1000_1200 5426 5426 0 0.00 2 54423 1526 0 0
x_make_qstorno_1200_2000 5481 5481 0 0.00 3 94989 3832 0 0
x_make_qstorno_2100_3300 449268 449268 0 0.00 0 100902 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-02-2021 10:29:12 to 20-02-2021 10:29: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
07:57:01 3 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
07:58:03 25178 8192000000 9735199488 10032820224 811974432 9.017 100 88 176 0 840204128 7539744 20689952
07:59:35 9750 8192000000 9770722336 10074800128 842433856 9.324 100 91 182 1 876795760 12728304 21633600
08:00:50 16218 8192000000 9798455952 10114326528 872079552 9.621 100 89 175 1 910295856 18089792 20126512
08:02:12 15298 8192000000 9854898656 10186485760 909356176 9.991 100 81 162 1 968679872 41579824 17743872
08:03:33 9580 8192000000 9920817936 10257702912 946986784 10.362 100 84 168 2 1029672944 64449376 18236784
08:04:45 6501 8192000000 9940775584 10293374976 979905296 10.683 100 78 156 2 1051350080 55207328 16237456
08:05:55 4247 8192000000 9989486368 10343821312 1009744528 10.973 100 84 166 4 1098303008 71955120 16603360
08:07:02 2794 8192000000 10010351520 10371776512 1033364896 11.201 100 96 188 3 1123936880 70863008 19708976
08:08:07 2126 8192000000 10024219024 10390433792 1051403024 11.374 100 93 185 1 1133029568 62365824 19260720
08:09:10 13381 8192000000 9996740656 10374144000 1066244688 11.516 100 66 134 1 1111461344 31171552 14045104
08:10:23 6118 8192000000 9994183648 10383400960 1077382960 11.623 100 50 98 0 1127142128 39051072 10708096
08:11:32 3878 8192000000 10004473968 10390319104 1079207776 11.640 100 42 83 2 1113561664 25374656 8979232
08:12:37 6148 8192000000 10000827120 10394324992 1085860096 11.703 100 39 79 0 1114391584 20205664 8325824
08:13:44 30693 8192000000 10011919632 10397216768 1092743520 11.769 100 70 143 1 1119740288 11053760 15943008
08:15:15 4959 8192000000 10028386368 10426511360 1097431136 11.813 100 50 101 3 1142574480 33803104 11340240
08:16:22 7426 8192000000 10020354080 10408050688 1087685856 11.721 99 33 68 2 1107923376 13028640 7208880
08:17:32 4993 8192000000 10020675200 10414538752 1095752304 11.797 100 46 89 2 1118349456 12216768 10380384
08:18:37 30300 8192000000 9999646640 10404429824 1061447936 11.470 97 30 60 1 1093629120 25877312 6303872
08:20:08 5034 8192000000 9994312768 10387275776 1054194448 11.401 98 49 97 3 1091988128 27189168 10604512
08:21:14 3955 8192000000 9946224176 10317647872 995537632 10.835 97 39 77 0 1040295808 37086688 7671488
08:22:18 6300 8192000000 9937236784 10319253504 1029300064 11.162 100 41 80 0 1064064752 26406416 8358272
08:23:25 35682 8192000000 9926080400 10328334336 1026512096 11.135 99 33 62 2 1045904080 12094256 7297728
08:25:06 4678 8192000000 9965000112 10360393728 1047969232 11.341 100 43 86 1 1084084560 25947904 10167424
08:26:13 4733 8192000000 9968116048 10360627200 1059549952 11.452 100 50 99 2 1085145632 13941696 11653984
08:27:22 8450 8192000000 10000044832 10389233664 1075997488 11.609 100 47 88 2 1094797392 10152160 8647744
08:28:32 69958 8192000000 10048586512 10451730432 1099105120 11.829 100 39 78 0 1146207008 37583904 9517984
08:30:46 11778 8192000000 10087481568 10489823232 1116717056 11.996 100 55 111 1 1187711696 59349552 11645088
08:32:07 15885 8192000000 10118733952 10532941824 1132485264 12.145 100 57 115 0 1234201040 89741568 11974208
08:33:30 6589 8192000000 10147766000 10559549440 1145597440 12.268 100 51 102 1 1246406336 89995280 10813616
08:34:39 6122 8192000000 10161776928 10567000064 1155775360 12.364 100 71 140 3 1272419120 100836960 15806800
08:35:51 4135 8192000000 10190237792 10622054400 1164384688 12.444 100 49 94 0 1300559248 126262496 9912064
08:36:59 9435 8192000000 10237296496 10647724032 1179262304 12.583 100 55 110 3 1339270704 148083984 11924416
08:38:13 4918 8192000000 10271048480 10685546496 1178095856 12.572 100 61 122 1 1380537952 188823424 13618672
08:39:23 5724 8192000000 10305031888 10715000832 1189507520 12.679 100 75 145 0 1400744288 195187760 16049008
08:40:35 4230 8192000000 10324311728 10737225728 1183394672 12.622 100 75 150 0 1430834112 230257040 17182400
08:41:46 5184 8192000000 10361652560 10768900096 1179926512 12.590 100 83 162 1 1459472016 261561024 17984480
08:42:59 9320 8192000000 10364407456 10764341248 1145186224 12.264 99 76 149 0 1462519904 300072208 17261472
08:44:14 3815 8192000000 10409956992 10824835072 1144994096 12.262 98 83 164 1 1495357392 330669024 19694272
08:45:23 5338 8192000000 10419039888 10827796480 1165602000 12.456 100 75 146 1 1519375808 336098528 17675280
08:46:34 1593 8192000000 10464509584 10877243392 1155717632 12.363 100 94 185 1 1560637776 382166848 22753296
08:47:43 1105 8192000000 10436979280 10858770432 1137230640 12.189 99 92 184 1 1534458240 375589792 21637808
08:48:51 11393 8192000000 10449614848 10901008384 1154715712 12.354 100 55 108 2 1553965472 385856352 13393408
08:50:15 11108 8192000000 10436720176 10863558656 1156920400 12.374 100 39 78 2 1534015520 367323648 9771472
08:51:34 9296 8192000000 10415760000 10833469440 1134142352 12.160 99 40 79 1 1511975408 368124096 9708960
08:52:48 9793 8192000000 10439113840 10865373184 1104702240 11.882 99 34 68 1 1523108352 409200896 9205216
08:54:01 8078 8192000000 10430577536 10888785920 1116121728 11.990 100 29 58 1 1553000032 428787632 8090672
08:55:13 4550 8192000000 10334505728 10788597760 1131716352 12.138 100 68 136 0 1433530432 284160432 17653648
08:56:22 16133 8192000000 10237172800 10742972416 1125767776 12.081 100 25 50 0 1324534976 192597232 6169968
08:58:37 78574 8192000000 10081415552 10509283328 1101939120 11.856 97 12 24 0 1170929776 66704016 2286640
09:00:57 79515 8192000000 10072019344 10553491456 1130213264 12.123 100 16 31 1 1189776112 56054000 3508848
09:03:29 101683 8192000000 10118774224 10552590336 1143165280 12.245 100 29 57 0 1204118400 54382416 6570704
09:06:22 25922 8192000000 10102022960 10541117440 1149232032 12.302 100 50 100 1 1207825728 47943776 10649920
09:08:16 52623 8192000000 10126590224 10571096064 1120214944 12.029 97 23 45 0 1192292464 66823248 5254272
09:10:14 40710 8192000000 10150279216 10591756288 1112643392 11.957 98 34 67 1 1241643104 121130080 7869632
09:11:57 46997 8192000000 10078534704 10561073152 1128293504 12.105 99 28 56 0 1204230000 69388448 6548048
09:13:48 111550 8192000000 10121745008 10572754944 1138638288 12.203 99 18 36 0 1227208768 84252896 4317584
09:16:40 65471 8192000000 10099972832 10549702656 1131081888 12.132 99 16 32 0 1201024608 66297952 3644768
09:18:49 42847 8192000000 10109732480 10576715776 1128999520 12.112 99 33 66 1 1209743424 73733568 7010336
09:20:42 33579 8192000000 10088186240 10529964032 1082642560 11.673 95 29 58 2 1148713072 59858400 6212112
09:22:23 29430 8192000000 10083512624 10510372864 1134133888 12.160 100 31 63 1 1162574816 22120944 6319984
09:23:56 63752 8192000000 10090972736 10577723392 1125169392 12.076 99 14 28 0 1181991936 54437200 2385344
09:26:01 72749 8192000000 10049585456 10525704192 1075379696 11.603 95 13 26 0 1113352080 35409536 2562848
09:28:15 85287 8192000000 10048825904 10509750272 1102915664 11.865 99 22 44 0 1151433872 44174496 4343712
09:30:53 30162 8192000000 10038838224 10503770112 1108968624 11.923 100 14 28 0 1144585680 32931696 2685360
09:32:35 45987 8192000000 10070483856 10520944640 1120403504 12.031 100 8 16 1 1169730432 47544880 1782048
09:34:21 45506 8192000000 10068280624 10515456000 1086969648 11.714 97 7 15 0 1149820432 61341728 1509056
09:36:07 58896 8192000000 10069563424 10527563776 1051288816 11.373 94 29 56 4 1132642976 75199584 6154576
09:38:06 26486 8192000000 10089480048 10534768640 1075337568 11.603 96 27 54 2 1174629296 92830768 6460960
09:39:39 41473 8192000000 10139118976 10564595712 1083636064 11.682 96 22 43 2 1190921696 102295424 4990208
09:41:32 45848 8192000000 10145780016 10618568704 1118264992 12.011 99 21 41 1 1211974384 88935056 4774336
09:43:18 15880 8192000000 10070132080 10489454592 1078591648 11.634 97 46 92 0 1138841344 50468992 9780704
09:44:39 49818 8192000000 10049162768 10515595264 1118288560 12.011 100 22 44 0 1176500208 54056336 4155312
09:46:34 106778 8192000000 10084669152 10570342400 1127587456 12.099 100 18 36 0 1202488704 70975920 3925328
09:50:00 76129 8192000000 10083417616 10575392768 1124332896 12.068 99 10 21 0 1177310640 50618144 2359600
09:52:34 42796 8192000000 10054583984 10511237120 1128219856 12.105 100 10 20 0 1164305840 33998704 2087280
09:54:18 63478 8192000000 10077865456 10542137344 1107773024 11.911 98 16 32 1 1166765712 55686080 3306608
09:56:27 23962 8192000000 10084529968 10520375296 1134224256 12.161 100 60 117 2 1175700368 28655104 12821008
09:58:15 77970 8192000000 10102155872 10582396928 1123502288 12.060 98 18 36 2 1189191248 61999104 3689856
10:00:36 73796 8192000000 10096781712 10544164864 1120408176 12.031 98 14 28 1 1163189504 40060192 2721136
10:02:54 37098 8192000000 10091193456 10578604032 1118542976 12.013 99 23 46 0 1209114736 85513680 5058080
10:04:32 17086 8192000000 10101959168 10538295296 1140893952 12.224 100 19 38 1 1185149152 40583968 3671232
10:05:58 107495 8192000000 10059965920 10520055808 1124470160 12.069 98 13 26 2 1165606768 38475696 2660912
10:08:47 69346 8192000000 10096386560 10561187840 1133498640 12.154 99 10 20 1 1205481120 70025264 1957216
10:12:36 76588 8192000000 10155848192 10615320576 1149590240 12.306 100 18 36 1 1237226624 83945328 3691056
10:14:56 37053 8192000000 10182301664 10611777536 1136962304 12.187 98 27 53 1 1253645664 110383744 6299616
10:16:37 63061 8192000000 10179594096 10627006464 1080497936 11.652 94 17 33 1 1215000096 129935648 4566512
10:18:50 49142 8192000000 10087521344 10676056064 1128041280 12.103 100 14 28 0 1246808432 116177456 2589696
10:20:46 80504 8192000000 10051711552 10482024448 1073467744 11.585 95 19 38 1 1103193728 26064768 3661216
10:23:10 177666 8192000000 10076507072 10546221056 1093704192 11.778 97 35 70 0 1137853440 37005568 7143680

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 20-02-2021 10:29:18 to 20-02-2021 10:29:33.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 88 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 20-02-2021 10:29:34 to 20-02-2021 10:29:36.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          230159
MON$OLDEST_ACTIVE               230741
MON$OLDEST_SNAPSHOT             230741
MON$NEXT_TRANSACTION            230741
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-30 07:30:08.6030 GMT
MON$PAGES                       2124992
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             793
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2371 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2371 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2371 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2371 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
on disk structure version 13.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 ---
:27: 
:28: mscs-test-161.budzdorov.ru    Sat Feb 20 10:27:23 2021
:29:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:30: 
:31: 
:32: mscs-test-161.budzdorov.ru    Sat Feb 20 10:27:23 2021
:33:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:34: 
:35: 
:36: mscs-test-161.budzdorov.ru    Sat Feb 20 10:27:23 2021
:37:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:38: 
:39: 
:40: mscs-test-161.budzdorov.ru    Sat Feb 20 10:27:23 2021
:41:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:42: 
--- end of diff output ---

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

20.02.21 10:29:37. There are 3 logs in /var/tmp/logs-oltp40 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-02-20 10:29:40 No severe PSQL-related problems occured
20.02.21 10:29:41. End of report.