Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.01.2023 16:43
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 15096928 kB
MemAvailable 30379156 kB
Buffers 0 kB
Cached 16614900 kB
SwapCached 4548 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-06 16:42:24
DTS_END                         2023-01-06 16:42:24.5900
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/34992
TRN_ID                          250037
ATT_ID                          734
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: 9427.53 20120 1131303
customer order: creation 184.28 363 22113
customer order: refuse 15.28 363 1833
order to supplier: creation 47.65 1210 5718
order to supplier: removal 3.59 91220 431
invoice (draft): creation 48.10 3219 5772
invoice (draft): removal 54.24 740 6509
invoice accept: apply 42.54 16442 5105
invoice accept: cancel 49.23 735 5907
customer reserve: creation 3828.72 167 459446
customer reserve: removal 4843.58 52 581230
realization accept: apply 90.42 179 10850
realization accept: cancel 82.73 111 9927
payment from customer: creation 61.94 107 7433
payment from customer: removal 6.23 70 748
payment to supplier: creation 52.43 134 6292
payment to supplier: removal 3.97 102 476
service: total inventory turnovers 4.93 1105 591
service: total monetary turnovers 6.84 24 821
service: aggregate perf. data 0.81 18192 97
service: refresh index statistics 0.03 267858 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 06-01-2023 16:44:01 to 06-01-2023 16:44:03.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3538
TEST_TIME 2 2695
TEST_TIME 3 2068
TEST_TIME 4 2918
TEST_TIME 5 4630
TEST_TIME 6 5422
TEST_TIME 7 5161
TEST_TIME 8 5386
TEST_TIME 9 5775
TEST_TIME 10 5813
TEST_TIME 11 6136
TEST_TIME 12 6178
TEST_TIME 13 6213
TEST_TIME 14 6717
TEST_TIME 15 6660
TEST_TIME 16 6999
TEST_TIME 17 7453
TEST_TIME 18 7562
TEST_TIME 19 7782
TEST_TIME 20 8359
TEST_TIME 21 8254
TEST_TIME 22 8279
TEST_TIME 23 8564
TEST_TIME 24 8760
TEST_TIME 25 8781
TEST_TIME 26 9552
TEST_TIME 27 9562
TEST_TIME 28 9538
TEST_TIME 29 9728
TEST_TIME 30 10444
TEST_TIME 31 10569
TEST_TIME 32 10540
TEST_TIME 33 10472
TEST_TIME 34 10377
TEST_TIME 35 10311
TEST_TIME 36 10264
TEST_TIME 37 10276
TEST_TIME 38 10047
TEST_TIME 39 9943
TEST_TIME 40 9840
TEST_TIME 41 9880
TEST_TIME 42 9786
TEST_TIME 43 9747
TEST_TIME 44 9707
TEST_TIME 45 9740
TEST_TIME 46 9787
TEST_TIME 47 9865
TEST_TIME 48 9840
TEST_TIME 49 9800
TEST_TIME 50 9685
TEST_TIME 51 9614
TEST_TIME 52 9595
TEST_TIME 53 9524
TEST_TIME 54 9515
TEST_TIME 55 9537
TEST_TIME 56 9505
TEST_TIME 57 9549
TEST_TIME 58 9498
TEST_TIME 59 9476
TEST_TIME 60 9574
TEST_TIME 61 9635
TEST_TIME 62 9613
TEST_TIME 63 9648
TEST_TIME 64 9700
TEST_TIME 65 9792
TEST_TIME 66 9806
TEST_TIME 67 9792
TEST_TIME 68 9861
TEST_TIME 69 9862
TEST_TIME 70 9821
TEST_TIME 71 9719
TEST_TIME 72 9684
TEST_TIME 73 9682
TEST_TIME 74 9618
TEST_TIME 75 9646
TEST_TIME 76 9566
TEST_TIME 77 9554
TEST_TIME 78 9501
TEST_TIME 79 9486
TEST_TIME 80 9461
TEST_TIME 81 9418
TEST_TIME 82 9469
TEST_TIME 83 9488
TEST_TIME 84 9512
TEST_TIME 85 9492
TEST_TIME 86 9508
TEST_TIME 87 9563
TEST_TIME 88 9596
TEST_TIME 89 9553
TEST_TIME 90 9610
TEST_TIME 91 9579
TEST_TIME 92 9620
TEST_TIME 93 9605
TEST_TIME 94 9660
TEST_TIME 95 9647
TEST_TIME 96 9558
TEST_TIME 97 9553
TEST_TIME 98 9511
TEST_TIME 99 9490
TEST_TIME 100 9463
TEST_TIME 101 9415
TEST_TIME 102 9405
TEST_TIME 103 9358
TEST_TIME 104 9301
TEST_TIME 105 9348
TEST_TIME 106 9455
TEST_TIME 107 9430
TEST_TIME 108 9475
TEST_TIME 109 9483
TEST_TIME 110 9506
TEST_TIME 111 9528
TEST_TIME 112 9497
TEST_TIME 113 9458
TEST_TIME 114 9568
TEST_TIME 115 9573
TEST_TIME 116 9602
TEST_TIME 117 9540
TEST_TIME 118 9496
TEST_TIME 119 9485
TEST_TIME 120 9430

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 06-01-2023 16:44:05 to 06-01-2023 16:44:08.

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 1561403 1561403 0 0.00 0 47410 6 0 0
doc_list_biud 1561401 1561401 0 0.00 0 22363 25 0 0
sp_add_invoice_to_stock 5242 5105 137 2.61 21 152259 16442 0 137
sp_cancel_adding_invoice 5907 5907 0 0.00 3 58813 736 0 0
sp_cancel_client_order 1833 1833 0 0.00 4 9613 363 0 0
sp_cancel_customer_reserve 581230 581230 0 0.00 0 46581 53 0 0
sp_cancel_pay_from_customer 748 748 0 0.00 2 3429 71 0 0
sp_cancel_pay_to_supplier 476 476 0 0.00 3 6102 102 0 0
sp_cancel_supplier_invoice 6519 6509 10 0.15 1 12922 740 0 10
sp_cancel_supplier_order 431 431 0 0.00 9 1020902 91221 0 0
sp_cancel_write_off 9927 9927 0 0.00 1 14720 112 0 0
sp_client_order 22113 22113 0 0.00 3 47672 363 0 0
sp_customer_reserve 459446 459446 0 0.00 0 88128 167 0 0
sp_fill_shopping_cart 33601 33601 0 0.00 1 17756 34 0 0
sp_fill_shopping_cart_clo_res 459443 459443 0 0.00 0 14320 12 0 0
sp_get_clo_for_invoice 5102 5102 0 0.00 0 34794 299 0 0
sp_kill_cost_storno 17058 17058 0 0.00 0 14684 54 0 0
sp_kill_qstorno_ret_qs2qd 1179327 1179327 0 0.00 0 22355 29 0 0
sp_kill_qty_storno 621788 621788 0 0.00 0 22362 63 0 0
sp_lock_selected_doc 22081 22081 0 0.00 0 5698 4 0 0
sp_make_cost_storno 29677 29677 0 0.00 0 22365 50 0 0
sp_multiply_rows_for_pdistr 29676 29676 0 0.00 0 15974 29 0 0
sp_multiply_rows_for_qdistr 22113 22113 0 0.00 0 20998 170 0 0
sp_pay_from_customer 7433 7433 0 0.00 4 15155 108 0 0
sp_pay_to_supplier 6292 6292 0 0.00 4 18076 134 0 0
sp_payment_common 13725 13725 0 0.00 2 13735 31 0 0
sp_qd_handle_on_cancel_clo 1833 1833 0 0.00 0 9608 331 0 0
sp_qd_handle_on_invoice_upd_sts 11009 11009 0 0.00 0 19605 310 0 0
sp_qd_handle_on_reserve_upd_sts 10849 10849 0 0.00 0 21782 61 0 0
sp_reserve_write_off 10850 10850 0 0.00 6 22374 179 0 0
sp_supplier_invoice 5772 5772 0 0.00 6 90583 3220 0 0
sp_supplier_order 5718 5718 0 0.00 5 38512 1211 0 0
srv_aggregate_perf_data 97 97 0 0.00 2447 188101 18192 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1632 109427 42924 0 0
srv_find_qd_qs_mism 1084123 1084123 0 0.00 0 47410 5 0 0
srv_make_invnt_saldo 591 591 0 0.00 35 76859 1105 0 0
srv_make_money_saldo 821 821 0 0.00 0 999 24 0 0
srv_recalc_idx_stat 4 4 0 0.00 163501 350618 267859 0 0
t$perf-abend:sp_add_invoice_to_stock 137 137 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 5107 5107 0 0.00 0 23521 168 0 0
t$perf-norm:sp_cancel_adding_invoice 439 439 0 0.00 0 22177 114 0 0
t$perf-norm:sp_cancel_client_order 1833 1833 0 0.00 0 15204 22 0 0
t$perf-norm:sp_cancel_customer_reserve 729 729 0 0.00 0 3715 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 748 748 0 0.00 0 3187 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 476 476 0 0.00 0 249 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 417 417 0 0.00 0 418 9 0 0
t$perf-norm:sp_cancel_supplier_order 431 431 0 0.00 0 22711 1509 0 0
t$perf-norm:sp_cancel_write_off 1057 1057 0 0.00 0 6061 20 0 0
t$perf-norm:sp_client_order 22113 22113 0 0.00 0 20240 17 0 0
t$perf-norm:sp_customer_reserve 1066 1066 0 0.00 0 6320 11 0 0
t$perf-norm:sp_pay_from_customer 7433 7433 0 0.00 0 17715 11 0 0
t$perf-norm:sp_pay_to_supplier 6292 6292 0 0.00 0 5065 12 0 0
t$perf-norm:sp_reserve_write_off 10850 10850 0 0.00 0 18782 15 0 0
t$perf-norm:sp_supplier_invoice 5772 5772 0 0.00 0 12696 25 0 0
t$perf-norm:sp_supplier_order 5718 5718 0 0.00 0 4189 17 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 734 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 446 12 0 0
t$perf-norm:srv_make_invnt_saldo 591 591 0 0.00 0 1369 23 0 0
t$perf-norm:srv_make_money_saldo 821 821 0 0.00 0 5078 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 10 4 0 0
v_add_invoice_to_stock 10478 10204 274 2.61 0 6343 18 0 274
v_all_customers 39770 39770 0 0.00 0 23 1 0 0
v_all_suppliers 32022 32022 0 0.00 0 18 1 0 0
v_all_wares 44226 44226 0 0.00 0 25 1 0 0
v_cancel_adding_invoice 878 878 0 0.00 0 557 6 0 0
v_cancel_client_order 3666 3666 0 0.00 0 8048 9 0 0
v_cancel_customer_prepayment 1496 1496 0 0.00 0 2909 8 0 0
v_cancel_customer_reserve 1458 1458 0 0.00 0 1647 6 0 0
v_cancel_payment_to_supplier 952 952 0 0.00 0 3705 13 0 0
v_cancel_supplier_invoice 854 834 20 2.34 0 3321 16 0 20
v_cancel_supplier_order 862 862 0 0.00 0 1683 9 0 0
v_cancel_write_off 2114 2114 0 0.00 0 3850 15 0 0
v_max_id_clo_ord 5718 5718 0 0.00 0 566 1 0 0
v_max_id_clo_res 1066 1066 0 0.00 0 1837 15 0 0
v_max_id_ord_sup 5770 5770 0 0.00 0 4287 5 0 0
v_max_non_paid_invoice 1769 1769 0 0.00 0 2475 6 0 0
v_max_non_paid_realizn 4098 4098 0 0.00 0 4479 6 0 0
v_min_id_clo_ord 5718 5718 0 0.00 0 17021 9 0 0
v_min_id_clo_res 1066 1066 0 0.00 0 2641 7 0 0
v_min_id_ord_sup 5770 5770 0 0.00 0 4398 7 0 0
v_min_non_paid_invoice 6292 6292 0 0.00 0 13269 20 0 0
v_min_non_paid_realizn 7433 7433 0 0.00 0 13728 29 0 0
v_our_firm 11126 11126 0 0.00 0 15 1 0 0
v_reserve_write_off 21698 21698 0 0.00 0 5694 7 0 0
x_lock_depdocs_on_canc_invoice 11999 11999 0 0.00 0 19353 110 0 0
x_lock_depdocs_on_canc_sup_ord 431 431 0 0.00 0 9317 372 0 0
x_make_qstorno_1000_1200 5718 5718 0 0.00 1 38501 1134 0 0
x_make_qstorno_1200_2000 5772 5772 0 0.00 3 90575 3098 0 0
x_make_qstorno_2100_3300 459446 459446 0 0.00 0 88126 152 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-01-2023 16:44:09 to 06-01-2023 16:44:13.

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:28 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:13:29 31242 8192000000 9915766304 10227855360 996439200 10.844 100 86 172 0 1025219360 6228480 22551680
14:15:05 23822 8192000000 9964021504 10287120384 1040101168 11.266 100 90 180 0 1071653424 8448144 23104112
14:16:32 19321 8192000000 9993984864 10331930624 1078943856 11.637 100 82 162 1 1108805680 9964288 19897536
14:17:59 11220 8192000000 10041790560 10391150592 1126763728 12.091 100 76 152 0 1156485984 11429072 18293184
14:19:13 3847 8192000000 10085189648 10443870208 1177047328 12.563 100 78 156 2 1207439840 12655776 17736736
14:20:19 2302 8192000000 10137108688 10507366400 1224909296 13.007 100 80 160 0 1254296032 11547408 17839328
14:21:24 1822 8192000000 10166580448 10538868736 1252908384 13.265 100 89 172 1 1285969504 14125936 18935184
14:22:28 1619 8192000000 10191693872 10571612160 1280386176 13.517 100 84 166 1 1310723984 12360112 17977696
14:23:31 864 8192000000 10208561712 10594959360 1298371808 13.680 100 88 174 0 1331042896 12389840 20281248
14:24:32 876 8192000000 10218059520 10608943104 1302898736 13.722 100 78 157 2 1329550752 8793072 17858944
14:25:34 3345 8192000000 10205789072 10612637696 1312239600 13.806 100 39 78 0 1328988944 8366160 8383184
14:26:40 10322 8192000000 10196402800 10615681024 1316798832 13.848 100 17 34 0 1326870384 6241840 3829712
14:27:51 13302 8192000000 10214411872 10634330112 1325947104 13.931 100 23 46 1 1341832720 10671328 5214288
14:29:08 31926 8192000000 10248232192 10648588288 1332884432 13.993 100 68 135 2 1355517584 6537520 16095632
14:30:41 5936 8192000000 10219851392 10639470592 1329242880 13.960 100 29 58 2 1342401424 6535792 6622752
14:31:47 9972 8192000000 10202913296 10615595008 1297433488 13.672 98 19 32 1 1310106784 9040016 3633280
14:32:57 12618 8192000000 10182876928 10592993280 1279152832 13.505 98 22 46 0 1294438320 10146816 5138672
14:34:10 17875 8192000000 10165965744 10563997696 1206467136 12.836 93 23 46 0 1217899664 5975152 5457376
14:35:29 6252 8192000000 10144941312 10535899136 1224188832 13.000 98 33 67 0 1237472144 5187776 8095536
14:36:41 7960 8192000000 10135161680 10532687872 1224858432 13.007 98 23 44 0 1235203616 5125840 5219344
14:37:50 69381 8192000000 10176526016 10588975104 1289734448 13.602 100 30 60 1 1302120592 5190768 7195376
14:40:00 12290 8192000000 10185316832 10600923136 1299391856 13.690 100 22 43 0 1309648656 5015792 5241008
14:41:12 7160 8192000000 10203944096 10623885312 1317757616 13.856 100 29 56 0 1329515024 5512816 6244592
14:42:22 73603 8192000000 10247890784 10656731136 1341906032 14.075 100 28 57 0 1354139280 5682160 6551088
14:44:37 109427 8192000000 10273033728 10697801728 1368529728 14.314 100 45 89 0 1393761808 14876752 10355328
14:47:28 29972 8192000000 10272086720 10706833408 1365947552 14.291 100 18 36 0 1385979424 16067104 3964768
14:49:10 4189 8192000000 10288343360 10714005504 1379868448 14.415 100 48 92 3 1404913264 14281808 10763008
14:50:21 7267 8192000000 10305250256 10724564992 1385758288 14.468 100 67 132 0 1420770160 20003168 15008704
14:51:36 9554 8192000000 10322996832 10745864192 1395035968 14.551 100 50 97 0 1432993072 25696960 12260144
14:52:52 11263 8192000000 10331099904 10755497984 1392560624 14.529 99 50 100 0 1435907616 31793712 11553280
14:54:09 5745 8192000000 10331802928 10756599808 1404921648 14.639 100 51 102 0 1442058848 24822160 12315040
14:55:19 9665 8192000000 10331706688 10757009408 1392368832 14.527 99 61 121 0 1432745344 26220544 14155968
14:56:35 4272 8192000000 10331199488 10746642432 1394141696 14.543 100 67 133 0 1435493808 25534752 15817360
14:57:45 4367 8192000000 10340642400 10749947904 1387209584 14.481 100 77 154 0 1439821872 32721680 19890608
14:58:54 1630 8192000000 10337062624 10747912192 1391669360 14.521 100 85 170 1 1445890112 33324528 20896224
15:00:02 2961 8192000000 10349709040 10764242944 1399965920 14.595 100 70 139 2 1452050768 34538208 17546640
15:01:07 3862 8192000000 10308293968 10729865216 1372486128 14.349 100 52 100 0 1415491616 30576064 12429424
15:02:19 6787 8192000000 10312834800 10737672192 1366826400 14.299 100 57 112 1 1423398208 42067968 14503840
15:03:33 13481 8192000000 10310711040 10737442816 1382198224 14.436 100 30 60 0 1421156992 30996704 7962064
15:04:50 18100 8192000000 10331184704 10759159808 1391120544 14.516 100 35 67 1 1440598160 40407408 9070208
15:06:13 11685 8192000000 10317657504 10749038592 1370668064 14.333 99 41 82 0 1420468480 38737712 11062704
15:07:29 9520 8192000000 10316413648 10751877120 1382506464 14.439 100 30 58 0 1428346144 37796016 8043664
15:08:40 41919 8192000000 10287557408 10738270208 1337870208 14.038 98 20 40 0 1378255280 35061008 5324064
15:10:22 54815 8192000000 10301131792 10736369664 1333464496 13.998 98 22 44 0 1382891552 43413408 6013648
15:12:25 31916 8192000000 10258061056 10694524928 1323466832 13.908 98 18 36 0 1342797120 15021056 4309232
15:14:43 76651 8192000000 10272416992 10706022400 1335504640 14.017 98 13 24 0 1349028192 11432768 2090784
15:17:03 33920 8192000000 10286894048 10717888512 1367817056 14.307 100 27 54 0 1385336544 11086544 6432944
15:18:51 34729 8192000000 10300527312 10736443392 1383515104 14.448 100 56 110 0 1407369424 10663776 13190544
15:20:38 52120 8192000000 10296195248 10741878784 1385839664 14.469 100 29 59 0 1398224096 5755168 6629264
15:22:30 29150 8192000000 10311946976 10755874816 1377236544 14.392 99 34 68 1 1397315168 12318960 7759664
15:24:04 46211 8192000000 10296949984 10743021568 1354768976 14.190 97 25 50 0 1374409904 13493056 6147872
15:25:51 60489 8192000000 10333575312 10775453696 1388222512 14.490 99 31 62 0 1411613456 16154192 7236752
15:27:53 27683 8192000000 10315932240 10781921280 1393079936 14.533 99 22 44 0 1424456992 25900784 5476272
15:29:24 22370 8192000000 10292326416 10753028096 1383578288 14.449 99 20 40 0 1404572384 16084304 4909792
15:30:52 39954 8192000000 10299928464 10757238784 1397712592 14.575 100 9 18 1 1411881136 11996624 2171920
15:32:39 57495 8192000000 10295815296 10762162176 1398524848 14.582 100 20 37 0 1412953040 10318448 4109744
15:34:37 9211 8192000000 10306455824 10762911744 1404076320 14.631 100 26 52 0 1416405392 6145520 6183552
15:35:47 74324 8192000000 10292722608 10769616896 1383251952 14.446 99 5 10 1 1397698160 13388096 1058112
15:38:16 39021 8192000000 10281031008 10744967168 1360531328 14.242 98 12 24 0 1378286032 14706016 3048688
15:40:05 40867 8192000000 10300879904 10757025792 1388916192 14.496 100 26 50 0 1403359568 9076016 5367360
15:42:00 56149 8192000000 10286841200 10735099904 1310670112 13.792 94 17 34 0 1324020400 9635584 3714704
15:44:01 29494 8192000000 10258800864 10700820480 1311195248 13.797 97 12 24 0 1324133072 10349568 2588256
15:45:32 101607 8192000000 10287653728 10731307008 1310180224 13.788 95 16 32 0 1324633600 10895616 3557760
15:48:14 87693 8192000000 10278741328 10725167104 1306660912 13.756 95 11 22 0 1322278992 12773984 2844096
15:50:49 47800 8192000000 10277600592 10720714752 1336837984 14.029 98 31 63 0 1355990032 12652960 6499088
15:52:37 75521 8192000000 10253676560 10693275648 1336658880 14.027 99 36 70 1 1357330144 11255904 9415360
15:54:53 73055 8192000000 10269961296 10726629376 1364794848 14.280 100 23 46 1 1381147600 11274224 5078528
15:57:07 59532 8192000000 10266638672 10719080448 1319517280 13.872 96 20 37 0 1333453440 9778976 4157184
15:59:07 48674 8192000000 10275228400 10732998656 1370559776 14.332 100 18 35 0 1387119888 12775120 3784992
16:01:00 47588 8192000000 10271913312 10737541120 1373700176 14.360 100 13 26 1 1391795824 14358080 3737568
16:03:01 64819 8192000000 10264448832 10729127936 1342633472 14.081 98 10 20 0 1359191600 14313872 2244256
16:05:18 89007 8192000000 10281958400 10728488960 1342555600 14.080 98 17 34 0 1374110256 27275456 4279200
16:07:54 72076 8192000000 10282792592 10741731328 1347845408 14.128 98 13 26 0 1364935968 13809872 3280688
16:10:07 32798 8192000000 10284851744 10733297664 1344202704 14.095 97 26 52 1 1363517344 13476368 5838272
16:11:40 97924 8192000000 10298711968 10752008192 1361886784 14.254 98 17 34 0 1379285424 13828624 3570016
16:14:19 75402 8192000000 10291289760 10750586880 1376384512 14.384 99 9 18 0 1388947872 10646080 1917280
16:16:34 37863 8192000000 10298022320 10746396672 1375706512 14.378 100 17 34 1 1391255760 11191152 4358096
16:18:18 60927 8192000000 10283879088 10741972992 1385475808 14.465 100 28 56 0 1398996128 6941152 6579168
16:20:22 28407 8192000000 10278498960 10739707904 1378208112 14.401 100 26 53 1 1394771856 11077072 5486672
16:21:57 42512 8192000000 10274167584 10740088832 1376934640 14.389 100 7 14 0 1385619152 6927072 1757440
16:23:39 87414 8192000000 10285758032 10745192448 1368608576 14.315 98 16 32 0 1381618752 9014400 3995776
16:26:55 69122 8192000000 10292378160 10762805248 1369768336 14.325 98 17 34 0 1389372560 15389424 4214800
16:29:15 48117 8192000000 10276930352 10733191168 1337173552 14.032 97 22 43 0 1357166736 15235120 4758064
16:31:07 49539 8192000000 10264514176 10717921280 1331695696 13.982 97 21 43 0 1346858896 10356368 4806832
16:32:57 70305 8192000000 10283541904 10742263808 1346889024 14.119 98 17 34 0 1368350976 17644880 3817072
16:35:08 14738 8192000000 10294003088 10733723648 1367967184 14.309 100 37 74 0 1386444336 10075952 8401200
16:36:23 51103 8192000000 10278378064 10743107584 1357502272 14.215 99 24 48 0 1380879760 17894704 5482784
16:39:49 76465 8192000000 10243234960 10695147520 1313944384 13.822 97 16 32 0 1329573856 11971536 3657936
16:42:11 26994 8192000000 9560533488 9900687360 704412336 7.917 52 21 42 0 713637760 4959440 4265984

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 06-01-2023 16:44:15 to 06-01-2023 16:44:31.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 137 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 06-01-2023 16:44:31 to 06-01-2023 16:44:33.

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          250674
MON$OLDEST_ACTIVE               250675
MON$OLDEST_SNAPSHOT             250675
MON$NEXT_TRANSACTION            250675
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                       2049824
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.33652 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33652 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33652 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33652 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Fri Jan  6 16:42:52 2023
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:9: 
--- end of diff output ---

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

06.01.23 16:44:35. 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-06 16:44:37 No severe PSQL-related problems occured
06.01.23 16:44:38. End of report.