Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.07.2022 16:39
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 15928376 kB
MemAvailable 31019452 kB
Buffers 0 kB
Cached 15767932 kB
SwapCached 2552 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.11
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 2022-07-25 16:38:23
DTS_END                         2022-07-25 16:38:23.5010
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/55942
TRN_ID                          249160
ATT_ID                          681
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: 9490.77 22676 1138892
customer order: creation 176.88 351 21225
customer order: refuse 15.82 335 1898
order to supplier: creation 46.97 1208 5636
order to supplier: removal 3.44 102223 413
invoice (draft): creation 46.91 3221 5629
invoice (draft): removal 55.17 711 6620
invoice accept: apply 43.53 17567 5223
invoice accept: cancel 49.88 794 5985
customer reserve: creation 3942.74 178 473129
customer reserve: removal 4792.73 58 575128
realization accept: apply 87.12 202 10454
realization accept: cancel 95.29 114 11435
payment from customer: creation 60.13 123 7215
payment from customer: removal 6.20 99 744
payment to supplier: creation 51.21 129 6145
payment to supplier: removal 3.83 75 459
service: total inventory turnovers 5.38 1116 646
service: total monetary turnovers 6.68 43 801
service: aggregate perf. data 0.86 22616 103
service: refresh index statistics 0.03 302357 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 25-07-2022 16:39:39 to 25-07-2022 16:39:41.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3561
TEST_TIME 2 2444
TEST_TIME 3 2662
TEST_TIME 4 3544
TEST_TIME 5 3858
TEST_TIME 6 4230
TEST_TIME 7 4174
TEST_TIME 8 4279
TEST_TIME 9 4299
TEST_TIME 10 4695
TEST_TIME 11 5009
TEST_TIME 12 5547
TEST_TIME 13 5835
TEST_TIME 14 6415
TEST_TIME 15 6399
TEST_TIME 16 6816
TEST_TIME 17 7162
TEST_TIME 18 7249
TEST_TIME 19 8476
TEST_TIME 20 8456
TEST_TIME 21 8553
TEST_TIME 22 9337
TEST_TIME 23 9523
TEST_TIME 24 9533
TEST_TIME 25 9688
TEST_TIME 26 10362
TEST_TIME 27 10559
TEST_TIME 28 11074
TEST_TIME 29 10870
TEST_TIME 30 10675
TEST_TIME 31 10633
TEST_TIME 32 10626
TEST_TIME 33 10413
TEST_TIME 34 10492
TEST_TIME 35 10583
TEST_TIME 36 10395
TEST_TIME 37 10288
TEST_TIME 38 10260
TEST_TIME 39 10253
TEST_TIME 40 10202
TEST_TIME 41 10150
TEST_TIME 42 10016
TEST_TIME 43 9993
TEST_TIME 44 10121
TEST_TIME 45 9955
TEST_TIME 46 9843
TEST_TIME 47 9862
TEST_TIME 48 9802
TEST_TIME 49 9727
TEST_TIME 50 9622
TEST_TIME 51 9720
TEST_TIME 52 9813
TEST_TIME 53 9741
TEST_TIME 54 9842
TEST_TIME 55 9851
TEST_TIME 56 9817
TEST_TIME 57 9888
TEST_TIME 58 10028
TEST_TIME 59 9968
TEST_TIME 60 10034
TEST_TIME 61 10018
TEST_TIME 62 10024
TEST_TIME 63 10059
TEST_TIME 64 9936
TEST_TIME 65 9889
TEST_TIME 66 9789
TEST_TIME 67 9758
TEST_TIME 68 9724
TEST_TIME 69 9743
TEST_TIME 70 9762
TEST_TIME 71 9675
TEST_TIME 72 9649
TEST_TIME 73 9650
TEST_TIME 74 9632
TEST_TIME 75 9614
TEST_TIME 76 9649
TEST_TIME 77 9645
TEST_TIME 78 9633
TEST_TIME 79 9780
TEST_TIME 80 9833
TEST_TIME 81 9838
TEST_TIME 82 9841
TEST_TIME 83 9883
TEST_TIME 84 9951
TEST_TIME 85 9964
TEST_TIME 86 9859
TEST_TIME 87 9822
TEST_TIME 88 9806
TEST_TIME 89 9789
TEST_TIME 90 9822
TEST_TIME 91 9772
TEST_TIME 92 9723
TEST_TIME 93 9704
TEST_TIME 94 9713
TEST_TIME 95 9712
TEST_TIME 96 9676
TEST_TIME 97 9696
TEST_TIME 98 9759
TEST_TIME 99 9735
TEST_TIME 100 9748
TEST_TIME 101 9772
TEST_TIME 102 9749
TEST_TIME 103 9808
TEST_TIME 104 9772
TEST_TIME 105 9856
TEST_TIME 106 9849
TEST_TIME 107 9850
TEST_TIME 108 9843
TEST_TIME 109 9763
TEST_TIME 110 9773
TEST_TIME 111 9725
TEST_TIME 112 9721
TEST_TIME 113 9689
TEST_TIME 114 9658
TEST_TIME 115 9628
TEST_TIME 116 9578
TEST_TIME 117 9535
TEST_TIME 118 9553
TEST_TIME 119 9534
TEST_TIME 120 9491

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 25-07-2022 16:39:43 to 25-07-2022 16:39: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 1577674 1577674 0 0.00 0 16879 6 0 0
doc_list_biud 1577657 1577657 0 0.00 0 38616 27 0 0
sp_add_invoice_to_stock 5333 5223 110 2.06 41 180948 17568 0 110
sp_cancel_adding_invoice 5985 5985 0 0.00 4 57858 794 0 0
sp_cancel_client_order 1898 1898 0 0.00 4 24039 335 0 0
sp_cancel_customer_reserve 575128 575128 0 0.00 1 24716 58 0 0
sp_cancel_pay_from_customer 744 744 0 0.00 2 23559 100 0 0
sp_cancel_pay_to_supplier 459 459 0 0.00 3 4976 76 0 0
sp_cancel_supplier_invoice 6629 6620 9 0.13 2 14732 711 0 9
sp_cancel_supplier_order 413 413 0 0.00 7 1095467 102224 0 0
sp_cancel_write_off 11435 11435 0 0.00 1 24483 115 0 0
sp_client_order 21225 21225 0 0.00 3 31113 352 0 0
sp_customer_reserve 473129 473129 0 0.00 0 59013 178 0 0
sp_fill_shopping_cart 32490 32490 0 0.00 1 27212 41 0 0
sp_fill_shopping_cart_clo_res 473127 473127 0 0.00 0 35310 16 0 0
sp_get_clo_for_invoice 5218 5218 0 0.00 0 37024 265 0 0
sp_kill_cost_storno 18623 18623 0 0.00 0 7798 55 0 0
sp_kill_qstorno_ret_qs2qd 1168724 1168724 0 0.00 0 24711 31 0 0
sp_kill_qty_storno 617138 617138 0 0.00 0 38616 68 0 0
sp_lock_selected_doc 21706 21706 0 0.00 0 7688 5 0 0
sp_make_cost_storno 29032 29032 0 0.00 0 16448 58 0 0
sp_multiply_rows_for_pdistr 29019 29019 0 0.00 0 10999 30 0 0
sp_multiply_rows_for_qdistr 21225 21225 0 0.00 0 23996 157 0 0
sp_pay_from_customer 7215 7215 0 0.00 4 18964 123 0 0
sp_pay_to_supplier 6145 6145 0 0.00 4 23094 129 0 0
sp_payment_common 13355 13355 0 0.00 2 10099 30 0 0
sp_qd_handle_on_cancel_clo 1898 1898 0 0.00 0 24026 305 0 0
sp_qd_handle_on_invoice_upd_sts 11202 11202 0 0.00 0 38615 323 0 0
sp_qd_handle_on_reserve_upd_sts 10442 10442 0 0.00 0 11253 58 0 0
sp_reserve_write_off 10454 10454 0 0.00 6 23329 203 0 0
sp_supplier_invoice 5629 5629 0 0.00 6 118576 3221 0 0
sp_supplier_order 5636 5636 0 0.00 4 45909 1208 0 0
srv_aggregate_perf_data 103 103 0 0.00 2291 411892 22616 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 863 145072 43327 0 0
srv_find_qd_qs_mism 1090119 1090119 0 0.00 0 16870 5 0 0
srv_make_invnt_saldo 646 646 0 0.00 25 112703 1116 0 0
srv_make_money_saldo 801 801 0 0.00 1 6906 43 0 0
srv_recalc_idx_stat 4 4 0 0.00 133715 404134 302358 0 0
t$perf-abend:sp_add_invoice_to_stock 110 110 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5223 5223 0 0.00 0 11418 176 0 0
t$perf-norm:sp_cancel_adding_invoice 426 426 0 0.00 0 6546 102 0 0
t$perf-norm:sp_cancel_client_order 1898 1898 0 0.00 0 3637 14 0 0
t$perf-norm:sp_cancel_customer_reserve 654 654 0 0.00 0 1595 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 744 744 0 0.00 0 3217 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 459 459 0 0.00 0 4170 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 409 409 0 0.00 0 4870 43 0 0
t$perf-norm:sp_cancel_supplier_order 413 413 0 0.00 0 24233 1547 0 0
t$perf-norm:sp_cancel_write_off 1045 1045 0 0.00 0 2495 10 0 0
t$perf-norm:sp_client_order 21225 21225 0 0.00 0 14517 18 0 0
t$perf-norm:sp_customer_reserve 1034 1034 0 0.00 0 2659 8 0 0
t$perf-norm:sp_pay_from_customer 7215 7215 0 0.00 0 8281 13 0 0
t$perf-norm:sp_pay_to_supplier 6145 6145 0 0.00 0 9708 17 0 0
t$perf-norm:sp_reserve_write_off 10454 10454 0 0.00 0 11204 15 0 0
t$perf-norm:sp_supplier_invoice 5629 5629 0 0.00 0 5644 19 0 0
t$perf-norm:sp_supplier_order 5636 5636 0 0.00 0 5150 16 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 98 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 7 0 0 0
t$perf-norm:srv_make_invnt_saldo 646 646 0 0.00 0 1600 25 0 0
t$perf-norm:srv_make_money_saldo 801 801 0 0.00 0 4458 23 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10631 10411 220 2.06 0 21998 24 0 220
v_all_customers 38564 38564 0 0.00 0 31 1 0 0
v_all_suppliers 31180 31180 0 0.00 0 28 1 0 0
v_all_wares 42450 42450 0 0.00 0 30 1 0 0
v_cancel_adding_invoice 852 852 0 0.00 0 4543 16 0 0
v_cancel_client_order 3796 3796 0 0.00 0 3572 7 0 0
v_cancel_customer_prepayment 1488 1488 0 0.00 0 23545 26 0 0
v_cancel_customer_reserve 1308 1308 0 0.00 0 3039 9 0 0
v_cancel_payment_to_supplier 918 918 0 0.00 0 2015 6 0 0
v_cancel_supplier_invoice 836 818 18 2.15 0 3433 22 0 18
v_cancel_supplier_order 826 826 0 0.00 0 3463 11 0 0
v_cancel_write_off 2090 2090 0 0.00 0 4962 18 0 0
v_max_id_clo_ord 5636 5636 0 0.00 0 3543 2 0 0
v_max_id_clo_res 1034 1034 0 0.00 0 6434 24 0 0
v_max_id_ord_sup 5629 5629 0 0.00 0 6021 7 0 0
v_max_non_paid_invoice 1818 1818 0 0.00 0 2007 4 0 0
v_max_non_paid_realizn 3782 3782 0 0.00 0 4728 6 0 0
v_min_id_clo_ord 5636 5636 0 0.00 0 3210 7 0 0
v_min_id_clo_res 1034 1034 0 0.00 0 8242 25 0 0
v_min_id_ord_sup 5629 5629 0 0.00 0 7629 9 0 0
v_min_non_paid_invoice 6143 6143 0 0.00 0 8602 22 0 0
v_min_non_paid_realizn 7212 7212 0 0.00 0 10095 22 0 0
v_our_firm 10746 10746 0 0.00 0 30 1 0 0
v_reserve_write_off 20884 20884 0 0.00 0 23271 16 0 0
x_lock_depdocs_on_canc_invoice 12196 12196 0 0.00 0 20348 111 0 0
x_lock_depdocs_on_canc_sup_ord 413 413 0 0.00 0 7639 330 0 0
x_make_qstorno_1000_1200 5636 5636 0 0.00 1 45901 1135 0 0
x_make_qstorno_1200_2000 5629 5629 0 0.00 2 108598 3060 0 0
x_make_qstorno_2100_3300 473129 473129 0 0.00 0 59010 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 25-07-2022 16:39:47 to 25-07-2022 16:39: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
14:08:27 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:28 11876 8192000000 9885275104 10189283328 971123792 10.598 100 91 182 0 1001795408 5678944 24992672
14:10:47 25243 8192000000 9944269408 10260963328 1020810272 11.080 100 91 182 0 1052182352 7737680 23634400
14:12:28 19761 8192000000 9974161888 10307751936 1060366064 11.460 100 81 162 0 1089296608 9435536 19495008
14:13:51 8664 8192000000 10035839632 10381799424 1120693728 12.034 100 83 166 0 1150982496 10694096 19594672
14:15:09 6207 8192000000 10097503056 10456682496 1177640912 12.568 100 81 162 1 1209237712 12781344 18815456
14:16:19 2619 8192000000 10134681792 10503925760 1221803104 12.978 100 85 170 0 1254841776 14239440 18799232
14:17:24 2434 8192000000 10174372784 10550038528 1257397120 13.306 100 83 163 0 1287906224 12867840 17641264
14:18:27 1417 8192000000 10203787808 10587140096 1284349840 13.553 100 82 164 0 1316875776 14536112 17989824
14:19:30 1862 8192000000 10222938672 10612383744 1309504320 13.782 100 84 168 2 1341646192 12957536 19184336
14:20:33 6809 8192000000 10211511568 10616737792 1314987120 13.831 100 32 64 1 1330347696 8059216 7301360
14:21:44 10114 8192000000 10215020560 10631909376 1327659856 13.946 100 23 47 0 1338107920 5571552 4876512
14:22:56 13816 8192000000 10211830288 10637189120 1331173808 13.978 100 14 28 0 1340397568 6360368 2863392
14:24:12 50153 8192000000 10229020368 10649960448 1339994784 14.057 100 36 68 1 1354307504 6075840 8236880
14:26:06 6326 8192000000 10229205664 10652266496 1341117552 14.067 100 31 59 1 1354052208 5432128 7502528
14:27:13 9227 8192000000 10214784096 10629599232 1281122400 13.523 96 16 30 0 1290035472 5481648 3431424
14:28:24 15325 8192000000 10185811088 10598023168 1244586480 13.188 95 34 69 0 1257641392 5526656 7528256
14:29:40 7620 8192000000 10180658256 10587684864 1254343680 13.278 97 45 88 0 1270414816 5749824 10321312
14:30:53 5596 8192000000 10146160432 10539802624 1206245408 12.834 96 30 57 1 1218133824 5268032 6620384
14:32:00 6054 8192000000 10162335776 10563633152 1262537616 13.353 100 34 70 0 1276343296 5442000 8363680
14:33:09 13534 8192000000 10168666064 10578890752 1276651456 13.482 100 16 32 1 1286425808 5930960 3843392
14:34:26 24326 8192000000 10174030688 10588209152 1285711824 13.565 100 25 50 0 1296872160 6092384 5067952
14:35:51 4779 8192000000 10199164464 10613202944 1303535008 13.727 100 30 60 1 1316654816 6510672 6609136
14:36:58 12053 8192000000 10206791936 10621181952 1298764576 13.684 99 28 57 0 1311127216 5272320 7090320
14:38:10 5525 8192000000 10214308160 10633388032 1325031568 13.922 100 19 38 0 1334703936 5363696 4308672
14:39:29 145072 8192000000 10262746224 10682400768 1355460960 14.197 100 38 75 2 1375819552 11422032 8936560
14:43:34 15135 8192000000 10299173808 10721550336 1380973552 14.425 100 33 64 0 1410438864 21664272 7801040
14:44:56 15271 8192000000 10318160576 10740076544 1395149424 14.552 100 46 92 1 1427565584 20961968 11454192
14:46:14 5763 8192000000 10333256784 10750947328 1403556832 14.627 100 48 96 0 1440249504 24395840 12296832
14:47:31 8055 8192000000 10338199040 10762489856 1408368096 14.669 100 45 90 2 1448448912 28678192 11402624
14:48:45 4585 8192000000 10349796128 10773913600 1416370864 14.741 100 51 102 0 1461777328 32741440 12665024
14:49:52 6924 8192000000 10347307248 10762653696 1402284352 14.615 100 62 124 0 1454872480 36886080 15702048
14:51:06 2223 8192000000 10356938064 10772893696 1403762576 14.628 99 75 150 0 1458877840 35839664 19275600
14:52:14 2600 8192000000 10343622144 10753196032 1376379088 14.384 98 78 152 0 1438542960 43460320 18703552
14:53:27 4390 8192000000 10360152384 10769432576 1395782928 14.557 100 73 146 0 1460591536 46531200 18277408
14:54:36 2873 8192000000 10361177008 10777690112 1388695760 14.494 100 80 161 1 1467471968 58509440 20266768
14:55:46 4151 8192000000 10335937664 10739929088 1366084784 14.292 100 84 166 4 1442381008 55776880 20519344
14:56:54 3374 8192000000 10352301968 10757390336 1372775344 14.352 100 78 157 2 1451555344 59401648 19378352
14:57:59 9287 8192000000 10323054048 10748174336 1365115008 14.283 99 42 79 1 1412768832 36987920 10665904
14:59:12 3594 8192000000 10340432000 10754936832 1370146384 14.328 99 68 131 1 1439003424 52104896 16752144
15:00:18 19054 8192000000 10333331024 10778415104 1387273344 14.482 100 34 68 1 1454901952 57450384 10178224
15:01:50 13961 8192000000 10311434320 10749505536 1360305312 14.240 99 29 59 1 1405859344 38390384 7163648
15:03:06 30766 8192000000 10312192720 10752720896 1369255280 14.320 100 22 44 0 1419842224 44182064 6404880
15:04:41 46477 8192000000 10288099072 10736812032 1348784928 14.137 99 16 32 0 1390819504 37518736 4515840
15:06:30 39815 8192000000 10256573200 10696687616 1299627152 13.692 96 5 10 0 1312670320 11984272 1058896
15:08:12 15510 8192000000 10293498016 10717151232 1359589360 14.234 100 44 91 0 1382980016 13572384 9818272
15:09:28 64761 8192000000 10305668016 10750885888 1384100496 14.453 100 34 66 1 1407380640 16189408 7090736
15:11:39 29189 8192000000 10310259504 10746212352 1385740064 14.468 100 40 82 0 1406767008 11153792 9873152
15:13:24 76848 8192000000 10278074528 10738950144 1365361840 14.285 99 18 36 0 1386946448 17938720 3645888
15:16:04 53457 8192000000 10286341504 10741981184 1379846336 14.415 99 16 32 1 1392718720 9039552 3832832
15:18:09 25615 8192000000 10310927488 10761531392 1386971392 14.479 99 41 81 0 1409807504 13954928 8881184
15:19:57 88543 8192000000 10297228640 10753736704 1374365584 14.366 98 11 22 0 1391224768 14008096 2851088
15:23:20 43573 8192000000 10287444896 10744471552 1389583008 14.502 100 16 30 1 1399334400 6622016 3129376
15:25:04 43897 8192000000 10297184896 10750840832 1385755856 14.468 100 29 57 0 1409062416 16846960 6459600
15:26:56 53509 8192000000 10281212912 10743664640 1362428336 14.259 98 9 18 0 1375208448 10739856 2040256
15:28:58 74272 8192000000 10293515072 10760757248 1393567328 14.538 100 18 36 0 1415825936 17896688 4361920
15:31:13 23847 8192000000 10301815200 10744840192 1377463568 14.394 99 48 94 0 1396795728 8479936 10852224
15:32:40 74322 8192000000 10270074256 10722357248 1289827872 13.603 93 10 20 0 1300633392 8725696 2079824
15:34:55 31935 8192000000 10303636608 10751750144 1383648640 14.449 100 26 52 0 1401414688 11966560 5799488
15:36:36 57759 8192000000 10289070848 10725572608 1346535152 14.116 98 12 24 0 1359704400 10316480 2852768
15:38:37 42758 8192000000 10275007040 10731593728 1336746400 14.028 97 24 48 0 1358580976 16508752 5325824
15:40:20 39036 8192000000 10267025168 10705760256 1298792320 13.684 95 33 66 1 1317383008 11273456 7317232
15:42:06 86373 8192000000 10247904032 10689875968 1304654656 13.738 96 14 28 0 1315489280 7594912 3239712
15:45:07 862 8192000000 10285185936 10719846400 1366837808 14.299 100 48 97 0 1391789536 13129104 11822624
15:46:08 49182 8192000000 10247048064 10685399040 1299547280 13.691 95 28 57 0 1317434064 11783392 6103392
15:47:58 40402 8192000000 10231740944 10690609152 1340488928 14.062 100 13 24 0 1355147744 11827296 2831520
15:49:39 73325 8192000000 10250990896 10709463040 1336363200 14.025 98 9 18 0 1352951504 14805760 1782544
15:51:53 53838 8192000000 10275802400 10725580800 1323854320 13.912 96 22 44 0 1336606752 7710608 5041824
15:53:53 35493 8192000000 10291867920 10735345664 1349527776 14.143 98 20 41 1 1369467984 15001440 4938768
15:55:36 49112 8192000000 10302257504 10747785216 1366628960 14.297 98 21 42 0 1388508272 17173024 4706288
15:57:26 66814 8192000000 10277999600 10741301248 1346864224 14.119 98 6 12 0 1362636608 14535632 1236752
15:59:41 60421 8192000000 10279198576 10722566144 1349729504 14.145 98 14 28 0 1366460304 13405984 3324816
16:01:45 36412 8192000000 10287344112 10736926720 1346574000 14.117 98 29 58 0 1366748544 13920304 6254240
16:03:33 99618 8192000000 10291241056 10737053696 1382011120 14.435 100 16 31 0 1395401648 10037952 3352576
16:06:23 49290 8192000000 10297850512 10754662400 1366288752 14.294 98 26 52 1 1391909824 20056368 5564704
16:08:16 68598 8192000000 10306002624 10772647936 1402473232 14.617 100 18 36 0 1416959008 10718960 3766816
16:10:25 56320 8192000000 10297949408 10751221760 1386043264 14.471 99 12 24 0 1399809472 11321984 2444224
16:12:26 43999 8192000000 10297888544 10760249344 1396253776 14.562 100 18 36 0 1410513200 10117616 4141808
16:14:10 47709 8192000000 10312225920 10766110720 1380004544 14.417 98 26 51 0 1393849264 7998656 5846064
16:16:00 29069 8192000000 10300566128 10755514368 1376152928 14.382 99 32 64 0 1397099264 13434208 7512128
16:17:29 59493 8192000000 10317032816 10764046336 1386763200 14.477 99 20 40 0 1400972288 9353328 4855760
16:19:37 78261 8192000000 10316186976 10770907136 1381977136 14.434 99 16 32 0 1406957856 21538816 3441904
16:21:57 76519 8192000000 10294147936 10745937920 1312178112 13.806 94 27 54 1 1332584832 14045696 6361024
16:24:28 69534 8192000000 10275787360 10721710080 1352851440 14.173 98 20 40 0 1370147952 13197040 4099472
16:26:38 110651 8192000000 10300598432 10749059072 1379165424 14.409 99 27 53 0 1396915504 11976560 5773520
16:29:30 21680 8192000000 10308016688 10757824512 1391257136 14.517 100 27 54 1 1409704896 12630864 5816896
16:30:53 51461 8192000000 10250915008 10706518016 1296042832 13.659 94 14 29 0 1305101824 5687152 3371840
16:32:54 55598 8192000000 10250962144 10704674816 1321318192 13.889 97 16 33 0 1331648208 7014352 3315664
16:34:54 89823 8192000000 10250121952 10702884864 1325188080 13.924 98 14 27 0 1336192896 8102800 2902016
16:37:33 34153 8192000000 10243114432 10693894144 1328456560 13.953 98 24 48 0 1340900944 7397040 5047344

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 25-07-2022 16:39:53 to 25-07-2022 16:40:08.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 110 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 25-07-2022 16:40:09 to 25-07-2022 16:40: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          247840
MON$OLDEST_ACTIVE               249322
MON$OLDEST_SNAPSHOT             249322
MON$NEXT_TRANSACTION            249322
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                       2034976
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33613 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33613 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33613 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33613 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 ---
:150: 
:151: mscs-test-161.budzdorov.ru    Mon Jul 25 16:38:30 2022
:152:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:153: 
:154: 
:155: mscs-test-161.budzdorov.ru    Mon Jul 25 16:38:30 2022
:156:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:157: 
:158: 
:159: mscs-test-161.budzdorov.ru    Mon Jul 25 16:38:30 2022
:160:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:161: 
:162: 
:163: mscs-test-161.budzdorov.ru    Mon Jul 25 16:38:30 2022
:164:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:165: 
--- end of diff output ---

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

25.07.22 16:40: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
2022-07-25 16:40:14 No severe PSQL-related problems occured
25.07.22 16:40:16. End of report.