Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 05.02.2023 22:39
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15233052 kB
MemAvailable 30433152 kB
Buffers 0 kB
Cached 16505496 kB
SwapCached 3564 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-05 22:38:34
DTS_END                         2023-02-05 22:38:34.3310
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/46348
TRN_ID                          242770
ATT_ID                          717
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: 9531.38 24626 1143766
customer order: creation 173.19 367 20783
customer order: refuse 14.96 341 1795
order to supplier: creation 46.45 1321 5574
order to supplier: removal 3.49 97792 419
invoice (draft): creation 47.43 3315 5691
invoice (draft): removal 54.71 789 6565
invoice accept: apply 43.50 16179 5220
invoice accept: cancel 49.41 754 5929
customer reserve: creation 3960.68 161 475282
customer reserve: removal 4848.42 55 581810
realization accept: apply 83.53 178 10024
realization accept: cancel 74.13 107 8895
payment from customer: creation 60.60 96 7272
payment from customer: removal 6.13 64 735
payment to supplier: creation 49.74 117 5969
payment to supplier: removal 3.38 89 405
service: total inventory turnovers 4.35 1129 522
service: total monetary turnovers 6.49 54 779
service: aggregate perf. data 0.78 24597 93
service: refresh index statistics 0.03 345024 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 05-02-2023 22:40:20 to 05-02-2023 22:40:22.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 860
TEST_TIME 2 2185
TEST_TIME 3 2986
TEST_TIME 4 3790
TEST_TIME 5 4059
TEST_TIME 6 4076
TEST_TIME 7 4345
TEST_TIME 8 4470
TEST_TIME 9 5056
TEST_TIME 10 5135
TEST_TIME 11 5300
TEST_TIME 12 5561
TEST_TIME 13 5811
TEST_TIME 14 6217
TEST_TIME 15 6642
TEST_TIME 16 6728
TEST_TIME 17 6949
TEST_TIME 18 8492
TEST_TIME 19 8673
TEST_TIME 20 8697
TEST_TIME 21 8856
TEST_TIME 22 8754
TEST_TIME 23 9561
TEST_TIME 24 9794
TEST_TIME 25 9829
TEST_TIME 26 10036
TEST_TIME 27 10737
TEST_TIME 28 10997
TEST_TIME 29 10939
TEST_TIME 30 10710
TEST_TIME 31 10544
TEST_TIME 32 10425
TEST_TIME 33 10331
TEST_TIME 34 10209
TEST_TIME 35 10642
TEST_TIME 36 10529
TEST_TIME 37 10408
TEST_TIME 38 10390
TEST_TIME 39 10246
TEST_TIME 40 10269
TEST_TIME 41 10055
TEST_TIME 42 10137
TEST_TIME 43 10018
TEST_TIME 44 9981
TEST_TIME 45 10002
TEST_TIME 46 9986
TEST_TIME 47 9910
TEST_TIME 48 9773
TEST_TIME 49 9688
TEST_TIME 50 9631
TEST_TIME 51 9615
TEST_TIME 52 9526
TEST_TIME 53 9727
TEST_TIME 54 9705
TEST_TIME 55 9688
TEST_TIME 56 9662
TEST_TIME 57 9690
TEST_TIME 58 9763
TEST_TIME 59 9753
TEST_TIME 60 9810
TEST_TIME 61 9791
TEST_TIME 62 9881
TEST_TIME 63 9856
TEST_TIME 64 9950
TEST_TIME 65 9940
TEST_TIME 66 9950
TEST_TIME 67 10001
TEST_TIME 68 9936
TEST_TIME 69 9884
TEST_TIME 70 9790
TEST_TIME 71 9777
TEST_TIME 72 9809
TEST_TIME 73 9739
TEST_TIME 74 9688
TEST_TIME 75 9625
TEST_TIME 76 9545
TEST_TIME 77 9466
TEST_TIME 78 9455
TEST_TIME 79 9564
TEST_TIME 80 9589
TEST_TIME 81 9568
TEST_TIME 82 9619
TEST_TIME 83 9591
TEST_TIME 84 9596
TEST_TIME 85 9634
TEST_TIME 86 9611
TEST_TIME 87 9617
TEST_TIME 88 9690
TEST_TIME 89 9723
TEST_TIME 90 9683
TEST_TIME 91 9865
TEST_TIME 92 9762
TEST_TIME 93 9666
TEST_TIME 94 9664
TEST_TIME 95 9650
TEST_TIME 96 9643
TEST_TIME 97 9618
TEST_TIME 98 9623
TEST_TIME 99 9653
TEST_TIME 100 9622
TEST_TIME 101 9607
TEST_TIME 102 9669
TEST_TIME 103 9682
TEST_TIME 104 9710
TEST_TIME 105 9738
TEST_TIME 106 9733
TEST_TIME 107 9827
TEST_TIME 108 9846
TEST_TIME 109 9830
TEST_TIME 110 9787
TEST_TIME 111 9730
TEST_TIME 112 9741
TEST_TIME 113 9709
TEST_TIME 114 9698
TEST_TIME 115 9650
TEST_TIME 116 9657
TEST_TIME 117 9626
TEST_TIME 118 9579
TEST_TIME 119 9567
TEST_TIME 120 9534

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 05-02-2023 22:40:24 to 05-02-2023 22:40:27.

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 1582491 1582491 0 0.00 0 17666 5 0 0
doc_list_biud 1582491 1582491 0 0.00 0 59572 26 0 0
sp_add_invoice_to_stock 5293 5220 73 1.37 35 188609 16179 0 73
sp_cancel_adding_invoice 5929 5929 0 0.00 3 37779 755 0 0
sp_cancel_client_order 1795 1795 0 0.00 4 11230 341 0 0
sp_cancel_customer_reserve 581810 581810 0 0.00 0 40127 55 0 0
sp_cancel_pay_from_customer 735 735 0 0.00 2 3357 64 0 0
sp_cancel_pay_to_supplier 405 405 0 0.00 3 10277 90 0 0
sp_cancel_supplier_invoice 6573 6565 8 0.12 1 59695 789 0 8
sp_cancel_supplier_order 419 419 0 0.00 4 1074646 97793 0 0
sp_cancel_write_off 8895 8895 0 0.00 1 13609 108 0 0
sp_client_order 20783 20783 0 0.00 3 64175 368 0 0
sp_customer_reserve 475282 475282 0 0.00 0 158627 161 0 0
sp_fill_shopping_cart 32035 32035 0 0.00 1 17573 34 0 0
sp_fill_shopping_cart_clo_res 475280 475280 0 0.00 0 19024 14 0 0
sp_get_clo_for_invoice 5217 5217 0 0.00 0 21949 319 0 0
sp_kill_cost_storno 15964 15964 0 0.00 0 8392 57 0 0
sp_kill_qstorno_ret_qs2qd 1179499 1179499 0 0.00 0 59571 30 0 0
sp_kill_qty_storno 620653 620653 0 0.00 0 59572 66 0 0
sp_lock_selected_doc 21068 21068 0 0.00 0 10274 8 0 0
sp_make_cost_storno 28481 28481 0 0.00 0 11679 44 0 0
sp_multiply_rows_for_pdistr 28481 28481 0 0.00 0 6240 26 0 0
sp_multiply_rows_for_qdistr 20783 20783 0 0.00 0 63957 169 0 0
sp_pay_from_customer 7272 7272 0 0.00 4 9315 96 0 0
sp_pay_to_supplier 5969 5969 0 0.00 4 6653 118 0 0
sp_payment_common 13241 13241 0 0.00 1 6345 23 0 0
sp_qd_handle_on_cancel_clo 1795 1795 0 0.00 0 11221 308 0 0
sp_qd_handle_on_invoice_upd_sts 11145 11145 0 0.00 0 38040 346 0 0
sp_qd_handle_on_reserve_upd_sts 10024 10024 0 0.00 0 17926 72 0 0
sp_reserve_write_off 10024 10024 0 0.00 6 17958 179 0 0
sp_supplier_invoice 5691 5691 0 0.00 6 104550 3316 0 0
sp_supplier_order 5574 5574 0 0.00 4 46850 1322 0 0
srv_aggregate_perf_data 93 93 0 0.00 2667 636205 24597 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 836 187476 45343 0 0
srv_find_qd_qs_mism 1094387 1094387 0 0.00 0 17610 5 0 0
srv_make_invnt_saldo 522 522 0 0.00 18 49889 1129 0 0
srv_make_money_saldo 779 779 0 0.00 0 21441 54 0 0
srv_recalc_idx_stat 4 4 0 0.00 179806 506198 345024 0 0
t$perf-abend:sp_add_invoice_to_stock 73 73 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5220 5220 0 0.00 0 11350 158 0 0
t$perf-norm:sp_cancel_adding_invoice 391 391 0 0.00 0 5904 112 0 0
t$perf-norm:sp_cancel_client_order 1795 1795 0 0.00 0 4870 15 0 0
t$perf-norm:sp_cancel_customer_reserve 658 658 0 0.00 0 788 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 735 735 0 0.00 0 2579 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 405 405 0 0.00 0 4169 21 0 0
t$perf-norm:sp_cancel_supplier_invoice 424 424 0 0.00 0 2139 18 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 18780 1424 0 0
t$perf-norm:sp_cancel_write_off 1001 1001 0 0.00 0 750 3 0 0
t$perf-norm:sp_client_order 20783 20783 0 0.00 0 27913 20 0 0
t$perf-norm:sp_customer_reserve 1017 1017 0 0.00 0 1368 4 0 0
t$perf-norm:sp_pay_from_customer 7272 7272 0 0.00 0 13762 13 0 0
t$perf-norm:sp_pay_to_supplier 5969 5969 0 0.00 0 6912 9 0 0
t$perf-norm:sp_reserve_write_off 10024 10024 0 0.00 0 10806 14 0 0
t$perf-norm:sp_supplier_invoice 5691 5691 0 0.00 0 6084 18 0 0
t$perf-norm:sp_supplier_order 5574 5574 0 0.00 0 6520 18 0 0
t$perf-norm:srv_aggregate_perf_data 93 93 0 0.00 0 108 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 72 2 0 0
t$perf-norm:srv_make_invnt_saldo 522 522 0 0.00 0 3337 33 0 0
t$perf-norm:srv_make_money_saldo 779 779 0 0.00 0 2800 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 14 6 0 0
v_add_invoice_to_stock 10578 10432 146 1.38 0 6200 17 0 146
v_all_customers 38072 38072 0 0.00 0 140 1 0 0
v_all_suppliers 31076 31076 0 0.00 0 116 1 0 0
v_all_wares 41566 41566 0 0.00 0 140 1 0 0
v_cancel_adding_invoice 782 782 0 0.00 0 2662 11 0 0
v_cancel_client_order 3590 3590 0 0.00 0 2300 7 0 0
v_cancel_customer_prepayment 1470 1470 0 0.00 0 2668 7 0 0
v_cancel_customer_reserve 1316 1316 0 0.00 0 2334 7 0 0
v_cancel_payment_to_supplier 810 810 0 0.00 0 228 3 0 0
v_cancel_supplier_invoice 864 848 16 1.85 0 472 12 0 16
v_cancel_supplier_order 838 838 0 0.00 0 1001 5 0 0
v_cancel_write_off 2002 2002 0 0.00 0 3241 19 0 0
v_max_id_clo_ord 5573 5573 0 0.00 0 1356 2 0 0
v_max_id_clo_res 1017 1017 0 0.00 0 3787 15 0 0
v_max_id_ord_sup 5679 5679 0 0.00 0 7685 3 0 0
v_max_non_paid_invoice 1683 1683 0 0.00 0 1608 3 0 0
v_max_non_paid_realizn 3824 3824 0 0.00 0 1109 3 0 0
v_min_id_clo_ord 5573 5573 0 0.00 0 1116 4 0 0
v_min_id_clo_res 1017 1017 0 0.00 0 5647 14 0 0
v_min_id_ord_sup 5679 5679 0 0.00 0 9941 10 0 0
v_min_non_paid_invoice 5969 5969 0 0.00 0 6233 18 0 0
v_min_non_paid_realizn 7272 7272 0 0.00 0 5320 16 0 0
v_our_firm 10390 10390 0 0.00 0 71 1 0 0
v_reserve_write_off 20048 20048 0 0.00 0 4613 6 0 0
x_lock_depdocs_on_canc_invoice 12070 12070 0 0.00 0 12571 107 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 14150 421 0 0
x_make_qstorno_1000_1200 5574 5574 0 0.00 1 46655 1247 0 0
x_make_qstorno_1200_2000 5691 5691 0 0.00 2 99777 3193 0 0
x_make_qstorno_2100_3300 475282 475282 0 0.00 0 158624 144 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-02-2023 22:40:28 to 05-02-2023 22:40:32.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
20:08:38 5 8192000000 8808140768 8822693888 12325328 0.150 4 3 4 0 12510448 78016 107104
20:09:39 37782 8192000000 9896390144 10202910720 957850112 10.468 100 85 170 0 987094688 6203104 23041472
20:11:25 17738 8192000000 9935666160 10251976704 1006710944 10.944 100 88 176 0 1038123856 8122096 23290816
20:13:03 12112 8192000000 9981763328 10318372864 1061075776 11.467 100 73 144 1 1087864320 8991456 17797088
20:14:20 10130 8192000000 10044591280 10391310336 1114104624 11.971 100 70 138 1 1141169680 10562400 16502656
20:15:36 5533 8192000000 10089926976 10450927616 1170266256 12.499 100 79 154 1 1200132240 12796480 17069504
20:16:44 4035 8192000000 10134850128 10503127040 1205953200 12.832 100 76 149 1 1234886272 12592368 16340704
20:17:49 1570 8192000000 10166652144 10544148480 1245526048 13.197 100 83 165 0 1276862016 13275936 18060032
20:18:52 1460 8192000000 10198814384 10577489920 1271407504 13.434 100 87 174 0 1304107216 14091904 18607808
20:19:54 8476 8192000000 10198023888 10587373568 1281987600 13.531 100 53 107 1 1301894528 7781232 12125696
20:21:03 7173 8192000000 10197763712 10601664512 1289511936 13.600 100 32 65 0 1308082080 11166480 7403664
20:22:11 6642 8192000000 10199733408 10611974144 1302816032 13.721 100 20 38 1 1313019760 5588080 4615648
20:23:18 8012 8192000000 10205502544 10622861312 1309730560 13.784 100 18 34 1 1319579824 6377552 3471712
20:24:27 28305 8192000000 10236701504 10638061568 1314975792 13.831 100 50 99 1 1331822288 5136832 11709664
20:25:57 9273 8192000000 10238182608 10651512832 1319205024 13.870 100 32 63 0 1337163008 10392672 7565312
20:27:09 8881 8192000000 10225568048 10645942272 1322038736 13.895 100 23 46 2 1333097456 5977744 5080976
20:28:21 10286 8192000000 10199892928 10616254464 1278707824 13.501 98 15 30 0 1287519088 5535056 3276208
20:29:32 19433 8192000000 10191133008 10608320512 1278618928 13.500 99 20 39 0 1288588752 5111008 4858816
20:30:55 3388 8192000000 10166207328 10565459968 1239269888 13.140 98 35 70 1 1254803248 7737520 7795840
20:32:02 6513 8192000000 10133033360 10531733504 1224101584 13.000 98 26 52 0 1235795376 5381712 6312080
20:33:09 54307 8192000000 10148930592 10550419456 1238724784 13.134 98 28 53 0 1249690880 5017616 5948480
20:35:04 6671 8192000000 10188082704 10591821824 1283778064 13.547 100 34 68 1 1298083952 6308144 7997744
20:36:13 4090 8192000000 10189172368 10606682112 1302979248 13.722 100 27 54 0 1315193472 5313072 6901152
20:37:22 9718 8192000000 10206755840 10620932096 1315070240 13.832 100 17 34 0 1324136896 5373392 3693264
20:38:36 99753 8192000000 10249190848 10666930176 1345061632 14.103 100 35 68 1 1359089504 5733856 8294016
20:41:20 5790 8192000000 10276030128 10693222400 1366687616 14.297 100 49 98 1 1392072032 14439792 10944624
20:42:30 46384 8192000000 10293903376 10715144192 1381858320 14.433 100 34 68 0 1406973536 16797952 8317264
20:44:21 11567 8192000000 10316242608 10739642368 1399863232 14.594 100 54 101 3 1430393952 18105152 12425568
20:45:36 13082 8192000000 10338048400 10761986048 1414262736 14.722 100 46 92 1 1450454816 24936160 11255920
20:46:53 12276 8192000000 10337531312 10766147584 1415432912 14.732 100 45 91 1 1453277024 26108512 11735600
20:48:25 8663 8192000000 10352661232 10779566080 1416905504 14.745 99 54 107 0 1455764096 25782112 13076480
20:49:38 2678 8192000000 10355354288 10776076288 1424539248 14.813 100 62 124 1 1467192896 27955248 14698400
20:50:44 4771 8192000000 10356991664 10778718208 1421687184 14.788 100 61 118 0 1468322464 31163152 15472128
20:51:55 2200 8192000000 10376440576 10788519936 1422458848 14.794 100 85 167 1 1477705376 35223088 20023440
20:53:02 6724 8192000000 10366736320 10781675520 1412946464 14.710 100 74 148 1 1469095200 37769120 18379616
20:54:18 11422 8192000000 10338608400 10754256896 1374184704 14.365 99 61 122 0 1432137584 42686208 15266672
20:55:32 833 8192000000 10351338528 10752401408 1376816496 14.388 100 83 164 0 1451565616 53838304 20910816
20:56:39 14838 8192000000 10307084656 10732232704 1362582848 14.261 99 43 86 0 1410567952 36454112 11530992
20:58:02 7833 8192000000 10299142320 10729205760 1368756992 14.316 100 33 66 0 1407347856 29130736 9460128
20:59:15 5390 8192000000 10318898016 10741575680 1369373296 14.321 100 42 82 1 1415875040 35423488 11078256
21:00:23 9994 8192000000 10306307632 10739847168 1360921840 14.246 99 38 76 0 1410714992 38379536 11413616
21:01:46 19568 8192000000 10304398864 10745917440 1376579840 14.386 100 31 60 1 1420070432 35499008 7991584
21:03:11 15333 8192000000 10298085808 10728620032 1366571472 14.296 100 22 44 0 1403647680 31213952 5862256
21:05:01 23094 8192000000 10291626416 10731859968 1344956672 14.102 99 28 56 0 1386828416 34590720 7281024
21:06:40 42445 8192000000 10244321104 10691170304 1314364592 13.826 97 6 10 0 1325013344 9520464 1128288
21:08:24 104863 8192000000 10284904400 10716196864 1299469024 13.690 95 24 48 2 1314695456 9944400 5282032
21:11:16 90185 8192000000 10297796464 10741145600 1375059024 14.372 100 33 66 0 1406272720 23576720 7636976
21:13:48 187475 8192000000 10286730032 10730442752 1333576304 13.999 97 20 40 0 1350341648 12836352 3928992
21:17:57 5938 8192000000 10303968304 10744745984 1383040144 14.444 100 39 77 2 1411495136 19500912 8954080
21:19:05 26296 8192000000 10289757008 10740670464 1381321568 14.428 100 19 38 0 1403564256 17342656 4900032
21:20:38 47309 8192000000 10284201520 10738577408 1372107936 14.346 99 8 16 0 1388996080 14945504 1942640
21:22:26 18415 8192000000 10282053392 10726289408 1364836160 14.281 99 26 52 1 1383264432 12080848 6347424
21:23:48 86682 8192000000 10283779920 10741276672 1382577680 14.440 100 15 30 0 1395162688 8988224 3596784
21:26:20 22168 8192000000 10289509264 10735239168 1373790560 14.361 99 33 67 0 1390223280 8942608 7490112
21:27:47 61112 8192000000 10293321360 10753622016 1380255168 14.419 99 14 28 1 1396309168 12736928 3317072
21:29:49 64886 8192000000 10286133680 10746511360 1381587104 14.431 99 16 32 0 1401485232 15985088 3913040
21:31:55 62773 8192000000 10290267248 10762571776 1350470448 14.152 97 6 12 0 1364524096 12950288 1103360
21:33:59 13018 8192000000 10281856832 10729955328 1357319776 14.213 98 27 54 1 1370948112 7956096 5672240
21:35:12 53186 8192000000 10285134960 10740858880 1338054416 14.040 96 19 38 0 1355446064 13083424 4308224
21:37:09 35502 8192000000 10276797424 10731085824 1357247280 14.213 98 27 54 1 1372219168 8642080 6329808
21:38:45 57910 8192000000 10297373488 10746626048 1331467360 13.980 96 22 44 0 1344088736 7913120 4708256
21:41:09 87175 8192000000 10256401104 10714447872 1273194224 13.451 92 12 24 0 1293727856 18259792 2273840
21:43:36 23596 8192000000 10278474416 10705104896 1326647184 13.937 97 44 88 0 1346315632 9712800 9955648
21:45:01 55395 8192000000 10287125264 10730229760 1353837520 14.182 99 38 76 0 1380615600 18527872 8250208
21:46:57 95248 8192000000 10290555120 10735431680 1348510304 14.134 98 17 34 1 1359764880 7196512 4058064
21:49:34 50538 8192000000 10265142224 10732134400 1373395376 14.357 100 8 16 0 1384671072 9445056 1830640
21:51:31 50072 8192000000 10238861728 10682417152 1289791632 13.602 94 19 35 1 1300066224 6105120 4169472
21:53:28 109387 8192000000 10259751984 10712010752 1345445328 14.106 99 29 58 1 1362994560 10481968 7067264
21:56:22 28352 8192000000 10255361744 10714062848 1348288464 14.132 99 19 38 0 1371408544 18157936 4962144
21:57:52 70096 8192000000 10251203904 10713325568 1334734128 14.010 98 8 16 0 1348829888 12149984 1945776
22:00:06 50528 8192000000 10262178720 10721796096 1353590624 14.180 99 30 59 0 1372586464 11809376 7186464
22:01:59 18866 8192000000 10288518112 10727727104 1373630416 14.360 100 57 114 0 1396333776 10380800 12322560
22:03:20 38945 8192000000 10276574704 10735276032 1373040656 14.354 100 22 44 0 1391124784 13173184 4910944
22:05:12 38220 8192000000 10305453888 10748665856 1376156784 14.382 99 28 55 1 1393850816 11312480 6381552
22:06:51 35384 8192000000 10290762688 10745282560 1362556064 14.260 98 19 39 0 1377058976 10192864 4310048
22:09:07 93914 8192000000 10304998288 10783899648 1398111248 14.578 100 14 28 0 1420229360 19264384 2853728
22:11:50 11516 8192000000 10312489504 10756960256 1397409328 14.572 100 58 109 1 1420782976 11692480 11681168
22:13:02 88066 8192000000 10294959872 10771435520 1384585696 14.458 98 11 22 0 1396550992 9754160 2211136
22:15:32 24209 8192000000 10292308112 10748833792 1387034048 14.479 100 13 24 1 1399813744 9704368 3075328
22:17:10 47270 8192000000 10302172512 10764881920 1388231072 14.490 100 20 40 0 1405058336 11947504 4879760
22:19:06 78262 8192000000 10302347248 10775810048 1399358592 14.589 100 19 36 0 1415576624 12144000 4074032
22:21:36 84428 8192000000 10303067776 10767409152 1384959136 14.461 99 10 20 0 1402453024 14922240 2571648
22:24:14 46009 8192000000 10318023600 10771996672 1382882448 14.442 99 31 58 1 1407125664 17018928 7224288
22:26:08 77049 8192000000 10291294032 10739187712 1342342176 14.079 97 19 38 0 1360474864 14131600 4001088
22:28:28 95120 8192000000 10235671920 10681630720 1284888048 13.558 94 25 50 0 1298316544 7727680 5700816
22:31:04 63910 8192000000 10261315168 10719928320 1355203552 14.194 100 14 28 0 1375291936 17391584 2696800
22:33:08 55336 8192000000 10255718240 10716155904 1314376400 13.826 96 11 22 0 1332839328 16180464 2282464
22:35:04 52518 8192000000 10242660000 10697953280 1339166784 14.050 99 16 32 0 1353343024 10457760 3718480
22:37:10 75413 8192000000 10247702688 10709344256 1304511632 13.736 97 8 16 0 1320994016 14305104 2177280

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 05-02-2023 22:40:34 to 05-02-2023 22:40:50.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 73 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 05-02-2023 22:40:51 to 05-02-2023 22:40:53.

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          241429
MON$OLDEST_ACTIVE               243097
MON$OLDEST_SNAPSHOT             243097
MON$NEXT_TRANSACTION            243097
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                       2040160
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.33659 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33659 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33659 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33659 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 ---
:76: 
:77: mscs-test-161.budzdorov.ru    Sun Feb  5 22:38:39 2023
:78:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:79: 
:80: 
:81: mscs-test-161.budzdorov.ru    Sun Feb  5 22:38:41 2023
:82:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:83: 
--- end of diff output ---

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

05.02.23 22:40:54. 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-05 22:40:56 No severe PSQL-related problems occured
05.02.23 22:40:58. End of report.