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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-12-15 16:43:27
DTS_END                         2021-12-15 16:43:27.4860
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/35570
TRN_ID                          243350
ATT_ID                          638
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: 9381.13 21512 1125735
customer order: creation 173.65 345 20838
customer order: refuse 15.63 353 1876
order to supplier: creation 47.08 1287 5649
order to supplier: removal 3.48 100616 417
invoice (draft): creation 45.96 3371 5515
invoice (draft): removal 53.97 830 6476
invoice accept: apply 42.68 17671 5121
invoice accept: cancel 48.57 855 5828
customer reserve: creation 3921.36 175 470563
customer reserve: removal 4733.88 57 568065
realization accept: apply 84.78 195 10174
realization accept: cancel 79.93 112 9592
payment from customer: creation 59.53 103 7144
payment from customer: removal 5.83 79 699
payment to supplier: creation 48.85 123 5862
payment to supplier: removal 3.46 83 415
service: total inventory turnovers 4.80 1213 576
service: total monetary turnovers 6.82 45 818
service: aggregate perf. data 0.86 20720 103
service: refresh index statistics 0.03 282002 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-12-2021 16:46:22 to 15-12-2021 16:46:24.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3304
TEST_TIME 2 4437
TEST_TIME 3 3699
TEST_TIME 4 4263
TEST_TIME 5 3840
TEST_TIME 6 3955
TEST_TIME 7 4146
TEST_TIME 8 4154
TEST_TIME 9 4866
TEST_TIME 10 4952
TEST_TIME 11 5588
TEST_TIME 12 6121
TEST_TIME 13 6200
TEST_TIME 14 6479
TEST_TIME 15 6487
TEST_TIME 16 6656
TEST_TIME 17 6849
TEST_TIME 18 7222
TEST_TIME 19 7212
TEST_TIME 20 8005
TEST_TIME 21 8444
TEST_TIME 22 8720
TEST_TIME 23 8863
TEST_TIME 24 9181
TEST_TIME 25 9730
TEST_TIME 26 10548
TEST_TIME 27 11013
TEST_TIME 28 11105
TEST_TIME 29 10865
TEST_TIME 30 10960
TEST_TIME 31 10758
TEST_TIME 32 10691
TEST_TIME 33 10723
TEST_TIME 34 10856
TEST_TIME 35 10752
TEST_TIME 36 10691
TEST_TIME 37 10488
TEST_TIME 38 10347
TEST_TIME 39 10225
TEST_TIME 40 10183
TEST_TIME 41 10073
TEST_TIME 42 10007
TEST_TIME 43 9986
TEST_TIME 44 9888
TEST_TIME 45 9971
TEST_TIME 46 9868
TEST_TIME 47 9753
TEST_TIME 48 9817
TEST_TIME 49 9670
TEST_TIME 50 9592
TEST_TIME 51 9776
TEST_TIME 52 9756
TEST_TIME 53 9767
TEST_TIME 54 9798
TEST_TIME 55 9871
TEST_TIME 56 9839
TEST_TIME 57 9814
TEST_TIME 58 9773
TEST_TIME 59 9798
TEST_TIME 60 9875
TEST_TIME 61 9905
TEST_TIME 62 10060
TEST_TIME 63 10067
TEST_TIME 64 10099
TEST_TIME 65 9956
TEST_TIME 66 9899
TEST_TIME 67 9817
TEST_TIME 68 9873
TEST_TIME 69 9803
TEST_TIME 70 9760
TEST_TIME 71 9714
TEST_TIME 72 9725
TEST_TIME 73 9692
TEST_TIME 74 9649
TEST_TIME 75 9599
TEST_TIME 76 9538
TEST_TIME 77 9528
TEST_TIME 78 9489
TEST_TIME 79 9437
TEST_TIME 80 9488
TEST_TIME 81 9460
TEST_TIME 82 9474
TEST_TIME 83 9454
TEST_TIME 84 9498
TEST_TIME 85 9535
TEST_TIME 86 9512
TEST_TIME 87 9587
TEST_TIME 88 9546
TEST_TIME 89 9626
TEST_TIME 90 9613
TEST_TIME 91 9676
TEST_TIME 92 9707
TEST_TIME 93 9698
TEST_TIME 94 9616
TEST_TIME 95 9543
TEST_TIME 96 9578
TEST_TIME 97 9575
TEST_TIME 98 9538
TEST_TIME 99 9525
TEST_TIME 100 9460
TEST_TIME 101 9390
TEST_TIME 102 9421
TEST_TIME 103 9470
TEST_TIME 104 9447
TEST_TIME 105 9465
TEST_TIME 106 9443
TEST_TIME 107 9455
TEST_TIME 108 9463
TEST_TIME 109 9492
TEST_TIME 110 9518
TEST_TIME 111 9470
TEST_TIME 112 9524
TEST_TIME 113 9521
TEST_TIME 114 9505
TEST_TIME 115 9582
TEST_TIME 116 9579
TEST_TIME 117 9503
TEST_TIME 118 9468
TEST_TIME 119 9449
TEST_TIME 120 9382

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 15-12-2021 16:46:25 to 15-12-2021 16:46:29.

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 1561852 1561852 0 0.00 0 24434 6 0 0
doc_list_biud 1561852 1561852 0 0.00 0 35552 27 0 0
sp_add_invoice_to_stock 5197 5121 76 1.46 39 172323 17671 0 76
sp_cancel_adding_invoice 5828 5828 0 0.00 4 95622 856 0 0
sp_cancel_client_order 1876 1876 0 0.00 4 16876 354 0 0
sp_cancel_customer_reserve 568065 568065 0 0.00 1 35555 58 0 0
sp_cancel_pay_from_customer 699 699 0 0.00 2 3634 80 0 0
sp_cancel_pay_to_supplier 415 415 0 0.00 3 4606 83 0 0
sp_cancel_supplier_invoice 6481 6476 5 0.07 1 20260 831 0 5
sp_cancel_supplier_order 417 417 0 0.00 10 1104829 100617 0 0
sp_cancel_write_off 9592 9592 0 0.00 1 9328 113 0 0
sp_client_order 20838 20838 0 0.00 3 31298 346 0 0
sp_customer_reserve 470563 470563 0 0.00 0 36884 176 0 0
sp_fill_shopping_cart 31991 31991 0 0.00 1 21434 37 0 0
sp_fill_shopping_cart_clo_res 470561 470561 0 0.00 0 18155 13 0 0
sp_get_clo_for_invoice 5119 5119 0 0.00 0 34843 286 0 0
sp_kill_cost_storno 16534 16534 0 0.00 0 15111 61 0 0
sp_kill_qstorno_ret_qs2qd 1152615 1152615 0 0.00 0 35496 32 0 0
sp_kill_qty_storno 607547 607547 0 0.00 0 35552 69 0 0
sp_lock_selected_doc 21112 21112 0 0.00 0 8544 6 0 0
sp_make_cost_storno 28299 28299 0 0.00 0 24366 53 0 0
sp_multiply_rows_for_pdistr 28299 28299 0 0.00 0 8936 29 0 0
sp_multiply_rows_for_qdistr 20838 20838 0 0.00 0 29914 140 0 0
sp_pay_from_customer 7144 7144 0 0.00 4 10221 104 0 0
sp_pay_to_supplier 5862 5862 0 0.00 4 10144 124 0 0
sp_payment_common 13006 13006 0 0.00 1 9381 28 0 0
sp_qd_handle_on_cancel_clo 1876 1876 0 0.00 0 16866 318 0 0
sp_qd_handle_on_invoice_upd_sts 10947 10947 0 0.00 0 27712 319 0 0
sp_qd_handle_on_reserve_upd_sts 10174 10174 0 0.00 0 15427 67 0 0
sp_reserve_write_off 10174 10174 0 0.00 6 15691 196 0 0
sp_supplier_invoice 5515 5515 0 0.00 6 105051 3372 0 0
sp_supplier_order 5649 5649 0 0.00 5 53563 1288 0 0
srv_aggregate_perf_data 103 103 0 0.00 2649 242025 20721 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1552 182511 49646 0 0
srv_find_qd_qs_mism 1077646 1077646 0 0.00 0 14887 6 0 0
srv_make_invnt_saldo 576 576 0 0.00 31 108570 1214 0 0
srv_make_money_saldo 818 818 0 0.00 1 17215 45 0 0
srv_recalc_idx_stat 4 4 0 0.00 181961 346388 282002 0 0
t$perf-abend:sp_add_invoice_to_stock 76 76 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5121 5121 0 0.00 0 21154 169 0 0
t$perf-norm:sp_cancel_adding_invoice 441 441 0 0.00 0 3605 87 0 0
t$perf-norm:sp_cancel_client_order 1876 1876 0 0.00 0 5235 17 0 0
t$perf-norm:sp_cancel_customer_reserve 647 647 0 0.00 0 1729 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 699 699 0 0.00 0 1157 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 415 415 0 0.00 0 6832 21 0 0
t$perf-norm:sp_cancel_supplier_invoice 391 391 0 0.00 0 10436 47 0 0
t$perf-norm:sp_cancel_supplier_order 417 417 0 0.00 0 20251 1614 0 0
t$perf-norm:sp_cancel_write_off 936 936 0 0.00 0 5856 12 0 0
t$perf-norm:sp_client_order 20838 20838 0 0.00 0 9948 17 0 0
t$perf-norm:sp_customer_reserve 1016 1016 0 0.00 0 3153 7 0 0
t$perf-norm:sp_pay_from_customer 7144 7144 0 0.00 0 7937 11 0 0
t$perf-norm:sp_pay_to_supplier 5862 5862 0 0.00 0 9950 10 0 0
t$perf-norm:sp_reserve_write_off 10174 10174 0 0.00 0 8109 12 0 0
t$perf-norm:sp_supplier_invoice 5515 5515 0 0.00 0 13554 23 0 0
t$perf-norm:sp_supplier_order 5649 5649 0 0.00 0 8120 19 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 402 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 52 2 0 0
t$perf-norm:srv_make_invnt_saldo 576 576 0 0.00 0 1169 19 0 0
t$perf-norm:srv_make_money_saldo 818 818 0 0.00 0 939 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 83 24 0 0
v_add_invoice_to_stock 10384 10232 152 1.46 0 4162 19 0 152
v_all_customers 37784 37784 0 0.00 0 18 1 0 0
v_all_suppliers 30764 30764 0 0.00 0 32 1 0 0
v_all_wares 41668 41668 0 0.00 0 40 1 0 0
v_cancel_adding_invoice 882 882 0 0.00 0 338 5 0 0
v_cancel_client_order 3752 3752 0 0.00 0 5162 8 0 0
v_cancel_customer_prepayment 1398 1398 0 0.00 0 3628 10 0 0
v_cancel_customer_reserve 1294 1294 0 0.00 0 3953 10 0 0
v_cancel_payment_to_supplier 830 830 0 0.00 0 3300 9 0 0
v_cancel_supplier_invoice 792 782 10 1.26 0 5021 24 0 10
v_cancel_supplier_order 834 834 0 0.00 0 1416 7 0 0
v_cancel_write_off 1872 1872 0 0.00 0 1959 15 0 0
v_max_id_clo_ord 5647 5647 0 0.00 0 11600 4 0 0
v_max_id_clo_res 1016 1016 0 0.00 0 3800 14 0 0
v_max_id_ord_sup 5510 5510 0 0.00 0 2976 3 0 0
v_max_non_paid_invoice 1637 1637 0 0.00 0 462 3 0 0
v_max_non_paid_realizn 3830 3830 0 0.00 0 5356 7 0 0
v_min_id_clo_ord 5647 5647 0 0.00 0 5111 7 0 0
v_min_id_clo_res 1016 1016 0 0.00 0 476 2 0 0
v_min_id_ord_sup 5510 5510 0 0.00 0 7671 4 0 0
v_min_non_paid_invoice 5862 5862 0 0.00 0 7265 17 0 0
v_min_non_paid_realizn 7144 7144 0 0.00 0 9376 22 0 0
v_our_firm 10512 10512 0 0.00 0 33 1 0 0
v_reserve_write_off 20348 20348 0 0.00 0 5051 8 0 0
x_lock_depdocs_on_canc_invoice 11913 11913 0 0.00 0 23147 119 0 0
x_lock_depdocs_on_canc_sup_ord 417 417 0 0.00 0 18138 379 0 0
x_make_qstorno_1000_1200 5649 5649 0 0.00 1 53557 1204 0 0
x_make_qstorno_1200_2000 5515 5515 0 0.00 2 102212 3246 0 0
x_make_qstorno_2100_3300 470563 470563 0 0.00 0 36879 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-12-2021 16:46:30 to 15-12-2021 16:46:34.

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
14:13:31 33 8192000000 8815562320 8832192512 19398128 0.236 5 5 8 0 19691616 100576 192912
14:14:32 34388 8192000000 9911313920 10223783936 990382160 10.785 100 89 178 0 1019928304 6203328 23342816
14:16:08 8015 8192000000 9945901136 10266607616 1028173184 11.151 100 91 182 0 1060252912 8418896 23660832
14:17:26 10641 8192000000 9972568208 10304860160 1057207040 11.430 100 78 156 0 1085956176 8968560 19780576
14:18:43 10907 8192000000 10033443840 10380058624 1119508464 12.022 100 82 164 0 1150471008 10903856 20058688
14:19:57 9096 8192000000 10080981264 10442002432 1166849136 12.467 100 70 139 1 1198431648 15206096 16376416
14:21:09 4651 8192000000 10127991968 10502500352 1223268368 12.992 100 79 158 0 1254276256 13089728 17918160
14:22:18 4940 8192000000 10169179952 10548310016 1255293904 13.287 100 74 148 1 1285758144 14760160 15704080
14:23:25 1508 8192000000 10202418416 10587828224 1290530096 13.609 100 88 175 1 1323047920 12856000 19661824
14:24:28 1440 8192000000 10213341984 10600235008 1298899952 13.685 100 76 151 2 1328065008 11839856 17325200
14:25:31 6409 8192000000 10196514544 10606866432 1308322688 13.771 100 35 68 1 1323915184 7956960 7635536
14:26:41 11245 8192000000 10207996304 10616856576 1313678736 13.819 100 23 44 1 1324470672 5816288 4975648
14:27:52 20668 8192000000 10237069920 10631888896 1321026320 13.886 100 68 141 2 1344176832 6258592 16891920
14:29:13 5049 8192000000 10214022896 10634063872 1324271248 13.915 100 32 63 0 1338990240 7440096 7278896
14:30:21 6907 8192000000 10205381744 10631868416 1324093024 13.914 100 16 32 1 1333216176 5658608 3464544
14:31:28 20048 8192000000 10213481456 10640039936 1329451008 13.962 100 31 60 2 1342639856 6137488 7051360
14:32:49 7667 8192000000 10221473200 10638340096 1328721264 13.956 100 31 63 1 1342432752 6071840 7639648
14:34:00 9204 8192000000 10173449424 10581581824 1226537024 13.022 94 22 44 0 1237095808 5183888 5374896
14:35:10 54987 8192000000 10158455008 10538479616 1120487616 12.032 87 59 118 1 1139903312 5471184 13944512
14:37:05 8232 8192000000 10132147344 10522148864 1225811728 13.015 99 26 52 1 1237275152 5397296 6066128
14:38:16 10332 8192000000 10165513344 10568794112 1271265920 13.433 100 39 78 4 1286702592 5512736 9923936
14:39:30 11595 8192000000 10174926544 10583638016 1287145200 13.578 100 12 24 0 1294902144 5094752 2662192
14:40:49 20961 8192000000 10181647040 10595659776 1290056048 13.605 100 19 37 2 1300685008 5429936 5199024
14:42:12 16782 8192000000 10193946640 10609700864 1300814496 13.703 100 21 42 0 1311614928 5475728 5324704
14:43:32 8878 8192000000 10234238176 10638139392 1326259792 13.933 100 31 62 0 1337881920 5049648 6572480
14:44:46 37182 8192000000 10266590176 10678988800 1351308272 14.159 100 47 93 2 1373650512 12303712 10038528
14:46:34 75801 8192000000 10279490736 10702336000 1368714448 14.316 100 33 66 0 1393122608 16287552 8120608
14:48:58 9172 8192000000 10301766176 10722856960 1385276416 14.464 100 44 88 1 1414590912 18870560 10443936
14:50:24 3933 8192000000 10320490944 10738335744 1393930864 14.541 100 66 132 2 1432421584 22668432 15822288
14:51:32 2455 8192000000 10335405408 10755010560 1400439776 14.599 100 48 96 1 1442794304 30182288 12172240
14:52:38 6123 8192000000 10323152944 10746228736 1381647360 14.431 99 56 111 2 1431946768 35962032 14337376
14:53:49 9550 8192000000 10338613664 10759507968 1400044896 14.595 100 60 121 2 1451546944 37301712 14200336
14:55:03 2508 8192000000 10352794736 10769166336 1403121072 14.623 100 78 156 1 1459299248 37596848 18581328
14:56:15 2302 8192000000 10364776672 10779492352 1412435712 14.706 100 76 152 0 1468820384 38370112 18014560
14:57:22 4579 8192000000 10338573904 10758791168 1390466448 14.510 100 66 133 1 1452233376 45478912 16288016
14:58:31 2469 8192000000 10372009216 10781110272 1405867152 14.647 100 89 176 1 1473932144 45890000 22174992
14:59:41 1724 8192000000 10381338128 10790694912 1408172464 14.668 100 81 162 1 1487593648 58544944 20876240
15:00:51 1551 8192000000 10378310992 10788974592 1395599280 14.556 99 72 143 1 1474988048 60733584 18655184
15:01:57 3547 8192000000 10382564912 10785083392 1400947584 14.603 100 86 172 1 1483027024 60442224 21637216
15:03:09 2796 8192000000 10368519472 10777268224 1387997264 14.488 100 79 158 0 1474885104 66253056 20634784
15:04:14 15108 8192000000 10335766688 10765307904 1365353072 14.285 99 37 74 3 1434725472 59145376 10227024
15:05:43 21310 8192000000 10332209872 10762969088 1389963392 14.506 100 27 54 1 1445581072 47620160 7997520
15:07:07 27533 8192000000 10334583456 10772426752 1381122832 14.427 100 31 62 1 1444948112 54294288 9530992
15:08:49 40996 8192000000 10297025984 10752212992 1353062336 14.175 99 15 30 0 1405398720 48032448 4303936
15:10:37 62596 8192000000 10277437360 10735583232 1361035536 14.247 99 11 22 0 1387198432 23414784 2748112
15:12:45 141730 8192000000 10277160768 10705670144 1259184944 13.323 91 21 42 0 1276163904 11206656 5772304
15:16:08 46064 8192000000 10271534512 10713366528 1342218496 14.077 98 17 33 0 1355563536 10329872 3015168
15:17:55 182510 8192000000 10291898560 10735411200 1362466624 14.259 99 37 72 2 1380075296 8389168 9219504
15:22:38 62599 8192000000 10285024784 10739306496 1384496464 14.457 100 28 54 1 1400987424 10564032 5926928
15:24:50 12078 8192000000 10285485648 10732974080 1380127696 14.418 100 33 66 2 1396068304 8541536 7399072
15:26:03 70912 8192000000 10319115808 10757521408 1394233824 14.544 100 37 73 0 1415728880 12566400 8928656
15:28:19 40372 8192000000 10290406848 10749341696 1365650528 14.288 98 14 28 0 1383696928 14553824 3492576
15:30:00 53780 8192000000 10298272800 10753232896 1394481232 14.546 100 11 22 0 1408122672 11130592 2510848
15:32:10 55254 8192000000 10308475040 10767753216 1403440528 14.626 100 13 26 1 1421118400 14315584 3362288
15:34:41 68902 8192000000 10292380912 10761715712 1380644592 14.422 99 14 28 0 1393142864 9212720 3285552
15:36:50 42751 8192000000 10293049296 10758766592 1381242512 14.428 100 19 38 1 1401074304 15487424 4344368
15:38:51 47913 8192000000 10291573392 10733936640 1338905312 14.048 97 31 62 2 1354064800 8037680 7121808
15:40:40 24471 8192000000 10306413600 10747162624 1365366528 14.286 99 41 80 0 1386067152 11416080 9284544
15:42:10 66298 8192000000 10298461056 10739093504 1331826768 13.984 97 19 38 1 1356632304 20218336 4587200
15:44:18 75763 8192000000 10281006560 10738618368 1329023840 13.958 97 13 26 0 1346793968 15199792 2570336
15:46:34 96698 8192000000 10261657600 10721071104 1310875968 13.794 96 7 14 1 1331256320 19153136 1227216
15:49:38 66538 8192000000 10263635856 10707562496 1335908512 14.021 98 32 64 1 1356334064 12898560 7526992
15:51:47 34209 8192000000 10248035568 10708807680 1324115376 13.914 97 9 18 0 1332903680 6822896 1965408
15:53:37 70322 8192000000 10251667920 10708287488 1336387104 14.025 98 15 29 1 1347767088 8101152 3278832
15:55:59 53128 8192000000 10237619968 10686451712 1316255040 13.843 97 13 26 1 1326194928 6723744 3216144
15:57:52 146448 8192000000 10241995216 10703331328 1291749088 13.620 95 12 24 1 1301767760 7811568 2207104
16:01:22 56997 8192000000 10255027312 10710171648 1346006288 14.112 99 18 36 1 1359572080 9262864 4302928
16:03:19 73326 8192000000 10269200032 10728239104 1356027376 14.202 100 4 8 0 1367589568 10556960 1005232
16:05:37 37389 8192000000 10269305456 10730426368 1352905376 14.174 99 25 50 0 1376346832 17805648 5635808
16:07:18 54701 8192000000 10290336224 10733916160 1368267328 14.312 100 33 66 0 1390947680 15277104 7403248
16:09:13 25378 8192000000 10297830368 10741772288 1360515584 14.242 99 38 76 0 1389417984 20775328 8127072
16:10:39 23936 8192000000 10298909984 10738069504 1351294016 14.159 98 43 82 4 1370020736 9585696 9141024
16:12:12 59549 8192000000 10283741760 10746032128 1351830976 14.164 98 23 46 1 1371169744 14693696 4645072
16:14:12 110387 8192000000 10281643632 10738540544 1331469312 13.980 96 16 32 1 1347122560 12373888 3279360
16:17:03 136481 8192000000 10306009392 10765312000 1396682320 14.565 100 24 48 2 1419086624 16833712 5570592
16:20:20 69614 8192000000 10278293200 10746884096 1370464496 14.331 98 32 64 1 1387244704 8709552 8070656
16:22:30 75885 8192000000 10279049648 10734338048 1319410336 13.871 95 9 18 0 1331683552 10251360 2021856
16:24:50 90649 8192000000 10271462176 10738331648 1366734480 14.298 99 14 28 0 1381931840 11798656 3398704
16:27:39 37697 8192000000 10294594080 10753609728 1392867792 14.531 100 22 44 0 1406084048 8126416 5089840
16:29:21 30287 8192000000 10272020416 10719363072 1321767440 13.893 96 34 67 2 1337422112 8473024 7181648
16:30:54 68083 8192000000 10279621744 10742681600 1350394752 14.151 98 27 54 1 1371608320 15401984 5811584
16:33:51 35541 8192000000 10298374192 10748706816 1361759808 14.253 98 20 40 1 1378127232 11797552 4569872
16:35:36 34393 8192000000 10296493264 10733690880 1349960432 14.147 98 35 71 0 1366406224 8891632 7554160
16:37:14 43521 8192000000 10289254000 10736566272 1339208976 14.050 97 25 50 0 1357996736 13443232 5344528
16:38:59 146557 8192000000 10260353872 10716119040 1318066832 13.859 96 15 30 0 1335748208 14357952 3323424
16:42:26 66752 8192000000 10090579056 10530185216 1190974960 12.692 87 9 18 0 1204592544 11584816 2032768

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 15-12-2021 16:46:35 to 15-12-2021 16:46:50.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 15-12-2021 16:46:51 to 15-12-2021 16:46:53.

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          244392
MON$OLDEST_ACTIVE               244903
MON$OLDEST_SNAPSHOT             244903
MON$NEXT_TRANSACTION            244903
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                       2040224
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.33544 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33544 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33544 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33544 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Wed Dec 15 16:43:37 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Wed Dec 15 16:44:05 2021
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:13: 
--- end of diff output ---

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

15.12.21 16:46:54. 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-15 16:46:57 No severe PSQL-related problems occured
15.12.21 16:46:58. End of report.