Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 29.12.2021 13:37
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 16317292 kB
MemAvailable 31353388 kB
Buffers 0 kB
Cached 15343048 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 13:37:10
DTS_END                         2021-12-29 13:37:10.2040
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/42150
TRN_ID                          235486
ATT_ID                          691
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: 9165.13 23186 1099816
customer order: creation 171.17 448 20540
customer order: refuse 14.45 318 1734
order to supplier: creation 44.88 1532 5385
order to supplier: removal 3.36 123060 403
invoice (draft): creation 46.28 3767 5553
invoice (draft): removal 52.73 965 6328
invoice accept: apply 41.23 19333 4947
invoice accept: cancel 47.64 850 5717
customer reserve: creation 3786.27 191 454352
customer reserve: removal 4660.88 69 559306
realization accept: apply 82.81 196 9937
realization accept: cancel 88.23 128 10588
payment from customer: creation 58.53 126 7024
payment from customer: removal 5.65 86 678
payment to supplier: creation 47.17 145 5660
payment to supplier: removal 3.38 112 406
service: total inventory turnovers 3.67 3903 440
service: total monetary turnovers 5.90 736 708
service: aggregate perf. data 0.88 15211 106
service: refresh index statistics 0.03 292537 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 29-12-2021 13:37:54 to 29-12-2021 13:37:56.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3913
TEST_TIME 2 5374
TEST_TIME 3 6139
TEST_TIME 4 5560
TEST_TIME 5 5031
TEST_TIME 6 5133
TEST_TIME 7 5236
TEST_TIME 8 5546
TEST_TIME 9 5500
TEST_TIME 10 5708
TEST_TIME 11 5903
TEST_TIME 12 5912
TEST_TIME 13 5969
TEST_TIME 14 6090
TEST_TIME 15 6587
TEST_TIME 16 6928
TEST_TIME 17 7494
TEST_TIME 18 7449
TEST_TIME 19 7413
TEST_TIME 20 7586
TEST_TIME 21 8110
TEST_TIME 22 8268
TEST_TIME 23 8561
TEST_TIME 24 8670
TEST_TIME 25 8894
TEST_TIME 26 9358
TEST_TIME 27 9691
TEST_TIME 28 11031
TEST_TIME 29 10965
TEST_TIME 30 10885
TEST_TIME 31 10647
TEST_TIME 32 10909
TEST_TIME 33 11004
TEST_TIME 34 11067
TEST_TIME 35 10829
TEST_TIME 36 10559
TEST_TIME 37 10323
TEST_TIME 38 10288
TEST_TIME 39 10265
TEST_TIME 40 10217
TEST_TIME 41 10168
TEST_TIME 42 10140
TEST_TIME 43 10107
TEST_TIME 44 10064
TEST_TIME 45 10006
TEST_TIME 46 9895
TEST_TIME 47 9819
TEST_TIME 48 9801
TEST_TIME 49 9820
TEST_TIME 50 9754
TEST_TIME 51 9876
TEST_TIME 52 9793
TEST_TIME 53 9817
TEST_TIME 54 9837
TEST_TIME 55 9861
TEST_TIME 56 9938
TEST_TIME 57 9941
TEST_TIME 58 9952
TEST_TIME 59 9941
TEST_TIME 60 10014
TEST_TIME 61 9954
TEST_TIME 62 9868
TEST_TIME 63 9833
TEST_TIME 64 9806
TEST_TIME 65 9729
TEST_TIME 66 9648
TEST_TIME 67 9572
TEST_TIME 68 9504
TEST_TIME 69 9482
TEST_TIME 70 9449
TEST_TIME 71 9406
TEST_TIME 72 9444
TEST_TIME 73 9480
TEST_TIME 74 9478
TEST_TIME 75 9497
TEST_TIME 76 9522
TEST_TIME 77 9502
TEST_TIME 78 9507
TEST_TIME 79 9478
TEST_TIME 80 9593
TEST_TIME 81 9643
TEST_TIME 82 9641
TEST_TIME 83 9566
TEST_TIME 84 9517
TEST_TIME 85 9467
TEST_TIME 86 9481
TEST_TIME 87 9401
TEST_TIME 88 9346
TEST_TIME 89 9338
TEST_TIME 90 9361
TEST_TIME 91 9322
TEST_TIME 92 9329
TEST_TIME 93 9308
TEST_TIME 94 9316
TEST_TIME 95 9345
TEST_TIME 96 9321
TEST_TIME 97 9312
TEST_TIME 98 9363
TEST_TIME 99 9329
TEST_TIME 100 9385
TEST_TIME 101 9431
TEST_TIME 102 9442
TEST_TIME 103 9401
TEST_TIME 104 9373
TEST_TIME 105 9347
TEST_TIME 106 9294
TEST_TIME 107 9272
TEST_TIME 108 9232
TEST_TIME 109 9215
TEST_TIME 110 9181
TEST_TIME 111 9192
TEST_TIME 112 9164
TEST_TIME 113 9187
TEST_TIME 114 9172
TEST_TIME 115 9151
TEST_TIME 116 9185
TEST_TIME 117 9221
TEST_TIME 118 9252
TEST_TIME 119 9230
TEST_TIME 120 9175

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 29-12-2021 13:37:57 to 29-12-2021 13:38:01.

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 1521505 1521505 0 0.00 0 20416 7 0 0
doc_list_biud 1521495 1521495 0 0.00 0 69121 32 0 0
sp_add_invoice_to_stock 5078 4947 131 2.57 34 170090 19333 0 131
sp_cancel_adding_invoice 5717 5717 0 0.00 5 87806 851 0 0
sp_cancel_client_order 1734 1734 0 0.00 5 8909 318 0 0
sp_cancel_customer_reserve 559306 559306 0 0.00 1 69122 69 0 0
sp_cancel_pay_from_customer 678 678 0 0.00 3 2999 86 0 0
sp_cancel_pay_to_supplier 406 406 0 0.00 3 6050 112 0 0
sp_cancel_supplier_invoice 6340 6328 12 0.18 2 23596 966 0 12
sp_cancel_supplier_order 403 403 0 0.00 7 1323327 123060 0 0
sp_cancel_write_off 10588 10588 0 0.00 1 25790 129 0 0
sp_client_order 20540 20540 0 0.00 4 25388 449 0 0
sp_customer_reserve 454352 454352 0 0.00 0 48424 191 0 0
sp_fill_shopping_cart 31463 31463 0 0.00 1 17045 48 0 0
sp_fill_shopping_cart_clo_res 454335 454335 0 0.00 0 22841 17 0 0
sp_get_clo_for_invoice 4928 4928 0 0.00 0 39381 392 0 0
sp_kill_cost_storno 17389 17389 0 0.00 0 11687 62 0 0
sp_kill_qstorno_ret_qs2qd 1135931 1135931 0 0.00 0 69117 38 0 0
sp_kill_qty_storno 598939 598939 0 0.00 0 69121 80 0 0
sp_lock_selected_doc 20625 20625 0 0.00 0 9347 6 0 0
sp_make_cost_storno 27549 27549 0 0.00 0 20389 59 0 0
sp_multiply_rows_for_pdistr 27549 27549 0 0.00 0 13110 37 0 0
sp_multiply_rows_for_qdistr 20540 20540 0 0.00 0 17958 254 0 0
sp_pay_from_customer 7024 7024 0 0.00 4 14740 127 0 0
sp_pay_to_supplier 5660 5660 0 0.00 4 15337 145 0 0
sp_payment_common 12684 12684 0 0.00 1 13698 22 0 0
sp_qd_handle_on_cancel_clo 1734 1734 0 0.00 0 8899 287 0 0
sp_qd_handle_on_invoice_upd_sts 10643 10643 0 0.00 0 26533 355 0 0
sp_qd_handle_on_reserve_upd_sts 9937 9937 0 0.00 0 14181 53 0 0
sp_reserve_write_off 9937 9937 0 0.00 7 14403 197 0 0
sp_supplier_invoice 5553 5553 0 0.00 7 104949 3768 0 0
sp_supplier_order 5385 5385 0 0.00 5 70236 1532 0 0
srv_aggregate_perf_data 106 106 0 0.00 2978 107440 15212 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1754 166839 43229 0 0
srv_find_qd_qs_mism 1053349 1053349 0 0.00 0 17136 5 0 0
srv_make_invnt_saldo 440 440 0 0.00 38 107302 3904 0 0
srv_make_money_saldo 708 708 0 0.00 1 69106 736 0 0
srv_recalc_idx_stat 4 4 0 0.00 146103 417409 292537 0 0
t$perf-abend:sp_add_invoice_to_stock 131 131 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4948 4948 0 0.00 0 18617 178 0 0
t$perf-norm:sp_cancel_adding_invoice 432 432 0 0.00 1 3220 89 0 0
t$perf-norm:sp_cancel_client_order 1734 1734 0 0.00 0 2956 15 0 0
t$perf-norm:sp_cancel_customer_reserve 696 696 0 0.00 0 1092 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 678 678 0 0.00 0 8472 22 0 0
t$perf-norm:sp_cancel_pay_to_supplier 406 406 0 0.00 0 236 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 391 391 0 0.00 0 4181 43 0 0
t$perf-norm:sp_cancel_supplier_order 403 403 0 0.00 0 21968 1596 0 0
t$perf-norm:sp_cancel_write_off 1023 1023 0 0.00 0 3336 12 0 0
t$perf-norm:sp_client_order 20540 20540 0 0.00 0 11362 20 0 0
t$perf-norm:sp_customer_reserve 1013 1013 0 0.00 0 3582 9 0 0
t$perf-norm:sp_pay_from_customer 7024 7024 0 0.00 0 13999 12 0 0
t$perf-norm:sp_pay_to_supplier 5660 5660 0 0.00 0 9219 14 0 0
t$perf-norm:sp_reserve_write_off 9937 9937 0 0.00 0 5822 10 0 0
t$perf-norm:sp_supplier_invoice 5553 5553 0 0.00 0 7099 20 0 0
t$perf-norm:sp_supplier_order 5385 5385 0 0.00 0 6140 19 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 1161 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 105 3 0 0
t$perf-norm:srv_make_invnt_saldo 440 440 0 0.00 0 1136 13 0 0
t$perf-norm:srv_make_money_saldo 708 708 0 0.00 0 1362 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10112 9850 262 2.59 0 8528 23 0 262
v_all_customers 37618 37618 0 0.00 0 157 1 0 0
v_all_suppliers 30166 30166 0 0.00 0 171 1 0 0
v_all_wares 41076 41076 0 0.00 0 246 1 0 0
v_cancel_adding_invoice 864 864 0 0.00 0 10040 24 0 0
v_cancel_client_order 3468 3468 0 0.00 0 3708 7 0 0
v_cancel_customer_prepayment 1356 1356 0 0.00 0 2890 9 0 0
v_cancel_customer_reserve 1392 1392 0 0.00 0 2797 8 0 0
v_cancel_payment_to_supplier 812 812 0 0.00 0 2319 10 0 0
v_cancel_supplier_invoice 806 782 24 2.97 0 2971 23 0 24
v_cancel_supplier_order 806 806 0 0.00 0 1523 6 0 0
v_cancel_write_off 2046 2046 0 0.00 0 4034 20 0 0
v_max_id_clo_ord 5382 5382 0 0.00 0 334 1 0 0
v_max_id_clo_res 1013 1013 0 0.00 0 1821 11 0 0
v_max_id_ord_sup 5543 5543 0 0.00 0 3545 4 0 0
v_max_non_paid_invoice 1502 1502 0 0.00 0 4585 6 0 0
v_max_non_paid_realizn 3589 3589 0 0.00 0 1144 3 0 0
v_min_id_clo_ord 5382 5382 0 0.00 0 2886 8 0 0
v_min_id_clo_res 1013 1013 0 0.00 0 332 2 0 0
v_min_id_ord_sup 5543 5543 0 0.00 0 7558 13 0 0
v_min_non_paid_invoice 5660 5660 0 0.00 0 13690 18 0 0
v_min_non_paid_realizn 7024 7024 0 0.00 0 7604 13 0 0
v_our_firm 10328 10328 0 0.00 0 14 1 0 0
v_reserve_write_off 19874 19874 0 0.00 0 11941 10 0 0
x_lock_depdocs_on_canc_invoice 11654 11654 0 0.00 0 9289 119 0 0
x_lock_depdocs_on_canc_sup_ord 403 403 0 0.00 0 12835 451 0 0
x_make_qstorno_1000_1200 5385 5385 0 0.00 2 70228 1444 0 0
x_make_qstorno_1200_2000 5553 5553 0 0.00 4 87896 3584 0 0
x_make_qstorno_2100_3300 454352 454352 0 0.00 0 48414 171 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-12-2021 13:38:02 to 29-12-2021 13:38:06.

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
11:07:14 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:14 21414 8192000000 9721030064 10018394112 796089888 8.857 100 92 184 0 826926880 7675696 23161296
11:09:46 21283 8192000000 9764267248 10070671360 838258752 9.282 100 89 178 0 871816688 12277616 21280320
11:11:11 15519 8192000000 9802858080 10120081408 868099536 9.581 100 87 174 1 915622688 27231744 20291408
11:12:33 15377 8192000000 9862136256 10194112512 916206000 10.059 100 82 164 0 969186832 35419248 17561584
11:13:50 7839 8192000000 9908842528 10251886592 960873840 10.498 100 84 167 0 1025595040 47328512 17392688
11:15:00 5224 8192000000 9967215280 10322092032 995943376 10.839 100 82 164 0 1086563344 73341248 17278720
11:16:08 3038 8192000000 9993511072 10361184256 1024544848 11.116 100 77 152 1 1110146000 69849680 15751472
11:17:13 1467 8192000000 10025756496 10392846336 1050513936 11.366 100 90 180 0 1137189760 67894816 18781008
11:18:16 6674 8192000000 10043670176 10419744768 1066948064 11.523 100 72 146 2 1157707248 76540784 14218400
11:19:24 8239 8192000000 9998009024 10394894336 1074220384 11.592 100 28 56 1 1128653360 48514704 5918272
11:20:32 11911 8192000000 9984963296 10380804096 1083651776 11.682 100 41 80 1 1105902896 14193376 8057744
11:21:44 14814 8192000000 10030155168 10447302656 1089225312 11.735 100 33 66 0 1140331440 44432016 6674112
11:23:00 4809 8192000000 10011965968 10417098752 1093401696 11.775 100 34 67 1 1121329120 20558096 7369328
11:24:07 6291 8192000000 10014764688 10411483136 1102022448 11.857 100 44 88 0 1131403920 19077248 10304224
11:25:14 9848 8192000000 10007736928 10418888704 1105467760 11.889 100 31 62 2 1141633808 29667904 6498144
11:26:29 17669 8192000000 10004692320 10422099968 1094460976 11.785 99 17 34 0 1117668144 19613504 3593664
11:27:47 7193 8192000000 9994250816 10393464832 1064088272 11.496 98 42 84 2 1095863936 21899584 9876080
11:28:59 3781 8192000000 9976026096 10357911552 1061592000 11.472 100 39 73 4 1099329536 29779232 7958304
11:30:04 76197 8192000000 9948955632 10350428160 959870512 10.488 92 28 56 1 983825808 18035040 5920256
11:32:21 8556 8192000000 9955321840 10340151296 1013673312 11.011 98 37 72 3 1043334560 21130640 8530608
11:33:30 7427 8192000000 9968939024 10364477440 1050225792 11.363 100 47 94 1 1081290192 20449680 10614720
11:34:43 7294 8192000000 9985735984 10379116544 1048587216 11.347 99 32 64 3 1072599456 17029152 6983088
11:35:56 30236 8192000000 10004321280 10400956416 1063712432 11.492 100 43 84 1 1108725280 36069792 8943056
11:37:31 12582 8192000000 10016089808 10408157184 1084741968 11.693 100 63 126 1 1125590000 28536032 12312000
11:38:54 11124 8192000000 10076543296 10468937728 1103977024 11.875 100 55 110 0 1179830688 64366976 11486688
11:40:06 30246 8192000000 10075146448 10488918016 1116602464 11.995 100 38 76 0 1185079472 59957296 8519712
11:41:40 47238 8192000000 10102823216 10511826944 1128564784 12.108 100 35 69 0 1213136192 77386768 7184640
11:43:36 15566 8192000000 10171362624 10589134848 1140654384 12.222 100 50 100 2 1276886288 125084320 11147584
11:45:05 10145 8192000000 10190091840 10598199296 1151302896 12.322 100 61 122 1 1295406592 130857488 13246208
11:46:18 5970 8192000000 10201159296 10608414720 1157269984 12.378 100 65 130 1 1301835536 130168768 14396784
11:47:30 2786 8192000000 10261765104 10668318720 1161665040 12.419 100 61 122 0 1365564224 189598576 14300608
11:48:36 4617 8192000000 10263051840 10682650624 1175035648 12.544 100 58 116 0 1370670928 182299360 13335920
11:49:44 2796 8192000000 10306463088 10716762112 1185551616 12.642 100 63 127 0 1410941840 210538960 14851264
11:50:52 1753 8192000000 10328368624 10727129088 1164988720 12.450 100 83 167 1 1423596176 240465536 18141920
11:51:59 1845 8192000000 10368260960 10762436608 1153774080 12.345 100 82 161 3 1464903056 292627104 18501872
11:53:07 6394 8192000000 10339972576 10757181440 1149964160 12.309 100 70 138 0 1438517136 272050400 16502576
11:54:22 2154 8192000000 10362954176 10763051008 1162435488 12.426 100 86 171 1 1461795088 278880336 20479264
11:55:28 2421 8192000000 10383157392 10779152384 1158635920 12.390 100 87 174 0 1477854896 298775216 20443760
11:56:37 3687 8192000000 10409397920 10820399104 1162995200 12.431 100 74 146 1 1508173104 327106192 18071712
11:57:46 2471 8192000000 10466639216 10867269632 1174815712 12.542 100 90 178 1 1563866000 367544080 21506208
11:58:53 6351 8192000000 10438553872 10863464448 1161099024 12.414 100 46 91 0 1543177968 370341120 11737824
12:00:17 15748 8192000000 10427029264 10862948352 1149341216 12.303 100 36 72 0 1530984896 372124752 9518928
12:01:44 14010 8192000000 10450606320 10899566592 1142501792 12.239 99 30 60 1 1543153504 392148912 8502800
12:03:04 24263 8192000000 10371216592 10877251584 1125305392 12.077 99 26 52 0 1515972160 383418256 7248512
12:04:33 17194 8192000000 10325798032 10781683712 1126555360 12.089 100 17 34 0 1444261552 312837040 4869152
12:05:52 29092 8192000000 10025125744 10473484288 1061990256 11.476 95 12 24 0 1102721680 37686432 3044992
12:07:22 41509 8192000000 10047648592 10455867392 1078341136 11.632 97 32 62 1 1117074880 32788096 5945648
12:09:05 53941 8192000000 10116572480 10604765184 1116802576 11.997 100 18 36 1 1224556320 103876192 3877552
12:11:30 166837 8192000000 10056806928 10480898048 1069888480 11.551 95 38 77 1 1120336448 42266544 8181424
12:16:26 27768 8192000000 10093307584 10523856896 1122649232 12.052 100 46 92 3 1180112992 48364208 9099552
12:17:56 27513 8192000000 10114991808 10540974080 1131911824 12.139 100 56 111 0 1215107440 71776960 11418656
12:19:24 27828 8192000000 10095695840 10555424768 1130722944 12.128 99 12 24 0 1188424928 55057600 2644384
12:20:54 46127 8192000000 10082521776 10559098880 1140459024 12.220 100 12 24 0 1184110320 40668976 2982320
12:22:41 79290 8192000000 10102959216 10564567040 1144337232 12.256 100 11 22 0 1200309888 53430096 2542560
12:27:19 21298 8192000000 10076785296 10520055808 1138036704 12.197 99 29 58 2 1178833520 34664000 6132816
12:28:41 54334 8192000000 10079002032 10518687744 1131425920 12.135 99 11 22 0 1150263344 16736368 2101056
12:30:47 79544 8192000000 10103663536 10564907008 1125287792 12.077 98 10 20 0 1175282256 47615360 2379104
12:33:07 43767 8192000000 10112944000 10575585280 1138780368 12.204 99 14 28 1 1213397808 71917216 2700224
12:35:00 46879 8192000000 10101232720 10554761216 1134734400 12.166 99 16 32 0 1181568768 43599584 3234784
12:36:53 88238 8192000000 10072099424 10579345408 1092452608 11.766 96 11 22 0 1182316832 87519744 2344480
12:40:28 20158 8192000000 10079118608 10511474688 1117154224 12.000 99 33 66 0 1169225328 45734064 6337040
12:41:48 49679 8192000000 10048427952 10513063936 1108518848 11.918 99 14 28 0 1149444016 37604416 3320752
12:43:51 77316 8192000000 10074992000 10519314432 1113266240 11.963 98 11 22 1 1166314944 50372832 2675872
12:46:22 71129 8192000000 10082335600 10568232960 1118729632 12.015 99 15 30 0 1189832912 67647392 3455888
12:48:38 63535 8192000000 10104288720 10536747008 1087004912 11.714 96 15 30 1 1150882720 60651104 3226704
12:50:48 57699 8192000000 10079782336 10535424000 1098719488 11.825 98 13 26 1 1158061536 56699552 2642496
12:52:46 49808 8192000000 10087536224 10530324480 1088236688 11.726 97 34 66 0 1165471056 69868576 7365792
12:54:42 22650 8192000000 10096676784 10523602944 1121264304 12.039 100 43 86 1 1182528608 52857184 8407120
12:56:06 74563 8192000000 10091395216 10607886336 1073281280 11.583 95 26 52 0 1175711680 97194064 5236336
12:58:23 62509 8192000000 10071956048 10542907392 1065033216 11.505 95 12 23 2 1113892320 46487184 2371920
13:00:27 114449 8192000000 10055563072 10492354560 1090730064 11.750 98 20 40 0 1149502640 54845808 3926768
13:03:22 92842 8192000000 10063841904 10504597504 1103881104 11.874 99 14 25 0 1145948784 39587456 2480224
13:05:55 61082 8192000000 10068246640 10548531200 1125812464 12.082 100 15 30 0 1188311424 59231776 3267184
13:07:56 44064 8192000000 10057360960 10519916544 1123806320 12.063 99 12 24 0 1164393392 37864208 2722864
13:09:41 47837 8192000000 10061750336 10505338880 1101553296 11.852 97 16 32 1 1138286656 33467072 3266288
13:11:39 40108 8192000000 10084066144 10516750336 1111349024 11.945 98 26 52 1 1158300688 41508256 5443408
13:13:23 64220 8192000000 10106028960 10544369664 1120514160 12.032 98 28 56 0 1169853680 43961152 5378368
13:15:28 59879 8192000000 10125438576 10576568320 1135790288 12.176 99 34 68 0 1184962704 42426448 6745968
13:17:28 62736 8192000000 10088566112 10600476672 1132914896 12.149 99 11 22 0 1221919840 87044800 1960144
13:19:31 49549 8192000000 10094450512 10550464512 1140494384 12.220 99 25 49 1 1198222624 52645056 5083184
13:21:21 115808 8192000000 10059750400 10507689984 1136577952 12.183 99 20 40 0 1207480400 66792208 4110240
13:24:27 51037 8192000000 10073031824 10527993856 1123382384 12.059 98 10 20 0 1151215616 25829472 2003760
13:26:31 80034 8192000000 10120431408 10570649600 1137952016 12.196 99 17 34 1 1196662352 55038240 3672096
13:28:57 71526 8192000000 10106481056 10574921728 1106755008 11.902 97 15 30 0 1190280400 80222832 3302560
13:31:21 48730 8192000000 10118158896 10551484416 1132364336 12.144 99 34 67 2 1189653376 49701840 7587200
13:33:14 61332 8192000000 10104677520 10556059648 1118064576 12.009 98 25 50 2 1206556528 82343760 6148192
13:35:29 76902 8192000000 10053586480 10479124480 1063713360 11.492 95 17 34 0 1101869664 34884384 3271920

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 29-12-2021 13:38:08 to 29-12-2021 13:38:23.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 131 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 29-12-2021 13:38:24 to 29-12-2021 13:38:26.

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          233708
MON$OLDEST_ACTIVE               236006
MON$OLDEST_SNAPSHOT             236006
MON$NEXT_TRANSACTION            236006
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                       2061216
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             799
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 ---
:72: 
:73: mscs-test-161.budzdorov.ru    Wed Dec 29 13:37:11 2021
:74:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:75: 
:76: 
:77: mscs-test-161.budzdorov.ru    Wed Dec 29 13:37:12 2021
:78:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:79: 
:80: 
:81: mscs-test-161.budzdorov.ru    Wed Dec 29 13:37:15 2021
:82:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:83: 
:84: 
:85: mscs-test-161.budzdorov.ru    Wed Dec 29 13:37:15 2021
:86:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:87: 
--- end of diff output ---

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

29.12.21 13:38:27. 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 13:38:29 No severe PSQL-related problems occured
29.12.21 13:38:30. End of report.