Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.01.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 15252356 kB
MemAvailable 30370032 kB
Buffers 0 kB
Cached 16437856 kB
SwapCached 5560 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-03 22:38:41
DTS_END                         2023-01-03 22:38:41.2080
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/48730
TRN_ID                          246004
ATT_ID                          665
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: 9543.82 16479 1145258
customer order: creation 182.08 350 21849
customer order: refuse 15.10 362 1812
order to supplier: creation 48.13 1381 5775
order to supplier: removal 3.66 87839 439
invoice (draft): creation 48.33 3279 5800
invoice (draft): removal 54.80 704 6576
invoice accept: apply 43.07 15706 5168
invoice accept: cancel 48.94 712 5873
customer reserve: creation 4007.14 155 480857
customer reserve: removal 4792.08 52 575049
realization accept: apply 87.95 173 10554
realization accept: cancel 78.36 106 9403
payment from customer: creation 61.13 108 7335
payment from customer: removal 5.83 108 700
payment to supplier: creation 51.17 126 6140
payment to supplier: removal 3.73 53 447
service: total inventory turnovers 4.86 1265 583
service: total monetary turnovers 6.51 25 781
service: aggregate perf. data 0.94 18149 113
service: refresh index statistics 0.03 198936 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-01-2023 22:39:38 to 03-01-2023 22:39:40.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2920
TEST_TIME 2 4349
TEST_TIME 3 5087
TEST_TIME 4 4838
TEST_TIME 5 4845
TEST_TIME 6 4792
TEST_TIME 7 5235
TEST_TIME 8 5215
TEST_TIME 9 5641
TEST_TIME 10 5797
TEST_TIME 11 6151
TEST_TIME 12 6545
TEST_TIME 13 6594
TEST_TIME 14 6937
TEST_TIME 15 7148
TEST_TIME 16 7942
TEST_TIME 17 8106
TEST_TIME 18 8178
TEST_TIME 19 8151
TEST_TIME 20 8622
TEST_TIME 21 8947
TEST_TIME 22 9263
TEST_TIME 23 9927
TEST_TIME 24 10088
TEST_TIME 25 9814
TEST_TIME 26 9639
TEST_TIME 27 9786
TEST_TIME 28 10109
TEST_TIME 29 10125
TEST_TIME 30 10106
TEST_TIME 31 9986
TEST_TIME 32 9950
TEST_TIME 33 9998
TEST_TIME 34 10092
TEST_TIME 35 10047
TEST_TIME 36 9997
TEST_TIME 37 10048
TEST_TIME 38 10025
TEST_TIME 39 10127
TEST_TIME 40 10117
TEST_TIME 41 10069
TEST_TIME 42 10218
TEST_TIME 43 10000
TEST_TIME 44 9828
TEST_TIME 45 9811
TEST_TIME 46 9816
TEST_TIME 47 9729
TEST_TIME 48 9693
TEST_TIME 49 9584
TEST_TIME 50 9567
TEST_TIME 51 9641
TEST_TIME 52 9745
TEST_TIME 53 9633
TEST_TIME 54 9652
TEST_TIME 55 9602
TEST_TIME 56 9617
TEST_TIME 57 9680
TEST_TIME 58 9659
TEST_TIME 59 9759
TEST_TIME 60 9727
TEST_TIME 61 9730
TEST_TIME 62 9834
TEST_TIME 63 9835
TEST_TIME 64 9867
TEST_TIME 65 9847
TEST_TIME 66 9805
TEST_TIME 67 9880
TEST_TIME 68 9972
TEST_TIME 69 9963
TEST_TIME 70 9920
TEST_TIME 71 9871
TEST_TIME 72 9839
TEST_TIME 73 9796
TEST_TIME 74 9750
TEST_TIME 75 9739
TEST_TIME 76 9749
TEST_TIME 77 9748
TEST_TIME 78 9694
TEST_TIME 79 9663
TEST_TIME 80 9652
TEST_TIME 81 9598
TEST_TIME 82 9505
TEST_TIME 83 9522
TEST_TIME 84 9522
TEST_TIME 85 9498
TEST_TIME 86 9532
TEST_TIME 87 9483
TEST_TIME 88 9522
TEST_TIME 89 9505
TEST_TIME 90 9564
TEST_TIME 91 9583
TEST_TIME 92 9588
TEST_TIME 93 9565
TEST_TIME 94 9723
TEST_TIME 95 9733
TEST_TIME 96 9753
TEST_TIME 97 9753
TEST_TIME 98 9764
TEST_TIME 99 9750
TEST_TIME 100 9779
TEST_TIME 101 9704
TEST_TIME 102 9675
TEST_TIME 103 9648
TEST_TIME 104 9628
TEST_TIME 105 9673
TEST_TIME 106 9633
TEST_TIME 107 9604
TEST_TIME 108 9604
TEST_TIME 109 9596
TEST_TIME 110 9553
TEST_TIME 111 9503
TEST_TIME 112 9434
TEST_TIME 113 9457
TEST_TIME 114 9549
TEST_TIME 115 9551
TEST_TIME 116 9541
TEST_TIME 117 9601
TEST_TIME 118 9606
TEST_TIME 119 9601
TEST_TIME 120 9553

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 03-01-2023 22:39:42 to 03-01-2023 22:39:45.

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 1591552 1591552 0 0.00 0 22039 5 0 0
doc_list_biud 1591551 1591551 0 0.00 0 24613 25 0 0
sp_add_invoice_to_stock 5289 5168 121 2.28 40 181976 15706 0 121
sp_cancel_adding_invoice 5873 5873 0 0.00 3 56999 712 0 0
sp_cancel_client_order 1812 1812 0 0.00 4 15332 363 0 0
sp_cancel_customer_reserve 575049 575049 0 0.00 0 24617 53 0 0
sp_cancel_pay_from_customer 700 700 0 0.00 2 9059 109 0 0
sp_cancel_pay_to_supplier 447 447 0 0.00 3 2449 53 0 0
sp_cancel_supplier_invoice 6585 6576 9 0.13 2 16874 705 0 9
sp_cancel_supplier_order 439 439 0 0.00 6 862548 87840 0 0
sp_cancel_write_off 9403 9403 0 0.00 1 10814 106 0 0
sp_client_order 21849 21849 0 0.00 3 24185 351 0 0
sp_customer_reserve 480857 480857 0 0.00 0 38390 155 0 0
sp_fill_shopping_cart 33419 33419 0 0.00 1 13223 35 0 0
sp_fill_shopping_cart_clo_res 480842 480842 0 0.00 0 12730 12 0 0
sp_get_clo_for_invoice 5152 5152 0 0.00 0 24482 263 0 0
sp_kill_cost_storno 16423 16423 0 0.00 0 13040 50 0 0
sp_kill_qstorno_ret_qs2qd 1166516 1166516 0 0.00 0 24611 29 0 0
sp_kill_qty_storno 614858 614858 0 0.00 0 24613 63 0 0
sp_lock_selected_doc 21757 21757 0 0.00 0 6810 4 0 0
sp_make_cost_storno 29181 29181 0 0.00 0 11648 48 0 0
sp_multiply_rows_for_pdistr 29181 29181 0 0.00 0 9548 25 0 0
sp_multiply_rows_for_qdistr 21848 21848 0 0.00 0 10861 143 0 0
sp_pay_from_customer 7335 7335 0 0.00 4 20355 109 0 0
sp_pay_to_supplier 6140 6140 0 0.00 5 17709 126 0 0
sp_payment_common 13475 13475 0 0.00 1 6600 25 0 0
sp_qd_handle_on_cancel_clo 1812 1812 0 0.00 0 15327 338 0 0
sp_qd_handle_on_invoice_upd_sts 11025 11025 0 0.00 0 22799 314 0 0
sp_qd_handle_on_reserve_upd_sts 10554 10554 0 0.00 0 20310 75 0 0
sp_reserve_write_off 10554 10554 0 0.00 6 20326 173 0 0
sp_supplier_invoice 5800 5800 0 0.00 6 98007 3280 0 0
sp_supplier_order 5775 5775 0 0.00 5 30780 1382 0 0
srv_aggregate_perf_data 113 113 0 0.00 2967 140793 18150 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1088 111623 46026 0 0
srv_find_qd_qs_mism 1095899 1095899 0 0.00 0 22026 4 0 0
srv_make_invnt_saldo 583 583 0 0.00 30 98821 1265 0 0
srv_make_money_saldo 781 781 0 0.00 1 4043 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 122171 387391 198936 0 0
t$perf-abend:sp_add_invoice_to_stock 121 121 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5168 5168 0 0.00 0 15966 163 0 0
t$perf-norm:sp_cancel_adding_invoice 396 396 0 0.00 0 2992 73 0 0
t$perf-norm:sp_cancel_client_order 1812 1812 0 0.00 0 5864 19 0 0
t$perf-norm:sp_cancel_customer_reserve 710 710 0 0.00 0 2118 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 700 700 0 0.00 0 8459 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 447 447 0 0.00 0 1762 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 450 450 0 0.00 0 913 10 0 0
t$perf-norm:sp_cancel_supplier_order 439 439 0 0.00 0 39279 1595 0 0
t$perf-norm:sp_cancel_write_off 1102 1102 0 0.00 0 3435 10 0 0
t$perf-norm:sp_client_order 21849 21849 0 0.00 0 8359 15 0 0
t$perf-norm:sp_customer_reserve 1033 1033 0 0.00 0 879 4 0 0
t$perf-norm:sp_pay_from_customer 7335 7335 0 0.00 0 6977 9 0 0
t$perf-norm:sp_pay_to_supplier 6140 6140 0 0.00 0 14950 17 0 0
t$perf-norm:sp_reserve_write_off 10554 10554 0 0.00 0 14874 13 0 0
t$perf-norm:sp_supplier_invoice 5800 5800 0 0.00 0 6233 17 0 0
t$perf-norm:sp_supplier_order 5775 5775 0 0.00 0 8961 14 0 0
t$perf-norm:srv_aggregate_perf_data 113 113 0 0.00 0 292 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 62 1 0 0
t$perf-norm:srv_make_invnt_saldo 583 583 0 0.00 0 1018 23 0 0
t$perf-norm:srv_make_money_saldo 781 781 0 0.00 0 6667 35 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 16 7 0 0
v_add_invoice_to_stock 10538 10296 242 2.29 0 6875 16 0 242
v_all_customers 39514 39514 0 0.00 0 192 0 0 0
v_all_suppliers 32036 32036 0 0.00 0 19 1 0 0
v_all_wares 43696 43696 0 0.00 0 34 1 0 0
v_cancel_adding_invoice 792 792 0 0.00 0 2974 9 0 0
v_cancel_client_order 3624 3624 0 0.00 0 2683 5 0 0
v_cancel_customer_prepayment 1400 1400 0 0.00 0 1812 7 0 0
v_cancel_customer_reserve 1420 1420 0 0.00 0 913 4 0 0
v_cancel_payment_to_supplier 894 894 0 0.00 0 2444 6 0 0
v_cancel_supplier_invoice 918 900 18 1.96 0 1211 14 0 18
v_cancel_supplier_order 878 878 0 0.00 0 3027 11 0 0
v_cancel_write_off 2204 2204 0 0.00 0 3670 18 0 0
v_max_id_clo_ord 5775 5775 0 0.00 0 4554 3 0 0
v_max_id_clo_res 1033 1033 0 0.00 0 4780 17 0 0
v_max_id_ord_sup 5796 5796 0 0.00 0 3148 3 0 0
v_max_non_paid_invoice 1693 1693 0 0.00 0 266 2 0 0
v_max_non_paid_realizn 4027 4027 0 0.00 0 3774 5 0 0
v_min_id_clo_ord 5775 5775 0 0.00 0 4553 7 0 0
v_min_id_clo_res 1033 1033 0 0.00 0 4717 11 0 0
v_min_id_ord_sup 5796 5796 0 0.00 0 6431 8 0 0
v_min_non_paid_invoice 6140 6140 0 0.00 0 6244 17 0 0
v_min_non_paid_realizn 7335 7335 0 0.00 0 6162 19 0 0
v_our_firm 10798 10798 0 0.00 0 14 1 0 0
v_reserve_write_off 21106 21106 0 0.00 0 3920 6 0 0
x_lock_depdocs_on_canc_invoice 11999 11999 0 0.00 0 18000 106 0 0
x_lock_depdocs_on_canc_sup_ord 439 439 0 0.00 0 6313 279 0 0
x_make_qstorno_1000_1200 5775 5775 0 0.00 1 30718 1314 0 0
x_make_qstorno_1200_2000 5800 5800 0 0.00 3 95763 3149 0 0
x_make_qstorno_2100_3300 480857 480857 0 0.00 0 38388 140 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-01-2023 22:39:46 to 03-01-2023 22:39:50.

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:45 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:46 21523 8192000000 9910817424 10220294144 993889952 10.819 100 87 174 0 1022853792 6095200 22868640
20:11:11 26487 8192000000 9944133984 10264911872 1026612784 11.136 100 84 168 0 1056211600 7465296 22133520
20:12:47 20464 8192000000 9994214112 10325553152 1070730064 11.559 100 85 170 0 1101364016 9521088 21112864
20:14:16 5709 8192000000 10037743056 10387234816 1126721616 12.090 100 83 166 0 1157974400 10987728 20265056
20:15:27 3739 8192000000 10096722448 10458034176 1186069024 12.647 100 89 178 0 1219421568 13585952 19766592
20:16:33 3052 8192000000 10131191552 10500788224 1220074448 12.962 100 81 159 0 1250851232 12446032 18330752
20:17:39 2170 8192000000 10167858592 10545631232 1251001312 13.247 100 80 160 0 1282350368 13311040 18038016
20:18:43 1613 8192000000 10198019088 10583498752 1285131408 13.560 100 87 174 0 1317965344 13012576 19821360
20:19:46 4330 8192000000 10214020880 10606247936 1305050368 13.741 100 77 154 4 1334636288 12290112 17295808
20:20:50 13301 8192000000 10198907888 10611376128 1315592576 13.837 100 19 39 0 1326199152 6105616 4500960
20:22:05 32511 8192000000 10217358720 10630602752 1324817056 13.920 100 51 102 1 1342069072 5059264 12192752
20:23:38 5469 8192000000 10222499648 10642931712 1331853824 13.984 100 29 58 0 1345708800 7403968 6451008
20:24:45 8622 8192000000 10230395440 10650406912 1337921808 14.039 100 14 28 0 1346763200 5289136 3552256
20:25:54 10152 8192000000 10236316400 10655363072 1343237904 14.087 100 23 47 0 1354606160 6651760 4716496
20:27:05 35311 8192000000 10229569456 10656661504 1334211040 14.005 99 14 27 0 1342009088 5092864 2705184
20:28:42 6017 8192000000 10220695328 10635931648 1308472240 13.772 98 32 64 1 1322564272 6813280 7278752
20:29:50 10435 8192000000 10148726080 10549133312 1220453904 12.966 96 21 42 0 1230856240 5321040 5081296
20:31:07 26661 8192000000 10100667856 10497273856 1159548816 12.399 93 27 54 1 1171993552 5051376 7393360
20:32:59 7513 8192000000 10131187616 10534305792 1233135376 13.083 99 28 52 2 1244407584 5469568 5802640
20:34:08 6228 8192000000 10163254896 10564403200 1265392848 13.379 100 15 30 0 1274429216 5353648 3682720
20:35:19 6538 8192000000 10179484944 10590633984 1285878944 13.567 100 24 48 0 1297485920 5899696 5707280
20:36:26 69385 8192000000 10187828720 10606116864 1298649424 13.683 100 26 53 1 1309660272 4726896 6283952
20:38:36 5809 8192000000 10218301472 10632896512 1322653472 13.901 100 25 50 0 1333462912 6005744 4803696
20:39:56 4389 8192000000 10253134064 10661945344 1343510768 14.089 100 53 105 0 1368114752 11852880 12751104
20:41:04 14616 8192000000 10265433296 10685198336 1360467744 14.242 100 39 75 1 1380816032 11537888 8810400
20:42:23 44137 8192000000 10286175072 10711027712 1377409392 14.393 100 43 85 0 1402295984 14624000 10262592
20:44:11 21720 8192000000 10292821600 10718916608 1384754768 14.459 100 36 72 0 1409937136 15808928 9373440
20:45:41 6567 8192000000 10306300208 10730856448 1385427920 14.465 99 45 88 0 1413977088 16293792 12255376
20:46:52 6110 8192000000 10325748208 10751627264 1404687632 14.637 100 51 102 0 1438076752 21216128 12172992
20:48:07 5797 8192000000 10339986240 10762715136 1412409408 14.705 100 64 128 0 1448326512 20427536 15489568
20:49:18 10550 8192000000 10342934800 10767368192 1416490768 14.742 100 49 97 0 1451968656 23023008 12454880
20:50:35 9108 8192000000 10344122480 10768269312 1414266448 14.722 100 57 114 0 1453408288 24085600 15056240
20:51:52 2785 8192000000 10356069744 10772779008 1414992016 14.728 100 76 151 0 1459777168 25698928 19086224
20:52:59 1087 8192000000 10354481680 10766606336 1408734000 14.673 100 82 157 2 1457095552 28731920 19629632
20:54:06 7595 8192000000 10317476576 10727862272 1355480384 14.197 99 74 147 0 1408739024 34519360 18739280
20:55:17 11836 8192000000 10300143840 10726498304 1380191888 14.418 100 39 78 0 1409279856 19057824 10030144
20:56:35 3797 8192000000 10303443712 10720354304 1367481152 14.304 100 47 95 1 1402384560 23234064 11669344
20:57:41 13073 8192000000 10306601216 10727489536 1362968624 14.264 99 30 60 1 1400285488 28968096 8348768
20:58:57 26182 8192000000 10279752640 10703773696 1318014848 13.859 96 33 64 1 1353190064 26313840 8861376
21:00:26 7385 8192000000 10267745792 10690879488 1329130544 13.959 99 41 76 1 1368852656 28300032 11422080
21:01:39 9718 8192000000 10262314704 10694696960 1330859872 13.975 99 25 50 0 1360490464 22764160 6866432
21:02:50 34937 8192000000 10260469696 10694881280 1326671264 13.937 98 19 39 0 1352155696 20786544 4697888
21:04:26 31670 8192000000 10267391296 10703802368 1348242416 14.132 100 37 73 2 1379506160 22723312 8540432
21:06:02 26642 8192000000 10246685168 10699587584 1324438544 13.917 99 6 12 0 1349401184 23313680 1648960
21:07:32 71229 8192000000 10272057200 10704760832 1340006656 14.057 99 20 40 0 1353917216 9603104 4307456
21:09:51 62163 8192000000 10287838608 10725675008 1371434784 14.340 100 16 32 0 1385921104 11301040 3185280
21:11:59 107357 8192000000 10295822976 10750210048 1375667136 14.378 100 11 22 0 1395742064 17725600 2349328
21:14:49 89556 8192000000 10298398560 10739277824 1356814608 14.209 98 15 30 1 1372586192 11929728 3841856
21:17:23 104677 8192000000 10311095440 10766417920 1396281600 14.562 100 5 10 0 1408633872 11335776 1016496
21:20:11 106356 8192000000 10320763200 10776518656 1405945856 14.648 100 12 24 0 1424308512 15653408 2709248
21:23:33 36406 8192000000 10323664256 10768822272 1403791936 14.629 100 42 82 0 1423764576 10492176 9480464
21:25:11 34207 8192000000 10317294032 10777522176 1412064064 14.702 100 15 28 0 1428821744 13486112 3271568
21:26:49 13365 8192000000 10344745072 10795343872 1426377488 14.829 100 23 46 1 1446467968 14608896 5481584
21:28:18 32929 8192000000 10338388032 10795663360 1412784624 14.709 99 19 38 1 1435863024 18590512 4487888
21:29:59 39095 8192000000 10312317616 10788212736 1394590224 14.547 99 13 26 0 1418751600 21055152 3106224
21:31:39 36612 8192000000 10327528832 10787917824 1412922976 14.710 100 14 29 0 1428692192 12027440 3741776
21:33:17 75167 8192000000 10332528832 10789867520 1408585360 14.671 100 19 39 0 1440062800 26976608 4500832
21:35:58 46213 8192000000 10304068320 10757169152 1386157280 14.472 99 32 64 1 1403718832 10585632 6975920
21:37:47 46500 8192000000 10304507664 10738847744 1310354976 13.789 94 23 46 0 1325752960 10418288 4979696
21:39:35 26874 8192000000 10306834816 10749571072 1360629760 14.243 98 35 70 0 1380059680 11475808 7954112
21:41:02 96487 8192000000 10296024864 10751725568 1353927168 14.183 97 19 38 1 1369630432 11638064 4065200
21:43:39 16219 8192000000 10277796592 10717274112 1355504880 14.197 99 39 78 0 1373382416 9566928 8310608
21:44:59 64274 8192000000 10258786976 10707824640 1312926592 13.813 97 21 42 0 1330838560 13067888 4844080
21:47:04 109005 8192000000 10243148640 10676789248 1207980928 12.850 90 16 32 0 1224409936 13027360 3401648
21:49:53 100810 8192000000 10255027552 10693857280 1322289616 13.897 99 30 56 0 1341641984 13012080 6340288
21:52:35 63473 8192000000 10246190400 10694877184 1326590288 13.936 99 33 66 1 1349138848 14551648 7996912
21:54:40 67177 8192000000 10226056224 10684493824 1278387792 13.498 95 21 42 0 1295894016 11716848 5789376
21:56:48 45619 8192000000 10244791408 10699530240 1343312752 14.087 100 22 44 0 1357549872 8691552 5545568
21:58:48 39028 8192000000 10263237808 10714591232 1362282784 14.258 100 12 24 0 1371479616 6615040 2581792
22:00:27 111166 8192000000 10260876288 10731806720 1334929552 14.012 97 21 40 0 1353374192 14146832 4297808
22:03:30 65073 8192000000 10267647552 10724192256 1364836880 14.281 100 18 37 1 1379489456 10319248 4333328
22:05:35 30026 8192000000 10293258544 10741387264 1380165856 14.418 100 28 55 0 1395569248 9456960 5946432
22:07:21 49718 8192000000 10301510144 10761302016 1384536896 14.457 99 28 56 1 1406703920 15673440 6493584
22:09:24 17265 8192000000 10306021232 10755072000 1395427056 14.554 100 22 43 0 1406571024 6775120 4368848
22:10:44 61214 8192000000 10324884432 10777477120 1396920176 14.568 99 30 60 0 1418445232 14598624 6926432
22:12:48 111623 8192000000 10296429104 10762543104 1358247056 14.222 97 10 20 1 1372920496 12370000 2303440
22:15:40 59718 8192000000 10289696832 10742779904 1329511024 13.963 95 21 43 0 1346273536 12555808 4206704
22:17:46 65735 8192000000 10300683744 10753277952 1344602736 14.099 97 23 46 0 1359886688 10078256 5205696
22:19:55 105613 8192000000 10277871648 10737332224 1358097600 14.220 98 18 36 0 1370921904 8641600 4182704
22:23:41 61863 8192000000 10272748720 10742046720 1377673040 14.396 100 8 16 0 1387504096 8192864 1638192
22:25:43 53432 8192000000 10269848608 10732802048 1344477072 14.098 97 21 42 0 1356382976 7315056 4590848
22:27:38 66534 8192000000 10286567712 10752184320 1373889376 14.362 99 13 25 0 1384282144 7453968 2938800
22:29:44 92993 8192000000 10294298864 10749313024 1360806176 14.245 98 27 54 0 1385344256 18065968 6472112
22:32:18 55693 8192000000 10269970304 10745548800 1346305872 14.114 98 12 24 0 1366285312 17031776 2947664
22:34:16 56688 8192000000 10278191888 10727030784 1340873264 14.065 97 15 30 0 1355826496 11530832 3422400
22:36:13 60590 8192000000 10241589536 10683707392 1236858592 13.117 92 15 30 1 1247715184 7315648 3540944
22:38:17 30360 8192000000 9075352480 9295843328 238500512 2.829 19 8 16 0 243091072 2907552 1683008

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 03-01-2023 22:39:52 to 03-01-2023 22:40:07.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 121 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

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

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          247811
MON$OLDEST_ACTIVE               247812
MON$OLDEST_SNAPSHOT             247812
MON$NEXT_TRANSACTION            247812
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                       2034464
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.33650 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33650 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33650 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33650 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 ---
:1622: 
:1623: mscs-test-161.budzdorov.ru    Tue Jan  3 22:38:49 2023
:1624:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1625: 
--- end of diff output ---

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

03.01.23 22:40:11. 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-03 22:40:14 No severe PSQL-related problems occured
03.01.23 22:40:15. End of report.