Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 29.12.2021 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 15399756 kB
MemAvailable 31406752 kB
Buffers 0 kB
Cached 16313588 kB
SwapCached 440 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.1
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 2021-12-29 07:37:11
DTS_END                         2021-12-29 07:37:11.0210
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/37890
TRN_ID                          238737
ATT_ID                          610
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: 9101.35 20061 1092162
customer order: creation 172.75 430 20730
customer order: refuse 14.53 330 1743
order to supplier: creation 46.58 1386 5590
order to supplier: removal 3.27 116024 392
invoice (draft): creation 45.72 3745 5486
invoice (draft): removal 52.23 881 6268
invoice accept: apply 40.57 19881 4868
invoice accept: cancel 47.24 908 5669
customer reserve: creation 3776.07 197 453128
customer reserve: removal 4591.29 65 550955
realization accept: apply 84.13 209 10096
realization accept: cancel 98.13 120 11775
payment from customer: creation 60.24 139 7229
payment from customer: removal 5.69 94 683
payment to supplier: creation 47.95 148 5754
payment to supplier: removal 3.31 173 397
service: total inventory turnovers 4.36 3134 523
service: total monetary turnovers 6.25 517 750
service: aggregate perf. data 1.02 13351 122
service: refresh index statistics 0.03 239494 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 29-12-2021 07:39:28 to 29-12-2021 07:39:30.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6521
TEST_TIME 2 6327
TEST_TIME 3 5755
TEST_TIME 4 5891
TEST_TIME 5 5818
TEST_TIME 6 5774
TEST_TIME 7 5934
TEST_TIME 8 6019
TEST_TIME 9 5941
TEST_TIME 10 5885
TEST_TIME 11 6045
TEST_TIME 12 6335
TEST_TIME 13 6372
TEST_TIME 14 6594
TEST_TIME 15 6688
TEST_TIME 16 6846
TEST_TIME 17 7109
TEST_TIME 18 7138
TEST_TIME 19 7608
TEST_TIME 20 7835
TEST_TIME 21 7888
TEST_TIME 22 7896
TEST_TIME 23 8645
TEST_TIME 24 8689
TEST_TIME 25 9217
TEST_TIME 26 9624
TEST_TIME 27 9955
TEST_TIME 28 10280
TEST_TIME 29 10644
TEST_TIME 30 10593
TEST_TIME 31 10726
TEST_TIME 32 10557
TEST_TIME 33 10490
TEST_TIME 34 10236
TEST_TIME 35 10068
TEST_TIME 36 10116
TEST_TIME 37 9964
TEST_TIME 38 9808
TEST_TIME 39 9669
TEST_TIME 40 9950
TEST_TIME 41 9958
TEST_TIME 42 9789
TEST_TIME 43 9769
TEST_TIME 44 9629
TEST_TIME 45 9689
TEST_TIME 46 9775
TEST_TIME 47 9779
TEST_TIME 48 9760
TEST_TIME 49 9861
TEST_TIME 50 9827
TEST_TIME 51 9901
TEST_TIME 52 9863
TEST_TIME 53 9781
TEST_TIME 54 9897
TEST_TIME 55 9907
TEST_TIME 56 9838
TEST_TIME 57 9938
TEST_TIME 58 9895
TEST_TIME 59 9749
TEST_TIME 60 9689
TEST_TIME 61 9576
TEST_TIME 62 9584
TEST_TIME 63 9628
TEST_TIME 64 9539
TEST_TIME 65 9469
TEST_TIME 66 9452
TEST_TIME 67 9508
TEST_TIME 68 9478
TEST_TIME 69 9499
TEST_TIME 70 9477
TEST_TIME 71 9474
TEST_TIME 72 9510
TEST_TIME 73 9495
TEST_TIME 74 9513
TEST_TIME 75 9526
TEST_TIME 76 9579
TEST_TIME 77 9613
TEST_TIME 78 9581
TEST_TIME 79 9520
TEST_TIME 80 9426
TEST_TIME 81 9383
TEST_TIME 82 9370
TEST_TIME 83 9323
TEST_TIME 84 9268
TEST_TIME 85 9215
TEST_TIME 86 9191
TEST_TIME 87 9251
TEST_TIME 88 9289
TEST_TIME 89 9286
TEST_TIME 90 9264
TEST_TIME 91 9291
TEST_TIME 92 9249
TEST_TIME 93 9342
TEST_TIME 94 9356
TEST_TIME 95 9379
TEST_TIME 96 9408
TEST_TIME 97 9418
TEST_TIME 98 9332
TEST_TIME 99 9283
TEST_TIME 100 9297
TEST_TIME 101 9251
TEST_TIME 102 9235
TEST_TIME 103 9196
TEST_TIME 104 9163
TEST_TIME 105 9241
TEST_TIME 106 9232
TEST_TIME 107 9213
TEST_TIME 108 9208
TEST_TIME 109 9184
TEST_TIME 110 9187
TEST_TIME 111 9197
TEST_TIME 112 9258
TEST_TIME 113 9259
TEST_TIME 114 9258
TEST_TIME 115 9276
TEST_TIME 116 9245
TEST_TIME 117 9185
TEST_TIME 118 9159
TEST_TIME 119 9178
TEST_TIME 120 9104

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 29-12-2021 07:39:31 to 29-12-2021 07:39:35.

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 1512225 1512225 0 0.00 0 15219 7 0 0
doc_list_biud 1512222 1512222 0 0.00 0 86436 30 0 0
sp_add_invoice_to_stock 4974 4868 106 2.13 44 197773 19882 0 106
sp_cancel_adding_invoice 5669 5669 0 0.00 5 79130 908 0 0
sp_cancel_client_order 1743 1743 0 0.00 4 10158 330 0 0
sp_cancel_customer_reserve 550955 550955 0 0.00 1 80948 66 0 0
sp_cancel_pay_from_customer 683 683 0 0.00 3 7850 94 0 0
sp_cancel_pay_to_supplier 397 397 0 0.00 3 31111 173 0 0
sp_cancel_supplier_invoice 6275 6268 7 0.11 2 86449 882 0 7
sp_cancel_supplier_order 392 392 0 0.00 7 1029849 116025 0 0
sp_cancel_write_off 11775 11775 0 0.00 1 15058 120 0 0
sp_client_order 20730 20730 0 0.00 4 45257 430 0 0
sp_customer_reserve 453128 453128 0 0.00 0 146722 197 0 0
sp_fill_shopping_cart 31800 31800 0 0.00 1 25264 41 0 0
sp_fill_shopping_cart_clo_res 453124 453124 0 0.00 0 13346 16 0 0
sp_get_clo_for_invoice 4863 4863 0 0.00 0 31975 334 0 0
sp_kill_cost_storno 18524 18524 0 0.00 0 15056 56 0 0
sp_kill_qstorno_ret_qs2qd 1120345 1120345 0 0.00 0 86433 36 0 0
sp_kill_qty_storno 591758 591758 0 0.00 0 86435 76 0 0
sp_lock_selected_doc 20705 20705 0 0.00 0 13146 10 0 0
sp_make_cost_storno 27942 27942 0 0.00 0 15188 54 0 0
sp_multiply_rows_for_pdistr 27941 27941 0 0.00 0 11393 35 0 0
sp_multiply_rows_for_qdistr 20730 20730 0 0.00 0 45250 216 0 0
sp_pay_from_customer 7229 7229 0 0.00 4 29830 140 0 0
sp_pay_to_supplier 5754 5754 0 0.00 5 29405 148 0 0
sp_payment_common 12983 12983 0 0.00 1 11239 21 0 0
sp_qd_handle_on_cancel_clo 1743 1743 0 0.00 0 10135 286 0 0
sp_qd_handle_on_invoice_upd_sts 10531 10531 0 0.00 0 34201 345 0 0
sp_qd_handle_on_reserve_upd_sts 10094 10094 0 0.00 0 17645 61 0 0
sp_reserve_write_off 10096 10096 0 0.00 7 31114 209 0 0
sp_supplier_invoice 5486 5486 0 0.00 7 102982 3746 0 0
sp_supplier_order 5590 5590 0 0.00 5 35942 1387 0 0
srv_aggregate_perf_data 122 122 0 0.00 2812 128775 13352 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1359 163253 46155 0 0
srv_find_qd_qs_mism 1044962 1044962 0 0.00 0 15103 7 0 0
srv_make_invnt_saldo 523 523 0 0.00 37 93735 3134 0 0
srv_make_money_saldo 750 750 0 0.00 1 15114 517 0 0
srv_recalc_idx_stat 4 4 0 0.00 178618 317239 239494 0 0
t$perf-abend:sp_add_invoice_to_stock 106 106 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4869 4869 0 0.00 0 21882 157 0 0
t$perf-norm:sp_cancel_adding_invoice 430 430 0 0.00 0 24317 184 0 0
t$perf-norm:sp_cancel_client_order 1743 1743 0 0.00 0 19082 23 0 0
t$perf-norm:sp_cancel_customer_reserve 674 674 0 0.00 0 3003 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 683 683 0 0.00 0 690 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 397 397 0 0.00 0 87 1 0 0
t$perf-norm:sp_cancel_supplier_invoice 405 405 0 0.00 0 1153 15 0 0
t$perf-norm:sp_cancel_supplier_order 392 392 0 0.00 0 23955 1392 0 0
t$perf-norm:sp_cancel_write_off 1025 1025 0 0.00 0 2352 8 0 0
t$perf-norm:sp_client_order 20730 20730 0 0.00 0 6943 16 0 0
t$perf-norm:sp_customer_reserve 1006 1006 0 0.00 0 6506 12 0 0
t$perf-norm:sp_pay_from_customer 7229 7229 0 0.00 0 2897 6 0 0
t$perf-norm:sp_pay_to_supplier 5754 5754 0 0.00 0 16519 11 0 0
t$perf-norm:sp_reserve_write_off 10096 10096 0 0.00 0 17575 15 0 0
t$perf-norm:sp_supplier_invoice 5486 5486 0 0.00 0 6058 16 0 0
t$perf-norm:sp_supplier_order 5590 5590 0 0.00 0 16130 17 0 0
t$perf-norm:srv_aggregate_perf_data 122 122 0 0.00 0 1506 44 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 99 4 0 0
t$perf-norm:srv_make_invnt_saldo 523 523 0 0.00 0 1969 13 0 0
t$perf-norm:srv_make_money_saldo 750 750 0 0.00 0 1836 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 4 0 0
v_add_invoice_to_stock 9936 9724 212 2.13 0 28593 21 0 212
v_all_customers 38004 38004 0 0.00 0 20 1 0 0
v_all_suppliers 30596 30596 0 0.00 0 18 1 0 0
v_all_wares 41460 41460 0 0.00 0 44 1 0 0
v_cancel_adding_invoice 860 860 0 0.00 0 7894 17 0 0
v_cancel_client_order 3486 3486 0 0.00 0 2405 7 0 0
v_cancel_customer_prepayment 1366 1366 0 0.00 0 5459 14 0 0
v_cancel_customer_reserve 1348 1348 0 0.00 0 799 4 0 0
v_cancel_payment_to_supplier 794 794 0 0.00 0 30582 50 0 0
v_cancel_supplier_invoice 824 810 14 1.69 0 6257 23 0 14
v_cancel_supplier_order 784 784 0 0.00 0 14007 30 0 0
v_cancel_write_off 2050 2050 0 0.00 0 11435 21 0 0
v_max_id_clo_ord 5586 5586 0 0.00 0 464 1 0 0
v_max_id_clo_res 1006 1006 0 0.00 0 2998 22 0 0
v_max_id_ord_sup 5484 5484 0 0.00 0 3683 6 0 0
v_max_non_paid_invoice 1526 1526 0 0.00 0 1952 3 0 0
v_max_non_paid_realizn 3751 3751 0 0.00 0 2312 4 0 0
v_min_id_clo_ord 5586 5586 0 0.00 0 25204 17 0 0
v_min_id_clo_res 1006 1006 0 0.00 0 9893 34 0 0
v_min_id_ord_sup 5484 5484 0 0.00 0 5166 7 0 0
v_min_non_paid_invoice 5754 5754 0 0.00 0 9385 11 0 0
v_min_non_paid_realizn 7229 7229 0 0.00 0 9171 14 0 0
v_our_firm 10412 10412 0 0.00 0 42 1 0 0
v_reserve_write_off 20188 20188 0 0.00 0 30580 9 0 0
x_lock_depdocs_on_canc_invoice 11532 11532 0 0.00 0 18928 119 0 0
x_lock_depdocs_on_canc_sup_ord 392 392 0 0.00 0 9355 323 0 0
x_make_qstorno_1000_1200 5590 5590 0 0.00 2 35902 1300 0 0
x_make_qstorno_1200_2000 5486 5486 0 0.00 3 97580 3596 0 0
x_make_qstorno_2100_3300 453128 453128 0 0.00 0 146649 178 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-12-2021 07:39:36 to 29-12-2021 07:39:40.

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:14 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:15 29246 8192000000 9726140416 10022854656 805126176 8.948 100 88 176 0 834815712 8340784 21348752
05:09:50 15392 8192000000 9763204896 10069819392 838194448 9.282 100 88 176 0 872100000 12913152 20992400
05:11:11 14191 8192000000 9792770800 10108141568 860491280 9.505 100 90 180 0 902952064 21392560 21068224
05:12:34 12818 8192000000 9859842016 10191380480 912488352 10.022 100 88 176 0 971988144 39919728 19580064
05:13:51 10264 8192000000 9922071440 10264948736 948505840 10.376 100 79 158 1 1031741168 66535456 16699872
05:15:06 5052 8192000000 9951642192 10305785856 991997536 10.801 100 81 162 0 1071387616 62522848 16867232
05:16:14 3044 8192000000 10011305264 10375569408 1022600128 11.097 100 89 177 1 1127010672 87229904 17180640
05:17:19 1514 8192000000 10007678688 10374995968 1046895840 11.331 100 84 166 1 1116260016 52533328 16830848
05:18:21 1303 8192000000 10039452224 10412081152 1070426192 11.556 100 93 187 0 1151902928 62654272 18822464
05:19:23 20266 8192000000 9975387072 10372435968 1071768272 11.569 100 29 58 0 1112406096 34410096 6227728
05:20:44 7843 8192000000 9999031664 10395328512 1078879168 11.637 100 44 88 1 1116332448 27879936 9573344
05:21:56 8742 8192000000 9999153200 10407903232 1086187408 11.706 100 33 66 1 1126565456 33432544 6945504
05:23:11 5953 8192000000 10008842944 10411900928 1090399088 11.746 100 26 52 1 1121655280 25994544 5261648
05:24:18 48156 8192000000 9998755568 10391871488 1085049552 11.696 99 34 66 1 1114137280 20654208 8433520
05:26:25 4191 8192000000 10041260336 10438971392 1098828592 11.827 100 50 101 1 1151101200 41717136 10555472
05:27:30 6537 8192000000 9978227584 10366046208 1049678736 11.358 98 34 67 0 1080021968 22854832 7488400
05:28:38 55407 8192000000 9976619440 10386857984 1025054064 11.121 97 26 52 1 1056774320 26711968 5008288
05:30:37 4581 8192000000 9953626944 10325614592 1008713776 10.963 98 50 97 2 1053013152 34211744 10087632
05:31:42 9128 8192000000 9940388128 10327089152 1012015024 10.995 99 36 72 0 1032375200 12353824 8006352
05:32:55 6399 8192000000 9950636576 10339504128 1045176096 11.314 100 43 88 0 1069031504 13451216 10404192
05:34:05 24462 8192000000 9975922544 10381537280 1049814240 11.359 100 27 54 0 1076815008 21260224 5740544
05:35:30 5073 8192000000 10002519040 10404651008 1059342064 11.450 100 32 64 1 1109939760 44164224 6433472
05:36:36 5981 8192000000 9977225648 10374778880 1070797072 11.560 100 39 80 0 1099100256 19319296 8983888
05:37:51 30503 8192000000 10064599504 10461687808 1098569136 11.824 100 51 102 0 1162825520 53828368 10428016
05:39:34 23463 8192000000 10049979008 10450604032 1111521920 11.947 100 47 90 3 1164399872 43181344 9696608
05:41:05 12405 8192000000 10091535040 10491842560 1123998832 12.065 100 61 119 1 1194754576 58449824 12305920
05:42:27 4349 8192000000 10098524816 10501607424 1130512112 12.126 100 51 102 0 1209760752 67820272 11428368
05:43:40 14740 8192000000 10134059168 10541395968 1136594832 12.183 100 45 90 1 1239153536 91737648 10821056
05:45:00 46565 8192000000 10134075376 10567749632 1150186656 12.311 100 39 76 1 1248000336 88824784 8988896
05:46:58 19479 8192000000 10182677952 10595803136 1148695728 12.297 99 53 106 1 1272576224 111449968 12430528
05:48:31 3509 8192000000 10202875200 10605432832 1142904400 12.243 98 75 150 0 1291687584 132545008 16238176
05:49:38 3440 8192000000 10235999392 10638860288 1169488080 12.492 100 84 168 1 1330677360 143364256 17825024
05:50:45 3943 8192000000 10283615440 10688339968 1170348032 12.500 100 72 142 2 1385951344 199863632 15739680
05:51:53 14487 8192000000 10285285184 10695413760 1162663216 12.428 99 74 149 0 1374353712 194593344 17097152
05:53:13 5273 8192000000 10305335408 10709241856 1165350592 12.453 99 82 164 2 1400041280 215126496 19564192
05:54:21 6783 8192000000 10301406688 10722680832 1151595808 12.324 99 42 84 1 1398834320 237537968 9700544
05:55:33 2335 8192000000 10340879536 10730729472 1139975152 12.215 99 90 181 3 1437090256 276149024 20966080
05:56:42 9883 8192000000 10350054384 10769960960 1126064640 12.084 99 47 94 1 1466384752 328526816 11793296
05:58:02 2256 8192000000 10354458208 10754457600 1138226992 12.199 100 69 138 2 1453131120 297056960 17847168
05:59:10 16875 8192000000 10389877888 10805747712 1131882224 12.139 100 46 94 1 1492682704 348645840 12154640
06:00:30 9964 8192000000 10406039904 10825474048 1141667968 12.231 100 39 78 1 1525518384 373747200 10103216
06:01:53 12266 8192000000 10338729280 10761867264 1123123504 12.056 99 34 68 2 1435580608 303944080 8513024
06:03:38 44652 8192000000 10251825664 10698432512 1100116032 11.839 98 18 36 0 1340907936 236328800 4463104
06:05:58 57725 8192000000 10129473136 10683990016 1109729136 11.930 100 11 22 0 1283177856 170818880 2629840
06:08:04 60108 8192000000 10081901216 10527526912 1092234448 11.764 97 19 38 0 1136771056 40833568 3703040
06:10:17 46486 8192000000 10110502560 10544693248 1118500288 12.013 98 19 38 0 1213976208 91257024 4218896
06:12:05 116347 8192000000 10109255232 10562850816 1124804112 12.072 99 24 48 0 1216053008 85615776 5633120
06:15:13 44912 8192000000 10190619216 10622537728 1145902224 12.271 100 20 40 2 1247883696 97805632 4175840
06:16:58 62703 8192000000 10171223680 10716827648 1144303408 12.256 99 19 38 0 1258239856 109592160 4344288
06:19:44 163232 8192000000 10165507696 10633097216 1146647472 12.278 99 19 38 0 1262633520 111727984 4258064
06:23:40 74506 8192000000 10097130640 10562195456 1133148096 12.151 98 12 24 0 1188031280 52452192 2430992
06:26:05 27447 8192000000 10119841856 10578284544 1147913488 12.290 99 61 122 1 1219050544 58452704 12684352
06:27:38 62597 8192000000 10089076496 10570137600 1146131296 12.273 99 7 14 0 1196109696 48702496 1275904
06:29:50 53144 8192000000 10114901232 10543878144 1122403056 12.050 97 18 36 0 1171303232 44815264 4084912
06:31:46 58463 8192000000 10094167120 10536751104 1118058080 12.009 97 9 18 2 1184231600 64536336 1637184
06:33:51 37123 8192000000 10135069472 10613379072 1137353248 12.191 99 20 40 1 1245047312 103484528 4209536
06:35:35 98159 8192000000 10094983760 10544701440 1125246432 12.077 98 41 82 0 1169121712 35245184 8630096
06:38:20 49698 8192000000 10126243760 10588401664 1124703856 12.071 98 35 70 0 1219966512 88594112 6668544
06:40:36 54943 8192000000 10080970368 10543259648 1114566400 11.976 98 21 42 2 1165695888 46873104 4256384
06:42:31 44956 8192000000 10121937568 10578825216 1117165152 12.000 98 12 24 0 1206238352 86108608 2964592
06:45:01 48087 8192000000 10096905008 10536869888 1110907648 11.941 98 20 40 0 1167377728 52439584 4030496
06:46:54 26449 8192000000 10081228768 10513190912 1132355680 12.144 100 48 96 0 1174892752 32149456 10387616
06:48:26 39286 8192000000 10101265248 10550964224 1120961904 12.036 99 35 70 0 1180271408 51042320 8267184
06:50:09 36312 8192000000 10038024784 10458595328 1053424288 11.394 93 15 30 1 1077111440 20819584 2867568
06:51:50 64908 8192000000 10113863184 10590011392 1108586608 11.919 98 32 64 0 1196316928 81111120 6619200
06:53:56 29583 8192000000 10097350640 10521505792 1111156352 11.943 99 40 80 0 1154139056 34708912 8273792
06:55:27 91025 8192000000 10047844448 10504232960 1116538768 11.994 99 30 60 0 1153285712 30317280 6429664
06:58:09 1359 8192000000 10045062880 10465910784 1100224832 11.840 99 55 108 1 1137960384 26615376 11120176
06:59:11 83601 8192000000 10049815456 10518659072 1120464464 12.031 100 15 30 0 1157843168 34220640 3158064
07:02:08 66745 8192000000 10116746160 10561642496 1119507024 12.022 99 16 32 0 1188520576 65603920 3409632
07:04:16 77628 8192000000 10092211632 10554613760 1095861936 11.798 97 17 34 0 1163978064 64178112 3938016
07:06:44 54452 8192000000 10124042416 10556669952 1137385424 12.191 100 29 59 0 1207376592 63460160 6531008
07:09:08 48051 8192000000 10135688768 10576539648 1140956240 12.225 100 17 34 0 1213122928 68726032 3440656
07:10:56 30585 8192000000 10104896496 10540744704 1125748048 12.081 98 46 92 0 1171157984 36581888 8828048
07:12:29 100564 8192000000 10101435312 10555265024 1108327664 11.917 97 17 34 0 1170950928 58804112 3819152
07:15:09 120067 8192000000 10101206304 10533003264 1130873312 12.130 99 28 55 3 1220295536 84223168 5199056
07:18:10 33042 8192000000 10103919152 10571452416 1143285280 12.246 100 7 14 0 1207853152 63042512 1525360
07:20:58 77033 8192000000 10117570208 10597752832 1140982048 12.225 99 11 22 0 1214886400 71357712 2546640
07:23:45 45848 8192000000 10086566112 10534248448 1113844384 11.969 98 19 38 0 1144876992 27538800 3493808
07:25:42 51270 8192000000 10071059184 10523574272 1104522736 11.881 98 14 28 0 1161139504 53745968 2870800
07:27:34 29933 8192000000 10101815120 10532315136 1117635984 12.005 99 34 68 0 1177284672 52776656 6872032
07:29:08 79636 8192000000 10094261904 10566410240 1108266992 11.916 97 25 50 0 1192334656 79083600 4984064
07:31:28 53929 8192000000 10053013680 10524729344 1057790208 11.435 94 22 44 0 1123341440 60744832 4806400
07:33:30 125512 8192000000 10077354880 10531708928 1097752416 11.816 98 21 42 0 1194784480 92811760 4220304
07:36:40 46875 8192000000 9420444224 9810079744 544360896 6.230 48 5 10 0 584494368 39073184 1060288

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 29-12-2021 07:39:42 to 29-12-2021 07:39:56.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 106 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 29-12-2021 07:39:57 to 29-12-2021 07:39:59.

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          239180
MON$OLDEST_ACTIVE               239444
MON$OLDEST_SNAPSHOT             239444
MON$NEXT_TRANSACTION            239444
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                       2041056
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             808
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2697 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2697 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2697 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2697 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 ---
:54: 
:55: mscs-test-161.budzdorov.ru    Wed Dec 29 07:37:38 2021
:56:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:57: 
--- end of diff output ---

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

29.12.21 07:40:00. 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
2021-12-29 07:40:03 No severe PSQL-related problems occured
29.12.21 07:40:04. End of report.