Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.01.2022 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 16346412 kB
MemAvailable 31298980 kB
Buffers 0 kB
Cached 15318044 kB
SwapCached 1220 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 2022-01-08 13:37:12
DTS_END                         2022-01-08 13:37:12.4070
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/55734
TRN_ID                          224783
ATT_ID                          599
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: 9153.18 17486 1098382
customer order: creation 180.68 490 21681
customer order: refuse 15.13 410 1816
order to supplier: creation 47.84 1342 5741
order to supplier: removal 3.32 165888 398
invoice (draft): creation 46.82 3188 5618
invoice (draft): removal 50.23 990 6028
invoice accept: apply 42.73 24793 5127
invoice accept: cancel 45.83 1255 5499
customer reserve: creation 3925.15 237 471018
customer reserve: removal 4496.99 96 539639
realization accept: apply 86.07 275 10328
realization accept: cancel 77.85 168 9342
payment from customer: creation 61.01 183 7321
payment from customer: removal 6.04 113 725
payment to supplier: creation 50.63 200 6076
payment to supplier: removal 3.88 138 465
service: total inventory turnovers 4.26 3609 511
service: total monetary turnovers 7.72 297 926
service: aggregate perf. data 0.98 15713 118
service: refresh index statistics 0.04 130342 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-01-2022 13:38:06 to 08-01-2022 13:38:08.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9425
TEST_TIME 2 7158
TEST_TIME 3 6347
TEST_TIME 4 6358
TEST_TIME 5 5898
TEST_TIME 6 5929
TEST_TIME 7 5798
TEST_TIME 8 6177
TEST_TIME 9 6268
TEST_TIME 10 6239
TEST_TIME 11 6253
TEST_TIME 12 6406
TEST_TIME 13 6541
TEST_TIME 14 6739
TEST_TIME 15 6837
TEST_TIME 16 7005
TEST_TIME 17 7152
TEST_TIME 18 7281
TEST_TIME 19 7293
TEST_TIME 20 7930
TEST_TIME 21 7840
TEST_TIME 22 7764
TEST_TIME 23 8320
TEST_TIME 24 8354
TEST_TIME 25 8258
TEST_TIME 26 8769
TEST_TIME 27 9003
TEST_TIME 28 9390
TEST_TIME 29 9899
TEST_TIME 30 10325
TEST_TIME 31 11148
TEST_TIME 32 11251
TEST_TIME 33 11185
TEST_TIME 34 11045
TEST_TIME 35 10843
TEST_TIME 36 10727
TEST_TIME 37 10692
TEST_TIME 38 10694
TEST_TIME 39 10556
TEST_TIME 40 10411
TEST_TIME 41 10370
TEST_TIME 42 10278
TEST_TIME 43 10211
TEST_TIME 44 10204
TEST_TIME 45 10128
TEST_TIME 46 10016
TEST_TIME 47 10096
TEST_TIME 48 9936
TEST_TIME 49 9762
TEST_TIME 50 9827
TEST_TIME 51 9784
TEST_TIME 52 9878
TEST_TIME 53 9889
TEST_TIME 54 9831
TEST_TIME 55 9916
TEST_TIME 56 9957
TEST_TIME 57 9881
TEST_TIME 58 9958
TEST_TIME 59 9896
TEST_TIME 60 10009
TEST_TIME 61 9942
TEST_TIME 62 9982
TEST_TIME 63 9937
TEST_TIME 64 9908
TEST_TIME 65 9805
TEST_TIME 66 9781
TEST_TIME 67 9702
TEST_TIME 68 9637
TEST_TIME 69 9554
TEST_TIME 70 9555
TEST_TIME 71 9520
TEST_TIME 72 9474
TEST_TIME 73 9463
TEST_TIME 74 9427
TEST_TIME 75 9417
TEST_TIME 76 9466
TEST_TIME 77 9423
TEST_TIME 78 9462
TEST_TIME 79 9449
TEST_TIME 80 9397
TEST_TIME 81 9456
TEST_TIME 82 9458
TEST_TIME 83 9404
TEST_TIME 84 9351
TEST_TIME 85 9364
TEST_TIME 86 9301
TEST_TIME 87 9244
TEST_TIME 88 9218
TEST_TIME 89 9199
TEST_TIME 90 9172
TEST_TIME 91 9152
TEST_TIME 92 9227
TEST_TIME 93 9216
TEST_TIME 94 9215
TEST_TIME 95 9206
TEST_TIME 96 9204
TEST_TIME 97 9222
TEST_TIME 98 9226
TEST_TIME 99 9426
TEST_TIME 100 9440
TEST_TIME 101 9420
TEST_TIME 102 9394
TEST_TIME 103 9336
TEST_TIME 104 9373
TEST_TIME 105 9317
TEST_TIME 106 9268
TEST_TIME 107 9218
TEST_TIME 108 9182
TEST_TIME 109 9209
TEST_TIME 110 9248
TEST_TIME 111 9205
TEST_TIME 112 9203
TEST_TIME 113 9176
TEST_TIME 114 9140
TEST_TIME 115 9189
TEST_TIME 116 9215
TEST_TIME 117 9262
TEST_TIME 118 9242
TEST_TIME 119 9222
TEST_TIME 120 9172

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 08-01-2022 13:38:10 to 08-01-2022 13:38:13.

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 1533050 1533050 0 0.00 0 17164 14 0 0
doc_list_biud 1533050 1533050 0 0.00 0 34287 39 0 0
sp_add_invoice_to_stock 5203 5127 76 1.46 34 187305 24794 0 76
sp_cancel_adding_invoice 5499 5499 0 0.00 5 120422 1256 0 0
sp_cancel_client_order 1816 1816 0 0.00 5 33281 411 0 0
sp_cancel_customer_reserve 539639 539639 0 0.00 1 34292 97 0 0
sp_cancel_pay_from_customer 725 725 0 0.00 4 4469 113 0 0
sp_cancel_pay_to_supplier 465 465 0 0.00 4 5796 139 0 0
sp_cancel_supplier_invoice 6032 6028 4 0.06 2 15396 991 0 4
sp_cancel_supplier_order 398 398 0 0.00 15 1589646 165889 0 0
sp_cancel_write_off 9342 9342 0 0.00 2 15242 168 0 0
sp_client_order 21681 21681 0 0.00 6 20568 491 0 0
sp_customer_reserve 471018 471018 0 0.00 0 108566 238 0 0
sp_fill_shopping_cart 33040 33040 0 0.00 1 18269 82 0 0
sp_fill_shopping_cart_clo_res 470966 470966 0 0.00 0 42630 20 0 0
sp_get_clo_for_invoice 5075 5075 0 0.00 0 16723 296 0 0
sp_kill_cost_storno 16031 16031 0 0.00 0 6613 66 0 0
sp_kill_qstorno_ret_qs2qd 1095046 1095046 0 0.00 0 34284 42 0 0
sp_kill_qty_storno 578125 578125 0 0.00 0 34285 97 0 0
sp_lock_selected_doc 21391 21391 0 0.00 0 8874 9 0 0
sp_make_cost_storno 28800 28800 0 0.00 0 17155 77 0 0
sp_multiply_rows_for_pdistr 28800 28800 0 0.00 0 8340 38 0 0
sp_multiply_rows_for_qdistr 21681 21681 0 0.00 0 13060 207 0 0
sp_pay_from_customer 7321 7321 0 0.00 6 15301 184 0 0
sp_pay_to_supplier 6076 6076 0 0.00 5 13612 201 0 0
sp_payment_common 13397 13397 0 0.00 2 8047 49 0 0
sp_qd_handle_on_cancel_clo 1816 1816 0 0.00 0 33271 330 0 0
sp_qd_handle_on_invoice_upd_sts 10574 10574 0 0.00 0 21585 498 0 0
sp_qd_handle_on_reserve_upd_sts 10328 10328 0 0.00 0 22430 65 0 0
sp_reserve_write_off 10328 10328 0 0.00 8 22599 276 0 0
sp_supplier_invoice 5618 5618 0 0.00 10 92212 3189 0 0
sp_supplier_order 5741 5741 0 0.00 7 27360 1342 0 0
srv_aggregate_perf_data 118 118 0 0.00 2104 140812 15713 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 760 148321 37063 0 0
srv_find_qd_qs_mism 1048320 1048320 0 0.00 0 11550 11 0 0
srv_make_invnt_saldo 511 511 0 0.00 94 103133 3609 0 0
srv_make_money_saldo 926 926 0 0.00 1 13655 297 0 0
srv_recalc_idx_stat 5 5 0 0.00 44828 228054 130342 0 0
t$perf-abend:sp_add_invoice_to_stock 76 76 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5127 5127 0 0.00 1 18239 875 0 0
t$perf-norm:sp_cancel_adding_invoice 422 422 0 0.00 1 6311 445 0 0
t$perf-norm:sp_cancel_client_order 1816 1816 0 0.00 0 1643 25 0 0
t$perf-norm:sp_cancel_customer_reserve 706 706 0 0.00 0 5226 32 0 0
t$perf-norm:sp_cancel_pay_from_customer 725 725 0 0.00 0 1015 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 465 465 0 0.00 0 762 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 408 408 0 0.00 0 591 30 0 0
t$perf-norm:sp_cancel_supplier_order 398 398 0 0.00 0 114987 7268 0 0
t$perf-norm:sp_cancel_write_off 1048 1048 0 0.00 0 1911 19 0 0
t$perf-norm:sp_client_order 21681 21681 0 0.00 0 11860 34 0 0
t$perf-norm:sp_customer_reserve 1010 1010 0 0.00 0 1114 13 0 0
t$perf-norm:sp_pay_from_customer 7321 7321 0 0.00 0 4809 17 0 0
t$perf-norm:sp_pay_to_supplier 6076 6076 0 0.00 0 4234 17 0 0
t$perf-norm:sp_reserve_write_off 10328 10328 0 0.00 0 17496 23 0 0
t$perf-norm:sp_supplier_invoice 5618 5618 0 0.00 0 4916 29 0 0
t$perf-norm:sp_supplier_order 5741 5741 0 0.00 0 4117 30 0 0
t$perf-norm:srv_aggregate_perf_data 118 118 0 0.00 0 736 20 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 397 12 0 0
t$perf-norm:srv_make_invnt_saldo 511 511 0 0.00 0 1432 14 0 0
t$perf-norm:srv_make_money_saldo 926 926 0 0.00 0 1830 8 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 6 174 74 0 0
v_add_invoice_to_stock 10302 10150 152 1.47 0 6486 24 0 152
v_all_customers 39104 39104 0 0.00 0 97 4 0 0
v_all_suppliers 31382 31382 0 0.00 0 152 4 0 0
v_all_wares 43362 43362 0 0.00 0 96 4 0 0
v_cancel_adding_invoice 844 844 0 0.00 0 1327 14 0 0
v_cancel_client_order 3632 3632 0 0.00 0 6491 12 0 0
v_cancel_customer_prepayment 1450 1450 0 0.00 0 4454 13 0 0
v_cancel_customer_reserve 1412 1412 0 0.00 0 2221 13 0 0
v_cancel_payment_to_supplier 930 930 0 0.00 0 2328 12 0 0
v_cancel_supplier_invoice 824 816 8 0.97 0 580 19 0 8
v_cancel_supplier_order 796 796 0 0.00 0 941 14 0 0
v_cancel_write_off 2096 2096 0 0.00 0 7015 31 0 0
v_max_id_clo_ord 5741 5741 0 0.00 0 17913 8 0 0
v_max_id_clo_res 1010 1010 0 0.00 0 1981 12 0 0
v_max_id_ord_sup 5618 5618 0 0.00 0 4996 8 0 0
v_max_non_paid_invoice 1744 1744 0 0.00 0 127 7 0 0
v_max_non_paid_realizn 3994 3994 0 0.00 0 6606 11 0 0
v_min_id_clo_ord 5741 5741 0 0.00 0 4195 9 0 0
v_min_id_clo_res 1010 1010 0 0.00 0 9073 18 0 0
v_min_id_ord_sup 5618 5618 0 0.00 0 8278 12 0 0
v_min_non_paid_invoice 6076 6076 0 0.00 0 4922 18 0 0
v_min_non_paid_realizn 7321 7321 0 0.00 0 6083 19 0 0
v_our_firm 10912 10912 0 0.00 0 51 4 0 0
v_reserve_write_off 20656 20656 0 0.00 0 5191 11 0 0
x_lock_depdocs_on_canc_invoice 11119 11119 0 0.00 0 13215 112 0 0
x_lock_depdocs_on_canc_sup_ord 398 398 0 0.00 0 4776 351 0 0
x_make_qstorno_1000_1200 5741 5741 0 0.00 3 27333 1176 0 0
x_make_qstorno_1200_2000 5618 5618 0 0.00 5 91888 2960 0 0
x_make_qstorno_2100_3300 471018 471018 0 0.00 0 108562 206 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-01-2022 13:38:14 to 08-01-2022 13:38:18.

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:16 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:17 37692 8192000000 9722428320 10017746944 793290144 8.828 100 87 174 0 822741680 7982752 21468784
11:09:59 20634 8192000000 9757218400 10062921728 830627440 9.206 100 92 183 0 866000512 13424288 21948784
11:11:25 17740 8192000000 9800303536 10118369280 868082736 9.581 100 82 165 0 911767280 24512992 19171552
11:12:45 10993 8192000000 9884972320 10217504768 928425856 10.179 100 81 159 1 997329840 51562160 17341824
11:14:05 3350 8192000000 9930429088 10274979840 969014384 10.577 100 80 155 2 1043218960 57790960 16413616
11:15:11 2307 8192000000 9972876080 10328846336 1005151200 10.928 100 91 177 2 1089471936 66420800 17899936
11:16:16 1549 8192000000 10007904592 10371457024 1025605248 11.126 100 90 174 0 1128113600 85160480 17347872
11:17:19 750 8192000000 10022736368 10391511040 1047159936 11.333 100 97 188 1 1144275856 77142768 19973152
11:18:21 1551 8192000000 10030943360 10409906176 1062521776 11.481 100 90 177 0 1157964288 77334144 18108368
11:19:24 1113 8192000000 10044873616 10421727232 1067420944 11.527 100 96 183 0 1168197024 82182480 18593600
11:20:26 2922 8192000000 10043092656 10428198912 1077311792 11.622 100 82 164 0 1171995344 78254208 16429344
11:21:31 1200 8192000000 10041605472 10421899264 1082318688 11.670 100 95 181 0 1162440176 61284736 18836752
11:22:33 1256 8192000000 10077300592 10458402816 1090932144 11.752 100 100 190 1 1198932560 89156752 18843664
11:23:35 1499 8192000000 10054001072 10437554176 1092639168 11.768 100 87 168 0 1174921248 64698224 17583856
11:24:37 1165 8192000000 10076424224 10460217344 1097130304 11.810 100 96 181 1 1200788496 84102416 19555776
11:25:42 1286 8192000000 10044443648 10429743104 1101972272 11.856 100 96 187 0 1172634256 50320608 20341376
11:26:44 6366 8192000000 10041698544 10436456448 1106042128 11.895 100 63 126 0 1170258400 52018912 12197360
11:27:51 1624 8192000000 10053137824 10443231232 1098857840 11.827 99 76 144 2 1175079408 60434368 15787200
11:28:53 1059 8192000000 10055770224 10436034560 1096040464 11.800 100 96 182 3 1173798544 59595312 18162768
11:29:55 1395 8192000000 10039736240 10418262016 1074414640 11.594 99 88 164 4 1164729552 74303104 16011808
11:30:58 1288 8192000000 10025771872 10404859904 1061459472 11.470 99 88 162 1 1155313600 77208240 16645888
11:32:00 1247 8192000000 9985198176 10357760000 1033277088 11.200 99 93 181 2 1110404912 58363584 18764240
11:33:02 1307 8192000000 9998116736 10365984768 1035469152 11.221 100 97 186 0 1121157248 65878848 19809248
11:34:04 1170 8192000000 10019951744 10390884352 1041436672 11.278 100 97 190 1 1142551632 81988288 19126672
11:35:06 5918 8192000000 10012973056 10390052864 1047716128 11.339 100 57 111 0 1141418784 82028672 11673984
11:36:13 995 8192000000 9973390320 10345201664 1035324336 11.220 100 84 168 2 1093054752 41341104 16389312
11:37:16 1279 8192000000 9973584400 10343489536 1038904608 11.254 100 94 177 2 1098631520 40457776 19269136
11:38:23 3892 8192000000 10006531920 10386710528 1062950416 11.485 100 72 128 0 1126812256 52359056 11502784
11:39:42 4989 8192000000 10035490912 10416553984 1073454208 11.585 100 66 123 0 1145534256 60256896 11823152
11:40:53 1344 8192000000 10086104528 10471014400 1097904160 11.818 100 77 139 1 1201415392 89775072 13736160
11:42:01 15373 8192000000 10113442416 10508369920 1116492640 11.994 100 74 142 3 1228473696 96410864 15570192
11:43:20 1358 8192000000 10182180800 10566557696 1128471696 12.107 100 85 157 3 1298105328 152766304 16867328
11:44:26 6327 8192000000 10209845600 10608963584 1139607008 12.212 100 67 132 2 1323591920 169214592 14770320
11:45:35 1316 8192000000 10253265984 10652700672 1143432224 12.248 100 94 184 5 1368146688 204640624 20073840
11:46:39 1358 8192000000 10273825280 10670641152 1153416752 12.342 100 92 184 0 1391467920 217821472 20229696
11:47:47 850 8192000000 10300564688 10696531968 1157694592 12.382 100 89 175 0 1410011584 233172576 19144416
11:48:51 788 8192000000 10350798720 10750742528 1169498528 12.492 100 84 164 1 1456358976 269237952 17622496
11:49:56 1858 8192000000 10366918208 10768031744 1172304592 12.518 100 91 167 2 1476264240 284922784 19036864
11:51:01 916 8192000000 10386289856 10800902144 1181339424 12.603 100 99 188 0 1499541648 295596112 22606112
11:52:04 850 8192000000 10425274608 10833915904 1181503872 12.604 100 95 180 1 1532160640 330451408 20205360
11:53:09 734 8192000000 10447203152 10859786240 1178830720 12.579 100 97 187 1 1555645072 355511936 21302416
11:54:13 890 8192000000 10442690768 10855804928 1172228352 12.518 99 96 190 0 1549352864 354830208 22294304
11:55:17 822 8192000000 10494043856 10905456640 1192571264 12.707 100 100 195 2 1606405792 390167536 23666992
11:56:20 836 8192000000 10485660480 10953711616 1188557424 12.670 100 96 189 1 1594583776 383476064 22550288
11:57:25 1445 8192000000 10521505424 10955272192 1196445584 12.743 100 89 169 2 1628242896 411270592 20526720
11:58:28 2872 8192000000 10567686832 10989117440 1206426912 12.836 100 63 126 0 1668813120 446527776 15858432
11:59:36 953 8192000000 10553843744 11022635008 1196099488 12.740 100 91 181 1 1660573200 442365920 22107792
12:00:41 773 8192000000 10560877856 11005538304 1190061264 12.684 99 85 164 2 1665403856 455978224 19364368
12:01:47 1703 8192000000 10583189776 11020718080 1171604112 12.512 98 66 133 1 1683403648 495267760 16531776
12:03:04 3395 8192000000 10585819712 11082907648 1182191088 12.611 100 59 118 0 1685202656 487548400 15463168
12:04:16 7586 8192000000 10553244608 11009011712 1178756304 12.579 100 50 99 1 1654827152 463185024 12885824
12:05:27 18808 8192000000 10471840128 10975342592 1183416160 12.622 100 34 67 1 1595805072 403869120 8519792
12:06:47 30572 8192000000 10411597328 10905391104 1172938528 12.524 100 27 54 2 1511544880 331208272 7398080
12:08:44 41102 8192000000 10123218624 10569605120 1169549632 12.493 100 18 35 1 1264121744 90132096 4440016
12:10:26 80860 8192000000 10140706208 10602459136 1128641104 12.109 97 21 41 0 1199146112 65947360 4557648
12:12:48 126776 8192000000 10129561984 10616594432 1153829584 12.345 100 24 46 1 1241616592 82798352 4988656
12:16:07 89149 8192000000 10093447824 10523111424 1127153136 12.095 99 33 67 1 1164686976 31310192 6223648
12:18:37 38575 8192000000 10068195280 10499248128 1090204848 11.745 96 35 70 1 1130896608 33583360 7108400
12:20:17 16156 8192000000 10088169328 10524418048 1121758624 12.044 100 41 78 1 1187217456 57578080 7880752
12:21:35 92470 8192000000 10113744880 10567032832 1115479280 11.984 98 17 34 1 1192108800 73032752 3596768
12:24:08 17356 8192000000 10108687808 10566791168 1127606208 12.099 100 30 58 1 1199299664 65323024 6370432
12:25:30 76273 8192000000 10108885408 10556674048 1122752944 12.053 99 17 34 0 1182572640 56151088 3668608
12:27:48 97032 8192000000 10099884144 10590072832 1097616256 11.815 96 20 40 0 1190362288 88316048 4429984
12:30:25 46738 8192000000 10108896128 10553401344 1122541488 12.051 98 25 49 1 1193491952 65430224 5520240
12:32:40 52933 8192000000 10089595648 10535202816 1110539168 11.938 97 14 28 0 1188719024 74928384 3251472
12:34:35 17793 8192000000 10144896960 10580799488 1142021632 12.235 100 61 121 2 1232376640 78147392 12207616
12:35:56 50078 8192000000 10131611936 10577252352 1122967504 12.055 98 37 65 0 1205533344 75535472 7030368
12:37:49 16859 8192000000 10075387920 10512330752 1124905808 12.073 98 40 78 1 1167194816 33950896 8338112
12:39:09 43066 8192000000 10178079440 10644168704 1134972096 12.168 99 40 80 1 1247721536 104604912 8144528
12:40:54 61321 8192000000 10050444976 10510712832 1113825088 11.969 99 25 49 1 1151520896 32382448 5313360
12:43:23 26638 8192000000 10032351056 10478669824 1113762816 11.968 99 22 43 0 1151460432 33208528 4489088
12:45:00 46366 8192000000 10054812576 10512031744 1117211648 12.001 99 11 23 0 1144784992 24845472 2727872
12:46:48 101164 8192000000 10061659312 10553425920 1124561472 12.070 99 13 26 0 1170525872 43244736 2719664
12:49:29 79953 8192000000 10059628016 10510536704 1064733072 11.502 94 22 44 1 1095774320 26172320 4868928
12:51:56 34769 8192000000 10115651488 10569179136 1131554480 12.136 100 32 64 1 1210409424 71910064 6944880
12:53:33 58256 8192000000 10076796240 10522456064 1112197280 11.953 98 30 59 3 1182029232 63061264 6770688
12:56:02 58795 8192000000 10081266144 10501394432 1097246752 11.812 97 44 86 1 1160573776 54693744 8633280
12:58:03 29941 8192000000 10080080144 10540421120 1130045344 12.122 100 34 68 0 1204717344 67737824 6934176
12:59:33 45499 8192000000 10090079312 10544242688 1111151872 11.943 98 30 60 1 1173414960 56330880 5932208
13:02:35 61056 8192000000 10045940368 10549456896 1110888352 11.941 99 7 14 0 1155955680 43632960 1434368
13:04:41 47798 8192000000 10064977472 10523078656 1098831584 11.827 97 10 18 1 1135483904 34909280 1743040
13:06:34 37151 8192000000 10078513200 10513981440 1092716480 11.768 98 34 68 0 1164783648 64425792 7641376
13:08:12 35865 8192000000 10130361280 10564718592 1087410144 11.718 97 33 66 0 1185993248 92055952 6527152
13:09:54 63640 8192000000 10088207184 10522443776 1084793520 11.693 97 17 34 1 1146281792 58125024 3363248
13:12:03 46671 8192000000 10143737984 10573537280 1115482304 11.984 98 32 64 2 1219523440 96813648 7227488
13:13:51 57902 8192000000 10219737024 10687442944 1124778944 12.072 98 23 46 2 1287813008 157530384 5503680
13:15:51 79096 8192000000 10107953152 10625826816 1127435888 12.097 99 17 34 0 1280146208 148706448 4003872
13:18:13 42346 8192000000 10093382944 10520055808 1103435824 11.870 97 34 63 0 1169124928 59109776 6579328
13:19:57 148321 8192000000 10088097568 10599346176 1127015808 12.093 99 20 40 1 1217459360 85755104 4688448
13:23:27 87719 8192000000 10140208736 10615992320 1150840816 12.317 100 15 30 0 1204655360 50807008 3007536
13:25:55 56047 8192000000 10139620560 10613432320 1141832544 12.233 100 16 31 0 1235943808 90275248 3836016
13:28:02 61884 8192000000 10062830592 10509086720 1098667120 11.825 97 18 36 1 1128285104 25775168 3842816
13:30:04 39955 8192000000 10112702000 10545471488 1117033456 11.999 98 35 70 2 1189519056 64015936 8469664
13:31:49 70427 8192000000 10117948752 10579968000 1136880256 12.186 100 26 52 1 1250061248 106876672 6304320
13:33:59 62302 8192000000 10083309456 10576809984 1119210128 12.020 99 17 33 0 1202581264 79712512 3658624
13:36:07 36157 8192000000 10125776560 10569469952 1132176816 12.142 100 43 86 0 1218035856 77153664 8705376

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 08-01-2022 13:38:20 to 08-01-2022 13:38:36.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 76 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 08-01-2022 13:38:37 to 08-01-2022 13:38:39.

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          221771
MON$OLDEST_ACTIVE               224972
MON$OLDEST_SNAPSHOT             224972
MON$NEXT_TRANSACTION            224972
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                       2048096
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             782
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2704 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2704 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2704 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2704 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 ---
:184: 
:185: mscs-test-161.budzdorov.ru    Sat Jan  8 13:37:15 2022
:186:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:187: 
:188: 
:189: mscs-test-161.budzdorov.ru    Sat Jan  8 13:37:17 2022
:190:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:191: 
:192: 
:193: mscs-test-161.budzdorov.ru    Sat Jan  8 13:37:23 2022
:194:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:195: 
--- end of diff output ---

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

08.01.22 13:38:40. 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-01-08 13:38:43 No severe PSQL-related problems occured
08.01.22 13:38:44. End of report.