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

Hardware and OS info

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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-12-21 22:38:00
DTS_END                         2021-12-21 22:38:00.1590
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/48394
TRN_ID                          248279
ATT_ID                          722
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.36 20311 1143403
customer order: creation 182.71 376 21925
customer order: refuse 15.76 334 1891
order to supplier: creation 47.59 1261 5711
order to supplier: removal 3.67 98038 440
invoice (draft): creation 47.30 3324 5676
invoice (draft): removal 52.85 756 6342
invoice accept: apply 43.41 17497 5209
invoice accept: cancel 47.69 731 5723
customer reserve: creation 4072.05 171 488646
customer reserve: removal 4709.75 61 565170
realization accept: apply 87.51 170 10501
realization accept: cancel 83.48 108 10017
payment from customer: creation 62.63 91 7515
payment from customer: removal 6.18 63 741
payment to supplier: creation 49.82 120 5978
payment to supplier: removal 3.66 62 439
service: total inventory turnovers 4.96 924 595
service: total monetary turnovers 6.44 31 773
service: aggregate perf. data 0.89 17637 107
service: refresh index statistics 0.03 264471 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-12-2021 22:38:39 to 21-12-2021 22:38:41.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2850
TEST_TIME 2 3092
TEST_TIME 3 4552
TEST_TIME 4 4442
TEST_TIME 5 4233
TEST_TIME 6 4348
TEST_TIME 7 4356
TEST_TIME 8 4989
TEST_TIME 9 5398
TEST_TIME 10 5771
TEST_TIME 11 5808
TEST_TIME 12 6203
TEST_TIME 13 7022
TEST_TIME 14 7541
TEST_TIME 15 7302
TEST_TIME 16 8056
TEST_TIME 17 8204
TEST_TIME 18 8088
TEST_TIME 19 8748
TEST_TIME 20 9118
TEST_TIME 21 9090
TEST_TIME 22 9398
TEST_TIME 23 9670
TEST_TIME 24 10213
TEST_TIME 25 10902
TEST_TIME 26 11327
TEST_TIME 27 11302
TEST_TIME 28 11051
TEST_TIME 29 10956
TEST_TIME 30 10931
TEST_TIME 31 10649
TEST_TIME 32 10455
TEST_TIME 33 10465
TEST_TIME 34 10276
TEST_TIME 35 10415
TEST_TIME 36 10356
TEST_TIME 37 10222
TEST_TIME 38 10210
TEST_TIME 39 10214
TEST_TIME 40 10150
TEST_TIME 41 10051
TEST_TIME 42 9887
TEST_TIME 43 9818
TEST_TIME 44 10009
TEST_TIME 45 10162
TEST_TIME 46 10150
TEST_TIME 47 10067
TEST_TIME 48 10123
TEST_TIME 49 10105
TEST_TIME 50 10192
TEST_TIME 51 10221
TEST_TIME 52 10128
TEST_TIME 53 10146
TEST_TIME 54 10112
TEST_TIME 55 10270
TEST_TIME 56 10254
TEST_TIME 57 10216
TEST_TIME 58 10065
TEST_TIME 59 9999
TEST_TIME 60 10048
TEST_TIME 61 10013
TEST_TIME 62 9984
TEST_TIME 63 9933
TEST_TIME 64 9869
TEST_TIME 65 9885
TEST_TIME 66 9811
TEST_TIME 67 9833
TEST_TIME 68 9851
TEST_TIME 69 9850
TEST_TIME 70 9918
TEST_TIME 71 9925
TEST_TIME 72 9939
TEST_TIME 73 9874
TEST_TIME 74 9918
TEST_TIME 75 9968
TEST_TIME 76 10032
TEST_TIME 77 10031
TEST_TIME 78 9984
TEST_TIME 79 9928
TEST_TIME 80 9907
TEST_TIME 81 9857
TEST_TIME 82 9842
TEST_TIME 83 9835
TEST_TIME 84 9799
TEST_TIME 85 9760
TEST_TIME 86 9756
TEST_TIME 87 9712
TEST_TIME 88 9683
TEST_TIME 89 9684
TEST_TIME 90 9695
TEST_TIME 91 9713
TEST_TIME 92 9682
TEST_TIME 93 9705
TEST_TIME 94 9752
TEST_TIME 95 9760
TEST_TIME 96 9775
TEST_TIME 97 9766
TEST_TIME 98 9834
TEST_TIME 99 9818
TEST_TIME 100 9841
TEST_TIME 101 9847
TEST_TIME 102 9769
TEST_TIME 103 9703
TEST_TIME 104 9672
TEST_TIME 105 9640
TEST_TIME 106 9626
TEST_TIME 107 9563
TEST_TIME 108 9540
TEST_TIME 109 9499
TEST_TIME 110 9490
TEST_TIME 111 9498
TEST_TIME 112 9557
TEST_TIME 113 9578
TEST_TIME 114 9586
TEST_TIME 115 9612
TEST_TIME 116 9594
TEST_TIME 117 9577
TEST_TIME 118 9604
TEST_TIME 119 9620
TEST_TIME 120 9540

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 21-12-2021 22:38:43 to 21-12-2021 22:38:46.

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 1595325 1595325 0 0.00 0 37758 6 0 0
doc_list_biud 1595325 1595325 0 0.00 0 48731 27 0 0
sp_add_invoice_to_stock 5324 5209 115 2.16 36 219102 17497 0 115
sp_cancel_adding_invoice 5723 5723 0 0.00 5 55438 732 0 0
sp_cancel_client_order 1891 1891 0 0.00 4 11049 335 0 0
sp_cancel_customer_reserve 565170 565170 0 0.00 1 48735 61 0 0
sp_cancel_pay_from_customer 741 741 0 0.00 2 5892 63 0 0
sp_cancel_pay_to_supplier 439 439 0 0.00 3 3093 63 0 0
sp_cancel_supplier_invoice 6353 6342 11 0.17 1 22636 756 0 11
sp_cancel_supplier_order 440 440 0 0.00 6 1140323 98039 0 0
sp_cancel_write_off 10017 10017 0 0.00 1 19686 109 0 0
sp_client_order 21925 21925 0 0.00 3 25055 376 0 0
sp_customer_reserve 488646 488646 0 0.00 0 81968 171 0 0
sp_fill_shopping_cart 33298 33298 0 0.00 1 19967 38 0 0
sp_fill_shopping_cart_clo_res 488629 488629 0 0.00 0 16959 15 0 0
sp_get_clo_for_invoice 5191 5191 0 0.00 0 17633 307 0 0
sp_kill_cost_storno 16920 16920 0 0.00 0 6886 50 0 0
sp_kill_qstorno_ret_qs2qd 1147139 1147139 0 0.00 0 48723 33 0 0
sp_kill_qty_storno 605275 605275 0 0.00 0 48731 71 0 0
sp_lock_selected_doc 21855 21855 0 0.00 0 11606 6 0 0
sp_make_cost_storno 29185 29185 0 0.00 0 10011 47 0 0
sp_multiply_rows_for_pdistr 29185 29185 0 0.00 0 6317 22 0 0
sp_multiply_rows_for_qdistr 21925 21925 0 0.00 0 25048 189 0 0
sp_pay_from_customer 7515 7515 0 0.00 4 9942 91 0 0
sp_pay_to_supplier 5978 5978 0 0.00 4 11705 120 0 0
sp_payment_common 13493 13493 0 0.00 1 7114 21 0 0
sp_qd_handle_on_cancel_clo 1891 1891 0 0.00 0 11038 290 0 0
sp_qd_handle_on_invoice_upd_sts 10914 10914 0 0.00 0 35640 309 0 0
sp_qd_handle_on_reserve_upd_sts 10501 10501 0 0.00 0 23551 55 0 0
sp_reserve_write_off 10501 10501 0 0.00 6 23601 171 0 0
sp_supplier_invoice 5676 5676 0 0.00 6 95276 3324 0 0
sp_supplier_order 5711 5711 0 0.00 5 63658 1262 0 0
srv_aggregate_perf_data 107 107 0 0.00 2165 362183 17637 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 2195 126280 47015 0 0
srv_find_qd_qs_mism 1092977 1092977 0 0.00 0 37756 5 0 0
srv_make_invnt_saldo 595 595 0 0.00 32 32503 924 0 0
srv_make_money_saldo 773 773 0 0.00 0 5971 32 0 0
srv_recalc_idx_stat 4 4 0 0.00 219128 353735 264472 0 0
t$perf-abend:sp_add_invoice_to_stock 115 115 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5209 5209 0 0.00 0 10948 148 0 0
t$perf-norm:sp_cancel_adding_invoice 416 416 0 0.00 1 3028 73 0 0
t$perf-norm:sp_cancel_client_order 1891 1891 0 0.00 0 2069 12 0 0
t$perf-norm:sp_cancel_customer_reserve 730 730 0 0.00 0 646 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 741 741 0 0.00 0 2973 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 439 439 0 0.00 0 4646 19 0 0
t$perf-norm:sp_cancel_supplier_invoice 419 419 0 0.00 0 4659 20 0 0
t$perf-norm:sp_cancel_supplier_order 440 440 0 0.00 0 13998 1324 0 0
t$perf-norm:sp_cancel_write_off 1087 1087 0 0.00 0 1225 3 0 0
t$perf-norm:sp_client_order 21925 21925 0 0.00 0 9098 15 0 0
t$perf-norm:sp_customer_reserve 1034 1034 0 0.00 0 2384 5 0 0
t$perf-norm:sp_pay_from_customer 7515 7515 0 0.00 0 6106 12 0 0
t$perf-norm:sp_pay_to_supplier 5978 5978 0 0.00 0 9163 12 0 0
t$perf-norm:sp_reserve_write_off 10501 10501 0 0.00 0 14128 11 0 0
t$perf-norm:sp_supplier_invoice 5676 5676 0 0.00 0 9149 14 0 0
t$perf-norm:sp_supplier_order 5711 5711 0 0.00 0 3834 11 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 949 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 2705 64 0 0
t$perf-norm:srv_make_invnt_saldo 595 595 0 0.00 0 2754 19 0 0
t$perf-norm:srv_make_money_saldo 773 773 0 0.00 0 1244 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 152 40 0 0
v_add_invoice_to_stock 10612 10382 230 2.16 0 4502 19 0 230
v_all_customers 39636 39636 0 0.00 0 25 0 0 0
v_all_suppliers 31406 31406 0 0.00 0 15 1 0 0
v_all_wares 43850 43850 0 0.00 0 66 1 0 0
v_cancel_adding_invoice 832 832 0 0.00 0 296 5 0 0
v_cancel_client_order 3782 3782 0 0.00 0 3499 8 0 0
v_cancel_customer_prepayment 1482 1482 0 0.00 0 3197 10 0 0
v_cancel_customer_reserve 1460 1460 0 0.00 0 5215 10 0 0
v_cancel_payment_to_supplier 878 878 0 0.00 0 2698 8 0 0
v_cancel_supplier_invoice 860 838 22 2.55 0 2957 19 0 22
v_cancel_supplier_order 880 880 0 0.00 0 469 3 0 0
v_cancel_write_off 2174 2174 0 0.00 0 2247 14 0 0
v_max_id_clo_ord 5711 5711 0 0.00 0 7682 2 0 0
v_max_id_clo_res 1034 1034 0 0.00 0 1394 12 0 0
v_max_id_ord_sup 5662 5662 0 0.00 0 2855 4 0 0
v_max_non_paid_invoice 1648 1648 0 0.00 0 340 2 0 0
v_max_non_paid_realizn 4017 4017 0 0.00 0 1115 4 0 0
v_min_id_clo_ord 5711 5711 0 0.00 0 2791 4 0 0
v_min_id_clo_res 1034 1034 0 0.00 0 8715 15 0 0
v_min_id_ord_sup 5662 5662 0 0.00 0 4663 5 0 0
v_min_non_paid_invoice 5978 5978 0 0.00 0 4716 14 0 0
v_min_non_paid_realizn 7515 7515 0 0.00 0 7106 15 0 0
v_our_firm 11210 11210 0 0.00 0 110 1 0 0
v_reserve_write_off 21002 21002 0 0.00 0 4109 7 0 0
x_lock_depdocs_on_canc_invoice 11646 11646 0 0.00 0 14916 112 0 0
x_lock_depdocs_on_canc_sup_ord 440 440 0 0.00 0 18652 393 0 0
x_make_qstorno_1000_1200 5711 5711 0 0.00 1 63582 1187 0 0
x_make_qstorno_1200_2000 5676 5676 0 0.00 3 95161 3173 0 0
x_make_qstorno_2100_3300 488646 488646 0 0.00 0 81966 154 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-12-2021 22:38:47 to 21-12-2021 22:38:51.

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
20:08:04 44 8192000000 8815763408 8832520192 16290592 0.198 4 4 6 0 16540000 99584 149824
20:09:04 22971 8192000000 9905822256 10217291776 986662176 10.749 100 87 174 0 1014523216 5954432 21906608
20:10:30 27694 8192000000 9958118304 10282602496 1033907040 11.206 100 89 178 0 1065383552 8862864 22613648
20:12:02 8291 8192000000 9985755552 10317205504 1067708352 11.530 100 91 182 0 1100900736 10443264 22749120
20:13:20 17296 8192000000 10037349568 10386018304 1120529712 12.032 100 84 168 1 1151738464 11060080 20148672
20:14:43 6406 8192000000 10075695072 10436042752 1162191792 12.424 100 80 160 0 1194545328 13648384 18705152
20:15:53 5802 8192000000 10119982896 10486964224 1207493376 12.846 100 74 148 1 1234997312 10626480 16877456
20:17:01 2798 8192000000 10170598704 10547830784 1249952208 13.238 100 84 166 0 1281382704 13560608 17869888
20:18:05 1763 8192000000 10198630512 10578817024 1277386352 13.489 100 93 184 0 1312242944 14783936 20072656
20:19:08 17988 8192000000 10205731984 10592079872 1293605936 13.637 100 68 137 3 1320320336 10581104 16133296
20:20:27 4397 8192000000 10188166944 10599669760 1301766336 13.711 100 32 64 1 1315684992 7269536 6649120
20:21:32 13706 8192000000 10193088640 10611748864 1313161168 13.815 100 10 20 1 1321006864 5500288 2345408
20:22:46 14572 8192000000 10208153200 10624266240 1326596320 13.936 100 31 62 1 1340190560 5857872 7736368
20:24:02 27719 8192000000 10220770032 10640125952 1330540880 13.972 100 42 83 3 1345833264 5151888 10140496
20:25:32 4713 8192000000 10228650112 10646290432 1335866848 14.020 100 34 65 1 1349340544 5332592 8141104
20:26:37 8504 8192000000 10223572912 10645450752 1333516976 13.999 100 23 46 1 1345128560 6418448 5193136
20:27:47 27677 8192000000 10185771968 10599432192 1223862256 12.997 92 22 44 0 1233439360 4931648 4645456
20:29:16 6829 8192000000 10167457568 10581204992 1273791216 13.456 99 15 30 0 1282918256 5478912 3648128
20:30:27 6996 8192000000 10152524976 10555109376 1206526560 12.837 94 29 58 3 1218821648 5857072 6438016
20:31:38 7433 8192000000 10148758208 10544312320 1217757648 12.941 97 31 62 1 1234823664 9146880 7919136
20:32:45 55690 8192000000 10169758672 10566438912 1267418288 13.398 100 37 72 2 1280980576 4895216 8667072
20:34:46 3283 8192000000 10187726672 10594324480 1289120928 13.596 100 30 59 1 1302462512 5940912 7400672
20:35:52 9202 8192000000 10190942368 10603700224 1301216128 13.706 100 21 42 0 1312054528 6429136 4409264
20:37:03 9095 8192000000 10194988144 10612908032 1306854224 13.758 100 14 28 1 1314939648 5034944 3050480
20:38:13 73098 8192000000 10227667472 10640060416 1325096704 13.923 100 51 103 1 1344097344 6549760 12450880
20:41:16 10198 8192000000 10275699152 10691334144 1365935024 14.291 100 59 118 1 1395648960 15616832 14097104
20:42:35 12484 8192000000 10290124112 10709409792 1379673872 14.414 100 45 90 2 1407555472 17114288 10767312
20:43:53 32521 8192000000 10311581216 10733424640 1395920080 14.559 100 49 98 0 1429957520 22263584 11773856
20:45:31 9416 8192000000 10326866240 10747195392 1402848016 14.620 100 48 96 2 1440727072 26161872 11717184
20:46:48 9621 8192000000 10341978544 10766016512 1399438080 14.590 99 45 88 0 1440741312 30232560 11070672
20:48:01 3604 8192000000 10347839856 10767642624 1411814624 14.700 100 69 134 0 1461843152 32590320 17438208
20:49:08 2195 8192000000 10361129504 10773159936 1410867184 14.692 100 81 163 0 1464979424 34737968 19374272
20:50:17 4367 8192000000 10371506720 10790854656 1419426496 14.768 100 67 134 1 1479543200 43122896 16993808
20:51:25 10309 8192000000 10359886848 10792935424 1424086384 14.809 100 37 74 1 1471379280 37249488 10043408
20:52:40 11029 8192000000 10356782560 10792259584 1406281200 14.651 99 58 116 1 1471772608 50710544 14780864
20:54:00 2866 8192000000 10380635248 10796552192 1412323104 14.705 100 77 153 0 1481252768 49348544 19581120
20:55:07 15439 8192000000 10344402848 10773557248 1397611744 14.574 100 35 70 3 1451233904 44115104 9507056
20:56:26 7374 8192000000 10362360752 10786304000 1406637472 14.654 100 57 114 0 1472024256 50584560 14802224
20:57:37 22674 8192000000 10324765424 10755723264 1342261168 14.078 97 30 60 0 1400236800 49236144 8739488
20:59:01 5591 8192000000 10333957184 10759106560 1383000960 14.443 100 38 76 2 1442152064 48964912 10186192
21:00:13 30942 8192000000 10325969632 10754777088 1366949872 14.300 99 31 61 1 1433234160 57451296 8832992
21:01:46 29338 8192000000 10305482080 10754007040 1365612192 14.288 99 25 50 1 1430340448 57252048 7476208
21:03:27 38787 8192000000 10277534240 10733268992 1335641808 14.018 99 11 22 0 1375990480 36645264 3703408
21:05:07 56067 8192000000 10244954512 10686242816 1302026816 13.714 96 21 42 1 1314213904 7178704 5008384
21:07:13 92394 8192000000 10266342288 10696278016 1300154192 13.697 96 29 58 2 1314654976 8538320 5962464
21:09:48 104828 8192000000 10284515760 10729013248 1352805456 14.173 99 33 66 1 1369297296 8363696 8128144
21:12:47 31444 8192000000 10288393360 10729680896 1376359888 14.384 100 30 59 1 1397530080 13813168 7357024
21:14:19 10622 8192000000 10279752240 10709573632 1344181248 14.095 98 38 75 1 1360796576 7904016 8711312
21:15:37 21124 8192000000 10298564768 10730340352 1363290720 14.267 99 60 120 0 1390200944 13329552 13580672
21:17:00 22566 8192000000 10281711840 10725666816 1371461248 14.340 100 15 30 0 1390391152 15314880 3615024
21:18:26 68874 8192000000 10282667024 10738188288 1367981808 14.309 99 12 24 1 1382409552 11549552 2878192
21:20:36 76459 8192000000 10303862240 10766036992 1390959696 14.514 100 11 22 0 1414817408 20714016 3143696
21:23:02 62522 8192000000 10286638384 10750685184 1363522256 14.269 98 8 16 0 1379333952 13979248 1832448
21:25:11 11420 8192000000 10318505376 10751479808 1386567072 14.475 99 47 94 3 1403585856 5892176 11126608
21:26:24 31929 8192000000 10309116048 10758475776 1367158256 14.302 98 25 50 0 1384275424 11506368 5610800
21:28:03 93461 8192000000 10292782784 10755223552 1370937536 14.335 98 19 38 1 1386862656 11949680 3975440
21:30:37 22310 8192000000 10307655088 10749181952 1391406512 14.518 100 39 78 0 1409003744 8744992 8852240
21:32:02 31110 8192000000 10306346320 10747633664 1371405792 14.340 99 38 76 2 1392219712 12261344 8552576
21:33:36 30919 8192000000 10281766288 10726580224 1345306672 14.105 97 7 14 0 1356791088 10212688 1271728
21:35:17 57215 8192000000 10309081296 10760962048 1389035856 14.497 100 35 69 0 1415062656 18983568 7043232
21:37:54 61286 8192000000 10278785552 10729324544 1352076896 14.166 98 28 56 1 1378308336 19801488 6429952
21:39:56 70297 8192000000 10266349936 10723803136 1360031744 14.238 99 32 64 2 1375315440 8113488 7170208
21:42:30 34759 8192000000 10266321504 10707484672 1324282064 13.915 97 8 16 0 1333277680 7080960 1914656
21:44:06 43791 8192000000 10272232800 10710048768 1316523280 13.845 96 29 58 2 1331465472 8531424 6410768
21:45:54 52132 8192000000 10266472736 10709540864 1326659376 13.937 97 25 49 0 1345434320 13125840 5649104
21:48:18 93771 8192000000 10267704592 10711289856 1270877536 13.430 93 13 26 0 1294557728 20826608 2853584
21:50:57 113455 8192000000 10298239888 10743238656 1362877424 14.263 99 17 34 2 1387666528 20768864 4020240
21:53:59 62860 8192000000 10303272752 10733789184 1364322288 14.276 99 23 46 0 1381352288 12194144 4835856
21:56:03 22696 8192000000 10300618560 10722992128 1364222560 14.275 100 45 87 2 1385454352 11811472 9420320
21:57:27 81242 8192000000 10270140304 10725552128 1341960032 14.075 98 23 46 0 1361658800 14389984 5308784
21:59:49 17419 8192000000 10270187888 10718515200 1362150896 14.257 100 28 56 0 1383597264 14892416 6553952
22:01:07 84647 8192000000 10265713712 10729873408 1360776112 14.244 99 7 14 1 1375560848 13003104 1781632
22:03:32 56045 8192000000 10273595664 10731446272 1378668704 14.405 100 18 36 0 1391636208 8245088 4722416
22:05:54 82663 8192000000 10281077776 10734559232 1357118944 14.211 99 17 34 0 1370104272 9137152 3848176
22:08:18 68658 8192000000 10268404528 10720698368 1326965840 13.940 96 17 34 0 1343264432 12340416 3958176
22:10:53 46570 8192000000 10271760096 10729058304 1362760336 14.262 99 16 32 1 1376223248 9952864 3510048
22:12:44 63436 8192000000 10268889712 10720509952 1316128768 13.842 95 16 32 1 1330538528 11335808 3073952
22:14:48 89784 8192000000 10306454784 10742345728 1361563456 14.251 99 32 63 0 1381881088 13298144 7019488
22:17:20 87855 8192000000 10300649424 10751430656 1370494048 14.331 99 16 32 1 1386424336 12464720 3465568
22:19:55 126280 8192000000 10293904208 10745171968 1352506352 14.170 97 28 56 0 1371318368 12409952 6402064
22:23:03 70921 8192000000 10281493984 10740883456 1344412112 14.097 97 17 34 1 1354771424 6839648 3519664
22:26:39 87311 8192000000 10286446560 10744610816 1339785600 14.055 96 21 42 1 1360854432 15560176 5508656
22:29:48 81166 8192000000 10281616640 10738868224 1340483200 14.062 97 17 34 2 1357928688 12827984 4617504
22:32:10 44158 8192000000 10258363872 10703511552 1320196000 13.878 97 36 72 0 1338973952 10739264 8038688
22:33:58 54684 8192000000 10266549392 10702438400 1279180624 13.506 94 39 78 1 1298078800 10181232 8716944
22:35:53 59858 8192000000 10265173776 10712211456 1339761936 14.055 99 26 52 1 1363893568 18145040 5986592

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-12-2021 22:38:53 to 21-12-2021 22:39:08.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 115 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-12-2021 22:39:09 to 21-12-2021 22:39:11.

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          247081
MON$OLDEST_ACTIVE               248508
MON$OLDEST_SNAPSHOT             248508
MON$NEXT_TRANSACTION            248508
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                       2023104
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


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

Database statistics

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

Database validation

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

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

--- start of diff output ---
:248: 
:249: mscs-test-161.budzdorov.ru    Tue Dec 21 22:38:01 2021
:250:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:251: 
:252: 
:253: mscs-test-161.budzdorov.ru    Tue Dec 21 22:38:03 2021
:254:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:255: 
--- end of diff output ---

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

21.12.21 22:39:12. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2021-12-21 22:39:14 No severe PSQL-related problems occured
21.12.21 22:39:15. End of report.