Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.02.2023 19:40
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 15161112 kB
MemAvailable 30422956 kB
Buffers 0 kB
Cached 16559976 kB
SwapCached 3144 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-25 19:38:47
DTS_END                         2023-02-25 19:38:47.0580
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> X_MAKE_QSTORNO_1000_1200(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/41110
TRN_ID                          243296
ATT_ID                          641
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: 9496.73 21130 1139607
customer order: creation 169.92 358 20390
customer order: refuse 14.33 310 1720
order to supplier: creation 45.78 1207 5493
order to supplier: removal 3.30 106505 396
invoice (draft): creation 46.43 3351 5572
invoice (draft): removal 53.99 918 6479
invoice accept: apply 41.93 18272 5031
invoice accept: cancel 48.60 773 5832
customer reserve: creation 3942.68 177 473121
customer reserve: removal 4831.63 55 579796
realization accept: apply 83.57 177 10028
realization accept: cancel 83.82 117 10058
payment from customer: creation 58.76 135 7051
payment from customer: removal 5.86 89 703
payment to supplier: creation 50.62 141 6074
payment to supplier: removal 3.48 94 417
service: total inventory turnovers 4.68 1351 562
service: total monetary turnovers 6.48 32 778
service: aggregate perf. data 0.85 22203 102
service: refresh index statistics 0.03 266335 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 25-02-2023 19:40:25 to 25-02-2023 19:40:27.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1961
TEST_TIME 2 1809
TEST_TIME 3 3079
TEST_TIME 4 3686
TEST_TIME 5 3919
TEST_TIME 6 3851
TEST_TIME 7 4421
TEST_TIME 8 5072
TEST_TIME 9 5281
TEST_TIME 10 6170
TEST_TIME 11 5935
TEST_TIME 12 6126
TEST_TIME 13 6243
TEST_TIME 14 6350
TEST_TIME 15 6432
TEST_TIME 16 6414
TEST_TIME 17 6558
TEST_TIME 18 6912
TEST_TIME 19 7105
TEST_TIME 20 7439
TEST_TIME 21 7489
TEST_TIME 22 7934
TEST_TIME 23 7876
TEST_TIME 24 7828
TEST_TIME 25 7674
TEST_TIME 26 7791
TEST_TIME 27 7898
TEST_TIME 28 8154
TEST_TIME 29 8322
TEST_TIME 30 8244
TEST_TIME 31 8877
TEST_TIME 32 10579
TEST_TIME 33 10613
TEST_TIME 34 10618
TEST_TIME 35 10476
TEST_TIME 36 10397
TEST_TIME 37 10240
TEST_TIME 38 10443
TEST_TIME 39 10209
TEST_TIME 40 10231
TEST_TIME 41 10105
TEST_TIME 42 10099
TEST_TIME 43 10144
TEST_TIME 44 10154
TEST_TIME 45 10037
TEST_TIME 46 9919
TEST_TIME 47 9971
TEST_TIME 48 9900
TEST_TIME 49 9782
TEST_TIME 50 9760
TEST_TIME 51 9739
TEST_TIME 52 9678
TEST_TIME 53 9588
TEST_TIME 54 9541
TEST_TIME 55 9491
TEST_TIME 56 9405
TEST_TIME 57 9448
TEST_TIME 58 9447
TEST_TIME 59 9537
TEST_TIME 60 9526
TEST_TIME 61 9581
TEST_TIME 62 9586
TEST_TIME 63 9679
TEST_TIME 64 9647
TEST_TIME 65 9669
TEST_TIME 66 9653
TEST_TIME 67 9797
TEST_TIME 68 9755
TEST_TIME 69 9819
TEST_TIME 70 9824
TEST_TIME 71 9706
TEST_TIME 72 9679
TEST_TIME 73 9635
TEST_TIME 74 9628
TEST_TIME 75 9619
TEST_TIME 76 9557
TEST_TIME 77 9556
TEST_TIME 78 9500
TEST_TIME 79 9390
TEST_TIME 80 9343
TEST_TIME 81 9375
TEST_TIME 82 9363
TEST_TIME 83 9397
TEST_TIME 84 9355
TEST_TIME 85 9431
TEST_TIME 86 9421
TEST_TIME 87 9472
TEST_TIME 88 9458
TEST_TIME 89 9523
TEST_TIME 90 9469
TEST_TIME 91 9562
TEST_TIME 92 9631
TEST_TIME 93 9636
TEST_TIME 94 9632
TEST_TIME 95 9575
TEST_TIME 96 9573
TEST_TIME 97 9562
TEST_TIME 98 9519
TEST_TIME 99 9533
TEST_TIME 100 9479
TEST_TIME 101 9457
TEST_TIME 102 9421
TEST_TIME 103 9421
TEST_TIME 104 9437
TEST_TIME 105 9412
TEST_TIME 106 9407
TEST_TIME 107 9474
TEST_TIME 108 9454
TEST_TIME 109 9454
TEST_TIME 110 9493
TEST_TIME 111 9505
TEST_TIME 112 9487
TEST_TIME 113 9492
TEST_TIME 114 9617
TEST_TIME 115 9618
TEST_TIME 116 9601
TEST_TIME 117 9600
TEST_TIME 118 9533
TEST_TIME 119 9516
TEST_TIME 120 9497

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 25-02-2023 19:40:28 to 25-02-2023 19:40:32.

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 1578640 1578640 0 0.00 0 28947 6 0 0
doc_list_biud 1578637 1578637 0 0.00 0 32687 26 0 0
sp_add_invoice_to_stock 5150 5031 119 2.31 22 168857 18272 0 119
sp_cancel_adding_invoice 5832 5832 0 0.00 3 58741 774 0 0
sp_cancel_client_order 1720 1720 0 0.00 4 8038 311 0 0
sp_cancel_customer_reserve 579796 579796 0 0.00 0 24495 55 0 0
sp_cancel_pay_from_customer 703 703 0 0.00 2 20191 89 0 0
sp_cancel_pay_to_supplier 417 417 0 0.00 2 7918 94 0 0
sp_cancel_supplier_invoice 6492 6479 13 0.20 1 32704 918 0 13
sp_cancel_supplier_order 396 396 0 0.00 8 1060710 106506 0 0
sp_cancel_write_off 10058 10058 0 0.00 1 9246 117 0 0
sp_client_order 20390 20390 0 0.00 3 36377 358 0 0
sp_customer_reserve 473121 473121 0 0.00 0 66671 177 0 0
sp_fill_shopping_cart 31452 31452 0 0.00 1 14073 43 0 0
sp_fill_shopping_cart_clo_res 473119 473119 0 0.00 0 14510 14 0 0
sp_get_clo_for_invoice 5029 5029 0 0.00 0 18273 324 0 0
sp_kill_cost_storno 17010 17010 0 0.00 0 11332 63 0 0
sp_kill_qstorno_ret_qs2qd 1176525 1176525 0 0.00 0 32681 31 0 0
sp_kill_qty_storno 619337 619337 0 0.00 0 32683 66 0 0
sp_lock_selected_doc 20771 20771 0 0.00 0 8597 5 0 0
sp_make_cost_storno 28182 28182 0 0.00 0 22271 55 0 0
sp_multiply_rows_for_pdistr 28182 28182 0 0.00 0 7540 29 0 0
sp_multiply_rows_for_qdistr 20390 20390 0 0.00 0 14657 151 0 0
sp_pay_from_customer 7051 7051 0 0.00 4 21265 135 0 0
sp_pay_to_supplier 6074 6074 0 0.00 5 23514 141 0 0
sp_payment_common 13124 13124 0 0.00 1 14455 33 0 0
sp_qd_handle_on_cancel_clo 1720 1720 0 0.00 0 8034 290 0 0
sp_qd_handle_on_invoice_upd_sts 10861 10861 0 0.00 0 27207 332 0 0
sp_qd_handle_on_reserve_upd_sts 10027 10027 0 0.00 0 13599 53 0 0
sp_reserve_write_off 10028 10028 0 0.00 6 19530 178 0 0
sp_supplier_invoice 5572 5572 0 0.00 6 87776 3351 0 0
sp_supplier_order 5493 5493 0 0.00 4 63925 1207 0 0
srv_aggregate_perf_data 102 102 0 0.00 2684 393504 22203 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1199 142114 47270 0 0
srv_find_qd_qs_mism 1091869 1091869 0 0.00 0 28946 5 0 0
srv_make_invnt_saldo 562 562 0 0.00 34 134416 1351 0 0
srv_make_money_saldo 778 778 0 0.00 0 4154 33 0 0
srv_recalc_idx_stat 4 4 0 0.00 200577 341853 266335 0 0
t$perf-abend:sp_add_invoice_to_stock 119 119 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5031 5031 0 0.00 0 6358 157 0 0
t$perf-norm:sp_cancel_adding_invoice 397 397 0 0.00 0 6412 135 0 0
t$perf-norm:sp_cancel_client_order 1720 1720 0 0.00 0 2675 10 0 0
t$perf-norm:sp_cancel_customer_reserve 662 662 0 0.00 0 1820 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 703 703 0 0.00 0 596 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 417 417 0 0.00 0 927 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 377 377 0 0.00 0 971 13 0 0
t$perf-norm:sp_cancel_supplier_order 396 396 0 0.00 0 30666 1939 0 0
t$perf-norm:sp_cancel_write_off 1044 1044 0 0.00 0 1677 12 0 0
t$perf-norm:sp_client_order 20390 20390 0 0.00 0 21480 17 0 0
t$perf-norm:sp_customer_reserve 1008 1008 0 0.00 0 1967 7 0 0
t$perf-norm:sp_pay_from_customer 7051 7051 0 0.00 0 2580 7 0 0
t$perf-norm:sp_pay_to_supplier 6074 6074 0 0.00 0 3801 8 0 0
t$perf-norm:sp_reserve_write_off 10028 10028 0 0.00 0 8913 10 0 0
t$perf-norm:sp_supplier_invoice 5572 5572 0 0.00 0 5976 20 0 0
t$perf-norm:sp_supplier_order 5493 5493 0 0.00 0 9127 16 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 562 19 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 512 8 0 0
t$perf-norm:srv_make_invnt_saldo 562 562 0 0.00 0 9293 42 0 0
t$perf-norm:srv_make_money_saldo 778 778 0 0.00 0 1096 23 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 16 5 0 0
v_add_invoice_to_stock 10292 10054 238 2.31 0 8962 18 0 238
v_all_customers 37238 37238 0 0.00 0 17 1 0 0
v_all_suppliers 31086 31086 0 0.00 0 23 1 0 0
v_all_wares 40778 40778 0 0.00 0 26 1 0 0
v_cancel_adding_invoice 794 794 0 0.00 0 8764 18 0 0
v_cancel_client_order 3440 3440 0 0.00 0 2618 5 0 0
v_cancel_customer_prepayment 1406 1406 0 0.00 0 20100 22 0 0
v_cancel_customer_reserve 1324 1324 0 0.00 0 19839 21 0 0
v_cancel_payment_to_supplier 834 834 0 0.00 0 778 4 0 0
v_cancel_supplier_invoice 780 754 26 3.33 0 2988 17 0 26
v_cancel_supplier_order 792 792 0 0.00 0 3674 14 0 0
v_cancel_write_off 2088 2088 0 0.00 0 1467 14 0 0
v_max_id_clo_ord 5492 5492 0 0.00 0 4715 2 0 0
v_max_id_clo_res 1008 1008 0 0.00 0 1839 13 0 0
v_max_id_ord_sup 5571 5571 0 0.00 0 3258 3 0 0
v_max_non_paid_invoice 1593 1593 0 0.00 0 1505 5 0 0
v_max_non_paid_realizn 3756 3756 0 0.00 0 6644 9 0 0
v_min_id_clo_ord 5492 5492 0 0.00 0 6808 7 0 0
v_min_id_clo_res 1008 1008 0 0.00 0 6796 16 0 0
v_min_id_ord_sup 5571 5571 0 0.00 0 3489 8 0 0
v_min_non_paid_invoice 6073 6073 0 0.00 0 12157 23 0 0
v_min_non_paid_realizn 7050 7050 0 0.00 0 8021 21 0 0
v_our_firm 10128 10128 0 0.00 0 14 1 0 0
v_reserve_write_off 20054 20054 0 0.00 0 19392 11 0 0
x_lock_depdocs_on_canc_invoice 11934 11934 0 0.00 0 15737 123 0 0
x_lock_depdocs_on_canc_sup_ord 396 396 0 0.00 0 3392 332 0 0
x_make_qstorno_1000_1200 5493 5493 0 0.00 1 63914 1121 0 0
x_make_qstorno_1200_2000 5572 5572 0 0.00 2 87733 3196 0 0
x_make_qstorno_2100_3300 473121 473121 0 0.00 0 66670 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 25-02-2023 19:40:33 to 25-02-2023 19:40:37.

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
17:08:51 21 8192000000 8810202608 8825380864 14628672 0.178 5 3 3 0 14790672 82688 79312
17:09:52 32789 8192000000 9896645504 10205851648 972449072 10.611 100 87 174 0 1001690736 5891856 23349808
17:11:32 18512 8192000000 9935338528 10254962688 1020342720 11.075 100 88 174 0 1051290752 8230176 22717856
17:12:58 20868 8192000000 9971997728 10301796352 1057167184 11.429 100 85 168 0 1088558480 9642576 21748720
17:14:22 9421 8192000000 10025213696 10371452928 1112598784 11.957 100 78 156 0 1142092928 10887664 18606480
17:15:40 4243 8192000000 10083502352 10440716288 1165996032 12.459 100 84 169 0 1197833136 12781648 19055456
17:16:46 2443 8192000000 10122266720 10491121664 1211538192 12.883 100 87 174 0 1244457632 13350080 19569360
17:17:50 1216 8192000000 10170593584 10547777536 1255409824 13.288 100 80 158 0 1286277712 12738656 18129232
17:18:53 1744 8192000000 10210100704 10589822976 1291275696 13.616 100 89 177 1 1322842048 12220240 19346112
17:19:56 45479 8192000000 10184584464 10597199872 1296773952 13.666 100 43 85 1 1313260944 6472096 10014896
17:21:43 4525 8192000000 10199523056 10611965952 1309743792 13.784 100 30 58 0 1323509504 7507680 6258032
17:22:49 4449 8192000000 10204945984 10617868288 1315654512 13.837 100 25 49 0 1326940928 6170080 5116336
17:23:54 13008 8192000000 10200200432 10622697472 1321276336 13.888 100 9 18 0 1328705840 5364368 2065136
17:25:08 55714 8192000000 10211419600 10637692928 1325533280 13.927 100 34 68 0 1340640096 7283344 7823472
17:27:04 4792 8192000000 10209543968 10627215360 1304472448 13.736 98 21 42 0 1314403520 5559664 4371408
17:28:12 12726 8192000000 10209916448 10630090752 1309668384 13.783 99 17 35 0 1318562592 5066064 3828144
17:29:26 58775 8192000000 10181662400 10593521664 1229897184 13.053 94 30 60 1 1242979504 6460272 6622048
17:31:29 5066 8192000000 10094710000 10486849536 1191633040 12.699 98 22 43 0 1201967296 5349104 4985152
17:32:34 4902 8192000000 10149516256 10551726080 1247755936 13.218 99 30 60 0 1260930032 5663568 7510528
17:33:40 6322 8192000000 10158574816 10559160320 1255628192 13.290 99 22 44 0 1266504176 5985824 4890160
17:34:47 30629 8192000000 10176356944 10589171712 1284848080 13.557 100 21 42 0 1295154896 5772336 4534480
17:36:21 7560 8192000000 10188176992 10598334464 1292946368 13.631 100 31 62 0 1307043024 6540272 7556384
17:37:32 10579 8192000000 10190042064 10605662208 1301057200 13.705 100 21 42 0 1311064192 5118336 4888656
17:38:43 57192 8192000000 10221906384 10635108352 1327087920 13.941 100 17 34 0 1335785232 4698640 3998672
17:40:50 45838 8192000000 10259546768 10675998720 1349859568 14.146 100 41 82 0 1368466160 9376624 9229968
17:42:38 27096 8192000000 10262041664 10686427136 1363007120 14.264 100 37 74 0 1382077968 10895888 8174960
17:44:22 9800 8192000000 10285429664 10699505664 1374051296 14.363 100 54 102 1 1396628032 10617968 11958768
17:45:36 4670 8192000000 10302194736 10720055296 1388163424 14.489 100 56 113 0 1416226976 14885216 13178336
17:46:44 15420 8192000000 10298478864 10726092800 1385468032 14.465 100 51 100 0 1417826784 20253360 12105392
17:48:05 15180 8192000000 10302530976 10719473664 1362295328 14.258 98 64 126 1 1399219952 22493168 14431456
17:49:25 9334 8192000000 10318144880 10741329920 1398055696 14.578 100 55 109 0 1427971568 16919216 12996656
17:50:51 2823 8192000000 10299259520 10721353728 1379291200 14.410 100 58 116 1 1412307632 18179312 14837120
17:52:00 7180 8192000000 10302105360 10719543296 1371013488 14.336 100 62 122 2 1404970832 19561312 14396032
17:53:39 1679 8192000000 10299852160 10712088576 1366780768 14.298 99 72 137 2 1409467728 26018112 16668848
17:54:48 2145 8192000000 10303845120 10703831040 1351050544 14.157 98 81 156 1 1401397504 32233648 18113312
17:55:56 6260 8192000000 10316431072 10721607680 1364231856 14.275 100 71 136 1 1420180080 38710864 17237360
17:57:09 1979 8192000000 10330875840 10737971200 1367424896 14.304 100 83 166 0 1432750528 44157088 21168544
17:58:13 1197 8192000000 10353407328 10757988352 1383957616 14.452 100 92 182 1 1453255216 46516160 22781440
17:59:20 4643 8192000000 10343421104 10746806272 1375531536 14.377 99 81 160 0 1444559088 49506720 19520832
18:00:29 1717 8192000000 10372011136 10773889024 1399698320 14.592 100 84 162 3 1469968400 50616240 19653840
18:01:34 18059 8192000000 10349418160 10770739200 1396129504 14.561 100 39 78 0 1456861376 50335648 10396224
18:02:59 4894 8192000000 10357340496 10777960448 1389847984 14.505 99 43 86 0 1455377712 53983424 11546304
18:04:15 9289 8192000000 10381227344 10798002176 1405235584 14.642 100 44 88 0 1480821504 63432048 12153872
18:05:34 12456 8192000000 10363777968 10787983360 1389868544 14.505 100 35 70 0 1467714480 68221024 9624912
18:06:49 24413 8192000000 10355545552 10791436288 1359197056 14.230 98 34 66 0 1438054896 69654064 9203776
18:08:22 16542 8192000000 10355504368 10783916032 1369082816 14.319 100 32 64 0 1459085664 80318304 9684544
18:10:06 17208 8192000000 10340308640 10788179968 1374043328 14.363 100 21 42 0 1460071536 79524960 6503248
18:11:25 92021 8192000000 10292572352 10754879488 1344737280 14.100 97 8 17 0 1361248864 15038496 1473088
18:13:58 26980 8192000000 10300237968 10734645248 1373619008 14.359 99 52 102 0 1395246896 10172672 11455216
18:15:29 77150 8192000000 10302329024 10757763072 1379056864 14.408 99 34 66 0 1399119936 13279616 6783456
18:17:47 26835 8192000000 10293217856 10760015872 1374664992 14.369 99 11 22 0 1399850336 22472480 2712864
18:19:16 83713 8192000000 10290239024 10743963648 1381111424 14.426 100 17 34 0 1401360176 16003872 4244880
18:21:43 50287 8192000000 10284776960 10742575104 1375326992 14.375 99 23 46 0 1388721488 8494800 4899696
18:23:34 93780 8192000000 10301454336 10762625024 1400382112 14.598 100 10 20 0 1416908464 13804080 2722272
18:26:15 46203 8192000000 10303254288 10773602304 1400148608 14.596 100 12 24 2 1413621808 10432400 3040800
18:28:07 142114 8192000000 10295397760 10765443072 1394896336 14.550 99 35 69 0 1411778448 8281424 8600688
18:31:30 55661 8192000000 10304599232 10769395712 1392556208 14.529 99 13 26 0 1407743984 12250160 2937616
18:33:57 56927 8192000000 10300684304 10771144704 1400055792 14.595 100 10 20 0 1418346384 15539728 2750864
18:35:58 65496 8192000000 10322127168 10788900864 1413910848 14.719 100 13 26 1 1432108816 15034912 3163056
18:38:06 73091 8192000000 10306893840 10753355776 1344655008 14.099 95 17 34 0 1359466576 11482176 3329392
18:40:20 107898 8192000000 10305534448 10766921728 1355296480 14.195 96 11 22 0 1367884448 10631344 1956624
18:43:08 51004 8192000000 10301152848 10755190784 1347664944 14.126 96 22 44 0 1362647888 10202128 4780816
18:45:00 93392 8192000000 10291719552 10734239744 1322192064 13.897 95 27 48 0 1340142368 12773872 5176432
18:47:37 63132 8192000000 10292244512 10730811392 1328613408 13.955 96 23 46 0 1351716800 17611840 5491552
18:49:44 100380 8192000000 10279394752 10721402880 1326872176 13.939 96 42 84 0 1346028048 9485968 9669904
18:52:26 45832 8192000000 10240657920 10698096640 1317482336 13.854 97 15 30 0 1329660192 8708992 3468864
18:54:15 59598 8192000000 10242371536 10705309696 1335707968 14.019 98 8 16 0 1346020000 8475840 1836192
18:56:15 56306 8192000000 10242378624 10696699904 1308634000 13.774 96 9 18 0 1320028080 9060912 2333168
18:58:22 53134 8192000000 10258948256 10718564352 1362888320 14.263 100 11 22 0 1376574672 11388528 2297824
19:00:18 60788 8192000000 10268793232 10722050048 1339769216 14.055 98 19 37 2 1354612688 10575248 4268224
19:02:34 25065 8192000000 10281902304 10727993344 1368587760 14.314 100 44 88 0 1392015488 13573296 9854432
19:04:06 81618 8192000000 10261571184 10708729856 1320088320 13.878 96 16 32 0 1335663040 12188848 3385872
19:06:28 20930 8192000000 10262634752 10696056832 1329558816 13.963 98 35 70 0 1348061264 10862000 7640448
19:07:52 87001 8192000000 10293819152 10745593856 1376658128 14.387 100 15 30 0 1396493088 16737456 3097504
19:10:20 87287 8192000000 10290486704 10747969536 1373797264 14.361 99 8 16 0 1390274576 14866944 1610368
19:12:50 97027 8192000000 10252922432 10706767872 1320100432 13.878 96 25 50 0 1336310080 10529360 5680288
19:15:31 51148 8192000000 10278853808 10731814912 1362983888 14.264 99 18 33 0 1378686880 11685968 4017024
19:17:23 68646 8192000000 10266046608 10726592512 1355185248 14.194 98 7 14 0 1364282704 7496288 1601168
19:19:33 53556 8192000000 10286201040 10748715008 1391424368 14.519 100 18 36 1 1404645680 8892176 4329136
19:21:37 85455 8192000000 10279654960 10748874752 1364412784 14.277 98 16 30 0 1385599760 17546000 3640976
19:24:11 42050 8192000000 10290884816 10747322368 1355567472 14.198 97 18 36 0 1370801984 10866752 4367760
19:26:05 57419 8192000000 10280943312 10735292416 1324192064 13.915 96 20 40 0 1339944016 11101056 4650896
19:28:03 65990 8192000000 10286394080 10736111616 1362674544 14.261 99 16 32 0 1381160032 15137840 3347648
19:30:25 53441 8192000000 10292123536 10747850752 1378937040 14.407 100 23 46 0 1402650688 18404096 5309552
19:32:30 115820 8192000000 10283723104 10746052608 1316352208 13.844 95 10 20 0 1335172128 17065424 1754496
19:35:26 86463 8192000000 10265433792 10716082176 1361395392 14.250 100 18 37 1 1377128464 12005968 3727104
19:38:01 47418 8192000000 10019715776 10457272320 1106487152 11.899 82 8 16 0 1125083760 16452352 2144256

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-02-2023 19:40:39 to 25-02-2023 19:40:54.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 119 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 25-02-2023 19:40:55 to 25-02-2023 19:40:57.

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          242012
MON$OLDEST_ACTIVE               243457
MON$OLDEST_SNAPSHOT             243457
MON$NEXT_TRANSACTION            243457
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                       2047264
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 ---
:599: 
:600: mscs-test-161.budzdorov.ru    Sat Feb 25 19:39:04 2023
:601:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:602: 
--- end of diff output ---

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

25.02.23 19:40:58. 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-25 19:41:01 No severe PSQL-related problems occured
25.02.23 19:41:02. End of report.