Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 05.12.2022 22: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 14872600 kB
MemAvailable 30115688 kB
Buffers 12 kB
Cached 16816592 kB
SwapCached 2112 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-12-05 22:38:38
DTS_END                         2022-12-05 22:38:38.4110
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/58804
TRN_ID                          242478
ATT_ID                          706
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: 9599.58 20715 1151950
customer order: creation 172.25 352 20670
customer order: refuse 14.79 365 1775
order to supplier: creation 47.63 1281 5716
order to supplier: removal 3.41 112841 409
invoice (draft): creation 44.72 3328 5366
invoice (draft): removal 54.22 898 6506
invoice accept: apply 42.92 18362 5150
invoice accept: cancel 48.90 885 5868
customer reserve: creation 4020.47 179 482456
customer reserve: removal 4839.59 62 580751
realization accept: apply 83.70 185 10044
realization accept: cancel 94.89 99 11387
payment from customer: creation 60.04 125 7205
payment from customer: removal 6.06 100 727
payment to supplier: creation 50.02 154 6002
payment to supplier: removal 3.12 86 374
service: total inventory turnovers 4.99 1104 599
service: total monetary turnovers 6.98 28 838
service: aggregate perf. data 0.86 23214 103
service: refresh index statistics 0.03 250654 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 05-12-2022 22:39:58 to 05-12-2022 22:40:01.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5627
TEST_TIME 2 5529
TEST_TIME 3 5591
TEST_TIME 4 6283
TEST_TIME 5 6074
TEST_TIME 6 6048
TEST_TIME 7 6269
TEST_TIME 8 6178
TEST_TIME 9 6005
TEST_TIME 10 5913
TEST_TIME 11 6013
TEST_TIME 12 6093
TEST_TIME 13 6070
TEST_TIME 14 6300
TEST_TIME 15 6412
TEST_TIME 16 6752
TEST_TIME 17 6971
TEST_TIME 18 6934
TEST_TIME 19 6908
TEST_TIME 20 7108
TEST_TIME 21 7527
TEST_TIME 22 7471
TEST_TIME 23 7637
TEST_TIME 24 7563
TEST_TIME 25 7817
TEST_TIME 26 7984
TEST_TIME 27 7905
TEST_TIME 28 7825
TEST_TIME 29 8339
TEST_TIME 30 8848
TEST_TIME 31 9578
TEST_TIME 32 10562
TEST_TIME 33 10335
TEST_TIME 34 10174
TEST_TIME 35 10333
TEST_TIME 36 10723
TEST_TIME 37 10581
TEST_TIME 38 10393
TEST_TIME 39 10273
TEST_TIME 40 10190
TEST_TIME 41 10166
TEST_TIME 42 10243
TEST_TIME 43 10305
TEST_TIME 44 10247
TEST_TIME 45 10318
TEST_TIME 46 10243
TEST_TIME 47 10171
TEST_TIME 48 10129
TEST_TIME 49 9959
TEST_TIME 50 10050
TEST_TIME 51 10004
TEST_TIME 52 9922
TEST_TIME 53 9883
TEST_TIME 54 9833
TEST_TIME 55 9740
TEST_TIME 56 9706
TEST_TIME 57 10066
TEST_TIME 58 10011
TEST_TIME 59 9937
TEST_TIME 60 9853
TEST_TIME 61 9770
TEST_TIME 62 9684
TEST_TIME 63 9688
TEST_TIME 64 9630
TEST_TIME 65 9611
TEST_TIME 66 9585
TEST_TIME 67 9661
TEST_TIME 68 9672
TEST_TIME 69 9692
TEST_TIME 70 9692
TEST_TIME 71 9767
TEST_TIME 72 9802
TEST_TIME 73 9766
TEST_TIME 74 9781
TEST_TIME 75 9795
TEST_TIME 76 9843
TEST_TIME 77 9839
TEST_TIME 78 9882
TEST_TIME 79 9852
TEST_TIME 80 9747
TEST_TIME 81 9738
TEST_TIME 82 9670
TEST_TIME 83 9721
TEST_TIME 84 9669
TEST_TIME 85 9693
TEST_TIME 86 9652
TEST_TIME 87 9637
TEST_TIME 88 9570
TEST_TIME 89 9552
TEST_TIME 90 9548
TEST_TIME 91 9584
TEST_TIME 92 9546
TEST_TIME 93 9587
TEST_TIME 94 9576
TEST_TIME 95 9673
TEST_TIME 96 9653
TEST_TIME 97 9725
TEST_TIME 98 9723
TEST_TIME 99 9671
TEST_TIME 100 9747
TEST_TIME 101 9755
TEST_TIME 102 9716
TEST_TIME 103 9791
TEST_TIME 104 9870
TEST_TIME 105 9849
TEST_TIME 106 9786
TEST_TIME 107 9774
TEST_TIME 108 9733
TEST_TIME 109 9748
TEST_TIME 110 9728
TEST_TIME 111 9679
TEST_TIME 112 9686
TEST_TIME 113 9641
TEST_TIME 114 9632
TEST_TIME 115 9659
TEST_TIME 116 9618
TEST_TIME 117 9598
TEST_TIME 118 9596
TEST_TIME 119 9641
TEST_TIME 120 9610

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 05-12-2022 22:40:02 to 05-12-2022 22:40:06.

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 1598191 1598191 0 0.00 0 14532 6 0 0
doc_list_biud 1598188 1598188 0 0.00 0 54998 29 0 0
sp_add_invoice_to_stock 5271 5150 121 2.29 16 204883 18362 0 121
sp_cancel_adding_invoice 5868 5868 0 0.00 4 85522 885 0 0
sp_cancel_client_order 1775 1775 0 0.00 4 11339 366 0 0
sp_cancel_customer_reserve 580751 580751 0 0.00 0 21551 62 0 0
sp_cancel_pay_from_customer 727 727 0 0.00 3 6504 100 0 0
sp_cancel_pay_to_supplier 374 374 0 0.00 3 4571 86 0 0
sp_cancel_supplier_invoice 6518 6506 12 0.18 1 55034 899 0 12
sp_cancel_supplier_order 409 409 0 0.00 7 1219997 112842 0 0
sp_cancel_write_off 11387 11387 0 0.00 1 8495 100 0 0
sp_client_order 20670 20670 0 0.00 3 17084 353 0 0
sp_customer_reserve 482456 482456 0 0.00 0 60500 179 0 0
sp_fill_shopping_cart 31741 31741 0 0.00 1 12198 37 0 0
sp_fill_shopping_cart_clo_res 482449 482449 0 0.00 0 17918 13 0 0
sp_get_clo_for_invoice 5135 5135 0 0.00 0 24080 292 0 0
sp_kill_cost_storno 18356 18356 0 0.00 0 8492 56 0 0
sp_kill_qstorno_ret_qs2qd 1179804 1179804 0 0.00 0 54997 34 0 0
sp_kill_qty_storno 621875 621875 0 0.00 0 54998 73 0 0
sp_lock_selected_doc 21018 21018 0 0.00 0 7094 5 0 0
sp_make_cost_storno 28386 28386 0 0.00 0 12176 48 0 0
sp_multiply_rows_for_pdistr 28386 28386 0 0.00 0 8922 30 0 0
sp_multiply_rows_for_qdistr 20670 20670 0 0.00 0 17048 155 0 0
sp_pay_from_customer 7205 7205 0 0.00 4 12443 126 0 0
sp_pay_to_supplier 6002 6002 0 0.00 4 11891 154 0 0
sp_payment_common 13207 13207 0 0.00 1 12437 36 0 0
sp_qd_handle_on_cancel_clo 1775 1775 0 0.00 0 11300 334 0 0
sp_qd_handle_on_invoice_upd_sts 11003 11003 0 0.00 0 27928 330 0 0
sp_qd_handle_on_reserve_upd_sts 10044 10044 0 0.00 0 12159 73 0 0
sp_reserve_write_off 10044 10044 0 0.00 6 12197 185 0 0
sp_supplier_invoice 5366 5366 0 0.00 7 101487 3328 0 0
sp_supplier_order 5716 5716 0 0.00 5 24685 1282 0 0
srv_aggregate_perf_data 103 103 0 0.00 2500 185163 23215 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 1069 140492 40097 0 0
srv_find_qd_qs_mism 1102887 1102887 0 0.00 0 14526 5 0 0
srv_make_invnt_saldo 599 599 0 0.00 44 77009 1105 0 0
srv_make_money_saldo 838 838 0 0.00 1 2025 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 125507 334755 250655 0 0
t$perf-abend:sp_add_invoice_to_stock 121 121 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5152 5152 0 0.00 0 20409 190 0 0
t$perf-norm:sp_cancel_adding_invoice 421 421 0 0.00 0 2420 86 0 0
t$perf-norm:sp_cancel_client_order 1775 1775 0 0.00 0 9999 15 0 0
t$perf-norm:sp_cancel_customer_reserve 687 687 0 0.00 0 2597 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 727 727 0 0.00 0 3404 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 374 374 0 0.00 0 1459 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 414 414 0 0.00 0 2614 25 0 0
t$perf-norm:sp_cancel_supplier_order 409 409 0 0.00 0 31850 1515 0 0
t$perf-norm:sp_cancel_write_off 1032 1032 0 0.00 0 2634 9 0 0
t$perf-norm:sp_client_order 20670 20670 0 0.00 0 6990 15 0 0
t$perf-norm:sp_customer_reserve 1068 1068 0 0.00 0 1378 5 0 0
t$perf-norm:sp_pay_from_customer 7205 7205 0 0.00 0 15643 12 0 0
t$perf-norm:sp_pay_to_supplier 6002 6002 0 0.00 0 9187 11 0 0
t$perf-norm:sp_reserve_write_off 10044 10044 0 0.00 0 9288 9 0 0
t$perf-norm:sp_supplier_invoice 5366 5366 0 0.00 0 12878 17 0 0
t$perf-norm:sp_supplier_order 5716 5716 0 0.00 0 6644 13 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 697 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 280 5 0 0
t$perf-norm:srv_make_invnt_saldo 599 599 0 0.00 0 1291 26 0 0
t$perf-norm:srv_make_money_saldo 838 838 0 0.00 0 891 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 14 8 0 0
v_add_invoice_to_stock 10512 10270 242 2.30 0 6628 19 0 242
v_all_customers 37784 37784 0 0.00 0 24 1 0 0
v_all_suppliers 31036 31036 0 0.00 0 29 1 0 0
v_all_wares 41330 41330 0 0.00 0 25 1 0 0
v_cancel_adding_invoice 842 842 0 0.00 0 1356 10 0 0
v_cancel_client_order 3550 3550 0 0.00 0 2601 7 0 0
v_cancel_customer_prepayment 1454 1454 0 0.00 0 4502 11 0 0
v_cancel_customer_reserve 1374 1374 0 0.00 0 2237 7 0 0
v_cancel_payment_to_supplier 748 748 0 0.00 0 2824 11 0 0
v_cancel_supplier_invoice 852 828 24 2.81 0 3272 23 0 24
v_cancel_supplier_order 818 818 0 0.00 0 3150 9 0 0
v_cancel_write_off 2064 2064 0 0.00 0 5399 19 0 0
v_max_id_clo_ord 5716 5716 0 0.00 0 3727 3 0 0
v_max_id_clo_res 1068 1068 0 0.00 0 3015 14 0 0
v_max_id_ord_sup 5360 5360 0 0.00 0 2594 3 0 0
v_max_non_paid_invoice 1560 1560 0 0.00 0 7055 10 0 0
v_max_non_paid_realizn 3783 3783 0 0.00 0 4077 5 0 0
v_min_id_clo_ord 5716 5716 0 0.00 0 6006 8 0 0
v_min_id_clo_res 1068 1068 0 0.00 0 3446 8 0 0
v_min_id_ord_sup 5360 5360 0 0.00 0 4589 5 0 0
v_min_non_paid_invoice 6002 6002 0 0.00 0 9627 26 0 0
v_min_non_paid_realizn 7205 7205 0 0.00 0 12432 27 0 0
v_our_firm 10390 10390 0 0.00 0 22 1 0 0
v_reserve_write_off 20088 20088 0 0.00 0 3258 6 0 0
x_lock_depdocs_on_canc_invoice 11960 11960 0 0.00 0 19639 134 0 0
x_lock_depdocs_on_canc_sup_ord 409 409 0 0.00 0 18500 457 0 0
x_make_qstorno_1000_1200 5716 5716 0 0.00 1 24647 1201 0 0
x_make_qstorno_1200_2000 5366 5366 0 0.00 3 97090 3199 0 0
x_make_qstorno_2100_3300 482456 482456 0 0.00 0 60498 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-12-2022 22:40:07 to 05-12-2022 22:40:11.

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:42 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:42 25643 8192000000 9900259968 10209021952 983878352 10.722 100 87 174 0 1012317920 5680624 22758944
20:11:15 19390 8192000000 9951682432 10276016128 1033981056 11.207 100 85 170 0 1063946928 8252480 21713392
20:12:42 8068 8192000000 9992625776 10324488192 1076592912 11.615 100 88 176 0 1108300128 10123632 21583584
20:13:51 6681 8192000000 10016640496 10363588608 1106144672 11.896 100 77 155 0 1135980560 11396128 18439760
20:14:59 10412 8192000000 10067619056 10423410688 1156695264 12.372 100 82 161 0 1186966912 11649040 18622608
20:16:17 2890 8192000000 10117564064 10482970624 1203477648 12.809 100 86 171 0 1235503184 13007776 19017760
20:17:21 2292 8192000000 10153252384 10527019008 1242203280 13.167 100 85 168 0 1274375744 14138960 18033504
20:18:26 5373 8192000000 10181809264 10559148032 1272541264 13.445 100 87 172 0 1306089728 14995712 18552752
20:19:33 1234 8192000000 10207499008 10592370688 1298633920 13.683 100 82 164 0 1329066048 12304848 18127280
20:20:34 3429 8192000000 10209263824 10612359168 1309030960 13.777 100 48 96 0 1330082400 10761440 10290000
20:21:39 13491 8192000000 10199427008 10615775232 1315506656 13.836 100 16 30 0 1329254928 9990528 3757744
20:22:54 49992 8192000000 10215716976 10636365824 1322024960 13.895 100 30 58 0 1334364432 5218176 7121296
20:24:47 9320 8192000000 10230467760 10647756800 1333155104 13.996 100 21 42 2 1347425072 9763024 4506944
20:25:56 13083 8192000000 10224612704 10648039424 1334465616 14.007 100 16 32 0 1344289808 6383152 3441040
20:27:12 41373 8192000000 10225250000 10646384640 1331230624 13.978 100 32 62 0 1343762816 5330256 7201936
20:28:55 4931 8192000000 10201372144 10612330496 1302545232 13.718 99 27 54 0 1314846928 6361360 5940336
20:30:01 8203 8192000000 10131785872 10520985600 1181837440 12.607 94 23 46 0 1193502912 6115680 5549792
20:31:10 6362 8192000000 10147543552 10542854144 1231578224 13.069 98 33 66 0 1244645664 5193216 7874224
20:32:18 20294 8192000000 10134177088 10539991040 1205392512 12.826 96 24 48 0 1217195712 6671616 5131584
20:33:41 9342 8192000000 10170384464 10569379840 1267807792 13.402 100 20 40 0 1282936848 10441920 4687136
20:34:53 12784 8192000000 10189138480 10598719488 1297813600 13.675 100 27 52 0 1309876176 5374192 6688384
20:36:07 18051 8192000000 10199002064 10610712576 1314641232 13.828 100 38 74 2 1328276608 6107936 7527440
20:37:27 12966 8192000000 10193173936 10612510720 1309534336 13.782 100 26 52 0 1321527376 6405040 5588000
20:38:40 2679 8192000000 10230321872 10639564800 1330762992 13.974 100 33 66 0 1343984672 5195056 8026624
20:39:48 18584 8192000000 10262312784 10670387200 1350260176 14.150 100 52 103 0 1370561984 8886560 11415248
20:41:10 24811 8192000000 10264186624 10682224640 1360359248 14.241 100 44 87 1 1380726304 10274832 10092224
20:42:55 18866 8192000000 10266040800 10694959104 1357956464 14.219 99 46 93 0 1377789392 8888096 10944832
20:44:31 16501 8192000000 10279765056 10708373504 1375636848 14.378 100 38 74 0 1395211760 10632272 8942640
20:45:54 3906 8192000000 10303496880 10730692608 1391641488 14.521 100 47 93 0 1415590592 13380400 10568704
20:47:13 19934 8192000000 10302697456 10731077632 1390557888 14.511 99 36 72 0 1412250352 13124048 8568416
20:48:40 9866 8192000000 10311387584 10740580352 1399633568 14.592 100 42 84 0 1425072768 15659456 9779744
20:50:02 5879 8192000000 10306892176 10730958848 1393466752 14.537 100 52 104 0 1421361664 15909904 11985008
20:51:18 6776 8192000000 10299105264 10724876288 1373851264 14.362 98 42 82 2 1401488016 17887056 9749696
20:52:30 2282 8192000000 10300550544 10719047680 1375569632 14.377 100 49 96 2 1405066000 17750704 11745664
20:53:38 2893 8192000000 10318970768 10732789760 1358483152 14.224 98 78 154 1 1400844032 24279664 18081216
20:54:49 3354 8192000000 10306456880 10722238464 1375075856 14.373 100 58 111 1 1411222640 22529808 13616976
20:56:01 10240 8192000000 10318459136 10734686208 1380867696 14.424 100 61 123 0 1426721264 30675728 15177840
20:57:16 1403 8192000000 10328271264 10726457344 1370858336 14.335 100 87 173 3 1431911664 39372832 21680496
20:58:22 1066 8192000000 10344559328 10739744768 1378644048 14.404 99 88 176 1 1442834848 42862912 21327888
20:59:29 3317 8192000000 10330231200 10735841280 1359356752 14.232 99 74 148 1 1425584784 47422880 18805152
21:00:37 4300 8192000000 10347556992 10750701568 1383609328 14.449 100 78 155 0 1449267632 45805888 19852416
21:01:50 7537 8192000000 10338434176 10749452288 1373310928 14.357 99 48 96 0 1430647696 44494880 12841888
21:03:03 7972 8192000000 10347182496 10759499776 1385535056 14.466 100 53 106 0 1453221472 52620544 15065872
21:04:13 4160 8192000000 10340832624 10754912256 1381247296 14.428 100 53 106 1 1446811760 51104496 14459968
21:05:23 14772 8192000000 10351835008 10771005440 1389978064 14.506 100 45 90 1 1453136480 50305312 12853104
21:06:49 12646 8192000000 10356985248 10784997376 1381187808 14.427 100 41 82 1 1460959120 67265296 12506016
21:08:18 26108 8192000000 10335112000 10778316800 1365824768 14.290 100 27 52 0 1441967136 67759840 8382528
21:09:50 30298 8192000000 10324544208 10766798848 1382394656 14.438 100 26 50 1 1439398256 50100048 6903552
21:11:22 65242 8192000000 10294226208 10728525824 1367599552 14.306 98 26 50 0 1385184976 12400880 5184544
21:13:30 55541 8192000000 10311016080 10782298112 1393342816 14.536 100 13 26 0 1419494704 22863168 3288720
21:15:45 51017 8192000000 10314044736 10743508992 1366110320 14.292 98 57 104 1 1386049216 7318560 12620336
21:17:38 81861 8192000000 10320557696 10767056896 1396050416 14.560 100 25 50 0 1412830160 11663552 5116192
21:20:12 75985 8192000000 10315104064 10772557824 1395446464 14.554 100 5 10 0 1414582576 17923872 1212240
21:22:28 140491 8192000000 10335288080 10774671360 1395694848 14.557 99 13 24 1 1410613168 11992000 2926320
21:26:04 109669 8192000000 10327503856 10778300416 1405227120 14.642 99 35 70 4 1423243248 9634656 8381472
21:29:04 47658 8192000000 10338555568 10785562624 1416611744 14.743 100 27 54 0 1430696368 8437728 5646896
21:30:55 47659 8192000000 10322264368 10775666688 1404627952 14.636 99 30 60 0 1419355952 8484672 6243328
21:33:12 35374 8192000000 10336276112 10782478336 1395227440 14.552 98 40 80 1 1421844672 16577728 10039504
21:34:54 72439 8192000000 10307845344 10804580352 1393266480 14.535 99 11 22 1 1416577824 20227632 3083712
21:37:47 67383 8192000000 10286201040 10738872320 1358834464 14.227 97 13 26 0 1370921968 9099952 2987552
21:39:57 78073 8192000000 10301234176 10761293824 1378943952 14.407 99 15 30 0 1393607936 11299664 3364320
21:42:16 37476 8192000000 10258061312 10719137792 1343939216 14.093 97 9 18 0 1351949344 6085072 1925056
21:43:58 24159 8192000000 10279330144 10740342784 1355558592 14.197 98 15 28 0 1369390576 10423936 3408048
21:45:27 40627 8192000000 10290904736 10759270400 1372224432 14.347 99 21 42 0 1391130672 14029920 4876320
21:47:08 47287 8192000000 10274485712 10724605952 1312905856 13.812 95 13 26 0 1323951808 8320080 2725872
21:48:58 54968 8192000000 10283948688 10751201280 1347933632 14.129 98 21 41 2 1373063328 20281824 4847872
21:50:55 46438 8192000000 10265150208 10717544448 1302936608 13.722 94 24 48 0 1323081728 14471456 5673664
21:52:42 37036 8192000000 10296551040 10735120384 1347112480 14.121 98 32 61 2 1365670144 12146848 6410816
21:54:34 46475 8192000000 10283096208 10726354944 1343524560 14.089 98 26 52 0 1361521392 12176816 5820016
21:56:21 34398 8192000000 10290590336 10748219392 1374662352 14.369 100 24 48 0 1396849664 15803696 6383616
21:57:58 83370 8192000000 10280039600 10731515904 1339285440 14.051 97 24 45 2 1353473200 9130144 5057616
22:00:25 41352 8192000000 10284307680 10735271936 1377883136 14.398 100 26 52 0 1397741296 13852928 6005232
22:02:48 50711 8192000000 10267005184 10730549248 1353601456 14.180 99 14 28 0 1374097984 17274336 3222192
22:04:39 77228 8192000000 10284935904 10741391360 1364640416 14.279 99 20 40 0 1378345744 9044384 4660944
22:06:58 90096 8192000000 10292407536 10764103680 1385632080 14.467 100 13 26 0 1403061360 14892144 2537136
22:09:28 50959 8192000000 10298506464 10760933376 1367966192 14.309 98 15 28 0 1383000608 11666384 3368032
22:11:19 46982 8192000000 10308007792 10768396288 1373992096 14.363 98 20 40 0 1386796896 7865696 4939104
22:13:10 38786 8192000000 10310117040 10770472960 1394007824 14.542 99 18 36 0 1412055136 13789504 4257808
22:14:49 14004 8192000000 10329107152 10778898432 1412424096 14.705 100 49 96 0 1433591072 10019664 11147312
22:16:03 53159 8192000000 10324338944 10780393472 1405883536 14.647 99 17 30 0 1421281696 11600480 3797680
22:18:00 48179 8192000000 10320093392 10786488320 1386348624 14.473 97 16 33 1 1404046880 13304400 4393856
22:19:49 35594 8192000000 10312284544 10758262784 1346771536 14.118 96 27 54 0 1369040192 16267904 6000752
22:21:27 59549 8192000000 10327385472 10805489664 1390826144 14.513 99 8 16 0 1416617456 23929856 1861456
22:23:27 114890 8192000000 10299435200 10760069120 1372890672 14.353 98 24 48 0 1393733952 15103600 5739680
22:26:31 23302 8192000000 10306001280 10769108992 1404417648 14.634 100 19 39 0 1416938944 8234432 4286864
22:27:57 67625 8192000000 10294436688 10765340672 1362279488 14.258 97 7 14 0 1374621216 10975904 1365824
22:30:12 61416 8192000000 10262423472 10727280640 1346384944 14.115 98 16 30 0 1360161728 10533952 3242832
22:32:15 53563 8192000000 10268153744 10727460864 1349472288 14.143 98 18 36 0 1362768832 8856880 4439664
22:34:23 48945 8192000000 10268388352 10715131904 1288366144 13.589 94 17 34 0 1306375328 14443984 3565200
22:36:16 69328 8192000000 10253283184 10699612160 1303249616 13.725 97 15 30 0 1322078288 15169136 3659536

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 05-12-2022 22:40:13 to 05-12-2022 22:40:28.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 121 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 05-12-2022 22:40:29 to 05-12-2022 22:40:31.

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          242067
MON$OLDEST_ACTIVE               243856
MON$OLDEST_SNAPSHOT             243856
MON$NEXT_TRANSACTION            243856
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                       2051552
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.33637 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33637 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33637 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33637 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 ---
:295: 
:296: mscs-test-161.budzdorov.ru    Mon Dec  5 22:38:39 2022
:297:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:298: 
:299: 
:300: mscs-test-161.budzdorov.ru    Mon Dec  5 22:38:40 2022
:301:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:302: 
:303: 
:304: mscs-test-161.budzdorov.ru    Mon Dec  5 22:38:49 2022
:305:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:306: 
:307: 
:308: mscs-test-161.budzdorov.ru    Mon Dec  5 22:38:55 2022
:309:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:310: 
--- end of diff output ---

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

05.12.22 22:40:32. 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-12-05 22:40:35 No severe PSQL-related problems occured
05.12.22 22:40:36. End of report.