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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-11-21 01:37:57
DTS_END                         2021-11-21 01:37:57.6670
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/45702
TRN_ID                          239798
ATT_ID                          641
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: 9528.27 25156 1143392
customer order: creation 170.11 362 20413
customer order: refuse 14.58 300 1750
order to supplier: creation 46.37 1239 5564
order to supplier: removal 3.53 89455 424
invoice (draft): creation 45.78 3140 5493
invoice (draft): removal 55.35 711 6642
invoice accept: apply 42.54 16865 5105
invoice accept: cancel 50.33 695 6039
customer reserve: creation 3928.34 168 471401
customer reserve: removal 4873.37 50 584804
realization accept: apply 82.50 179 9900
realization accept: cancel 86.88 99 10425
payment from customer: creation 58.37 127 7004
payment from customer: removal 5.86 76 703
payment to supplier: creation 48.83 128 5860
payment to supplier: removal 3.73 86 447
service: total inventory turnovers 4.38 1335 526
service: total monetary turnovers 6.60 76 792
service: aggregate perf. data 0.80 23197 96
service: refresh index statistics 0.03 364829 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 21-11-2021 01:40:44 to 21-11-2021 01:40:47.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2618
TEST_TIME 2 3598
TEST_TIME 3 4453
TEST_TIME 4 4947
TEST_TIME 5 5068
TEST_TIME 6 4917
TEST_TIME 7 4748
TEST_TIME 8 4785
TEST_TIME 9 4825
TEST_TIME 10 5791
TEST_TIME 11 6047
TEST_TIME 12 6366
TEST_TIME 13 6691
TEST_TIME 14 6973
TEST_TIME 15 7326
TEST_TIME 16 7991
TEST_TIME 17 7966
TEST_TIME 18 8210
TEST_TIME 19 8485
TEST_TIME 20 8403
TEST_TIME 21 8814
TEST_TIME 22 8727
TEST_TIME 23 8952
TEST_TIME 24 9593
TEST_TIME 25 9658
TEST_TIME 26 9809
TEST_TIME 27 10084
TEST_TIME 28 11102
TEST_TIME 29 11024
TEST_TIME 30 11004
TEST_TIME 31 11085
TEST_TIME 32 10925
TEST_TIME 33 11023
TEST_TIME 34 10890
TEST_TIME 35 10702
TEST_TIME 36 10526
TEST_TIME 37 10387
TEST_TIME 38 10469
TEST_TIME 39 10227
TEST_TIME 40 10183
TEST_TIME 41 10140
TEST_TIME 42 10109
TEST_TIME 43 10161
TEST_TIME 44 10079
TEST_TIME 45 10020
TEST_TIME 46 9995
TEST_TIME 47 9888
TEST_TIME 48 9751
TEST_TIME 49 9790
TEST_TIME 50 9809
TEST_TIME 51 9775
TEST_TIME 52 9787
TEST_TIME 53 9780
TEST_TIME 54 9836
TEST_TIME 55 9893
TEST_TIME 56 9874
TEST_TIME 57 9835
TEST_TIME 58 9772
TEST_TIME 59 9860
TEST_TIME 60 9877
TEST_TIME 61 9854
TEST_TIME 62 10008
TEST_TIME 63 9964
TEST_TIME 64 10012
TEST_TIME 65 10025
TEST_TIME 66 10136
TEST_TIME 67 9994
TEST_TIME 68 9977
TEST_TIME 69 9919
TEST_TIME 70 9837
TEST_TIME 71 9828
TEST_TIME 72 9772
TEST_TIME 73 9658
TEST_TIME 74 9719
TEST_TIME 75 9820
TEST_TIME 76 9754
TEST_TIME 77 9720
TEST_TIME 78 9688
TEST_TIME 79 9773
TEST_TIME 80 9728
TEST_TIME 81 9655
TEST_TIME 82 9674
TEST_TIME 83 9722
TEST_TIME 84 9712
TEST_TIME 85 9742
TEST_TIME 86 9788
TEST_TIME 87 9749
TEST_TIME 88 9826
TEST_TIME 89 9850
TEST_TIME 90 9796
TEST_TIME 91 9816
TEST_TIME 92 9893
TEST_TIME 93 9799
TEST_TIME 94 9730
TEST_TIME 95 9695
TEST_TIME 96 9676
TEST_TIME 97 9682
TEST_TIME 98 9631
TEST_TIME 99 9642
TEST_TIME 100 9657
TEST_TIME 101 9659
TEST_TIME 102 9679
TEST_TIME 103 9685
TEST_TIME 104 9676
TEST_TIME 105 9661
TEST_TIME 106 9663
TEST_TIME 107 9649
TEST_TIME 108 9699
TEST_TIME 109 9701
TEST_TIME 110 9694
TEST_TIME 111 9723
TEST_TIME 112 9777
TEST_TIME 113 9731
TEST_TIME 114 9705
TEST_TIME 115 9670
TEST_TIME 116 9646
TEST_TIME 117 9606
TEST_TIME 118 9614
TEST_TIME 119 9594
TEST_TIME 120 9539

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 21-11-2021 01:40:48 to 21-11-2021 01:40:51.

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 1578329 1578329 0 0.00 0 24242 6 0 0
doc_list_biud 1578319 1578319 0 0.00 0 56445 24 0 0
sp_add_invoice_to_stock 5206 5105 101 1.94 38 155020 16866 0 101
sp_cancel_adding_invoice 6039 6039 0 0.00 4 60569 695 0 0
sp_cancel_client_order 1750 1750 0 0.00 4 16425 300 0 0
sp_cancel_customer_reserve 584804 584804 0 0.00 1 56447 51 0 0
sp_cancel_pay_from_customer 703 703 0 0.00 2 5625 76 0 0
sp_cancel_pay_to_supplier 447 447 0 0.00 2 4412 87 0 0
sp_cancel_supplier_invoice 6656 6642 14 0.21 1 54054 712 0 14
sp_cancel_supplier_order 424 424 0 0.00 15 1004040 89455 0 0
sp_cancel_write_off 10425 10425 0 0.00 1 11955 100 0 0
sp_client_order 20413 20413 0 0.00 3 56384 363 0 0
sp_customer_reserve 471401 471401 0 0.00 0 46120 168 0 0
sp_fill_shopping_cart 31462 31462 0 0.00 1 8895 35 0 0
sp_fill_shopping_cart_clo_res 471387 471387 0 0.00 0 16233 11 0 0
sp_get_clo_for_invoice 5090 5090 0 0.00 0 19222 244 0 0
sp_kill_cost_storno 17614 17614 0 0.00 0 13840 51 0 0
sp_kill_qstorno_ret_qs2qd 1187099 1187099 0 0.00 0 56395 28 0 0
sp_kill_qty_storno 625073 625073 0 0.00 0 56445 60 0 0
sp_lock_selected_doc 20878 20878 0 0.00 0 8020 5 0 0
sp_make_cost_storno 27854 27854 0 0.00 0 24230 55 0 0
sp_multiply_rows_for_pdistr 27853 27853 0 0.00 0 10496 29 0 0
sp_multiply_rows_for_qdistr 20412 20412 0 0.00 0 56308 146 0 0
sp_pay_from_customer 7004 7004 0 0.00 4 27433 128 0 0
sp_pay_to_supplier 5860 5860 0 0.00 5 20488 129 0 0
sp_payment_common 12864 12864 0 0.00 1 27423 40 0 0
sp_qd_handle_on_cancel_clo 1750 1750 0 0.00 0 16399 280 0 0
sp_qd_handle_on_invoice_upd_sts 11128 11128 0 0.00 0 15149 292 0 0
sp_qd_handle_on_reserve_upd_sts 9900 9900 0 0.00 0 32986 65 0 0
sp_reserve_write_off 9900 9900 0 0.00 6 33074 179 0 0
sp_supplier_invoice 5493 5493 0 0.00 6 63316 3141 0 0
sp_supplier_order 5564 5564 0 0.00 5 43396 1240 0 0
srv_aggregate_perf_data 96 96 0 0.00 2771 201269 23198 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1719 146019 49956 0 0
srv_find_qd_qs_mism 1094702 1094702 0 0.00 0 22504 5 0 0
srv_make_invnt_saldo 526 526 0 0.00 37 110017 1335 0 0
srv_make_money_saldo 792 792 0 0.00 1 26948 77 0 0
srv_recalc_idx_stat 4 4 0 0.00 184567 629898 364829 0 0
t$perf-abend:sp_add_invoice_to_stock 101 101 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5105 5105 0 0.00 0 12079 168 0 0
t$perf-norm:sp_cancel_adding_invoice 437 437 0 0.00 0 9423 122 0 0
t$perf-norm:sp_cancel_client_order 1750 1750 0 0.00 0 3216 10 0 0
t$perf-norm:sp_cancel_customer_reserve 679 679 0 0.00 0 1327 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 703 703 0 0.00 0 556 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 447 447 0 0.00 0 1324 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 390 390 0 0.00 0 3228 38 0 0
t$perf-norm:sp_cancel_supplier_order 424 424 0 0.00 0 22572 1683 0 0
t$perf-norm:sp_cancel_write_off 1059 1059 0 0.00 0 5225 14 0 0
t$perf-norm:sp_client_order 20413 20413 0 0.00 0 14495 22 0 0
t$perf-norm:sp_customer_reserve 1033 1033 0 0.00 0 1876 9 0 0
t$perf-norm:sp_pay_from_customer 7004 7004 0 0.00 0 6368 12 0 0
t$perf-norm:sp_pay_to_supplier 5860 5860 0 0.00 0 7717 12 0 0
t$perf-norm:sp_reserve_write_off 9900 9900 0 0.00 0 9755 15 0 0
t$perf-norm:sp_supplier_invoice 5493 5493 0 0.00 0 8851 20 0 0
t$perf-norm:sp_supplier_order 5564 5564 0 0.00 0 4681 15 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 194 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 1167 20 0 0
t$perf-norm:srv_make_invnt_saldo 526 526 0 0.00 0 621 18 0 0
t$perf-norm:srv_make_money_saldo 792 792 0 0.00 0 908 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 49 14 0 0
v_add_invoice_to_stock 10380 10178 202 1.94 0 5336 16 0 202
v_all_customers 37286 37286 0 0.00 0 35 1 0 0
v_all_suppliers 30616 30616 0 0.00 0 19 1 0 0
v_all_wares 40824 40824 0 0.00 0 56 1 0 0
v_cancel_adding_invoice 874 874 0 0.00 0 227 6 0 0
v_cancel_client_order 3500 3500 0 0.00 0 3805 4 0 0
v_cancel_customer_prepayment 1406 1406 0 0.00 0 1804 7 0 0
v_cancel_customer_reserve 1358 1358 0 0.00 0 1938 6 0 0
v_cancel_payment_to_supplier 894 894 0 0.00 0 4372 11 0 0
v_cancel_supplier_invoice 808 780 28 3.46 0 2872 25 0 28
v_cancel_supplier_order 848 848 0 0.00 0 766 4 0 0
v_cancel_write_off 2118 2118 0 0.00 0 2600 14 0 0
v_max_id_clo_ord 5562 5562 0 0.00 0 630 1 0 0
v_max_id_clo_res 1033 1033 0 0.00 0 2869 11 0 0
v_max_id_ord_sup 5488 5488 0 0.00 0 6732 5 0 0
v_max_non_paid_invoice 1602 1602 0 0.00 0 4706 7 0 0
v_max_non_paid_realizn 3664 3664 0 0.00 0 4707 5 0 0
v_min_id_clo_ord 5562 5562 0 0.00 0 5552 6 0 0
v_min_id_clo_res 1033 1033 0 0.00 0 5473 14 0 0
v_min_id_ord_sup 5488 5488 0 0.00 0 6373 11 0 0
v_min_non_paid_invoice 5860 5860 0 0.00 0 19674 31 0 0
v_min_non_paid_realizn 7004 7004 0 0.00 0 27413 33 0 0
v_our_firm 10218 10218 0 0.00 0 10 1 0 0
v_reserve_write_off 19800 19800 0 0.00 0 4317 5 0 0
x_lock_depdocs_on_canc_invoice 12291 12291 0 0.00 0 16302 109 0 0
x_lock_depdocs_on_canc_sup_ord 424 424 0 0.00 0 9334 328 0 0
x_make_qstorno_1000_1200 5564 5564 0 0.00 1 43389 1162 0 0
x_make_qstorno_1200_2000 5493 5493 0 0.00 3 63290 3019 0 0
x_make_qstorno_2100_3300 471401 471401 0 0.00 0 43416 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-11-2021 01:40:52 to 21-11-2021 01:40:56.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
23:08:01 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:02 38211 8192000000 9916235488 10228817920 987943536 10.761 100 89 178 0 1017396816 6168288 23284992
23:10:45 24226 8192000000 9962918144 10287214592 1041658096 11.281 100 91 182 1 1073650400 8659024 23333280
23:12:12 21084 8192000000 9999932752 10336428032 1075842272 11.608 100 83 166 0 1106656480 10147056 20667152
23:13:38 11190 8192000000 10050782464 10398117888 1132549296 12.145 100 87 175 0 1164817824 11932336 20336192
23:15:00 8741 8192000000 10092522272 10454990848 1180964656 12.599 100 78 156 0 1210718848 11667904 18086288
23:16:10 3763 8192000000 10145120992 10514857984 1230112896 13.055 100 88 177 0 1263491216 14532672 18845648
23:17:16 4586 8192000000 10175172688 10556743680 1264865312 13.375 100 86 169 3 1297353776 13746128 18742336
23:18:23 2122 8192000000 10214895216 10598014976 1295279424 13.652 100 91 182 2 1328738944 12464192 20995328
23:19:25 14412 8192000000 10204628400 10610593792 1308552192 13.773 100 60 121 1 1332389808 10503088 13334528
23:20:40 8188 8192000000 10215429024 10624368640 1315958304 13.840 100 36 72 2 1331876064 7593584 8324176
23:21:50 9533 8192000000 10206761664 10629586944 1327070688 13.941 100 11 22 0 1335616976 5618144 2928144
23:23:00 9400 8192000000 10222582432 10643320832 1333203920 13.996 100 18 36 1 1342481232 5769440 3507872
23:24:14 71533 8192000000 10263083936 10665160704 1341238992 14.069 100 54 106 2 1359378960 5390688 12749280
23:26:25 4267 8192000000 10232869824 10652999680 1336702128 14.028 100 33 66 0 1350958080 5869424 8386528
23:27:31 37762 8192000000 10226631536 10645352448 1307162752 13.760 98 20 40 1 1317467104 5585248 4719104
23:29:13 7589 8192000000 10220309040 10634588160 1321137360 13.887 100 28 57 1 1333414608 5857360 6419888
23:30:23 7663 8192000000 10134102960 10527395840 1184524832 12.632 95 32 62 2 1201582288 9274592 7782864
23:31:35 49712 8192000000 10132432736 10514452480 1220022128 12.962 98 45 86 3 1235201232 4913392 10265712
23:33:35 5421 8192000000 10160346256 10565074944 1267571488 13.399 100 35 70 1 1281737792 5654768 8511536
23:34:41 9433 8192000000 10177405600 10589868032 1287458816 13.581 100 22 44 0 1297976912 5395568 5122528
23:35:51 65984 8192000000 10188914368 10608267264 1302660080 13.719 100 23 46 1 1314570448 6200144 5710224
23:38:02 1718 8192000000 10226142000 10630524928 1319873328 13.876 100 65 127 4 1341008448 6378832 14756288
23:39:25 18518 8192000000 10258766928 10666213376 1339620128 14.054 100 51 102 1 1365641392 15011520 11009744
23:40:50 18762 8192000000 10274388992 10691244032 1362436592 14.259 100 55 109 0 1389994496 14793184 12764720
23:42:13 64122 8192000000 10290310480 10712817664 1375827872 14.379 100 37 74 2 1400598256 16666912 8103472
23:44:20 13219 8192000000 10306008688 10726203392 1376912112 14.389 99 46 92 1 1406401456 18883088 10606256
23:45:44 10004 8192000000 10325063056 10745319424 1398382624 14.581 100 51 102 1 1437032768 27416672 11233472
23:47:04 3850 8192000000 10346406656 10767683584 1405323072 14.642 100 66 132 2 1455422448 34226864 15872512
23:48:19 5210 8192000000 10353385584 10770026496 1413216464 14.713 100 70 140 0 1460045856 30013328 16816064
23:49:31 4686 8192000000 10359693104 10779242496 1412961568 14.710 100 65 130 0 1466401952 37814800 15625584
23:50:39 5112 8192000000 10359147200 10774396928 1406210064 14.650 100 73 145 1 1460704096 36283568 18210464
23:51:47 14137 8192000000 10355493440 10783387648 1411379744 14.696 100 54 106 1 1463157520 38251984 13525792
23:53:06 3844 8192000000 10351565168 10770386944 1395430112 14.554 100 72 144 3 1456917456 43071072 18416272
23:54:16 12546 8192000000 10313893472 10747064320 1371234448 14.338 99 35 70 1 1415492832 34923536 9334848
23:55:42 12991 8192000000 10309681184 10727235584 1356433408 14.205 99 51 102 1 1409266576 39603040 13230128
23:57:01 10884 8192000000 10324788112 10752151552 1371760384 14.343 99 48 95 3 1428707776 44761456 12185936
23:58:28 15102 8192000000 10298577536 10730160128 1342683520 14.082 98 31 62 3 1398101472 46940768 8477184
00:00:00 11283 8192000000 10311164896 10730573824 1348788448 14.137 100 39 78 1 1417262544 56922448 11551648
00:01:18 17968 8192000000 10295035504 10725281792 1329140400 13.959 98 23 46 1 1383016656 47338448 6537808
00:02:59 24210 8192000000 10285267296 10721308672 1332854432 13.993 98 20 40 1 1385053792 46506352 5693008
00:04:34 21721 8192000000 10300028992 10737278976 1350839216 14.155 100 22 44 0 1407009536 50281824 5888496
00:05:58 67217 8192000000 10277829184 10713235456 1348193808 14.131 99 15 30 0 1367642416 16577584 2871024
00:08:08 81063 8192000000 10273350240 10705031168 1311401008 13.799 96 17 34 1 1326303552 11311984 3590560
00:10:38 74622 8192000000 10272858480 10714275840 1315602288 13.837 96 14 28 0 1326682768 8117088 2963392
00:12:53 98550 8192000000 10307517680 10745184256 1382054384 14.435 100 28 56 2 1402230592 12651936 7524272
00:15:41 87875 8192000000 10298487728 10741395456 1373051936 14.354 99 29 56 0 1388045312 8683680 6309696
00:18:09 29955 8192000000 10321705136 10763493376 1401311968 14.607 100 22 44 1 1414593328 8284368 4996992
00:19:40 43235 8192000000 10310957632 10769133568 1399050208 14.587 99 25 51 1 1417061632 11754944 6256480
00:21:30 45341 8192000000 10314094608 10785431552 1410545936 14.689 100 9 19 1 1426727760 14269904 1911920
00:23:18 30820 8192000000 10322029504 10789105664 1410170576 14.685 100 16 32 2 1432342032 18407008 3764448
00:25:32 92121 8192000000 10322969296 10795483136 1415577408 14.733 100 22 44 0 1439126016 17647440 5901168
00:28:07 66073 8192000000 10316083040 10789191680 1386106896 14.471 98 8 16 0 1404754656 16835088 1812672
00:30:14 91245 8192000000 10336914592 10790313984 1384188960 14.454 98 20 40 0 1404291776 15564720 4538096
00:32:51 89155 8192000000 10289273968 10755915776 1335379472 14.016 95 19 38 0 1357256832 17380528 4496832
00:35:27 40940 8192000000 10326782672 10791346176 1408390560 14.670 100 32 64 1 1429352192 13250224 7711408
00:37:16 49656 8192000000 10293785136 10742738944 1336238144 14.023 96 31 63 0 1355046112 12136320 6671648
00:39:10 113082 8192000000 10277359584 10737160192 1270802640 13.429 92 15 30 0 1288226128 14421584 3001904
00:42:28 87375 8192000000 10254809888 10709323776 1257314544 13.305 92 10 20 0 1273942768 14885792 1742432
00:45:00 76972 8192000000 10267190336 10723147776 1357850928 14.218 99 27 54 1 1373162800 8808240 6503632
00:47:19 39712 8192000000 10285265936 10724757504 1343210416 14.086 99 30 61 0 1358812096 8873024 6728656
00:50:42 75414 8192000000 10282210800 10748661760 1365421904 14.286 99 22 44 0 1397630960 26839616 5369440
00:53:03 86768 8192000000 10269656192 10726338560 1314556528 13.827 96 13 26 1 1336192576 18275344 3360704
00:55:29 118201 8192000000 10254242400 10715492352 1311333600 13.798 96 8 17 1 1322087328 8948128 1805600
00:58:28 45993 8192000000 10288509264 10732298240 1352449104 14.170 99 28 55 0 1371166112 11530256 7186752
01:00:17 146018 8192000000 10287781008 10740125696 1298005264 13.677 95 14 28 0 1324446048 23168480 3272304
01:03:47 52276 8192000000 10283965936 10730655744 1354132560 14.185 99 20 40 0 1370465776 11918160 4415056
01:05:43 78809 8192000000 10277665856 10738282496 1355939472 14.201 99 9 18 0 1376113648 18398480 1775696
01:08:09 19140 8192000000 10312059376 10751025152 1384010464 14.452 100 49 94 1 1406689392 12150592 10528336
01:09:29 84756 8192000000 10300347376 10751291392 1389700416 14.503 100 11 22 1 1401883584 9950784 2232384
01:11:57 42394 8192000000 10294299344 10755588096 1396808688 14.567 100 15 30 1 1412326176 12305872 3211616
01:13:40 89005 8192000000 10299074432 10772348928 1383231584 14.445 99 10 20 0 1398494608 13191584 2071440
01:17:21 38278 8192000000 10290787344 10746802176 1357048016 14.211 97 22 44 1 1371562416 9558576 4955824
01:19:02 38171 8192000000 10300119600 10763304960 1397286864 14.571 100 27 54 0 1413896704 10476176 6133664
01:20:41 25179 8192000000 10297569488 10748944384 1368219520 14.311 98 32 64 3 1382483632 7315248 6948864
01:22:10 39107 8192000000 10311603952 10759028736 1380894784 14.425 99 31 62 0 1396420336 8134736 7390816
01:23:59 59464 8192000000 10291384160 10739560448 1347184464 14.122 97 28 56 2 1367386112 14017712 6183936
01:25:58 59708 8192000000 10262505040 10711711744 1304680256 13.738 94 18 36 0 1318417776 9842048 3895472
01:28:02 64709 8192000000 10270341760 10723889152 1333869248 14.002 97 19 38 0 1357024320 19004800 4150272
01:30:12 116540 8192000000 10266215280 10725081088 1356213216 14.203 99 17 34 0 1381285168 21130976 3940976
01:33:12 64424 8192000000 10263612336 10720784384 1353257488 14.177 99 21 42 0 1371737728 13829360 4650880
01:35:18 47126 8192000000 10274552832 10740248576 1357523472 14.215 99 9 18 0 1367350064 7977936 1848656
01:37:13 60815 8192000000 9573861264 9920577536 710067088 7.976 52 7 14 0 721457952 9561424 1829440

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 21-11-2021 01:40:58 to 21-11-2021 01:41:13.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 101 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-11-2021 01:41:14 to 21-11-2021 01:41:16.

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          238345
MON$OLDEST_ACTIVE               240010
MON$OLDEST_SNAPSHOT             240010
MON$NEXT_TRANSACTION            240010
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                       2051808
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


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

Database statistics

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

Database validation

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

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

--- start of diff output ---
:113: 
:114: mscs-test-161.budzdorov.ru    Sun Nov 21 01:38:27 2021
:115:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:116: 
:117: 
:118: mscs-test-161.budzdorov.ru    Sun Nov 21 01:38:37 2021
:119:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:120: 
--- end of diff output ---

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

21.11.21 01:41:17. 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-11-21 01:41:19 No severe PSQL-related problems occured
21.11.21 01:41:20. End of report.