Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 29.07.2022 07: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 15451332 kB
MemAvailable 31051256 kB
Buffers 0 kB
Cached 16279452 kB
SwapCached 2512 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 4.0.2
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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 2022-07-29 07:37:19
DTS_END                         2022-07-29 07:37:19.1150
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(68:17)
IP                              ::1/42464
TRN_ID                          226610
ATT_ID                          652
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: 9354.92 31799 1122591
customer order: creation 172.99 436 20759
customer order: refuse 15.14 400 1817
order to supplier: creation 45.27 1295 5432
order to supplier: removal 3.38 159523 406
invoice (draft): creation 47.28 2968 5673
invoice (draft): removal 50.73 922 6088
invoice accept: apply 44.12 26565 5294
invoice accept: cancel 46.09 1190 5531
customer reserve: creation 4087.33 255 490479
customer reserve: removal 4553.20 93 546384
realization accept: apply 84.15 286 10098
realization accept: cancel 72.78 169 8733
payment from customer: creation 59.94 180 7193
payment from customer: removal 5.89 132 707
payment to supplier: creation 50.08 202 6009
payment to supplier: removal 3.47 122 416
service: total inventory turnovers 3.92 4270 470
service: total monetary turnovers 8.16 354 979
service: aggregate perf. data 0.99 17316 119
service: refresh index statistics 0.03 419305 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 29-07-2022 07:39:49 to 29-07-2022 07:39:51.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5796
TEST_TIME 2 7685
TEST_TIME 3 6474
TEST_TIME 4 6661
TEST_TIME 5 6787
TEST_TIME 6 7088
TEST_TIME 7 7072
TEST_TIME 8 6990
TEST_TIME 9 7038
TEST_TIME 10 6965
TEST_TIME 11 6872
TEST_TIME 12 7348
TEST_TIME 13 7368
TEST_TIME 14 7575
TEST_TIME 15 7602
TEST_TIME 16 7757
TEST_TIME 17 7906
TEST_TIME 18 8137
TEST_TIME 19 8149
TEST_TIME 20 8343
TEST_TIME 21 8531
TEST_TIME 22 8605
TEST_TIME 23 8640
TEST_TIME 24 9074
TEST_TIME 25 9154
TEST_TIME 26 9165
TEST_TIME 27 9088
TEST_TIME 28 9412
TEST_TIME 29 9395
TEST_TIME 30 9786
TEST_TIME 31 9897
TEST_TIME 32 10156
TEST_TIME 33 10730
TEST_TIME 34 11327
TEST_TIME 35 11598
TEST_TIME 36 11812
TEST_TIME 37 11689
TEST_TIME 38 11522
TEST_TIME 39 11543
TEST_TIME 40 11349
TEST_TIME 41 11507
TEST_TIME 42 11375
TEST_TIME 43 11224
TEST_TIME 44 10990
TEST_TIME 45 10961
TEST_TIME 46 10815
TEST_TIME 47 10705
TEST_TIME 48 10640
TEST_TIME 49 10567
TEST_TIME 50 10507
TEST_TIME 51 10599
TEST_TIME 52 10552
TEST_TIME 53 10492
TEST_TIME 54 10419
TEST_TIME 55 10327
TEST_TIME 56 10307
TEST_TIME 57 10277
TEST_TIME 58 10219
TEST_TIME 59 10177
TEST_TIME 60 10201
TEST_TIME 61 10226
TEST_TIME 62 10187
TEST_TIME 63 10240
TEST_TIME 64 10271
TEST_TIME 65 10243
TEST_TIME 66 10240
TEST_TIME 67 10314
TEST_TIME 68 10245
TEST_TIME 69 10242
TEST_TIME 70 10313
TEST_TIME 71 10173
TEST_TIME 72 10108
TEST_TIME 73 10013
TEST_TIME 74 10036
TEST_TIME 75 9949
TEST_TIME 76 9928
TEST_TIME 77 9857
TEST_TIME 78 9822
TEST_TIME 79 9820
TEST_TIME 80 9791
TEST_TIME 81 9819
TEST_TIME 82 9774
TEST_TIME 83 9758
TEST_TIME 84 9789
TEST_TIME 85 9761
TEST_TIME 86 9793
TEST_TIME 87 9781
TEST_TIME 88 9733
TEST_TIME 89 9775
TEST_TIME 90 9864
TEST_TIME 91 9837
TEST_TIME 92 9870
TEST_TIME 93 9837
TEST_TIME 94 9767
TEST_TIME 95 9723
TEST_TIME 96 9666
TEST_TIME 97 9688
TEST_TIME 98 9632
TEST_TIME 99 9592
TEST_TIME 100 9541
TEST_TIME 101 9507
TEST_TIME 102 9450
TEST_TIME 103 9404
TEST_TIME 104 9438
TEST_TIME 105 9531
TEST_TIME 106 9491
TEST_TIME 107 9484
TEST_TIME 108 9487
TEST_TIME 109 9477
TEST_TIME 110 9549
TEST_TIME 111 9562
TEST_TIME 112 9563
TEST_TIME 113 9548
TEST_TIME 114 9578
TEST_TIME 115 9585
TEST_TIME 116 9524
TEST_TIME 117 9488
TEST_TIME 118 9434
TEST_TIME 119 9431
TEST_TIME 120 9372

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 29-07-2022 07:39:53 to 29-07-2022 07:39:56.

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 1572728 1572728 0 0.00 0 28766 14 0 0
doc_list_biud 1572718 1572718 0 0.00 0 26373 38 0 0
sp_add_invoice_to_stock 5438 5294 144 2.64 56 234267 26565 0 144
sp_cancel_adding_invoice 5531 5531 0 0.00 6 70948 1190 0 0
sp_cancel_client_order 1817 1817 0 0.00 5 13257 401 0 0
sp_cancel_customer_reserve 546384 546384 0 0.00 1 20207 93 0 0
sp_cancel_pay_from_customer 707 707 0 0.00 3 5525 132 0 0
sp_cancel_pay_to_supplier 416 416 0 0.00 4 8705 122 0 0
sp_cancel_supplier_invoice 6098 6088 10 0.16 2 26385 923 0 10
sp_cancel_supplier_order 406 406 0 0.00 6 1704785 159524 0 0
sp_cancel_write_off 8733 8733 0 0.00 2 11208 169 0 0
sp_client_order 20759 20759 0 0.00 6 26904 436 0 0
sp_customer_reserve 490479 490479 0 0.00 0 71543 255 0 0
sp_fill_shopping_cart 31848 31848 0 0.00 1 36615 83 0 0
sp_fill_shopping_cart_clo_res 490463 490463 0 0.00 0 19807 17 0 0
sp_get_clo_for_invoice 5271 5271 0 0.00 0 38706 288 0 0
sp_kill_cost_storno 15387 15387 0 0.00 0 27175 72 0 0
sp_kill_qstorno_ret_qs2qd 1107995 1107995 0 0.00 0 26370 40 0 0
sp_kill_qty_storno 584325 584325 0 0.00 0 26372 93 0 0
sp_lock_selected_doc 21218 21218 0 0.00 0 7128 7 0 0
sp_make_cost_storno 28570 28570 0 0.00 0 28758 80 0 0
sp_multiply_rows_for_pdistr 28568 28568 0 0.00 0 7890 37 0 0
sp_multiply_rows_for_qdistr 20759 20759 0 0.00 0 10221 162 0 0
sp_pay_from_customer 7193 7193 0 0.00 5 13550 180 0 0
sp_pay_to_supplier 6009 6009 0 0.00 6 17636 202 0 0
sp_payment_common 13201 13201 0 0.00 2 10795 48 0 0
sp_qd_handle_on_cancel_clo 1817 1817 0 0.00 0 13249 320 0 0
sp_qd_handle_on_invoice_upd_sts 10800 10800 0 0.00 0 19008 515 0 0
sp_qd_handle_on_reserve_upd_sts 10097 10097 0 0.00 0 26289 76 0 0
sp_reserve_write_off 10098 10098 0 0.00 7 26769 287 0 0
sp_supplier_invoice 5673 5673 0 0.00 10 88907 2968 0 0
sp_supplier_order 5432 5432 0 0.00 8 37447 1296 0 0
srv_aggregate_perf_data 119 119 0 0.00 2293 415040 17316 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 692 138935 35426 0 0
srv_find_qd_qs_mism 1071372 1071372 0 0.00 0 20364 11 0 0
srv_make_invnt_saldo 470 470 0 0.00 62 51294 4270 0 0
srv_make_money_saldo 979 979 0 0.00 1 11966 355 0 0
srv_recalc_idx_stat 4 4 0 0.00 376088 443898 419305 0 0
t$perf-abend:sp_add_invoice_to_stock 144 144 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5299 5299 0 0.00 1 10636 962 0 0
t$perf-norm:sp_cancel_adding_invoice 396 396 0 0.00 0 6068 479 0 0
t$perf-norm:sp_cancel_client_order 1817 1817 0 0.00 0 1526 25 0 0
t$perf-norm:sp_cancel_customer_reserve 658 658 0 0.00 0 1473 30 0 0
t$perf-norm:sp_cancel_pay_from_customer 707 707 0 0.00 0 565 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 416 416 0 0.00 0 10412 41 0 0
t$perf-norm:sp_cancel_supplier_invoice 395 395 0 0.00 0 2770 39 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 112253 6757 0 0
t$perf-norm:sp_cancel_write_off 1061 1061 0 0.00 0 1829 18 0 0
t$perf-norm:sp_client_order 20759 20759 0 0.00 0 7708 32 0 0
t$perf-norm:sp_customer_reserve 1034 1034 0 0.00 0 2592 14 0 0
t$perf-norm:sp_pay_from_customer 7193 7193 0 0.00 0 2724 18 0 0
t$perf-norm:sp_pay_to_supplier 6009 6009 0 0.00 0 10185 23 0 0
t$perf-norm:sp_reserve_write_off 10100 10100 0 0.00 0 10630 25 0 0
t$perf-norm:sp_supplier_invoice 5673 5673 0 0.00 0 4311 31 0 0
t$perf-norm:sp_supplier_order 5432 5432 0 0.00 0 4064 32 0 0
t$perf-norm:srv_aggregate_perf_data 119 119 0 0.00 0 654 28 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 939 25 0 0
t$perf-norm:srv_make_invnt_saldo 470 470 0 0.00 0 943 12 0 0
t$perf-norm:srv_make_money_saldo 979 979 0 0.00 0 2915 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 213 57 0 0
v_add_invoice_to_stock 10818 10530 288 2.66 0 13062 28 0 288
v_all_customers 38007 38007 0 0.00 0 91 4 0 0
v_all_suppliers 30758 30758 0 0.00 0 84 4 0 0
v_all_wares 41506 41506 0 0.00 0 66 4 0 0
v_cancel_adding_invoice 792 792 0 0.00 0 2763 23 0 0
v_cancel_client_order 3634 3634 0 0.00 0 6412 15 0 0
v_cancel_customer_prepayment 1414 1414 0 0.00 0 5462 15 0 0
v_cancel_customer_reserve 1316 1316 0 0.00 0 1347 13 0 0
v_cancel_payment_to_supplier 832 832 0 0.00 0 532 11 0 0
v_cancel_supplier_invoice 810 790 20 2.46 0 2317 26 0 20
v_cancel_supplier_order 812 812 0 0.00 0 422 10 0 0
v_cancel_write_off 2122 2122 0 0.00 0 2377 26 0 0
v_max_id_clo_ord 5430 5430 0 0.00 0 1126 5 0 0
v_max_id_clo_res 1034 1034 0 0.00 0 3940 17 0 0
v_max_id_ord_sup 5661 5661 0 0.00 0 7870 8 0 0
v_max_non_paid_invoice 1720 1720 0 0.00 0 457 8 0 0
v_max_non_paid_realizn 3687 3687 0 0.00 0 6131 9 0 0
v_min_id_clo_ord 5430 5430 0 0.00 0 5547 9 0 0
v_min_id_clo_res 1034 1034 0 0.00 0 1433 9 0 0
v_min_id_ord_sup 5661 5661 0 0.00 0 6000 10 0 0
v_min_non_paid_invoice 6008 6008 0 0.00 0 7844 18 0 0
v_min_non_paid_realizn 7193 7193 0 0.00 0 10789 20 0 0
v_our_firm 10510 10510 0 0.00 0 129 5 0 0
v_reserve_write_off 20192 20192 0 0.00 0 5294 11 0 0
x_lock_depdocs_on_canc_invoice 11224 11224 0 0.00 0 15258 127 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 5174 335 0 0
x_make_qstorno_1000_1200 5432 5432 0 0.00 3 28231 1124 0 0
x_make_qstorno_1200_2000 5673 5673 0 0.00 3 88879 2751 0 0
x_make_qstorno_2100_3300 490479 490479 0 0.00 0 71536 226 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-07-2022 07:39:57 to 29-07-2022 07:40:01.

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
05:07:22 2 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:23 25317 8192000000 9711775488 10006642688 792129584 8.816 100 93 186 2 823776176 7839312 23807280
05:09:51 23580 8192000000 9758885168 10063691776 833057616 9.230 100 89 178 0 870863936 16777920 21028400
05:11:18 14189 8192000000 9781315536 10099257344 852431920 9.424 100 81 162 0 893371168 22166032 18773216
05:12:35 8276 8192000000 9848448960 10179125248 902825632 9.926 100 78 154 0 960161168 40623776 16711760
05:13:47 5983 8192000000 9890357792 10231189504 955754816 10.447 100 82 161 0 1003623216 31324688 16543712
05:14:56 2438 8192000000 9987799216 10337480704 992343776 10.804 100 94 184 0 1102246976 91767152 18136048
05:16:00 1244 8192000000 10011431728 10373320704 1021107632 11.083 100 94 181 3 1122850176 83557728 18184816
05:17:03 972 8192000000 10012048592 10379706368 1038496752 11.250 100 92 178 0 1129619168 73375168 17747248
05:18:05 1032 8192000000 10019436096 10398683136 1052403936 11.384 100 97 178 2 1143025280 72093184 18528160
05:19:07 946 8192000000 10035988544 10415087616 1062208384 11.478 100 93 181 0 1153864144 73178848 18476912
05:20:08 1011 8192000000 10046567344 10427146240 1067087312 11.524 100 92 175 1 1164895824 80566992 17241520
05:21:10 8236 8192000000 10016180704 10408030208 1076978928 11.619 100 60 119 1 1148831856 60088640 11764288
05:22:19 2198 8192000000 10043690608 10439786496 1079716416 11.645 100 77 142 3 1167012032 73662000 13633616
05:23:24 3508 8192000000 10039779984 10432057344 1084976656 11.695 100 67 131 0 1159366176 61971456 12418064
05:24:29 1114 8192000000 10059846240 10447654912 1088684000 11.730 100 96 181 0 1181241968 74215552 18342416
05:25:32 1327 8192000000 10082683792 10474901504 1090839696 11.751 100 95 187 2 1206120160 96462704 18817760
05:26:34 1649 8192000000 10029230224 10419986432 1085696208 11.702 99 80 153 2 1146404016 45331072 15376736
05:27:38 2473 8192000000 10038329344 10445479936 1094376304 11.784 100 57 113 0 1157980752 51574624 12029824
05:28:41 3399 8192000000 10047083648 10436571136 1091524096 11.757 100 77 135 5 1170464112 66889680 12050336
05:29:51 2629 8192000000 10064338944 10455822336 1088877184 11.732 100 78 150 2 1184063584 80342448 14843952
05:30:56 1029 8192000000 9981175776 10360709120 1053553056 11.395 100 88 167 1 1106683424 35044112 18086256
05:31:57 1956 8192000000 10021210256 10410110976 1056063664 11.419 100 65 128 1 1142957264 73305024 13588576
05:33:00 1270 8192000000 9989864496 10357133312 1022124272 11.093 99 93 165 1 1107078896 68546064 16408560
05:34:02 974 8192000000 9982051056 10351976448 1030976688 11.178 100 96 183 4 1106537408 57094896 18465824
05:35:07 7028 8192000000 9959124992 10332872704 1012340896 10.998 99 61 117 0 1079423184 55649760 11432528
05:36:16 7714 8192000000 9989321872 10368528384 1030503728 11.173 100 61 114 2 1093930416 51139296 12287392
05:37:27 14600 8192000000 9956566432 10338914304 1030177520 11.170 100 53 97 1 1077532928 36488272 10867136
05:38:51 7326 8192000000 10016795344 10402381824 1050263024 11.363 100 62 118 2 1132113424 69446400 12404000
05:40:07 1290 8192000000 10030062880 10406924288 1064173888 11.496 100 90 170 1 1146236480 63719136 18343456
05:41:12 1737 8192000000 10071643952 10449981440 1077102608 11.620 100 88 170 1 1186579408 92683168 16793632
05:42:18 2023 8192000000 10101436832 10491158528 1092767488 11.769 100 80 155 0 1218352368 109083936 16500944
05:43:23 1879 8192000000 10135752624 10521133056 1108851936 11.922 100 90 169 2 1248216704 121402560 17962208
05:44:31 4529 8192000000 10140553040 10536411136 1121608864 12.042 100 76 139 1 1256883536 121496976 13777696
05:45:39 5215 8192000000 10189418032 10579984384 1134505472 12.164 100 78 149 1 1306325120 155654848 16164800
05:47:00 5313 8192000000 10222831024 10624958464 1152641248 12.334 100 71 141 0 1333833792 165782784 15409760
05:48:09 681 8192000000 10263660080 10659188736 1174551120 12.539 100 94 176 0 1373900992 179774336 19575536
05:49:13 914 8192000000 10316377872 10738159616 1175090912 12.544 100 100 186 0 1427196320 231836880 20268528
05:50:16 3333 8192000000 10320404896 10730237952 1185514160 12.642 100 84 161 0 1433845600 230164608 18166832
05:51:23 1101 8192000000 10334831696 10737618944 1184369040 12.631 100 90 170 0 1442353104 239437968 18546096
05:52:27 843 8192000000 10389711616 10793676800 1189394208 12.678 100 93 179 2 1495792720 286294560 20103952
05:53:29 884 8192000000 10416426752 10829762560 1192237632 12.704 100 97 186 2 1517090704 303935184 20917888
05:54:33 879 8192000000 10423568080 10834128896 1195299568 12.733 100 92 183 1 1525822592 309667136 20855888
05:55:37 844 8192000000 10451041536 10856579072 1194599184 12.726 100 99 194 0 1560097248 342131072 23366992
05:56:40 1913 8192000000 10472458384 10905055232 1188237712 12.667 100 95 186 0 1586525824 376817168 21470944
05:57:46 938 8192000000 10467284176 10889154560 1184808976 12.635 99 97 187 2 1573674656 367032032 21833648
05:58:50 1020 8192000000 10513515104 10923532288 1194139680 12.722 100 93 177 3 1622326208 406907408 21279120
05:59:53 1272 8192000000 10492757696 10928496640 1180713296 12.597 100 97 186 1 1595928560 392597072 22618192
06:00:56 847 8192000000 10511761552 10966548480 1185767808 12.644 100 89 173 2 1614028256 407238896 21021552
06:02:00 3801 8192000000 10485634704 10933755904 1187719040 12.662 100 58 112 0 1594149344 392209376 14220928
06:03:11 3509 8192000000 10559946192 10985926656 1180825008 12.598 100 69 135 0 1662196912 464663792 16708112
06:04:23 2250 8192000000 10581504336 11007762432 1174573936 12.540 100 63 117 1 1683964592 493749952 15640704
06:05:33 4950 8192000000 10540392768 11027877888 1168629024 12.484 99 47 93 0 1639989024 459180064 12179936
06:06:44 11773 8192000000 10593394384 11043520512 1169623088 12.493 100 45 90 0 1689321808 507736624 11962096
06:08:01 29689 8192000000 10493388528 11002634240 1146107152 12.273 99 33 66 0 1597874336 443228528 8538656
06:09:36 26425 8192000000 10426494544 10972524544 1148749856 12.298 100 31 58 0 1562347904 405449888 8148160
06:11:03 32133 8192000000 10240630464 10776055808 1124948288 12.074 99 25 50 0 1389578832 258457808 6172736
06:12:41 53733 8192000000 10116227888 10699726848 1133331440 12.153 100 11 22 0 1207528048 71969024 2227584
06:14:37 66229 8192000000 10096817840 10531041280 1138429104 12.201 100 43 77 1 1201461872 53896688 9136080
06:16:50 64341 8192000000 10149830640 10592018432 1134485360 12.164 100 15 30 0 1222049072 84740432 2823280
06:18:56 69478 8192000000 10063190336 10496266240 1124009136 12.065 98 23 43 0 1159864560 31687840 4167584
06:21:07 54207 8192000000 10096706720 10540335104 1129114128 12.113 99 35 67 0 1193354384 56007296 8232960
06:23:03 74287 8192000000 10068049120 10518130688 1108862384 11.922 98 29 56 0 1159999856 45037536 6099936
06:25:18 66503 8192000000 10095408416 10534260736 1066713872 11.521 94 22 44 0 1121383136 49706816 4962448
06:27:33 18265 8192000000 10100922528 10532442112 1120746416 12.034 99 54 106 0 1202260624 69916976 11597232
06:28:52 38075 8192000000 10082185456 10551586816 1125665600 12.080 99 31 60 1 1196141728 63428864 7047264
06:30:33 25537 8192000000 10067883584 10533031936 1112089120 11.952 98 18 31 1 1168605936 53672256 2844560
06:31:59 59743 8192000000 10084898912 10548576256 1106605024 11.900 98 16 32 0 1170477760 60117328 3755408
06:34:00 66634 8192000000 10046207744 10501160960 1112254048 11.954 99 8 16 0 1144527792 30681248 1592496
06:36:10 20329 8192000000 10122386640 10568945664 1130061600 12.122 100 29 58 0 1216593792 80281120 6251072
06:37:34 42433 8192000000 10091761664 10537070592 1103749488 11.873 97 35 70 0 1167172448 56593552 6829408
06:40:15 68076 8192000000 10081568912 10556551168 1136881904 12.186 100 13 26 0 1204278560 64266608 3130048
06:42:24 44268 8192000000 10124343616 10565963776 1108557936 11.919 97 27 53 0 1185462896 71609264 5295696
06:44:09 68376 8192000000 10057209728 10504105984 1068978112 11.542 94 18 36 0 1104097920 30282960 4836848
06:46:20 39856 8192000000 10160287232 10636828672 1141000976 12.225 100 34 68 0 1266015072 117801392 7212704
06:48:08 138935 8192000000 10107250752 10550906880 1095136688 11.791 96 33 66 0 1122815632 20950576 6728368
06:51:29 57335 8192000000 10080737392 10543992832 1114861792 11.978 98 32 58 1 1186204384 65341632 6000960
06:53:29 46092 8192000000 10082782288 10531696640 1104757168 11.883 97 25 50 0 1165977280 56218224 5001888
06:55:15 77471 8192000000 10088907808 10605309952 1114602864 11.976 99 15 30 0 1198470176 80518480 3348832
06:57:33 62366 8192000000 10090289424 10559676416 1098823328 11.826 97 16 32 0 1169607632 67446656 3337648
07:00:04 55735 8192000000 10074580400 10530738176 1106703056 11.901 99 43 83 1 1180281536 64782448 8796032
07:02:00 88762 8192000000 10081929744 10513293312 1080915888 11.656 96 30 52 2 1153596544 67318864 5361792
07:04:30 14501 8192000000 10079993440 10527334400 1122582176 12.051 100 51 98 0 1204620224 71603488 10434560
07:05:51 10335 8192000000 10162242656 10585952256 1129847120 12.120 100 60 118 1 1255307744 113889120 11571504
07:07:04 49454 8192000000 10073098176 10552807424 1108153456 11.915 98 26 48 0 1216925680 103810752 4961472
07:09:00 114753 8192000000 10050300304 10494722048 1115685552 11.986 99 16 28 0 1154020448 35593488 2741408
07:12:35 73302 8192000000 10082109584 10532093952 1103668448 11.872 97 19 36 1 1166951392 59454432 3828512
07:14:49 66989 8192000000 10056370896 10529189888 1120226128 12.029 99 12 24 0 1160299408 37721248 2352032
07:16:56 53838 8192000000 10087127616 10556215296 1113090656 11.962 98 14 28 0 1175540208 60007536 2442016
07:18:50 66870 8192000000 10123964752 10574196736 1120143856 12.028 98 19 36 0 1201770128 77617264 4009008
07:21:03 90578 8192000000 10100149584 10586374144 1143005680 12.244 100 23 46 0 1236075632 87695648 5374304
07:23:36 22727 8192000000 10117449536 10556633088 1101073264 11.848 96 43 81 0 1164125248 55332640 7719344
07:25:00 41201 8192000000 10157169680 10602176512 1121781728 12.044 98 38 75 0 1227521520 97977152 7762640
07:27:05 14294 8192000000 10148913152 10593394688 1138430032 12.201 100 52 104 0 1248497232 99356016 10711184
07:28:22 40507 8192000000 10107536416 10554847232 1128594080 12.108 99 36 72 0 1199948448 64342464 7011904
07:30:03 38338 8192000000 10118410000 10569097216 1107273056 11.907 98 49 96 0 1177702048 61004848 9424144
07:31:43 87381 8192000000 10064441040 10497769472 1082126528 11.668 96 31 62 0 1161845408 73457136 6261744
07:34:11 85005 8192000000 10056536176 10503258112 1125640288 12.080 100 29 58 0 1164050960 32301504 6109168
07:36:43 39401 8192000000 9905745232 10357874688 971803792 10.604 87 12 24 0 1002703680 28141184 2758704

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 29-07-2022 07:40:03 to 29-07-2022 07:40:19.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 29-07-2022 07:40:20 to 29-07-2022 07:40:22.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          226869
MON$OLDEST_ACTIVE               226870
MON$OLDEST_SNAPSHOT             226870
MON$NEXT_TRANSACTION            226870
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-30 07:30:08.6030 GMT
MON$PAGES                       2101248
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             788
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2811 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2811 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2811 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2811 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.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 ---
:206: 
:207: mscs-test-161.budzdorov.ru    Fri Jul 29 07:37:31 2022
:208:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:209: 
:210: 
:211: mscs-test-161.budzdorov.ru    Fri Jul 29 07:37:32 2022
:212:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:213: 
:214: 
:215: mscs-test-161.budzdorov.ru    Fri Jul 29 07:37:38 2022
:216:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:217: 
:218: 
:219: mscs-test-161.budzdorov.ru    Fri Jul 29 07:37:45 2022
:220:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:221: 
--- end of diff output ---

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

29.07.22 07:40:23. There are 3 logs in /var/tmp/logs-oltp40 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
2022-07-29 07:40:26 No severe PSQL-related problems occured
29.07.22 07:40:27. End of report.