Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.01.2023 16:44
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 15272880 kB
MemAvailable 30433852 kB
Buffers 0 kB
Cached 16461848 kB
SwapCached 4480 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-01-11 16:42:33
DTS_END                         2023-01-11 16:42:33.0070
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/55222
TRN_ID                          249855
ATT_ID                          715
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: 9493.81 18211 1139257
customer order: creation 185.82 353 22298
customer order: refuse 16.08 345 1930
order to supplier: creation 48.15 1378 5778
order to supplier: removal 3.50 95389 420
invoice (draft): creation 49.78 3485 5973
invoice (draft): removal 53.98 851 6478
invoice accept: apply 44.24 17439 5309
invoice accept: cancel 47.84 795 5741
customer reserve: creation 4042.38 175 485085
customer reserve: removal 4685.83 54 562299
realization accept: apply 90.23 169 10827
realization accept: cancel 86.71 109 10405
payment from customer: creation 64.59 83 7751
payment from customer: removal 6.13 102 735
payment to supplier: creation 51.82 105 6218
payment to supplier: removal 3.87 52 464
service: total inventory turnovers 5.38 917 646
service: total monetary turnovers 6.53 33 784
service: aggregate perf. data 0.93 16937 112
service: refresh index statistics 0.03 225443 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-01-2023 16:44:43 to 11-01-2023 16:44:45.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1656
TEST_TIME 2 2264
TEST_TIME 3 3749
TEST_TIME 4 4776
TEST_TIME 5 4877
TEST_TIME 6 4639
TEST_TIME 7 4855
TEST_TIME 8 4887
TEST_TIME 9 5109
TEST_TIME 10 5584
TEST_TIME 11 5551
TEST_TIME 12 5502
TEST_TIME 13 5722
TEST_TIME 14 6038
TEST_TIME 15 6376
TEST_TIME 16 6492
TEST_TIME 17 7142
TEST_TIME 18 7677
TEST_TIME 19 7791
TEST_TIME 20 8105
TEST_TIME 21 8960
TEST_TIME 22 8893
TEST_TIME 23 8800
TEST_TIME 24 8911
TEST_TIME 25 8866
TEST_TIME 26 8683
TEST_TIME 27 8791
TEST_TIME 28 8847
TEST_TIME 29 9204
TEST_TIME 30 9319
TEST_TIME 31 9697
TEST_TIME 32 10114
TEST_TIME 33 10229
TEST_TIME 34 10225
TEST_TIME 35 10275
TEST_TIME 36 10161
TEST_TIME 37 10068
TEST_TIME 38 10189
TEST_TIME 39 10040
TEST_TIME 40 9867
TEST_TIME 41 9795
TEST_TIME 42 9785
TEST_TIME 43 9685
TEST_TIME 44 9620
TEST_TIME 45 9625
TEST_TIME 46 9630
TEST_TIME 47 9581
TEST_TIME 48 9703
TEST_TIME 49 9608
TEST_TIME 50 9590
TEST_TIME 51 9505
TEST_TIME 52 9363
TEST_TIME 53 9338
TEST_TIME 54 9310
TEST_TIME 55 9428
TEST_TIME 56 9342
TEST_TIME 57 9420
TEST_TIME 58 9364
TEST_TIME 59 9401
TEST_TIME 60 9518
TEST_TIME 61 9494
TEST_TIME 62 9540
TEST_TIME 63 9537
TEST_TIME 64 9615
TEST_TIME 65 9614
TEST_TIME 66 9607
TEST_TIME 67 9719
TEST_TIME 68 9732
TEST_TIME 69 9771
TEST_TIME 70 9785
TEST_TIME 71 9737
TEST_TIME 72 9644
TEST_TIME 73 9703
TEST_TIME 74 9635
TEST_TIME 75 9628
TEST_TIME 76 9672
TEST_TIME 77 9609
TEST_TIME 78 9544
TEST_TIME 79 9494
TEST_TIME 80 9502
TEST_TIME 81 9462
TEST_TIME 82 9430
TEST_TIME 83 9398
TEST_TIME 84 9410
TEST_TIME 85 9400
TEST_TIME 86 9437
TEST_TIME 87 9532
TEST_TIME 88 9487
TEST_TIME 89 9551
TEST_TIME 90 9570
TEST_TIME 91 9610
TEST_TIME 92 9584
TEST_TIME 93 9595
TEST_TIME 94 9676
TEST_TIME 95 9665
TEST_TIME 96 9734
TEST_TIME 97 9733
TEST_TIME 98 9715
TEST_TIME 99 9648
TEST_TIME 100 9632
TEST_TIME 101 9560
TEST_TIME 102 9546
TEST_TIME 103 9542
TEST_TIME 104 9502
TEST_TIME 105 9462
TEST_TIME 106 9422
TEST_TIME 107 9352
TEST_TIME 108 9424
TEST_TIME 109 9433
TEST_TIME 110 9421
TEST_TIME 111 9428
TEST_TIME 112 9439
TEST_TIME 113 9474
TEST_TIME 114 9536
TEST_TIME 115 9520
TEST_TIME 116 9492
TEST_TIME 117 9580
TEST_TIME 118 9557
TEST_TIME 119 9526
TEST_TIME 120 9495

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-01-2023 16:44:47 to 11-01-2023 16:44:50.

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 1588894 1588894 0 0.00 0 11655 6 0 0
doc_list_biud 1588894 1588894 0 0.00 0 29431 26 0 0
sp_add_invoice_to_stock 5401 5309 92 1.70 40 220694 17439 0 92
sp_cancel_adding_invoice 5741 5741 0 0.00 3 63176 796 0 0
sp_cancel_client_order 1930 1930 0 0.00 3 18248 345 0 0
sp_cancel_customer_reserve 562299 562299 0 0.00 0 29433 55 0 0
sp_cancel_pay_from_customer 735 735 0 0.00 2 10583 102 0 0
sp_cancel_pay_to_supplier 464 464 0 0.00 3 1065 52 0 0
sp_cancel_supplier_invoice 6488 6478 10 0.15 1 22182 851 0 10
sp_cancel_supplier_order 420 420 0 0.00 6 925756 95390 0 0
sp_cancel_write_off 10405 10405 0 0.00 1 15847 110 0 0
sp_client_order 22298 22298 0 0.00 3 18496 353 0 0
sp_customer_reserve 485085 485085 0 0.00 0 87526 175 0 0
sp_fill_shopping_cart 34027 34027 0 0.00 1 17465 35 0 0
sp_fill_shopping_cart_clo_res 485082 485082 0 0.00 0 14788 13 0 0
sp_get_clo_for_invoice 5306 5306 0 0.00 0 16792 241 0 0
sp_kill_cost_storno 17345 17345 0 0.00 0 10037 51 0 0
sp_kill_qstorno_ret_qs2qd 1141901 1141901 0 0.00 0 29336 31 0 0
sp_kill_qty_storno 603406 603406 0 0.00 0 29431 67 0 0
sp_lock_selected_doc 22409 22409 0 0.00 0 7474 5 0 0
sp_make_cost_storno 30102 30102 0 0.00 0 11262 45 0 0
sp_multiply_rows_for_pdistr 30102 30102 0 0.00 0 9606 23 0 0
sp_multiply_rows_for_qdistr 22298 22298 0 0.00 0 13764 146 0 0
sp_pay_from_customer 7751 7751 0 0.00 4 10139 83 0 0
sp_pay_to_supplier 6218 6218 0 0.00 4 11498 106 0 0
sp_payment_common 13969 13969 0 0.00 2 10134 20 0 0
sp_qd_handle_on_cancel_clo 1930 1930 0 0.00 0 18239 305 0 0
sp_qd_handle_on_invoice_upd_sts 11047 11047 0 0.00 0 25656 353 0 0
sp_qd_handle_on_reserve_upd_sts 10827 10827 0 0.00 0 17346 70 0 0
sp_reserve_write_off 10827 10827 0 0.00 6 17811 169 0 0
sp_supplier_invoice 5973 5973 0 0.00 5 106456 3485 0 0
sp_supplier_order 5778 5778 0 0.00 5 68751 1378 0 0
srv_aggregate_perf_data 112 112 0 0.00 3134 111805 16938 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 2042 123636 38073 0 0
srv_find_qd_qs_mism 1088573 1088573 0 0.00 0 9837 6 0 0
srv_make_invnt_saldo 646 646 0 0.00 26 24059 918 0 0
srv_make_money_saldo 784 784 0 0.00 0 7233 34 0 0
srv_recalc_idx_stat 4 4 0 0.00 146746 320993 225443 0 0
t$perf-abend:sp_add_invoice_to_stock 92 92 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5309 5309 0 0.00 0 18151 144 0 0
t$perf-norm:sp_cancel_adding_invoice 424 424 0 0.00 0 6282 124 0 0
t$perf-norm:sp_cancel_client_order 1930 1930 0 0.00 0 8540 17 0 0
t$perf-norm:sp_cancel_customer_reserve 732 732 0 0.00 0 928 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 735 735 0 0.00 0 2103 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 464 464 0 0.00 0 610 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 453 453 0 0.00 0 1023 20 0 0
t$perf-norm:sp_cancel_supplier_order 420 420 0 0.00 0 19123 1633 0 0
t$perf-norm:sp_cancel_write_off 1118 1118 0 0.00 0 2755 10 0 0
t$perf-norm:sp_client_order 22298 22298 0 0.00 0 10057 16 0 0
t$perf-norm:sp_customer_reserve 1030 1030 0 0.00 0 1661 10 0 0
t$perf-norm:sp_pay_from_customer 7751 7751 0 0.00 0 15736 14 0 0
t$perf-norm:sp_pay_to_supplier 6218 6218 0 0.00 0 8065 12 0 0
t$perf-norm:sp_reserve_write_off 10827 10827 0 0.00 0 8556 11 0 0
t$perf-norm:sp_supplier_invoice 5973 5973 0 0.00 0 12809 19 0 0
t$perf-norm:sp_supplier_order 5778 5778 0 0.00 0 6090 14 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 528 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 23 1 0 0
t$perf-norm:srv_make_invnt_saldo 646 646 0 0.00 0 5801 28 0 0
t$perf-norm:srv_make_money_saldo 784 784 0 0.00 0 1255 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 14 6 0 0
v_add_invoice_to_stock 10796 10612 184 1.70 0 7320 17 0 184
v_all_customers 40802 40802 0 0.00 0 38 1 0 0
v_all_suppliers 32260 32260 0 0.00 0 120 1 0 0
v_all_wares 44596 44596 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 848 848 0 0.00 0 761 7 0 0
v_cancel_client_order 3860 3860 0 0.00 0 3781 6 0 0
v_cancel_customer_prepayment 1470 1470 0 0.00 0 2826 9 0 0
v_cancel_customer_reserve 1464 1464 0 0.00 0 431 3 0 0
v_cancel_payment_to_supplier 928 928 0 0.00 0 355 4 0 0
v_cancel_supplier_invoice 926 906 20 2.15 0 1877 16 0 20
v_cancel_supplier_order 840 840 0 0.00 0 5977 11 0 0
v_cancel_write_off 2236 2236 0 0.00 0 1896 15 0 0
v_max_id_clo_ord 5777 5777 0 0.00 0 846 1 0 0
v_max_id_clo_res 1030 1030 0 0.00 0 1788 18 0 0
v_max_id_ord_sup 5952 5952 0 0.00 0 6991 5 0 0
v_max_non_paid_invoice 1817 1817 0 0.00 0 237 2 0 0
v_max_non_paid_realizn 4172 4172 0 0.00 0 1074 3 0 0
v_min_id_clo_ord 5777 5777 0 0.00 0 3524 6 0 0
v_min_id_clo_res 1030 1030 0 0.00 0 2575 12 0 0
v_min_id_ord_sup 5952 5952 0 0.00 0 3321 5 0 0
v_min_non_paid_invoice 6218 6218 0 0.00 0 9708 11 0 0
v_min_non_paid_realizn 7751 7751 0 0.00 0 10128 14 0 0
v_our_firm 10952 10952 0 0.00 0 16 1 0 0
v_reserve_write_off 21654 21654 0 0.00 0 6041 6 0 0
x_lock_depdocs_on_canc_invoice 11766 11766 0 0.00 0 20144 109 0 0
x_lock_depdocs_on_canc_sup_ord 420 420 0 0.00 0 8675 345 0 0
x_make_qstorno_1000_1200 5778 5778 0 0.00 1 68702 1309 0 0
x_make_qstorno_1200_2000 5973 5973 0 0.00 2 102605 3344 0 0
x_make_qstorno_2100_3300 485085 485085 0 0.00 0 87522 159 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-01-2023 16:44:51 to 11-01-2023 16:44:55.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
14:12:36 5 8192000000 8810529648 8825511936 14869664 0.181 4 4 5 0 15097824 88896 139264
14:13:39 29596 8192000000 9933067184 10251628544 1012883360 11.003 100 84 165 1 1040814352 6407072 21523920
14:15:16 13498 8192000000 9971712368 10301079552 1055166688 11.410 100 88 176 0 1085449600 8583792 21699120
14:16:33 17694 8192000000 10002126224 10340864000 1082917280 11.675 100 82 164 0 1113133840 10043360 20173200
14:17:54 5222 8192000000 10035118848 10382602240 1126064752 12.084 100 82 164 0 1156847600 11540496 19242352
14:19:04 5599 8192000000 10081984720 10437660672 1164021312 12.441 100 83 165 0 1195639504 12446160 19172032
14:20:12 3434 8192000000 10115384656 10486366208 1207792624 12.849 100 77 151 1 1237569680 12899216 16877840
14:21:17 2033 8192000000 10164636000 10533511168 1242529424 13.170 100 94 189 1 1277536800 14424592 20582784
14:22:21 2459 8192000000 10187657712 10568507392 1270745920 13.428 100 84 168 0 1303201680 13671936 18783824
14:23:25 2730 8192000000 10204215456 10593505280 1292865824 13.630 100 72 143 0 1321069872 12823456 15380592
14:24:31 4114 8192000000 10212446192 10603634688 1304128176 13.733 100 76 151 0 1331878560 11684192 16066192
14:25:36 5636 8192000000 10207053408 10612322304 1311149856 13.797 100 43 85 0 1328162720 7220864 9792000
14:26:45 11063 8192000000 10199849264 10616479744 1317989760 13.859 100 27 54 1 1330884288 6973712 5920816
14:27:59 7749 8192000000 10218551296 10626617344 1327176368 13.942 100 36 72 0 1341551888 5428736 8946784
14:29:07 18633 8192000000 10225735584 10648694784 1331248400 13.978 100 33 64 0 1348299728 9924608 7126720
14:30:28 8166 8192000000 10231951520 10654920704 1339245792 14.051 100 35 62 0 1356903856 10471024 7187040
14:31:40 9770 8192000000 10215642368 10632826880 1305599424 13.746 98 14 29 0 1314846224 5836784 3410016
14:32:50 53742 8192000000 10204333600 10610491392 1251901056 13.256 95 37 75 0 1266459840 4961248 9597536
14:34:44 9507 8192000000 10152083664 10550956032 1229926256 13.053 96 20 40 0 1239227968 4609008 4692704
14:35:55 6080 8192000000 10137168976 10540625920 1249578512 13.234 100 23 46 0 1260223376 4979456 5665408
14:37:03 5822 8192000000 10153155968 10557939712 1264461136 13.371 100 18 36 0 1274159184 5303072 4394976
14:38:09 15491 8192000000 10150371504 10562072576 1255182992 13.286 99 15 30 0 1264201920 5773664 3245264
14:39:27 4641 8192000000 10184316992 10593464320 1291498224 13.618 100 34 69 0 1305441072 5994400 7948448
14:40:32 5195 8192000000 10205788816 10614407168 1307680112 13.765 100 20 40 0 1317344448 4844576 4819760
14:41:40 17460 8192000000 10211162624 10624593920 1313375232 13.817 100 15 30 1 1322231328 5289440 3566656
14:43:00 45481 8192000000 10229597952 10649387008 1331824784 13.984 100 38 75 1 1346056864 5157360 9074720
14:44:50 17706 8192000000 10251841216 10668740608 1351803024 14.164 100 41 82 1 1369978896 8969872 9206000
14:46:11 6615 8192000000 10270300672 10692907008 1367764432 14.307 100 42 84 0 1387030496 9595040 9671024
14:47:32 8781 8192000000 10281919984 10707341312 1368105776 14.310 99 36 70 0 1385577024 9552192 7919056
14:48:49 15146 8192000000 10311037088 10733322240 1392338528 14.527 100 47 94 0 1419936656 16343760 11254368
14:50:13 3957 8192000000 10320707200 10745536512 1405619872 14.645 100 54 107 1 1435561360 16968944 12972544
14:51:21 3911 8192000000 10341118064 10760978432 1411300896 14.695 100 63 124 0 1450712000 24907216 14503888
14:52:29 17751 8192000000 10337606736 10766544896 1399168128 14.588 99 53 106 0 1442969216 31415136 12385952
14:53:59 4693 8192000000 10349244176 10769080320 1413644592 14.716 100 70 138 0 1456287568 26329312 16313664
14:55:12 2669 8192000000 10350134272 10769018880 1408162928 14.668 99 65 130 0 1456339232 31968576 16207728
14:56:20 2670 8192000000 10333006848 10745405440 1388912208 14.496 100 74 147 0 1438581664 31650096 18019360
14:57:32 9346 8192000000 10343005632 10760699904 1399508752 14.591 100 67 131 0 1446008592 30507328 15992512
14:58:46 5351 8192000000 10353575648 10767151104 1401471440 14.608 100 76 152 2 1459306608 39258208 18576960
14:59:57 7613 8192000000 10327283904 10758033408 1387528928 14.484 99 53 106 0 1435335360 34611888 13194544
15:01:12 5632 8192000000 10299097424 10723414016 1353288496 14.177 99 51 98 0 1397009184 30987424 12733264
15:02:19 13928 8192000000 10296126448 10720083968 1367601376 14.306 100 34 66 1 1409592000 33233632 8756992
15:03:37 22743 8192000000 10253474752 10695630848 1314107584 13.823 97 27 52 1 1348768336 27442976 7217776
15:05:06 10583 8192000000 10278239168 10708795392 1346120048 14.113 99 23 46 0 1380592496 28233936 6238512
15:06:22 5890 8192000000 10332544272 10758758400 1389129344 14.498 100 55 109 0 1441566704 38470448 13966912
15:07:41 11452 8192000000 10330749680 10745393152 1385321744 14.464 100 56 113 0 1431479488 32169184 13988560
15:08:56 13423 8192000000 10324763296 10752241664 1373214960 14.356 99 29 58 0 1422799936 41582528 8002448
15:10:12 21780 8192000000 10311260928 10744356864 1365962832 14.291 100 26 52 0 1416935904 43540624 7432448
15:11:35 33232 8192000000 10310055808 10747359232 1359879152 14.236 100 17 34 0 1409915312 44810112 5226048
15:13:10 27370 8192000000 10312189744 10743582720 1362431504 14.259 99 18 35 0 1409079824 41899088 4749232
15:14:42 39249 8192000000 10296196976 10723004416 1361525344 14.251 99 33 64 1 1382720400 13882448 7312608
15:16:23 33276 8192000000 10292246672 10743685120 1365761488 14.289 99 26 54 1 1386917312 15307344 5848480
15:18:01 25638 8192000000 10325993760 10750525440 1389977712 14.506 100 43 87 1 1409864512 9735680 10151120
15:19:27 55040 8192000000 10307554400 10745131008 1377909536 14.398 99 24 46 0 1398223024 14773040 5540448
15:21:35 104444 8192000000 10300062528 10737340416 1367160048 14.302 98 26 51 0 1379586896 7057088 5369760
15:24:22 36320 8192000000 10316571040 10761277440 1403764352 14.628 100 34 66 0 1421542480 10435584 7342544
15:26:13 18664 8192000000 10309017680 10761474048 1403201648 14.623 100 14 28 0 1412515296 6422720 2890928
15:27:35 20952 8192000000 10327849968 10775617536 1403350368 14.625 99 36 70 0 1420222304 8423616 8448320
15:29:04 25454 8192000000 10343867392 10790531072 1421386544 14.785 100 54 105 1 1446609280 12832416 12390320
15:30:31 56379 8192000000 10315224720 10787753984 1412929968 14.710 100 7 14 0 1429625216 14913536 1781712
15:32:27 17503 8192000000 10309687936 10769240064 1389162256 14.498 98 13 26 0 1401115504 8634000 3319248
15:33:52 109777 8192000000 10313212192 10780270592 1403145584 14.623 99 19 38 0 1422909728 15201504 4562640
15:36:44 2040 8192000000 10322623760 10768146432 1402565344 14.618 100 71 139 5 1432741696 14151504 16024848
15:37:47 59265 8192000000 10293880912 10762293248 1390603520 14.511 99 8 16 0 1404280048 11962016 1714512
15:39:46 35825 8192000000 10305936928 10760314880 1396322528 14.562 99 27 55 0 1410807808 8341264 6144016
15:41:23 21544 8192000000 10304376912 10755743744 1371434864 14.340 98 29 58 0 1390388496 12396336 6557296
15:42:46 41898 8192000000 10294718320 10742472704 1350442704 14.151 97 12 23 1 1362259808 9540432 2276672
15:44:30 36235 8192000000 10305989600 10745987072 1359532192 14.233 98 21 42 0 1370796160 6958048 4305920
15:46:08 25361 8192000000 10273596496 10715717632 1311978816 13.804 95 29 58 0 1330045920 11844992 6222112
15:47:37 14508 8192000000 10256699120 10691424256 1316480272 13.845 98 42 84 0 1341700208 15522096 9697840
15:48:59 85678 8192000000 10277669488 10722578432 1337049408 14.031 98 15 30 0 1352819104 12640016 3129680
15:51:25 59087 8192000000 10265272192 10703613952 1303635264 13.728 96 16 32 0 1323236272 16077376 3523632
15:53:24 54679 8192000000 10262137600 10704666624 1335549552 14.017 99 46 92 0 1360199568 14599056 10050960
15:55:25 108161 8192000000 10251969664 10708742144 1333307792 13.997 98 15 30 0 1353184928 16436960 3440176
15:58:42 71525 8192000000 10256306128 10713436160 1342617952 14.081 99 28 52 1 1357668848 7886736 7164160
16:00:55 32288 8192000000 10262347280 10712076288 1337716496 14.037 98 24 48 1 1354351728 10957968 5677264
16:02:30 67530 8192000000 10268551584 10736259072 1361512352 14.251 99 13 26 0 1376967184 12396688 3058144
16:04:38 29732 8192000000 10267110064 10726572032 1354929456 14.192 99 23 46 0 1369248512 9131840 5187216
16:06:09 38175 8192000000 10271866416 10726793216 1361325328 14.249 99 22 42 0 1374743088 8893120 4524640
16:07:50 84329 8192000000 10275007952 10743791616 1363559360 14.269 99 14 28 0 1382683088 15627808 3495920
16:10:15 86781 8192000000 10288487232 10747101184 1349503696 14.143 98 11 22 0 1369539984 17510352 2525936
16:12:43 11034 8192000000 10284054848 10724589568 1365161728 14.284 99 40 77 1 1379549824 6045568 8342528
16:13:55 51360 8192000000 10299998624 10744877056 1349263728 14.141 97 20 39 1 1361216688 7721360 4231600
16:16:00 74705 8192000000 10303925008 10770472960 1365898880 14.290 98 6 12 0 1386518448 19343472 1276096
16:18:15 67868 8192000000 10301737392 10766299136 1362905408 14.263 98 8 16 0 1381740272 17061760 1773104
16:20:24 123636 8192000000 10287772896 10744942592 1372795504 14.352 99 26 52 0 1390748336 12024464 5928368
16:23:43 10794 8192000000 10298273664 10748321792 1384603888 14.458 100 32 62 0 1399596128 7906688 7085552
16:24:55 68667 8192000000 10265443552 10730299392 1339619552 14.054 97 8 16 0 1350239120 9104672 1514896
16:27:04 51178 8192000000 10269305088 10725240832 1340820016 14.065 97 23 46 1 1353740432 7306544 5613872
16:28:56 56989 8192000000 10271020336 10726445056 1366018832 14.291 100 17 32 0 1383868768 13663552 4186384
16:30:53 69852 8192000000 10249226512 10701430784 1288010688 13.586 94 22 44 1 1306236496 13029728 5196080
16:33:06 29269 8192000000 10274219344 10711777280 1341729232 14.073 98 31 62 0 1358021232 9102144 7189856
16:34:37 23645 8192000000 10278493680 10719662080 1329789408 13.965 97 33 62 1 1346554816 9452080 7313328
16:36:03 101923 8192000000 10259297088 10716770304 1310930336 13.795 96 3 6 0 1325640784 14177184 533264
16:38:47 97677 8192000000 10257091072 10711281664 1270542912 13.427 93 8 16 0 1286860368 14546688 1770768
16:41:33 11364 8192000000 10273975072 10704265216 1337881744 14.038 99 42 84 0 1357559952 10653584 9024624

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 11-01-2023 16:44:57 to 11-01-2023 16:45:13.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 92 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-01-2023 16:45:14 to 11-01-2023 16:45:16.

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          247979
MON$OLDEST_ACTIVE               252221
MON$OLDEST_SNAPSHOT             252221
MON$NEXT_TRANSACTION            252221
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                       2036128
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.33653 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33653 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33653 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33653 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 ---
--- end of diff output ---

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

11.01.23 16:45:17. 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-01-11 16:45:20 No severe PSQL-related problems occured
11.01.23 16:45:21. End of report.