Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.11.2020 07:27
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 16576028 kB
MemAvailable 31454320 kB
Buffers 0 kB
Cached 15064616 kB
SwapCached 3136 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 2020-11-24 07:26:52
DTS_END                         2020-11-24 07:26:52.4870
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(127:13) ==> X_MAKE_QSTORNO_2100_3300(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/37054
TRN_ID                          230132
ATT_ID                          649
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: 9035.58 21950 1084269
customer order: creation 168.76 389 20251
customer order: refuse 14.30 392 1716
order to supplier: creation 45.52 1373 5462
order to supplier: removal 3.33 120111 399
invoice (draft): creation 45.72 3584 5486
invoice (draft): removal 51.55 912 6186
invoice accept: apply 40.67 17109 4881
invoice accept: cancel 46.35 942 5562
customer reserve: creation 3786.61 165 454393
customer reserve: removal 4547.42 69 545690
realization accept: apply 81.87 188 9824
realization accept: cancel 78.38 113 9406
payment from customer: creation 57.73 104 6928
payment from customer: removal 5.28 128 634
payment to supplier: creation 47.85 142 5742
payment to supplier: removal 3.69 83 443
service: total inventory turnovers 3.43 4268 411
service: total monetary turnovers 6.19 540 743
service: aggregate perf. data 0.90 14533 108
service: refresh index statistics 0.03 273849 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 24-11-2020 07:28:07 to 24-11-2020 07:28:10.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1390
TEST_TIME 2 3119
TEST_TIME 3 3496
TEST_TIME 4 3356
TEST_TIME 5 3448
TEST_TIME 6 3687
TEST_TIME 7 4099
TEST_TIME 8 4333
TEST_TIME 9 4590
TEST_TIME 10 4820
TEST_TIME 11 4867
TEST_TIME 12 5166
TEST_TIME 13 5395
TEST_TIME 14 5664
TEST_TIME 15 6346
TEST_TIME 16 6674
TEST_TIME 17 6832
TEST_TIME 18 7054
TEST_TIME 19 7095
TEST_TIME 20 7873
TEST_TIME 21 7763
TEST_TIME 22 7841
TEST_TIME 23 8066
TEST_TIME 24 8312
TEST_TIME 25 8762
TEST_TIME 26 9253
TEST_TIME 27 9799
TEST_TIME 28 9887
TEST_TIME 29 10819
TEST_TIME 30 10878
TEST_TIME 31 10759
TEST_TIME 32 10505
TEST_TIME 33 10561
TEST_TIME 34 11017
TEST_TIME 35 10881
TEST_TIME 36 10929
TEST_TIME 37 10792
TEST_TIME 38 10633
TEST_TIME 39 10479
TEST_TIME 40 10412
TEST_TIME 41 10407
TEST_TIME 42 10291
TEST_TIME 43 10421
TEST_TIME 44 10522
TEST_TIME 45 10457
TEST_TIME 46 10424
TEST_TIME 47 10223
TEST_TIME 48 10056
TEST_TIME 49 9876
TEST_TIME 50 9779
TEST_TIME 51 9801
TEST_TIME 52 9796
TEST_TIME 53 9770
TEST_TIME 54 9747
TEST_TIME 55 9675
TEST_TIME 56 9564
TEST_TIME 57 9522
TEST_TIME 58 9509
TEST_TIME 59 9429
TEST_TIME 60 9443
TEST_TIME 61 9432
TEST_TIME 62 9444
TEST_TIME 63 9397
TEST_TIME 64 9431
TEST_TIME 65 9374
TEST_TIME 66 9495
TEST_TIME 67 9509
TEST_TIME 68 9464
TEST_TIME 69 9532
TEST_TIME 70 9598
TEST_TIME 71 9612
TEST_TIME 72 9594
TEST_TIME 73 9625
TEST_TIME 74 9603
TEST_TIME 75 9526
TEST_TIME 76 9420
TEST_TIME 77 9367
TEST_TIME 78 9336
TEST_TIME 79 9291
TEST_TIME 80 9265
TEST_TIME 81 9178
TEST_TIME 82 9120
TEST_TIME 83 9133
TEST_TIME 84 9213
TEST_TIME 85 9203
TEST_TIME 86 9194
TEST_TIME 87 9197
TEST_TIME 88 9250
TEST_TIME 89 9231
TEST_TIME 90 9254
TEST_TIME 91 9287
TEST_TIME 92 9303
TEST_TIME 93 9293
TEST_TIME 94 9413
TEST_TIME 95 9324
TEST_TIME 96 9252
TEST_TIME 97 9200
TEST_TIME 98 9203
TEST_TIME 99 9186
TEST_TIME 100 9146
TEST_TIME 101 9124
TEST_TIME 102 9096
TEST_TIME 103 9109
TEST_TIME 104 9133
TEST_TIME 105 9161
TEST_TIME 106 9141
TEST_TIME 107 9110
TEST_TIME 108 9131
TEST_TIME 109 9209
TEST_TIME 110 9222
TEST_TIME 111 9284
TEST_TIME 112 9249
TEST_TIME 113 9181
TEST_TIME 114 9148
TEST_TIME 115 9139
TEST_TIME 116 9084
TEST_TIME 117 9080
TEST_TIME 118 9056
TEST_TIME 119 9032
TEST_TIME 120 9036

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 24-11-2020 07:28:11 to 24-11-2020 07:28:14.

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 1506134 1506134 0 0.00 0 25479 6 0 0
doc_list_biud 1506133 1506133 0 0.00 0 82039 32 0 0
sp_add_invoice_to_stock 5054 4881 173 3.42 25 172483 17109 0 173
sp_cancel_adding_invoice 5562 5562 0 0.00 6 90277 943 0 0
sp_cancel_client_order 1716 1716 0 0.00 4 9527 392 0 0
sp_cancel_customer_reserve 545690 545690 0 0.00 1 82041 70 0 0
sp_cancel_pay_from_customer 634 634 0 0.00 3 8079 129 0 0
sp_cancel_pay_to_supplier 443 443 0 0.00 3 8184 84 0 0
sp_cancel_supplier_invoice 6203 6186 17 0.27 2 28371 912 0 17
sp_cancel_supplier_order 399 399 0 0.00 6 1262697 120112 0 0
sp_cancel_write_off 9406 9406 0 0.00 1 12345 113 0 0
sp_client_order 20251 20251 0 0.00 4 15499 390 0 0
sp_customer_reserve 454393 454393 0 0.00 0 55495 166 0 0
sp_fill_shopping_cart 31177 31177 0 0.00 1 32770 46 0 0
sp_fill_shopping_cart_clo_res 454390 454390 0 0.00 0 26158 16 0 0
sp_get_clo_for_invoice 4878 4878 0 0.00 0 33752 368 0 0
sp_kill_cost_storno 16045 16045 0 0.00 0 8177 64 0 0
sp_kill_qstorno_ret_qs2qd 1107371 1107371 0 0.00 0 82022 37 0 0
sp_kill_qty_storno 583660 583660 0 0.00 0 82039 81 0 0
sp_lock_selected_doc 20426 20426 0 0.00 0 10297 5 0 0
sp_make_cost_storno 27372 27372 0 0.00 0 14930 63 0 0
sp_multiply_rows_for_pdistr 27372 27372 0 0.00 0 7403 31 0 0
sp_multiply_rows_for_qdistr 20251 20251 0 0.00 0 15474 198 0 0
sp_pay_from_customer 6928 6928 0 0.00 4 12763 104 0 0
sp_pay_to_supplier 5742 5742 0 0.00 5 22121 143 0 0
sp_payment_common 12670 12670 0 0.00 1 21956 25 0 0
sp_qd_handle_on_cancel_clo 1716 1716 0 0.00 0 9520 353 0 0
sp_qd_handle_on_invoice_upd_sts 10439 10439 0 0.00 0 26218 396 0 0
sp_qd_handle_on_reserve_upd_sts 9824 9824 0 0.00 0 19255 52 0 0
sp_reserve_write_off 9824 9824 0 0.00 7 19267 188 0 0
sp_supplier_invoice 5486 5486 0 0.00 7 87691 3585 0 0
sp_supplier_order 5462 5462 0 0.00 5 99813 1374 0 0
srv_aggregate_perf_data 108 108 0 0.00 2461 238457 14534 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1562 172988 46227 0 0
srv_find_qd_qs_mism 1038031 1038031 0 0.00 0 25479 5 0 0
srv_make_invnt_saldo 411 411 0 0.00 49 127983 4269 0 0
srv_make_money_saldo 743 743 0 0.00 1 19724 540 0 0
srv_recalc_idx_stat 4 4 0 0.00 80780 409918 273849 0 0
t$perf-abend:sp_add_invoice_to_stock 173 173 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 17 17 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4881 4881 0 0.00 0 23721 183 0 0
t$perf-norm:sp_cancel_adding_invoice 439 439 0 0.00 0 6623 118 0 0
t$perf-norm:sp_cancel_client_order 1716 1716 0 0.00 0 2460 13 0 0
t$perf-norm:sp_cancel_customer_reserve 685 685 0 0.00 0 922 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 634 634 0 0.00 0 624 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 443 443 0 0.00 0 88 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 420 420 0 0.00 0 2545 18 0 0
t$perf-norm:sp_cancel_supplier_order 399 399 0 0.00 0 19303 1630 0 0
t$perf-norm:sp_cancel_write_off 989 989 0 0.00 0 242 3 0 0
t$perf-norm:sp_client_order 20251 20251 0 0.00 0 19852 21 0 0
t$perf-norm:sp_customer_reserve 1006 1006 0 0.00 0 18128 25 0 0
t$perf-norm:sp_pay_from_customer 6928 6928 0 0.00 0 5274 10 0 0
t$perf-norm:sp_pay_to_supplier 5742 5742 0 0.00 0 12063 11 0 0
t$perf-norm:sp_reserve_write_off 9824 9824 0 0.00 0 6394 14 0 0
t$perf-norm:sp_supplier_invoice 5486 5486 0 0.00 0 10865 24 0 0
t$perf-norm:sp_supplier_order 5462 5462 0 0.00 0 13207 23 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 486 18 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 69 2 0 0
t$perf-norm:srv_make_invnt_saldo 411 411 0 0.00 0 1486 10 0 0
t$perf-norm:srv_make_money_saldo 743 743 0 0.00 0 592 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 7 4 0 0
v_add_invoice_to_stock 10100 9754 346 3.42 0 7149 17 0 346
v_all_customers 36918 36918 0 0.00 0 68 1 0 0
v_all_suppliers 30412 30412 0 0.00 0 20 1 0 0
v_all_wares 40500 40500 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 878 878 0 0.00 0 4180 12 0 0
v_cancel_client_order 3432 3432 0 0.00 0 2839 6 0 0
v_cancel_customer_prepayment 1268 1268 0 0.00 0 3231 12 0 0
v_cancel_customer_reserve 1370 1370 0 0.00 0 3233 7 0 0
v_cancel_payment_to_supplier 886 886 0 0.00 0 1148 6 0 0
v_cancel_supplier_invoice 874 840 34 3.89 0 1606 19 0 34
v_cancel_supplier_order 798 798 0 0.00 0 1268 6 0 0
v_cancel_write_off 1978 1978 0 0.00 0 2948 14 0 0
v_max_id_clo_ord 5460 5460 0 0.00 0 2800 2 0 0
v_max_id_clo_res 1006 1006 0 0.00 0 6589 26 0 0
v_max_id_ord_sup 5467 5467 0 0.00 0 5233 3 0 0
v_max_non_paid_invoice 1463 1463 0 0.00 0 420 2 0 0
v_max_non_paid_realizn 3705 3705 0 0.00 0 6261 5 0 0
v_min_id_clo_ord 5460 5460 0 0.00 0 8723 9 0 0
v_min_id_clo_res 1006 1006 0 0.00 0 1225 3 0 0
v_min_id_ord_sup 5467 5467 0 0.00 0 3084 4 0 0
v_min_non_paid_invoice 5742 5742 0 0.00 0 21951 22 0 0
v_min_non_paid_realizn 6928 6928 0 0.00 0 8179 16 0 0
v_our_firm 10028 10028 0 0.00 0 12 1 0 0
v_reserve_write_off 19648 19648 0 0.00 0 3942 6 0 0
x_lock_depdocs_on_canc_invoice 11328 11328 0 0.00 0 19721 127 0 0
x_lock_depdocs_on_canc_sup_ord 399 399 0 0.00 0 17641 429 0 0
x_make_qstorno_1000_1200 5462 5462 0 0.00 2 99796 1287 0 0
x_make_qstorno_1200_2000 5486 5486 0 0.00 3 81137 3415 0 0
x_make_qstorno_2100_3300 454393 454393 0 0.00 0 55492 148 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 24-11-2020 07:28:15 to 24-11-2020 07:28:20.

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
04:56:56 2 8192000000 8796641312 8807108608 3168704 0.038 1 1 2 0 3266400 61840 35856
04:57:57 16952 8192000000 9709043664 10001575936 788181712 8.776 100 88 176 0 817157056 7646256 21329088
04:59:17 11159 8192000000 9733833520 10032975872 809978960 8.997 100 91 182 0 844393728 11916928 22497840
05:00:34 15639 8192000000 9771358960 10078867456 838027792 9.280 100 91 182 1 878015728 18303296 21684640
05:01:57 15448 8192000000 9826075280 10152157184 879573888 9.695 100 73 146 1 935846048 38954928 17317232
05:03:15 13313 8192000000 9896296608 10233618432 936541120 10.259 100 79 158 2 1002051056 48544080 16965856
05:04:33 6092 8192000000 9945564464 10295660544 972331136 10.609 100 80 160 2 1059669648 70630992 16707520
05:05:42 4699 8192000000 9971187056 10331930624 1000714560 10.885 100 74 148 0 1086015216 70278080 15022576
05:06:49 3574 8192000000 10028344960 10386714624 1018873248 11.061 100 87 174 0 1138304576 102317728 17113600
05:07:55 2246 8192000000 10040164480 10405122048 1049515984 11.356 100 94 184 0 1146914800 78739888 18658928
05:08:58 955 8192000000 10044153504 10416885760 1064494128 11.499 100 89 179 0 1151447984 68363904 18589952
05:10:02 14611 8192000000 10042212160 10437701632 1073269072 11.583 100 54 106 0 1154888096 71370448 10248576
05:11:19 5279 8192000000 9985586880 10381799424 1083269264 11.679 100 42 84 0 1110247456 17277328 9700864
05:12:25 7962 8192000000 10003727152 10394939392 1084664704 11.692 100 34 68 1 1120205744 27943984 7597056
05:13:36 6225 8192000000 9995659152 10396135424 1089731536 11.740 100 31 59 3 1115732928 19523952 6477440
05:14:45 19140 8192000000 10027430144 10410934272 1099186432 11.830 100 73 149 4 1128173200 12843600 16143168
05:16:05 8419 8192000000 10018237392 10417356800 1094879984 11.789 100 36 72 2 1113538896 10758352 7900560
05:17:14 9677 8192000000 10022032288 10431442944 1092342496 11.765 100 34 68 0 1132396656 33077008 6977152
05:18:24 54686 8192000000 10020559872 10407342080 1075437456 11.604 98 60 121 4 1103048144 14190048 13420640
05:20:20 3977 8192000000 9979657376 10361147392 1045103728 11.314 99 45 91 0 1091060784 37066240 8890816
05:21:26 10749 8192000000 9937758976 10305605632 940077120 10.294 90 41 82 1 962866928 13576544 9213264
05:22:37 4507 8192000000 9934795120 10314989568 1016784320 11.041 99 46 91 5 1046329920 19349632 10195968
05:23:42 39994 8192000000 9956680896 10359803904 1009771392 10.973 97 24 48 2 1047092720 32504832 4816496
05:25:24 6719 8192000000 9961695840 10361061376 1048148192 11.343 100 54 108 1 1084733088 24518976 12065920
05:26:31 6680 8192000000 10007192400 10400182272 1077062432 11.619 100 39 79 1 1111324032 24948720 9312880
05:27:45 28488 8192000000 10040403104 10439393280 1097837856 11.817 100 59 118 2 1144890096 34819440 12232800
05:29:21 53260 8192000000 10076943952 10488008704 1116682192 11.996 100 32 64 2 1190074880 66276112 7116576
05:31:25 33077 8192000000 10114897280 10530574336 1126178960 12.085 100 34 68 2 1222876144 89312096 7385088
05:33:03 5089 8192000000 10163274640 10570768384 1141629936 12.231 100 50 98 1 1266060896 113918864 10512096
05:34:10 21525 8192000000 10210079200 10621304832 1147903024 12.290 100 62 124 0 1306474528 145653664 12917840
05:35:42 25019 8192000000 10238033968 10673303552 1166641888 12.465 100 47 93 1 1350153440 172933728 10577824
05:37:20 19138 8192000000 10250760944 10672496640 1172987120 12.525 100 39 78 0 1352601376 170278576 9335680
05:38:46 3869 8192000000 10325083968 10734317568 1182916928 12.617 100 74 147 0 1427917760 228559232 16441600
05:39:54 14143 8192000000 10373435296 10786304000 1183411200 12.622 100 73 145 0 1461951296 262129568 16410528
05:41:19 8932 8192000000 10358330720 10773655552 1191113824 12.694 100 67 134 0 1459467488 253036464 15317200
05:42:32 2651 8192000000 10386909872 10805116928 1198359280 12.761 100 77 154 1 1493615056 277283360 17972416
05:43:39 2944 8192000000 10397083408 10819796992 1185697072 12.643 100 80 161 1 1505448688 300744816 19006800
05:44:47 1559 8192000000 10390011360 10796699648 1160603184 12.409 99 89 179 1 1485790368 304526912 20660272
05:45:57 3298 8192000000 10430528048 10854842368 1162782448 12.429 100 86 172 0 1531161584 348851776 19527360
05:47:06 7280 8192000000 10360314064 10805047296 1135612752 12.174 98 32 64 1 1446704704 302936288 8155664
05:48:17 3862 8192000000 10437603296 10839859200 1158556672 12.390 100 88 176 0 1524315680 345342016 20416992
05:49:24 10697 8192000000 10452323024 10912026624 1162764512 12.429 100 47 94 1 1556440736 380983088 12693136
05:50:39 9125 8192000000 10428738032 10881486848 1150329664 12.313 100 33 66 1 1531880128 372618928 8931536
05:51:54 10552 8192000000 10402835120 10868305920 1160073488 12.404 100 46 92 0 1510377248 338834928 11468832
05:53:06 19537 8192000000 10339812096 10805329920 1148559904 12.296 100 28 56 1 1469576832 313685232 7331696
05:54:30 34166 8192000000 10211006320 10794799104 1130890912 12.130 99 16 32 0 1380159696 244935040 4333744
05:56:06 46797 8192000000 10040911776 10476134400 1073829728 11.589 95 13 24 1 1118709424 42977296 1902400
05:57:57 86645 8192000000 10194015296 10647764992 1103211216 11.868 97 18 36 4 1237788944 130498976 4078752
06:00:24 32763 8192000000 10081339776 10644406272 1116594000 11.995 100 5 10 0 1210756384 93449168 713216
06:01:58 49490 8192000000 10074169248 10528878592 1114075456 11.971 99 8 16 0 1167504656 51970144 1459056
06:03:48 74577 8192000000 10073438144 10507239424 1124409424 12.069 99 14 26 2 1156047120 28580080 3057616
06:06:03 114524 8192000000 10114875984 10610823168 1118905744 12.017 98 19 38 0 1206136224 83890272 3340208
06:08:59 38395 8192000000 10146205216 10584043520 1123752064 12.062 98 14 28 2 1216472352 89763936 2956352
06:10:39 113851 8192000000 10100288496 10568933376 1146073792 12.273 100 7 14 0 1205486000 58181616 1230592
06:13:34 172988 8192000000 10089791616 10524057600 1107915616 11.913 97 18 36 1 1135024624 23223360 3885648
06:17:31 44581 8192000000 10108472704 10552098816 1133210368 12.152 99 34 68 0 1222047056 81482240 7354448
06:19:17 2267 8192000000 10099064048 10523672576 1135218096 12.171 100 72 140 4 1187442944 37442224 14782624
06:20:21 52768 8192000000 10090510304 10563768320 1139477360 12.211 100 19 37 2 1201258528 57897584 3883584
06:22:15 36199 8192000000 10104381584 10560176128 1137586640 12.193 99 13 26 1 1196911344 56051024 3273680
06:23:51 77903 8192000000 10130477520 10624696320 1149759712 12.307 100 15 30 0 1243956624 90757312 3439600
06:28:00 32123 8192000000 10097346432 10551762944 1139504080 12.211 100 22 44 0 1181332528 37551488 4276960
06:29:33 44562 8192000000 10074544384 10548678656 1147934672 12.290 100 25 51 1 1210395728 57484832 4976224
06:31:18 42212 8192000000 10153809232 10590097408 1138411600 12.201 99 50 100 1 1233084304 84654624 10018080
06:33:00 39529 8192000000 10064877856 10521649152 1096200240 11.802 97 23 46 2 1138024896 36697632 5127024
06:34:45 56177 8192000000 10097162240 10541109248 1064831360 11.503 95 24 48 0 1151476880 81583344 5062176
06:36:45 41948 8192000000 10059827200 10496176128 1084999712 11.695 97 18 36 0 1127490928 38675040 3816176
06:38:27 53769 8192000000 10055596416 10487853056 1086323440 11.708 97 14 28 1 1134400128 45296224 2780464
06:40:25 15900 8192000000 10067376496 10494873600 1115844464 11.988 100 42 84 0 1160550464 35892016 8813984
06:41:44 104060 8192000000 10042800736 10485870592 1094827360 11.789 98 34 68 1 1142861008 41152208 6881440
06:44:36 45820 8192000000 10055723584 10520748032 1116550128 11.994 100 12 24 0 1167715936 48879104 2286704
06:47:02 89688 8192000000 10120976896 10566414336 1072703968 11.578 96 16 32 1 1145947248 69644272 3599008
06:49:33 51155 8192000000 10026245552 10526523392 1061545056 11.471 96 32 60 1 1120683728 51521040 7617632
06:51:28 42318 8192000000 10059768160 10494570496 1084938160 11.695 98 25 50 4 1130544784 40704704 4901920
06:53:11 68813 8192000000 10065336176 10529394688 1068381392 11.537 96 13 26 0 1123519616 52468992 2669232
06:55:23 53692 8192000000 10091327296 10531184640 1113020656 11.961 100 30 60 0 1165762896 46897552 5844688
06:57:28 47447 8192000000 10088113824 10540687360 1132078624 12.141 100 10 20 1 1176361168 42653664 1628880
06:59:26 81398 8192000000 10116827408 10625220608 1143070768 12.244 100 15 30 0 1243819168 97545152 3203248
07:01:48 64240 8192000000 10063773600 10537730048 1131003040 12.131 99 17 34 1 1174073552 39550576 3519936
07:03:56 65767 8192000000 10076600288 10559180800 1133724720 12.156 99 4 8 0 1172054864 37796128 534016
07:06:03 44198 8192000000 10071302208 10514223104 1133014800 12.150 99 14 28 0 1161594016 25780896 2798320
07:07:49 80635 8192000000 10143708912 10600411136 1141302720 12.228 99 14 28 0 1224626720 80122240 3201760
07:10:19 78263 8192000000 10086563584 10559889408 1125173936 12.076 98 15 30 1 1189019184 60772768 3072480
07:12:50 58487 8192000000 10153100320 10595487744 1148318688 12.294 99 28 52 3 1211442720 57240880 5883152
07:14:58 68071 8192000000 10103230896 10557915136 1131937904 12.140 98 6 12 0 1193302528 60179872 1184752
07:17:08 77174 8192000000 10092571520 10522214400 1092479648 11.766 95 16 30 1 1150256384 54529040 3247696
07:19:25 139091 8192000000 10097604448 10601603072 1133145792 12.151 99 22 44 0 1220942256 82558544 5237920
07:22:52 49411 8192000000 10071968352 10579566592 1128385568 12.106 99 4 8 0 1158849904 29754784 709552
07:24:47 45807 8192000000 10052000272 10486845440 1081860176 11.665 96 9 18 0 1107352704 23235920 2256608
07:26:47 14733 8192000000 8985434464 9196228608 141848144 1.702 13 2 4 0 157004640 14606528 549968

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 24-11-2020 07:28:21 to 24-11-2020 07:28:37.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 173 v_add_invoice_to_stock 335544517
except 17 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 24-11-2020 07:28:38 to 24-11-2020 07:28:40.

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          230920
MON$OLDEST_ACTIVE               230921
MON$OLDEST_SNAPSHOT             230921
MON$NEXT_TRANSACTION            230921
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                       2128128
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                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             788
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2272 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2272 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2272 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.2272 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 ---
:134: 
:135: mscs-test-161.budzdorov.ru    Tue Nov 24 07:27:07 2020
:136:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:137: 
:138: 
:139: mscs-test-161.budzdorov.ru    Tue Nov 24 07:27:07 2020
:140:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:141: 
--- end of diff output ---

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

24.11.20 07:28:41. 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
2020-11-24 07:28:43 No severe PSQL-related problems occured
24.11.20 07:28:44. End of report.