Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.02.2023 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 15110904 kB
MemAvailable 30393684 kB
Buffers 0 kB
Cached 16570244 kB
SwapCached 3140 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 2023-02-22 22:38:37
DTS_END                         2023-02-22 22:38:37.2130
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/53790
TRN_ID                          242127
ATT_ID                          648
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: 9618.98 22277 1154277
customer order: creation 171.03 358 20523
customer order: refuse 14.88 347 1786
order to supplier: creation 45.12 1205 5414
order to supplier: removal 3.66 99143 439
invoice (draft): creation 45.43 3379 5452
invoice (draft): removal 56.99 761 6839
invoice accept: apply 41.39 18891 4967
invoice accept: cancel 51.29 762 6155
customer reserve: creation 3846.42 186 461570
customer reserve: removal 5038.75 56 604650
realization accept: apply 82.97 173 9956
realization accept: cancel 90.62 104 10874
payment from customer: creation 59.74 120 7169
payment from customer: removal 5.74 70 689
payment to supplier: creation 49.09 133 5891
payment to supplier: removal 3.54 89 425
service: total inventory turnovers 4.49 1428 539
service: total monetary turnovers 6.90 30 828
service: aggregate perf. data 0.89 18962 107
service: refresh index statistics 0.03 299345 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 22-02-2023 22:39:28 to 22-02-2023 22:39:31.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3495
TEST_TIME 2 3804
TEST_TIME 3 4650
TEST_TIME 4 4641
TEST_TIME 5 4449
TEST_TIME 6 5416
TEST_TIME 7 5607
TEST_TIME 8 5686
TEST_TIME 9 5618
TEST_TIME 10 6375
TEST_TIME 11 6557
TEST_TIME 12 6575
TEST_TIME 13 6661
TEST_TIME 14 6576
TEST_TIME 15 6862
TEST_TIME 16 7037
TEST_TIME 17 7151
TEST_TIME 18 7388
TEST_TIME 19 7501
TEST_TIME 20 7697
TEST_TIME 21 7637
TEST_TIME 22 7881
TEST_TIME 23 7942
TEST_TIME 24 8405
TEST_TIME 25 8462
TEST_TIME 26 8364
TEST_TIME 27 8674
TEST_TIME 28 8653
TEST_TIME 29 8894
TEST_TIME 30 9344
TEST_TIME 31 9375
TEST_TIME 32 10517
TEST_TIME 33 10348
TEST_TIME 34 10300
TEST_TIME 35 10742
TEST_TIME 36 10628
TEST_TIME 37 10800
TEST_TIME 38 10564
TEST_TIME 39 10326
TEST_TIME 40 10224
TEST_TIME 41 10376
TEST_TIME 42 10346
TEST_TIME 43 10320
TEST_TIME 44 10316
TEST_TIME 45 10197
TEST_TIME 46 10048
TEST_TIME 47 10208
TEST_TIME 48 10143
TEST_TIME 49 10101
TEST_TIME 50 10100
TEST_TIME 51 10026
TEST_TIME 52 9955
TEST_TIME 53 9881
TEST_TIME 54 9801
TEST_TIME 55 9725
TEST_TIME 56 9589
TEST_TIME 57 9638
TEST_TIME 58 9777
TEST_TIME 59 9856
TEST_TIME 60 9793
TEST_TIME 61 9837
TEST_TIME 62 9861
TEST_TIME 63 9912
TEST_TIME 64 9902
TEST_TIME 65 9960
TEST_TIME 66 9981
TEST_TIME 67 9945
TEST_TIME 68 10037
TEST_TIME 69 10034
TEST_TIME 70 10020
TEST_TIME 71 10055
TEST_TIME 72 10165
TEST_TIME 73 10145
TEST_TIME 74 10031
TEST_TIME 75 9989
TEST_TIME 76 9988
TEST_TIME 77 9930
TEST_TIME 78 9933
TEST_TIME 79 10011
TEST_TIME 80 9969
TEST_TIME 81 9917
TEST_TIME 82 9877
TEST_TIME 83 9939
TEST_TIME 84 9878
TEST_TIME 85 9857
TEST_TIME 86 9816
TEST_TIME 87 9792
TEST_TIME 88 9728
TEST_TIME 89 9681
TEST_TIME 90 9634
TEST_TIME 91 9661
TEST_TIME 92 9620
TEST_TIME 93 9642
TEST_TIME 94 9666
TEST_TIME 95 9636
TEST_TIME 96 9649
TEST_TIME 97 9695
TEST_TIME 98 9731
TEST_TIME 99 9695
TEST_TIME 100 9750
TEST_TIME 101 9754
TEST_TIME 102 9799
TEST_TIME 103 9825
TEST_TIME 104 9824
TEST_TIME 105 9810
TEST_TIME 106 9748
TEST_TIME 107 9748
TEST_TIME 108 9690
TEST_TIME 109 9721
TEST_TIME 110 9655
TEST_TIME 111 9631
TEST_TIME 112 9616
TEST_TIME 113 9589
TEST_TIME 114 9597
TEST_TIME 115 9588
TEST_TIME 116 9561
TEST_TIME 117 9597
TEST_TIME 118 9590
TEST_TIME 119 9640
TEST_TIME 120 9624

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 22-02-2023 22:39:32 to 22-02-2023 22:39:35.

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 1582279 1582279 0 0.00 0 18122 6 0 0
doc_list_biud 1582279 1582279 0 0.00 0 29172 27 0 0
sp_add_invoice_to_stock 5076 4967 109 2.14 37 180763 18892 0 109
sp_cancel_adding_invoice 6155 6155 0 0.00 5 60312 763 0 0
sp_cancel_client_order 1786 1786 0 0.00 4 12854 347 0 0
sp_cancel_customer_reserve 604650 604650 0 0.00 0 19021 57 0 0
sp_cancel_pay_from_customer 689 689 0 0.00 2 5211 71 0 0
sp_cancel_pay_to_supplier 425 425 0 0.00 3 4120 89 0 0
sp_cancel_supplier_invoice 6845 6839 6 0.08 2 17010 761 0 6
sp_cancel_supplier_order 439 439 0 0.00 6 926264 99144 0 0
sp_cancel_write_off 10874 10874 0 0.00 1 8803 104 0 0
sp_client_order 20523 20523 0 0.00 3 25026 359 0 0
sp_customer_reserve 461570 461570 0 0.00 0 118325 186 0 0
sp_fill_shopping_cart 31382 31382 0 0.00 1 24081 39 0 0
sp_fill_shopping_cart_clo_res 461563 461563 0 0.00 0 16498 14 0 0
sp_get_clo_for_invoice 4960 4960 0 0.00 0 23014 320 0 0
sp_kill_cost_storno 18143 18143 0 0.00 0 7401 55 0 0
sp_kill_qstorno_ret_qs2qd 1227452 1227452 0 0.00 0 18922 30 0 0
sp_kill_qty_storno 645659 645659 0 0.00 0 29172 66 0 0
sp_lock_selected_doc 20782 20782 0 0.00 0 8779 6 0 0
sp_make_cost_storno 27976 27976 0 0.00 0 18115 53 0 0
sp_multiply_rows_for_pdistr 27976 27976 0 0.00 0 7434 29 0 0
sp_multiply_rows_for_qdistr 20523 20523 0 0.00 0 13717 152 0 0
sp_pay_from_customer 7169 7169 0 0.00 4 21876 120 0 0
sp_pay_to_supplier 5891 5891 0 0.00 5 24978 133 0 0
sp_payment_common 13060 13060 0 0.00 1 8515 26 0 0
sp_qd_handle_on_cancel_clo 1786 1786 0 0.00 0 12848 317 0 0
sp_qd_handle_on_invoice_upd_sts 11115 11115 0 0.00 0 29170 326 0 0
sp_qd_handle_on_reserve_upd_sts 9956 9956 0 0.00 0 12970 51 0 0
sp_reserve_write_off 9956 9956 0 0.00 6 23900 174 0 0
sp_supplier_invoice 5452 5452 0 0.00 6 84675 3379 0 0
sp_supplier_order 5414 5414 0 0.00 4 29445 1205 0 0
srv_aggregate_perf_data 107 107 0 0.00 2109 186656 18963 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1920 156926 43190 0 0
srv_find_qd_qs_mism 1106885 1106885 0 0.00 0 9001 5 0 0
srv_make_invnt_saldo 539 539 0 0.00 45 149663 1428 0 0
srv_make_money_saldo 828 828 0 0.00 1 2453 30 0 0
srv_recalc_idx_stat 4 4 0 0.00 219354 461155 299345 0 0
t$perf-abend:sp_add_invoice_to_stock 109 109 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4967 4967 0 0.00 0 14547 179 0 0
t$perf-norm:sp_cancel_adding_invoice 396 396 0 0.00 0 18308 148 0 0
t$perf-norm:sp_cancel_client_order 1786 1786 0 0.00 0 3311 15 0 0
t$perf-norm:sp_cancel_customer_reserve 682 682 0 0.00 0 521 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 689 689 0 0.00 0 2619 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 425 425 0 0.00 0 1623 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 401 401 0 0.00 0 602 9 0 0
t$perf-norm:sp_cancel_supplier_order 439 439 0 0.00 0 20636 1687 0 0
t$perf-norm:sp_cancel_write_off 1048 1048 0 0.00 0 1892 8 0 0
t$perf-norm:sp_client_order 20523 20523 0 0.00 0 13599 18 0 0
t$perf-norm:sp_customer_reserve 1029 1029 0 0.00 0 4287 13 0 0
t$perf-norm:sp_pay_from_customer 7169 7169 0 0.00 0 6295 9 0 0
t$perf-norm:sp_pay_to_supplier 5891 5891 0 0.00 0 5095 10 0 0
t$perf-norm:sp_reserve_write_off 9956 9956 0 0.00 0 10570 11 0 0
t$perf-norm:sp_supplier_invoice 5452 5452 0 0.00 0 12331 21 0 0
t$perf-norm:sp_supplier_order 5414 5414 0 0.00 0 5929 15 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 478 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 31 1 0 0
t$perf-norm:srv_make_invnt_saldo 539 539 0 0.00 0 1822 27 0 0
t$perf-norm:srv_make_money_saldo 828 828 0 0.00 0 3814 27 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10138 9920 218 2.15 0 20398 24 0 218
v_all_customers 37434 37434 0 0.00 0 17 1 0 0
v_all_suppliers 30386 30386 0 0.00 0 55 1 0 0
v_all_wares 41046 41046 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 792 792 0 0.00 0 1001 9 0 0
v_cancel_client_order 3572 3572 0 0.00 0 723 3 0 0
v_cancel_customer_prepayment 1378 1378 0 0.00 0 2145 5 0 0
v_cancel_customer_reserve 1364 1364 0 0.00 0 2100 8 0 0
v_cancel_payment_to_supplier 850 850 0 0.00 0 4106 13 0 0
v_cancel_supplier_invoice 814 802 12 1.47 0 3672 23 0 12
v_cancel_supplier_order 878 878 0 0.00 0 20766 34 0 0
v_cancel_write_off 2096 2096 0 0.00 0 6545 19 0 0
v_max_id_clo_ord 5412 5412 0 0.00 0 1477 2 0 0
v_max_id_clo_res 1029 1029 0 0.00 0 6410 19 0 0
v_max_id_ord_sup 5446 5446 0 0.00 0 5657 6 0 0
v_max_non_paid_invoice 1556 1556 0 0.00 0 4379 6 0 0
v_max_non_paid_realizn 3789 3789 0 0.00 0 205 3 0 0
v_min_id_clo_ord 5412 5412 0 0.00 0 3723 7 0 0
v_min_id_clo_res 1029 1029 0 0.00 0 3995 17 0 0
v_min_id_ord_sup 5446 5446 0 0.00 0 6847 4 0 0
v_min_non_paid_invoice 5891 5891 0 0.00 0 6251 16 0 0
v_min_non_paid_realizn 7169 7169 0 0.00 0 8513 20 0 0
v_our_firm 10372 10372 0 0.00 0 23 1 0 0
v_reserve_write_off 19912 19912 0 0.00 0 22335 10 0 0
x_lock_depdocs_on_canc_invoice 12593 12593 0 0.00 0 18867 116 0 0
x_lock_depdocs_on_canc_sup_ord 439 439 0 0.00 0 24400 402 0 0
x_make_qstorno_1000_1200 5414 5414 0 0.00 1 29429 1125 0 0
x_make_qstorno_1200_2000 5452 5452 0 0.00 3 84661 3230 0 0
x_make_qstorno_2100_3300 461570 461570 0 0.00 0 118321 169 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-02-2023 22:39:37 to 22-02-2023 22:39:41.

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:41 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:42 30781 8192000000 9902697264 10213273600 987818608 10.760 100 92 184 0 1018517152 5982048 24716496
20:11:26 14631 8192000000 9950185456 10270916608 1029263856 11.161 100 89 178 0 1060852816 8684848 22904112
20:12:47 15330 8192000000 9974085216 10308849664 1054739344 11.406 100 76 152 0 1084079376 9916320 19423712
20:14:11 12576 8192000000 10035605056 10382852096 1121124112 12.038 100 77 154 0 1150303376 10702688 18476576
20:15:25 4445 8192000000 10090581120 10452316160 1179186512 12.583 100 85 170 0 1210861024 13193568 18480944
20:16:32 3089 8192000000 10137218448 10506936320 1224786480 13.006 100 81 159 0 1255067712 13175536 17105696
20:17:37 2017 8192000000 10173964240 10551083008 1258755728 13.319 100 93 182 0 1291813008 12786432 20270848
20:18:40 1780 8192000000 10200817760 10583101440 1286249696 13.570 100 81 161 2 1315597024 11798112 17549216
20:19:44 1197 8192000000 10219263664 10603520000 1301496416 13.709 100 90 178 1 1335860768 14784704 19579648
20:20:47 5508 8192000000 10219680928 10610692096 1308992416 13.777 100 68 131 2 1332628848 8949520 14686912
20:21:53 5624 8192000000 10198557280 10614243328 1313271648 13.816 100 28 56 0 1326712016 7549040 5891328
20:22:59 14997 8192000000 10210495216 10627158016 1329182240 13.960 100 28 54 0 1340759360 5581088 5996032
20:24:16 36919 8192000000 10214176272 10638008320 1331241824 13.978 100 26 52 2 1342871424 5675616 5953984
20:25:55 8055 8192000000 10233733232 10652033024 1337230368 14.032 100 22 44 0 1348021680 5780752 5010560
20:27:08 5086 8192000000 10217469168 10639060992 1322655616 13.901 99 19 36 0 1332426448 5325888 4444944
20:28:14 42392 8192000000 10204149280 10615312384 1268487664 13.408 95 27 52 2 1279022400 4409520 6125216
20:29:59 6967 8192000000 10174575088 10588643328 1279400960 13.508 99 23 46 0 1290702992 5977392 5324640
20:31:06 6290 8192000000 10138800032 10535198720 1236763552 13.116 98 22 44 0 1247649808 5538000 5348256
20:32:13 8811 8192000000 10124286128 10515554304 1204166064 12.815 96 30 61 1 1217342944 4896032 8280848
20:33:24 52047 8192000000 10167119312 10571513856 1262994512 13.357 99 35 69 0 1276903904 5041824 8867568
20:35:19 5886 8192000000 10175755536 10586767360 1277675072 13.492 99 30 58 0 1290459088 6024960 6759056
20:36:25 10652 8192000000 10184684176 10601922560 1299207488 13.688 100 18 35 0 1308557824 5092288 4258048
20:37:36 9035 8192000000 10197611808 10615664640 1311399536 13.799 100 16 32 0 1320101600 5270992 3431072
20:38:46 63341 8192000000 10230437936 10646323200 1322494064 13.899 99 35 70 0 1336904608 6124752 8285792
20:40:59 15911 8192000000 10264578224 10680467456 1353834592 14.182 100 47 95 1 1372150800 8230576 10085632
20:42:18 5787 8192000000 10282442016 10701504512 1374010384 14.363 100 47 94 1 1400138848 14859952 11268512
20:43:29 21369 8192000000 10308129232 10723799040 1386302864 14.473 100 76 148 1 1416755760 13247888 17205008
20:45:20 12195 8192000000 10301635536 10722807808 1383671472 14.449 99 39 78 0 1408282432 14946832 9664128
20:46:36 5124 8192000000 10315791088 10741932032 1400328464 14.598 100 50 97 0 1426776528 15157488 11290576
20:47:48 12793 8192000000 10317864336 10753470464 1406499552 14.653 100 36 70 1 1434099408 18780256 8819600
20:49:06 24919 8192000000 10311691920 10746638336 1401348496 14.607 100 49 96 0 1431132912 17795232 11989184
20:50:43 7996 8192000000 10320546256 10745102336 1398789712 14.584 100 49 98 1 1425343456 14829936 11723808
20:51:55 1919 8192000000 10317525632 10735333376 1388710544 14.494 100 74 149 1 1423261760 16394512 18156704
20:53:04 4602 8192000000 10324666240 10746765312 1384729856 14.459 99 61 119 1 1422632048 22926576 14975616
20:54:16 2191 8192000000 10331287680 10741223424 1393513776 14.537 100 81 155 0 1433887648 21873376 18500496
20:55:22 2351 8192000000 10332518416 10741874688 1387340896 14.482 100 82 162 0 1432996560 25725968 19929696
20:56:28 3369 8192000000 10317983520 10725519360 1372890816 14.353 99 70 134 2 1421279680 31519040 16869824
20:57:36 2559 8192000000 10319561904 10729426944 1380182432 14.418 100 69 138 1 1427388528 30086336 17119760
20:58:45 2065 8192000000 10337281456 10740113408 1384342704 14.455 100 85 170 3 1439671568 34102784 21226080
20:59:51 2466 8192000000 10349103520 10752479232 1382684544 14.441 99 82 162 1 1441114416 38458608 19971264
21:00:59 7777 8192000000 10315170512 10737786880 1361055312 14.247 98 39 78 0 1415070672 42873568 11141792
21:02:13 5612 8192000000 10332956976 10751086592 1385298608 14.464 100 50 98 1 1447596400 49093680 13204112
21:03:27 11605 8192000000 10334513632 10759356416 1388410288 14.492 100 38 75 0 1441904352 43473632 10020432
21:05:14 9381 8192000000 10340032304 10763005952 1392307120 14.526 100 38 72 0 1446329664 44052032 9970512
21:06:29 30104 8192000000 10338560928 10772541440 1388390144 14.492 100 30 60 0 1445993392 48737728 8865520
21:08:03 38232 8192000000 10308620272 10747305984 1354601664 14.189 98 26 52 0 1416565808 54621152 7342992
21:09:42 51731 8192000000 10283127696 10743414784 1353879648 14.182 99 21 42 1 1398438368 39373328 5185392
21:11:34 31915 8192000000 10305125264 10744397824 1374753664 14.370 99 19 38 0 1392495824 13392672 4349488
21:13:07 62025 8192000000 10283887488 10751328256 1337212896 14.032 97 8 16 0 1373109888 33863472 2033520
21:15:10 85106 8192000000 10285524416 10736504832 1353954272 14.183 98 11 22 0 1367813152 11171264 2687616
21:17:40 48708 8192000000 10310088432 10754531328 1395210112 14.552 100 37 73 2 1415197440 11574992 8412336
21:19:38 83318 8192000000 10318980320 10760884224 1386573744 14.475 99 26 50 0 1402586896 10475696 5537456
21:22:02 156926 8192000000 10318591264 10768019456 1376735168 14.387 98 45 90 0 1401156512 14425968 9995376
21:25:42 29697 8192000000 10302602320 10761834496 1397924016 14.577 100 16 32 0 1412300800 10438624 3938160
21:27:13 16123 8192000000 10321783216 10756763648 1398641136 14.583 100 42 86 0 1415326320 8031712 8653472
21:28:31 30862 8192000000 10310686704 10767147008 1392851872 14.531 99 9 18 0 1402520224 7715280 1953072
21:30:05 39558 8192000000 10309869840 10772013056 1402370304 14.616 99 15 29 0 1415797712 9703392 3724016
21:31:47 24786 8192000000 10321667488 10779394048 1413553648 14.716 100 18 36 0 1430808576 13309136 3945792
21:33:13 118304 8192000000 10326593776 10794635264 1405661360 14.645 99 24 48 0 1434539888 22835216 6043312
21:36:12 33813 8192000000 10317005664 10796453888 1412194272 14.703 100 21 42 0 1439032720 21942080 4896368
21:37:47 52655 8192000000 10299500512 10745516032 1349971152 14.147 96 15 28 0 1361818000 8457632 3389216
21:40:00 72328 8192000000 10268462192 10729689088 1315622608 13.837 94 11 22 0 1328003296 10385472 1995216
21:42:12 35551 8192000000 10282135920 10727260160 1350614080 14.153 98 32 64 0 1368766560 11583872 6568608
21:43:48 63221 8192000000 10271108688 10725904384 1342636960 14.081 98 10 20 0 1357728576 12541680 2549936
21:45:51 66978 8192000000 10258808864 10712428544 1273767792 13.456 93 13 26 0 1291760048 14983856 3008400
21:48:07 39821 8192000000 10274183824 10721808384 1333811232 14.002 98 19 38 0 1350330192 12370816 4148144
21:49:49 86740 8192000000 10273259680 10716110848 1271903920 13.439 93 18 36 0 1295127504 19837120 3386464
21:52:16 116213 8192000000 10285114512 10731757568 1353834784 14.182 99 34 71 1 1373781776 12161168 7785824
21:55:17 19400 8192000000 10270674608 10725011456 1357080800 14.211 99 17 30 0 1374720816 14081552 3558464
21:56:40 63164 8192000000 10269404112 10739863552 1354712352 14.190 99 15 30 0 1374861456 16151760 3997344
21:59:06 51164 8192000000 10277058208 10727981056 1333462480 13.998 96 23 47 1 1347910880 8908064 5540336
22:01:04 55522 8192000000 10260324736 10731433984 1362414928 14.259 100 10 18 0 1375317840 10853712 2049200
22:03:04 57524 8192000000 10276310528 10739478528 1370967088 14.336 100 10 20 0 1387425424 14554992 1903344
22:05:01 55793 8192000000 10268382960 10735181824 1357610048 14.216 99 12 25 0 1374441920 14173600 2658272
22:06:58 74562 8192000000 10265242416 10724077568 1350796192 14.155 98 12 24 1 1360795856 7380736 2618928
22:09:13 69470 8192000000 10276633872 10751004672 1376660848 14.387 100 6 12 0 1390554160 12603056 1290256
22:11:24 49795 8192000000 10281618096 10739208192 1357570320 14.216 98 19 38 0 1369335728 7803952 3961456
22:13:24 57326 8192000000 10309912480 10766229504 1382212112 14.436 99 19 38 0 1398751152 12197584 4341456
22:15:26 56744 8192000000 10326331056 10785374208 1390796832 14.513 99 33 66 1 1414095936 16279472 7019632
22:17:24 8893 8192000000 10322491888 10776059904 1390055488 14.506 99 41 82 0 1412138576 13344848 8738240
22:18:34 27284 8192000000 10311154032 10771992576 1393696944 14.539 100 25 50 0 1418092400 18747936 5647520
22:20:06 127987 8192000000 10293360928 10757738496 1331175504 13.978 95 11 22 0 1344915824 11440000 2300320
22:23:15 93851 8192000000 10290891776 10752720896 1368801536 14.316 98 10 20 0 1382092432 11285840 2005056
22:25:55 44018 8192000000 10294188832 10749648896 1387044368 14.479 100 27 55 1 1402871248 9546448 6280432
22:27:54 47566 8192000000 10266557360 10733883392 1362799056 14.262 99 8 16 0 1378205904 13971792 1435056
22:29:42 60811 8192000000 10266983440 10737143808 1348872896 14.137 97 12 24 0 1359725232 8101888 2750448
22:31:52 79579 8192000000 10266284544 10737885184 1324344864 13.916 96 10 20 0 1336606736 10075120 2186752
22:34:26 38699 8192000000 10294991392 10745982976 1366944544 14.300 99 33 66 1 1384821264 9880032 7996688
22:36:18 71242 8192000000 10280507872 10734764032 1313547120 13.818 95 25 48 1 1333811872 13742352 6522400

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 22-02-2023 22:39:43 to 22-02-2023 22:39:58.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 109 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 22-02-2023 22:39:59 to 22-02-2023 22:40:01.

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          240818
MON$OLDEST_ACTIVE               242259
MON$OLDEST_SNAPSHOT             242259
MON$NEXT_TRANSACTION            242259
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                       2046240
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.33665 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33665 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33665 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33665 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 ---
:440: 
:441: mscs-test-161.budzdorov.ru    Wed Feb 22 22:38:42 2023
:442:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:443: 
:444: 
:445: mscs-test-161.budzdorov.ru    Wed Feb 22 22:38:43 2023
:446:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:447: 
:448: 
:449: mscs-test-161.budzdorov.ru    Wed Feb 22 22:38:43 2023
:450:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:451: 
:452: 
:453: mscs-test-161.budzdorov.ru    Wed Feb 22 22:38:43 2023
:454:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:455: 
--- end of diff output ---

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

22.02.23 22:40:02. 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
2023-02-22 22:40:05 No severe PSQL-related problems occured
22.02.23 22:40:06. End of report.