Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.02.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 15031316 kB
MemAvailable 30427712 kB
Buffers 0 kB
Cached 16689112 kB
SwapCached 3348 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-14 16:42:27
DTS_END                         2023-02-14 16:42:27.4460
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/39970
TRN_ID                          249815
ATT_ID                          708
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: 9938.25 19045 1192590
customer order: creation 183.71 370 22045
customer order: refuse 15.36 358 1843
order to supplier: creation 48.55 1241 5826
order to supplier: removal 3.76 92759 451
invoice (draft): creation 50.16 3072 6019
invoice (draft): removal 57.18 766 6861
invoice accept: apply 44.14 16209 5297
invoice accept: cancel 51.50 714 6180
customer reserve: creation 4072.18 161 488662
customer reserve: removal 5094.77 53 611372
realization accept: apply 90.39 175 10847
realization accept: cancel 87.99 98 10559
payment from customer: creation 63.55 121 7626
payment from customer: removal 6.03 83 724
payment to supplier: creation 52.60 126 6312
payment to supplier: removal 3.80 59 456
service: total inventory turnovers 4.57 1298 548
service: total monetary turnovers 7.11 51 853
service: aggregate perf. data 0.88 21588 105
service: refresh index statistics 0.03 241592 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-02-2023 16:44:10 to 14-02-2023 16:44:12.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3272
TEST_TIME 2 7784
TEST_TIME 3 6084
TEST_TIME 4 6106
TEST_TIME 5 5725
TEST_TIME 6 5827
TEST_TIME 7 6149
TEST_TIME 8 5969
TEST_TIME 9 6337
TEST_TIME 10 6068
TEST_TIME 11 6056
TEST_TIME 12 6136
TEST_TIME 13 6271
TEST_TIME 14 7162
TEST_TIME 15 7502
TEST_TIME 16 7965
TEST_TIME 17 8334
TEST_TIME 18 8587
TEST_TIME 19 8421
TEST_TIME 20 8474
TEST_TIME 21 8590
TEST_TIME 22 8481
TEST_TIME 23 8710
TEST_TIME 24 8961
TEST_TIME 25 9240
TEST_TIME 26 9359
TEST_TIME 27 9860
TEST_TIME 28 9916
TEST_TIME 29 10212
TEST_TIME 30 11180
TEST_TIME 31 11215
TEST_TIME 32 11054
TEST_TIME 33 11098
TEST_TIME 34 11084
TEST_TIME 35 11162
TEST_TIME 36 11159
TEST_TIME 37 10951
TEST_TIME 38 10797
TEST_TIME 39 10734
TEST_TIME 40 10733
TEST_TIME 41 10687
TEST_TIME 42 10673
TEST_TIME 43 10530
TEST_TIME 44 10360
TEST_TIME 45 10431
TEST_TIME 46 10424
TEST_TIME 47 10499
TEST_TIME 48 10477
TEST_TIME 49 10362
TEST_TIME 50 10318
TEST_TIME 51 10233
TEST_TIME 52 10160
TEST_TIME 53 10146
TEST_TIME 54 10092
TEST_TIME 55 10007
TEST_TIME 56 9995
TEST_TIME 57 10169
TEST_TIME 58 10159
TEST_TIME 59 10124
TEST_TIME 60 10268
TEST_TIME 61 10283
TEST_TIME 62 10289
TEST_TIME 63 10261
TEST_TIME 64 10326
TEST_TIME 65 10270
TEST_TIME 66 10373
TEST_TIME 67 10412
TEST_TIME 68 10379
TEST_TIME 69 10378
TEST_TIME 70 10374
TEST_TIME 71 10386
TEST_TIME 72 10304
TEST_TIME 73 10218
TEST_TIME 74 10175
TEST_TIME 75 10176
TEST_TIME 76 10122
TEST_TIME 77 10100
TEST_TIME 78 10064
TEST_TIME 79 10007
TEST_TIME 80 9973
TEST_TIME 81 9879
TEST_TIME 82 9884
TEST_TIME 83 9913
TEST_TIME 84 9955
TEST_TIME 85 9895
TEST_TIME 86 9931
TEST_TIME 87 9926
TEST_TIME 88 10017
TEST_TIME 89 9959
TEST_TIME 90 10030
TEST_TIME 91 10077
TEST_TIME 92 10073
TEST_TIME 93 10045
TEST_TIME 94 10058
TEST_TIME 95 10119
TEST_TIME 96 10150
TEST_TIME 97 10170
TEST_TIME 98 10193
TEST_TIME 99 10142
TEST_TIME 100 10081
TEST_TIME 101 10028
TEST_TIME 102 9980
TEST_TIME 103 9974
TEST_TIME 104 9983
TEST_TIME 105 9933
TEST_TIME 106 9879
TEST_TIME 107 9854
TEST_TIME 108 9897
TEST_TIME 109 9887
TEST_TIME 110 9877
TEST_TIME 111 9838
TEST_TIME 112 9845
TEST_TIME 113 9908
TEST_TIME 114 9977
TEST_TIME 115 9983
TEST_TIME 116 9991
TEST_TIME 117 10006
TEST_TIME 118 10007
TEST_TIME 119 10011
TEST_TIME 120 9942

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 14-02-2023 16:44:14 to 14-02-2023 16:44:17.

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 1646360 1646360 0 0.00 0 34521 5 0 0
doc_list_biud 1646355 1646355 0 0.00 0 46911 25 0 0
sp_add_invoice_to_stock 5399 5297 102 1.88 36 235401 16209 0 102
sp_cancel_adding_invoice 6180 6180 0 0.00 4 59830 715 0 0
sp_cancel_client_order 1843 1843 0 0.00 4 15134 358 0 0
sp_cancel_customer_reserve 611372 611372 0 0.00 0 20254 54 0 0
sp_cancel_pay_from_customer 724 724 0 0.00 3 4776 83 0 0
sp_cancel_pay_to_supplier 456 456 0 0.00 3 1945 59 0 0
sp_cancel_supplier_invoice 6870 6861 9 0.13 1 46919 767 0 9
sp_cancel_supplier_order 451 451 0 0.00 7 1201192 92759 0 0
sp_cancel_write_off 10559 10559 0 0.00 1 15795 98 0 0
sp_client_order 22045 22045 0 0.00 3 17625 370 0 0
sp_customer_reserve 488662 488662 0 0.00 0 67689 162 0 0
sp_fill_shopping_cart 33885 33885 0 0.00 1 27400 36 0 0
sp_fill_shopping_cart_clo_res 488656 488656 0 0.00 0 16042 12 0 0
sp_get_clo_for_invoice 5291 5291 0 0.00 0 19311 224 0 0
sp_kill_cost_storno 17919 17919 0 0.00 0 7223 49 0 0
sp_kill_qstorno_ret_qs2qd 1240615 1240615 0 0.00 0 46907 29 0 0
sp_kill_qty_storno 653403 653403 0 0.00 0 46910 64 0 0
sp_lock_selected_doc 22262 22262 0 0.00 0 7900 5 0 0
sp_make_cost_storno 30076 30076 0 0.00 0 19916 51 0 0
sp_multiply_rows_for_pdistr 30075 30075 0 0.00 0 6424 28 0 0
sp_multiply_rows_for_qdistr 22045 22045 0 0.00 0 15368 163 0 0
sp_pay_from_customer 7626 7626 0 0.00 4 21866 121 0 0
sp_pay_to_supplier 6312 6312 0 0.00 4 14665 127 0 0
sp_payment_common 13938 13938 0 0.00 1 21859 32 0 0
sp_qd_handle_on_cancel_clo 1843 1843 0 0.00 0 15123 331 0 0
sp_qd_handle_on_invoice_upd_sts 11470 11470 0 0.00 0 13650 310 0 0
sp_qd_handle_on_reserve_upd_sts 10847 10847 0 0.00 0 18206 66 0 0
sp_reserve_write_off 10847 10847 0 0.00 6 18307 175 0 0
sp_supplier_invoice 6019 6019 0 0.00 6 109749 3072 0 0
sp_supplier_order 5826 5826 0 0.00 5 77311 1242 0 0
srv_aggregate_perf_data 105 105 0 0.00 2539 374451 21589 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1558 98866 41803 0 0
srv_find_qd_qs_mism 1142326 1142326 0 0.00 0 34521 4 0 0
srv_make_invnt_saldo 548 548 0 0.00 21 114202 1298 0 0
srv_make_money_saldo 853 853 0 0.00 0 19339 52 0 0
srv_recalc_idx_stat 4 4 0 0.00 138770 413126 241593 0 0
t$perf-abend:sp_add_invoice_to_stock 102 102 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 5297 5297 0 0.00 0 16889 172 0 0
t$perf-norm:sp_cancel_adding_invoice 422 422 0 0.00 0 2537 74 0 0
t$perf-norm:sp_cancel_client_order 1843 1843 0 0.00 0 2975 18 0 0
t$perf-norm:sp_cancel_customer_reserve 738 738 0 0.00 0 19690 35 0 0
t$perf-norm:sp_cancel_pay_from_customer 724 724 0 0.00 0 899 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 456 456 0 0.00 0 12258 37 0 0
t$perf-norm:sp_cancel_supplier_invoice 424 424 0 0.00 0 7507 32 0 0
t$perf-norm:sp_cancel_supplier_order 451 451 0 0.00 0 26186 1607 0 0
t$perf-norm:sp_cancel_write_off 1067 1067 0 0.00 0 1037 6 0 0
t$perf-norm:sp_client_order 22045 22045 0 0.00 0 12363 24 0 0
t$perf-norm:sp_customer_reserve 1090 1090 0 0.00 0 19700 24 0 0
t$perf-norm:sp_pay_from_customer 7626 7626 0 0.00 0 12367 12 0 0
t$perf-norm:sp_pay_to_supplier 6312 6312 0 0.00 0 13768 16 0 0
t$perf-norm:sp_reserve_write_off 10847 10847 0 0.00 0 13755 20 0 0
t$perf-norm:sp_supplier_invoice 6019 6019 0 0.00 0 9822 21 0 0
t$perf-norm:sp_supplier_order 5826 5826 0 0.00 0 8147 19 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 147 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 395 13 0 0
t$perf-norm:srv_make_invnt_saldo 548 548 0 0.00 0 5821 27 0 0
t$perf-norm:srv_make_money_saldo 853 853 0 0.00 0 3600 21 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 8 3 0 0
v_add_invoice_to_stock 10784 10580 204 1.89 0 6894 18 0 204
v_all_customers 39784 39784 0 0.00 0 21 1 0 0
v_all_suppliers 32968 32968 0 0.00 0 22 1 0 0
v_all_wares 44090 44090 0 0.00 0 40 1 0 0
v_cancel_adding_invoice 844 844 0 0.00 0 131 6 0 0
v_cancel_client_order 3686 3686 0 0.00 0 3340 4 0 0
v_cancel_customer_prepayment 1448 1448 0 0.00 0 4770 10 0 0
v_cancel_customer_reserve 1476 1476 0 0.00 0 3300 8 0 0
v_cancel_payment_to_supplier 912 912 0 0.00 0 1116 6 0 0
v_cancel_supplier_invoice 866 848 18 2.07 0 5295 20 0 18
v_cancel_supplier_order 902 902 0 0.00 0 397 3 0 0
v_cancel_write_off 2134 2134 0 0.00 0 995 12 0 0
v_max_id_clo_ord 5826 5826 0 0.00 0 1162 2 0 0
v_max_id_clo_res 1090 1090 0 0.00 0 4182 13 0 0
v_max_id_ord_sup 6014 6014 0 0.00 0 2958 3 0 0
v_max_non_paid_invoice 1668 1668 0 0.00 0 5662 9 0 0
v_max_non_paid_realizn 4175 4175 0 0.00 0 5662 6 0 0
v_min_id_clo_ord 5826 5826 0 0.00 0 2885 6 0 0
v_min_id_clo_res 1090 1090 0 0.00 0 2218 6 0 0
v_min_id_ord_sup 6014 6014 0 0.00 0 12656 10 0 0
v_min_non_paid_invoice 6312 6312 0 0.00 0 14618 21 0 0
v_min_non_paid_realizn 7626 7626 0 0.00 0 21848 24 0 0
v_our_firm 11208 11208 0 0.00 0 22 1 0 0
v_reserve_write_off 21694 21694 0 0.00 0 6905 6 0 0
x_lock_depdocs_on_canc_invoice 12617 12617 0 0.00 0 15459 101 0 0
x_lock_depdocs_on_canc_sup_ord 451 451 0 0.00 0 10629 306 0 0
x_make_qstorno_1000_1200 5826 5826 0 0.00 1 76173 1163 0 0
x_make_qstorno_1200_2000 6019 6019 0 0.00 2 109737 2946 0 0
x_make_qstorno_2100_3300 488662 488662 0 0.00 0 67687 147 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-02-2023 16:44:18 to 14-02-2023 16:44:22.

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:31 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:13:32 25045 8192000000 9896412672 10204528640 981124352 10.695 100 88 176 1 1010071264 5643296 23303616
14:14:59 13795 8192000000 9945221664 10267389952 1027441792 11.144 100 88 174 0 1057522672 7828704 22252176
14:16:22 16816 8192000000 9983002128 10311901184 1063500944 11.490 100 84 168 0 1093783936 9575936 20707056
14:17:49 6596 8192000000 10024652336 10368425984 1108868192 11.922 100 83 165 0 1139468528 11200480 19399856
14:18:59 7080 8192000000 10056289184 10410737664 1144047440 12.254 100 74 146 0 1172881808 12121008 16713360
14:20:11 4858 8192000000 10107677616 10471075840 1193588384 12.717 100 84 167 0 1225566080 12739872 19237824
14:21:18 1037 8192000000 10147471136 10519113728 1233143616 13.083 100 84 166 0 1264108624 12008480 18956528
14:22:20 1819 8192000000 10173507520 10549571584 1256930464 13.302 100 89 176 2 1290124848 14128688 19065696
14:23:24 1517 8192000000 10194877904 10584911872 1284395232 13.553 100 82 160 0 1314394848 12083024 17916592
14:24:26 2286 8192000000 10194819088 10587992064 1292943824 13.631 100 70 140 2 1318107712 8640592 16523296
14:25:29 7117 8192000000 10184090640 10596397056 1299233584 13.688 100 21 42 0 1310259488 6477856 4548048
14:26:37 6590 8192000000 10198937200 10613194752 1312928448 13.813 100 23 44 1 1324192080 6362016 4901616
14:27:44 14873 8192000000 10202417776 10621476864 1319883936 13.876 100 16 32 0 1329484032 6144064 3456032
14:29:05 66446 8192000000 10218922512 10643980288 1336821536 14.029 100 21 43 2 1346309472 4641568 4846368
14:31:12 5665 8192000000 10248360128 10666139648 1344393296 14.097 100 41 82 0 1361525632 8492544 8639792
14:32:19 4603 8192000000 10220756768 10640326656 1318941808 13.867 99 18 36 0 1327584656 5044560 3598288
14:33:25 8745 8192000000 10183971520 10590806016 1277112448 13.487 98 16 32 1 1286086528 5177584 3796496
14:34:34 16763 8192000000 10169670736 10582413312 1244788080 13.190 96 27 54 0 1256687072 5990048 5908944
14:35:52 7008 8192000000 10124856816 10525024256 1200332512 12.779 96 24 48 1 1211311024 5437312 5541200
14:37:01 8093 8192000000 10167467888 10576429056 1265062992 13.376 99 32 64 1 1282443664 10039808 7340864
14:38:11 22966 8192000000 10169367120 10581585920 1281336128 13.525 100 30 60 1 1293795888 6296496 6163264
14:39:40 5682 8192000000 10178700096 10591928320 1287950528 13.586 100 33 66 0 1303622544 8589200 7082816
14:40:46 5994 8192000000 10209890800 10615287808 1307281168 13.761 100 34 67 0 1321286592 5448672 8556752
14:41:57 10648 8192000000 10209720656 10623217664 1319044976 13.868 100 17 34 0 1328386352 5622624 3718752
14:43:12 63263 8192000000 10261488512 10671980544 1346558208 14.116 100 59 117 0 1370133488 10446736 13128544
14:45:20 8546 8192000000 10271380304 10691977216 1369517856 14.323 100 43 86 0 1388268224 8987168 9763200
14:46:50 4720 8192000000 10294362240 10717540352 1388264864 14.490 100 47 93 1 1409963584 10346592 11352128
14:48:00 29710 8192000000 10308944432 10736054272 1396661344 14.565 100 40 80 0 1418473680 12581696 9230640
14:49:41 3132 8192000000 10336645008 10757468160 1412598240 14.707 100 52 97 0 1441011456 16161664 12251552
14:50:48 44787 8192000000 10317435744 10758127616 1399946800 14.595 99 32 63 0 1424059744 16472224 7640720
14:52:36 8805 8192000000 10333342048 10767798272 1417882864 14.754 100 37 73 0 1448626896 22081040 8662992
14:53:49 3137 8192000000 10352951472 10778980352 1422760560 14.797 100 60 119 0 1461100768 24082848 14257360
14:54:56 5754 8192000000 10333918112 10758475776 1391443824 14.519 99 56 112 1 1433769552 28517072 13808656
14:56:08 4474 8192000000 10338481792 10762620928 1391036464 14.515 99 55 109 0 1438366704 33388848 13941392
14:57:20 3861 8192000000 10351426080 10767372288 1403832096 14.629 100 77 151 0 1454815008 33286400 17696512
14:58:31 4210 8192000000 10315001552 10740248576 1366460192 14.295 99 58 113 1 1411412992 31461088 13491712
14:59:40 2068 8192000000 10332342176 10742587392 1378496528 14.403 100 78 155 0 1433050592 36326480 18227584
15:00:54 4127 8192000000 10278622096 10698272768 1340653440 14.063 100 43 84 0 1388188112 35701648 11833024
15:02:03 4642 8192000000 10315988864 10723090432 1359748336 14.235 100 74 143 2 1425479760 47894688 17836736
15:03:15 1558 8192000000 10344216496 10743558144 1370331904 14.330 100 87 170 2 1440376976 49542768 20502304
15:04:20 3398 8192000000 10350474192 10764414976 1378584656 14.404 100 64 127 1 1452450848 57547856 16318336
15:05:31 10991 8192000000 10338505424 10760536064 1379595040 14.413 100 42 85 1 1443349312 51746144 12008128
15:06:45 10393 8192000000 10334384192 10756874240 1360262224 14.240 99 39 78 0 1425825920 54877776 10685920
15:07:58 11113 8192000000 10335351888 10772557824 1379680480 14.414 100 34 68 0 1444784704 55263152 9841072
15:09:11 26695 8192000000 10327621584 10770087936 1376340880 14.384 100 25 50 0 1439880624 56369216 7170528
15:10:39 19770 8192000000 10322826592 10762588160 1367623952 14.306 100 18 36 0 1425128688 52028048 5476688
15:12:00 69914 8192000000 10284533520 10727911424 1340191984 14.059 98 16 34 1 1377001472 32917200 3892288
15:14:20 44027 8192000000 10281611616 10717921280 1347740224 14.127 98 22 45 0 1361110880 8702672 4667984
15:16:06 74854 8192000000 10289380608 10741288960 1355796448 14.200 98 9 18 0 1369900512 12483968 1620096
15:18:27 49832 8192000000 10295501920 10750070784 1365016384 14.282 98 16 33 0 1378114608 9821504 3276720
15:20:22 97357 8192000000 10318699392 10765840384 1370100624 14.328 98 15 30 1 1388631184 15300544 3230016
15:23:00 89025 8192000000 10308796848 10766118912 1397566960 14.573 100 39 78 0 1416771184 9756816 9447408
15:25:34 48816 8192000000 10326635664 10779533312 1408537552 14.671 100 39 76 0 1432089872 14845216 8707104
15:27:32 22000 8192000000 10313009888 10765582336 1387094416 14.480 99 26 51 2 1404975856 11979248 5902192
15:28:58 53376 8192000000 10316577936 10780176384 1400763136 14.602 100 36 72 1 1424098736 14658928 8676672
15:31:02 39462 8192000000 10315250576 10782298112 1417340368 14.749 100 14 26 0 1431077504 10756704 2980432
15:32:42 28029 8192000000 10316491728 10785026048 1411033280 14.693 100 11 19 0 1426379696 13321632 2024784
15:34:11 61802 8192000000 10307833296 10780569600 1391662112 14.521 98 13 25 1 1406140368 11468032 3010224
15:36:32 50771 8192000000 10294591360 10766118912 1399435648 14.590 100 26 52 0 1417605744 11885744 6284352
15:39:13 77800 8192000000 10245037760 10703032320 1243862128 13.182 89 14 28 0 1260301552 13233600 3205824
15:41:36 36356 8192000000 10274324608 10724601856 1340762512 14.064 97 30 59 0 1358505280 11191072 6551696
15:43:20 57782 8192000000 10259825824 10724597760 1298709808 13.684 94 13 26 0 1310820064 9231408 2878848
15:45:20 63827 8192000000 10256965296 10706911232 1289979984 13.604 95 17 34 0 1306212896 11840944 4391968
15:47:28 39850 8192000000 10238445744 10684571648 1287758672 13.584 96 30 60 0 1307168096 12922272 6487152
15:49:09 52036 8192000000 10252994512 10708992000 1342066592 14.076 99 18 36 0 1359198000 12773936 4357472
15:51:01 23806 8192000000 10281373984 10719965184 1357564160 14.215 100 30 61 0 1376668192 12770512 6333520
15:52:31 98865 8192000000 10279192320 10740883456 1346234272 14.114 98 22 44 0 1365031440 13550880 5246288
15:55:10 48908 8192000000 10269229664 10714939392 1344376240 14.097 98 27 53 0 1357241536 7031968 5833328
15:56:59 54216 8192000000 10260659136 10716327936 1339073104 14.049 98 27 54 0 1356162784 10356096 6733584
15:59:05 40773 8192000000 10270881904 10723917824 1358332800 14.222 99 29 56 2 1371859648 7362816 6164032
16:00:46 47005 8192000000 10280604736 10735652864 1366470848 14.295 99 15 28 0 1380526976 11043136 3012992
16:02:37 58370 8192000000 10295482816 10755801088 1372222992 14.347 99 17 34 1 1403518640 27422848 3872800
16:04:45 51216 8192000000 10300756640 10771001344 1381369168 14.429 100 7 14 0 1405126240 22224096 1532976
16:08:11 76925 8192000000 10275244160 10738180096 1351372688 14.160 97 12 24 0 1365606896 11483632 2750576
16:12:16 66970 8192000000 10303428160 10768470016 1394046960 14.542 100 10 21 0 1415625888 19496864 2082064
16:14:27 55755 8192000000 10305959456 10757566464 1377825328 14.397 98 18 36 0 1396200768 14416960 3958480
16:16:28 52938 8192000000 10333806928 10796998656 1409818032 14.682 100 32 63 0 1433702064 17345248 6538784
16:18:28 66833 8192000000 10311068704 10757386240 1353584608 14.180 96 8 14 0 1368868432 13690800 1593024
16:20:38 57483 8192000000 10311011184 10751680512 1371834144 14.343 98 17 34 0 1384519984 8914736 3771104
16:22:38 92652 8192000000 10291672352 10758885376 1383232448 14.445 99 18 36 0 1395863024 8584400 4046176
16:25:19 45853 8192000000 10300264176 10765357056 1376267888 14.383 98 18 36 1 1391333632 10933216 4132528
16:27:14 68877 8192000000 10284309360 10746277888 1347061424 14.121 97 14 28 0 1364861792 14571488 3228880
16:29:34 87010 8192000000 10276428560 10746118144 1336379184 14.025 96 11 22 0 1351562752 12750240 2433328
16:32:05 31987 8192000000 10266504000 10714484736 1329109072 13.959 97 23 44 0 1345023312 10653696 5260544
16:33:42 53824 8192000000 10248984672 10695077888 1274243312 13.460 93 19 38 0 1291720064 13032720 4444032
16:35:41 58549 8192000000 10257941184 10717720576 1320015328 13.877 98 14 28 0 1341526032 18547376 2963328
16:37:43 23688 8192000000 10271585104 10722222080 1355083312 14.193 100 21 42 1 1368177536 8738144 4356080
16:39:07 38306 8192000000 10282052304 10734936064 1368931648 14.317 100 32 64 0 1387643664 11710320 7001696
16:40:45 80183 8192000000 10260800608 10708606976 1287159008 13.578 94 21 42 0 1301443104 9561776 4722320

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 14-02-2023 16:44:24 to 14-02-2023 16:44:39.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 102 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 14-02-2023 16:44:40 to 14-02-2023 16:44:42.

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          247770
MON$OLDEST_ACTIVE               250114
MON$OLDEST_SNAPSHOT             250114
MON$NEXT_TRANSACTION            250114
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                       2062944
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.33664 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33664 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33664 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33664 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    Tue Feb 14 16:42:33 2023
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Tue Feb 14 16:42:43 2023
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Tue Feb 14 16:42:43 2023
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:17: 
:18: 
:19: mscs-test-161.budzdorov.ru    Tue Feb 14 16:42:43 2023
:20:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:21: 
--- end of diff output ---

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

14.02.23 16:44:43. 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-14 16:44:46 No severe PSQL-related problems occured
14.02.23 16:44:47. End of report.