Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 29.12.2021 10: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 16207900 kB
MemAvailable 31335704 kB
Buffers 0 kB
Cached 15432240 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 10:37:09
DTS_END                         2021-12-29 10:37:09.5920
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/40092
TRN_ID                          231365
ATT_ID                          659
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: 8951.77 23175 1074212
customer order: creation 165.11 403 19813
customer order: refuse 14.73 410 1767
order to supplier: creation 44.48 1473 5337
order to supplier: removal 3.28 122514 394
invoice (draft): creation 44.53 3614 5344
invoice (draft): removal 51.62 1006 6194
invoice accept: apply 40.58 17887 4869
invoice accept: cancel 46.38 1007 5566
customer reserve: creation 3714.63 175 445756
customer reserve: removal 4544.90 70 545388
realization accept: apply 80.84 220 9701
realization accept: cancel 75.73 127 9088
payment from customer: creation 57.68 112 6922
payment from customer: removal 5.79 82 695
payment to supplier: creation 47.32 122 5678
payment to supplier: removal 3.48 95 418
service: total inventory turnovers 3.58 4119 429
service: total monetary turnovers 5.99 638 719
service: aggregate perf. data 1.08 13026 130
service: refresh index statistics 0.03 296400 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 29-12-2021 10:38:09 to 29-12-2021 10:38:11.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7253
TEST_TIME 2 7454
TEST_TIME 3 7245
TEST_TIME 4 6600
TEST_TIME 5 6312
TEST_TIME 6 6169
TEST_TIME 7 6057
TEST_TIME 8 6405
TEST_TIME 9 6667
TEST_TIME 10 6668
TEST_TIME 11 7382
TEST_TIME 12 7924
TEST_TIME 13 8404
TEST_TIME 14 8334
TEST_TIME 15 8614
TEST_TIME 16 8870
TEST_TIME 17 8942
TEST_TIME 18 8872
TEST_TIME 19 8854
TEST_TIME 20 8738
TEST_TIME 21 8891
TEST_TIME 22 9191
TEST_TIME 23 9702
TEST_TIME 24 9551
TEST_TIME 25 9700
TEST_TIME 26 9819
TEST_TIME 27 10250
TEST_TIME 28 10768
TEST_TIME 29 11239
TEST_TIME 30 11633
TEST_TIME 31 11366
TEST_TIME 32 11371
TEST_TIME 33 11542
TEST_TIME 34 11657
TEST_TIME 35 11440
TEST_TIME 36 11127
TEST_TIME 37 11037
TEST_TIME 38 10872
TEST_TIME 39 10798
TEST_TIME 40 10568
TEST_TIME 41 10513
TEST_TIME 42 10488
TEST_TIME 43 10389
TEST_TIME 44 10236
TEST_TIME 45 10127
TEST_TIME 46 10109
TEST_TIME 47 10113
TEST_TIME 48 9993
TEST_TIME 49 9938
TEST_TIME 50 9878
TEST_TIME 51 9823
TEST_TIME 52 9755
TEST_TIME 53 9604
TEST_TIME 54 9580
TEST_TIME 55 9532
TEST_TIME 56 9588
TEST_TIME 57 9463
TEST_TIME 58 9390
TEST_TIME 59 9339
TEST_TIME 60 9209
TEST_TIME 61 9185
TEST_TIME 62 9232
TEST_TIME 63 9217
TEST_TIME 64 9181
TEST_TIME 65 9198
TEST_TIME 66 9190
TEST_TIME 67 9199
TEST_TIME 68 9093
TEST_TIME 69 9299
TEST_TIME 70 9339
TEST_TIME 71 9395
TEST_TIME 72 9349
TEST_TIME 73 9480
TEST_TIME 74 9553
TEST_TIME 75 9507
TEST_TIME 76 9402
TEST_TIME 77 9344
TEST_TIME 78 9306
TEST_TIME 79 9329
TEST_TIME 80 9259
TEST_TIME 81 9231
TEST_TIME 82 9190
TEST_TIME 83 9130
TEST_TIME 84 9073
TEST_TIME 85 9028
TEST_TIME 86 9044
TEST_TIME 87 9026
TEST_TIME 88 8982
TEST_TIME 89 8969
TEST_TIME 90 9020
TEST_TIME 91 9063
TEST_TIME 92 9078
TEST_TIME 93 9099
TEST_TIME 94 9126
TEST_TIME 95 9149
TEST_TIME 96 9152
TEST_TIME 97 9235
TEST_TIME 98 9268
TEST_TIME 99 9336
TEST_TIME 100 9342
TEST_TIME 101 9255
TEST_TIME 102 9209
TEST_TIME 103 9222
TEST_TIME 104 9199
TEST_TIME 105 9146
TEST_TIME 106 9152
TEST_TIME 107 9111
TEST_TIME 108 9061
TEST_TIME 109 9047
TEST_TIME 110 9002
TEST_TIME 111 8956
TEST_TIME 112 8950
TEST_TIME 113 8939
TEST_TIME 114 9012
TEST_TIME 115 9021
TEST_TIME 116 8987
TEST_TIME 117 8979
TEST_TIME 118 9007
TEST_TIME 119 9014
TEST_TIME 120 8954

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 29-12-2021 10:38:12 to 29-12-2021 10:38:16.

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 1487053 1487053 0 0.00 0 19299 7 0 0
doc_list_biud 1487053 1487053 0 0.00 0 67297 33 0 0
sp_add_invoice_to_stock 4962 4869 93 1.87 31 176507 17887 0 93
sp_cancel_adding_invoice 5566 5566 0 0.00 3 80590 1007 0 0
sp_cancel_client_order 1767 1767 0 0.00 4 29624 410 0 0
sp_cancel_customer_reserve 545388 545388 0 0.00 1 43466 70 0 0
sp_cancel_pay_from_customer 695 695 0 0.00 3 2270 82 0 0
sp_cancel_pay_to_supplier 418 418 0 0.00 3 2019 95 0 0
sp_cancel_supplier_invoice 6201 6194 7 0.11 2 67303 1006 0 7
sp_cancel_supplier_order 394 394 0 0.00 6 1011480 122515 0 0
sp_cancel_write_off 9088 9088 0 0.00 1 10769 127 0 0
sp_client_order 19813 19813 0 0.00 4 40419 403 0 0
sp_customer_reserve 445756 445756 0 0.00 0 44451 176 0 0
sp_fill_shopping_cart 30483 30483 0 0.00 1 12134 45 0 0
sp_fill_shopping_cart_clo_res 445750 445750 0 0.00 0 12148 16 0 0
sp_get_clo_for_invoice 4863 4863 0 0.00 0 17135 380 0 0
sp_kill_cost_storno 15767 15767 0 0.00 0 14076 62 0 0
sp_kill_qstorno_ret_qs2qd 1106452 1106452 0 0.00 0 67296 38 0 0
sp_kill_qty_storno 582961 582961 0 0.00 0 67297 83 0 0
sp_lock_selected_doc 20319 20319 0 0.00 0 8916 5 0 0
sp_make_cost_storno 27164 27164 0 0.00 0 19295 60 0 0
sp_multiply_rows_for_pdistr 27164 27164 0 0.00 0 12219 32 0 0
sp_multiply_rows_for_qdistr 19813 19813 0 0.00 0 11702 205 0 0
sp_pay_from_customer 6922 6922 0 0.00 4 15870 112 0 0
sp_pay_to_supplier 5678 5678 0 0.00 5 14382 123 0 0
sp_payment_common 12600 12600 0 0.00 1 9188 21 0 0
sp_qd_handle_on_cancel_clo 1767 1767 0 0.00 0 29596 378 0 0
sp_qd_handle_on_invoice_upd_sts 10429 10429 0 0.00 0 48840 407 0 0
sp_qd_handle_on_reserve_upd_sts 9701 9701 0 0.00 0 39474 89 0 0
sp_reserve_write_off 9701 9701 0 0.00 7 39641 220 0 0
sp_supplier_invoice 5344 5344 0 0.00 7 53753 3615 0 0
sp_supplier_order 5337 5337 0 0.00 6 40923 1474 0 0
srv_aggregate_perf_data 130 130 0 0.00 2332 107869 13026 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 792 239038 44291 0 0
srv_find_qd_qs_mism 1028156 1028156 0 0.00 0 14896 6 0 0
srv_make_invnt_saldo 429 429 0 0.00 45 102875 4119 0 0
srv_make_money_saldo 719 719 0 0.00 1 13278 639 0 0
srv_recalc_idx_stat 4 4 0 0.00 164874 580116 296400 0 0
t$perf-abend:sp_add_invoice_to_stock 93 93 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 4870 4870 0 0.00 0 15988 201 0 0
t$perf-norm:sp_cancel_adding_invoice 424 424 0 0.00 0 6698 132 0 0
t$perf-norm:sp_cancel_client_order 1767 1767 0 0.00 0 3248 19 0 0
t$perf-norm:sp_cancel_customer_reserve 640 640 0 0.00 0 2419 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 695 695 0 0.00 0 4727 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 418 418 0 0.00 0 2083 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 421 421 0 0.00 0 566 20 0 0
t$perf-norm:sp_cancel_supplier_order 394 394 0 0.00 0 17676 1618 0 0
t$perf-norm:sp_cancel_write_off 997 997 0 0.00 0 6724 15 0 0
t$perf-norm:sp_client_order 19813 19813 0 0.00 0 9526 24 0 0
t$perf-norm:sp_customer_reserve 963 963 0 0.00 0 2525 10 0 0
t$perf-norm:sp_pay_from_customer 6922 6922 0 0.00 0 20639 14 0 0
t$perf-norm:sp_pay_to_supplier 5678 5678 0 0.00 0 10391 18 0 0
t$perf-norm:sp_reserve_write_off 9701 9701 0 0.00 0 12666 17 0 0
t$perf-norm:sp_supplier_invoice 5344 5344 0 0.00 0 6475 21 0 0
t$perf-norm:sp_supplier_order 5337 5337 0 0.00 0 4786 19 0 0
t$perf-norm:srv_aggregate_perf_data 130 130 0 0.00 0 414 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 360 18 0 0
t$perf-norm:srv_make_invnt_saldo 429 429 0 0.00 0 2799 17 0 0
t$perf-norm:srv_make_money_saldo 719 719 0 0.00 0 4277 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 4 0 0
v_add_invoice_to_stock 9910 9724 186 1.87 0 9642 20 0 186
v_all_customers 36032 36032 0 0.00 0 22 1 0 0
v_all_suppliers 29512 29512 0 0.00 0 21 1 0 0
v_all_wares 39626 39626 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 848 848 0 0.00 0 7661 15 0 0
v_cancel_client_order 3534 3534 0 0.00 0 4162 6 0 0
v_cancel_customer_prepayment 1390 1390 0 0.00 0 1487 7 0 0
v_cancel_customer_reserve 1280 1280 0 0.00 0 8099 14 0 0
v_cancel_payment_to_supplier 836 836 0 0.00 0 1393 5 0 0
v_cancel_supplier_invoice 856 842 14 1.63 0 4201 19 0 14
v_cancel_supplier_order 788 788 0 0.00 0 4045 22 0 0
v_cancel_write_off 1994 1994 0 0.00 0 1967 16 0 0
v_max_id_clo_ord 5336 5336 0 0.00 0 470 2 0 0
v_max_id_clo_res 963 963 0 0.00 0 3068 15 0 0
v_max_id_ord_sup 5334 5334 0 0.00 0 7395 7 0 0
v_max_non_paid_invoice 1592 1592 0 0.00 0 5356 7 0 0
v_max_non_paid_realizn 3658 3658 0 0.00 0 1955 4 0 0
v_min_id_clo_ord 5336 5336 0 0.00 0 3151 7 0 0
v_min_id_clo_res 963 963 0 0.00 0 2035 10 0 0
v_min_id_ord_sup 5334 5334 0 0.00 0 5492 8 0 0
v_min_non_paid_invoice 5678 5678 0 0.00 0 8059 15 0 0
v_min_non_paid_realizn 6922 6922 0 0.00 0 9186 13 0 0
v_our_firm 10122 10122 0 0.00 0 19 1 0 0
v_reserve_write_off 19402 19402 0 0.00 0 8100 8 0 0
x_lock_depdocs_on_canc_invoice 11339 11339 0 0.00 0 25133 114 0 0
x_lock_depdocs_on_canc_sup_ord 394 394 0 0.00 0 22540 425 0 0
x_make_qstorno_1000_1200 5337 5337 0 0.00 2 40897 1385 0 0
x_make_qstorno_1200_2000 5344 5344 0 0.00 4 52571 3449 0 0
x_make_qstorno_2100_3300 445756 445756 0 0.00 0 44449 157 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-12-2021 10:38:17 to 29-12-2021 10:38:21.

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
08:07:13 3 8192000000 8797987392 8809009152 4149872 0.050 2 1 2 0 4249264 63616 35776
08:08:14 27127 8192000000 9724740736 10023825408 800201344 8.898 100 89 177 1 829680656 7814560 21664752
08:09:44 20990 8192000000 9770254416 10078117888 838797040 9.288 100 90 180 0 872388144 12598336 20992768
08:11:16 20042 8192000000 9812140112 10133110784 881114656 9.711 100 84 168 0 922522224 22547664 18859904
08:12:45 15704 8192000000 9887749488 10220855296 926996720 10.165 100 84 168 1 988435248 42970160 18468368
08:14:04 8905 8192000000 9939280480 10282663936 960071232 10.490 100 81 161 0 1048070144 71718736 16280176
08:15:15 3786 8192000000 9973562560 10328489984 1002725392 10.905 100 81 162 0 1086533376 67495776 16312208
08:16:21 2530 8192000000 9990832240 10353688576 1027758176 11.147 100 83 165 3 1111204848 66497456 16949216
08:17:25 1410 8192000000 10021183936 10391322624 1059191104 11.449 100 84 168 1 1129015488 53233584 16590800
08:18:27 1550 8192000000 10045216224 10418368512 1075546176 11.605 100 95 192 1 1154930192 59475312 19908704
08:19:29 4818 8192000000 10019108528 10404114432 1082588384 11.672 100 45 89 1 1138200288 46295488 9316416
08:20:34 1760 8192000000 10018987664 10399576064 1087423392 11.718 100 86 173 2 1124198768 17261744 19513632
08:21:37 4371 8192000000 10012373504 10413797376 1089245616 11.735 100 36 72 0 1118343232 21386000 7711616
08:22:42 8231 8192000000 10007674848 10419892224 1094733856 11.788 100 39 77 2 1128836576 24504096 9598624
08:23:54 8582 8192000000 10003863840 10416898048 1095916624 11.799 100 25 50 1 1121863296 20380352 5566320
08:25:06 61979 8192000000 10009353920 10427019264 1099381120 11.832 100 35 67 0 1122803376 16067568 7354688
08:27:08 6993 8192000000 10017431808 10409533440 1085640640 11.701 99 47 93 1 1116350256 20692432 10017184
08:28:15 12839 8192000000 9972565520 10375852032 1015006736 11.024 94 34 69 0 1042504160 19420912 8076512
08:29:32 6825 8192000000 9944927360 10329296896 1003919920 10.917 96 34 68 2 1033935552 22147392 7868240
08:30:41 43402 8192000000 9931429456 10326224896 968384608 10.571 95 40 80 1 991823360 14827280 8611472
08:32:27 5405 8192000000 9948054464 10316476416 982757504 10.711 96 58 116 0 1012570960 16992864 12820592
08:33:34 7426 8192000000 9946844128 10337300480 1034327760 11.210 99 32 62 1 1053097968 10575200 8195008
08:34:44 17752 8192000000 9975700368 10385289216 1061610288 11.472 100 27 53 0 1081145248 13069536 6465424
08:36:03 24167 8192000000 9993166672 10396721152 1065512432 11.509 100 24 49 1 1092612144 21860896 5238816
08:37:31 15485 8192000000 10015580224 10408857600 1091089888 11.753 100 49 94 0 1119568112 18529152 9949072
08:38:50 3086 8192000000 10053078944 10438696960 1107774848 11.911 100 64 127 2 1154411616 32658896 13977872
08:40:00 13805 8192000000 10095800592 10504867840 1128446944 12.107 100 57 113 0 1207971168 66940320 12583904
08:41:18 23519 8192000000 10129233024 10539442176 1142003056 12.234 100 47 94 0 1243218880 90909968 10305856
08:42:48 6958 8192000000 10163851408 10578378752 1151335504 12.322 100 49 98 0 1278815760 117308704 10171552
08:43:59 12642 8192000000 10181058288 10590117888 1157060224 12.376 100 50 96 1 1284968624 117418080 10490320
08:45:17 13004 8192000000 10247523728 10657853440 1174085200 12.535 100 70 141 1 1350654000 161309072 15259728
08:46:35 6119 8192000000 10243325056 10652753920 1182292432 12.612 100 55 111 1 1349759696 154734384 12732880
08:47:47 6851 8192000000 10305956608 10719289344 1175248800 12.546 100 71 139 1 1415186832 223944640 15993392
08:49:00 4566 8192000000 10272993920 10683117568 1155918672 12.365 100 87 173 0 1378666400 203297984 19449744
08:50:08 2358 8192000000 10250881152 10663264256 1170951552 12.506 100 80 159 2 1353310752 163790784 18568416
08:51:23 2400 8192000000 10263404544 10661691392 1163088864 12.432 100 89 174 1 1354632592 171869360 19674368
08:52:29 4688 8192000000 10253198608 10667327488 1131025824 12.131 98 67 132 4 1353659696 206928032 15705840
08:53:37 4665 8192000000 10294323968 10698264576 1143605056 12.249 100 65 129 0 1394109136 234666224 15837856
08:54:47 4384 8192000000 10312490000 10709450752 1144710864 12.260 100 79 158 3 1401275504 238953552 17611088
08:55:57 5817 8192000000 10331186592 10738782208 1139293824 12.209 99 62 124 0 1428946896 275384064 14269008
08:57:07 9367 8192000000 10362245536 10763399168 1130416208 12.125 100 59 110 2 1462296192 317979440 13900544
08:58:24 788 8192000000 10432849504 10844647424 1157183920 12.377 100 90 174 1 1523914480 346261840 20468720
08:59:30 2840 8192000000 10434339840 10868617216 1158663504 12.391 100 57 114 1 1529068320 355961536 14443280
09:00:56 14157 8192000000 10405027328 10840821760 1166042192 12.460 100 37 74 0 1501791568 326467232 9282144
09:02:23 14760 8192000000 10473219376 10938318848 1165102384 12.451 100 41 82 0 1570630112 394831088 10696640
09:03:40 10316 8192000000 10448731360 10933026816 1160537536 12.408 100 29 58 2 1556341472 388091632 7712304
09:04:53 19607 8192000000 10336018160 10834116608 1141081632 12.226 99 28 54 1 1457776432 310112160 6582640
09:06:15 35648 8192000000 10176679232 10707922944 1128271232 12.105 99 14 28 0 1280405712 147968784 4165696
09:07:52 90420 8192000000 10113812288 10578800640 1120921744 12.036 98 17 34 0 1191064816 66817600 3325472
09:10:23 50608 8192000000 10096097824 10647646208 1121556416 12.042 98 10 20 0 1229613264 105704592 2352256
09:12:23 79929 8192000000 10082933200 10534506496 1140691856 12.222 100 16 25 1 1187078896 44212288 2174752
09:14:45 40997 8192000000 10095365744 10537017344 1133163440 12.151 99 19 38 0 1170956160 33834640 3958080
09:16:28 39282 8192000000 10102918448 10571931648 1142676592 12.241 99 11 22 0 1198074448 53171152 2226704
09:18:23 76323 8192000000 10108065088 10565480448 1139605488 12.212 98 34 68 0 1191059392 43637984 7815920
09:20:40 52987 8192000000 10146745504 10592600064 1162643952 12.428 100 25 51 0 1224062416 55926640 5491824
09:22:48 83777 8192000000 10133634624 10583990272 1147433968 12.285 99 18 33 0 1215594112 64523536 3636608
09:25:23 84363 8192000000 10114237856 10572267520 1138676832 12.203 98 35 70 0 1192815600 46179280 7959488
09:28:15 44683 8192000000 10082446880 10529382400 1131826768 12.139 98 18 36 0 1175858000 40428208 3603024
09:30:04 55749 8192000000 10146084992 10581798912 1159676688 12.400 100 28 56 0 1203490592 38160496 5653408
09:32:04 73272 8192000000 10118605536 10585657344 1126773920 12.091 97 12 24 0 1224449840 94932128 2743792
09:34:19 87783 8192000000 10085540912 10539745280 1120034624 12.027 97 24 48 1 1160289984 35249248 5006112
09:37:30 62800 8192000000 10111729120 10581643264 1134711520 12.166 99 24 48 0 1197599680 57792768 5095392
09:39:34 67466 8192000000 10051222592 10512666624 1077432992 11.623 94 8 16 0 1111660752 32852720 1375040
09:42:03 46178 8192000000 10075715232 10533879808 1102160368 11.858 97 24 48 0 1155602176 47840288 5601520
09:43:51 39921 8192000000 10146686336 10595274752 1121812256 12.044 99 36 72 1 1212708672 83358352 7538064
09:45:40 28181 8192000000 10158365120 10626826240 1139348960 12.209 100 45 90 0 1250539328 101233296 9957072
09:47:11 42665 8192000000 10112554272 10553745408 1117665248 12.005 98 29 58 1 1175705760 51940992 6099520
09:49:14 49336 8192000000 10115982000 10580439040 1116142576 11.991 99 23 46 0 1225819696 104915424 4761696
09:52:14 159240 8192000000 10072808976 10543030272 1098154080 11.820 97 15 30 1 1154511312 53103024 3254208
09:56:02 69012 8192000000 10041145056 10520735744 1103375136 11.870 98 20 40 1 1165559696 58003952 4180608
09:58:17 52545 8192000000 10055892176 10525261824 1108618656 11.919 98 17 32 1 1167836224 55483248 3734320
10:00:14 76987 8192000000 10037174896 10494332928 1092565504 11.767 97 16 32 0 1115400896 19484192 3351200
10:02:43 68870 8192000000 10064821712 10529140736 1107594352 11.910 98 13 27 0 1163691008 53270896 2825760
10:05:04 61864 8192000000 10092338352 10533974016 1091258304 11.755 95 22 42 1 1146081472 50251968 4571200
10:07:06 29540 8192000000 10103466000 10556436480 1128197744 12.104 99 30 60 0 1207625632 73277968 6149920
10:08:47 39014 8192000000 10073369616 10514214912 1137866784 12.195 100 24 48 0 1183791552 41291600 4633168
10:10:28 47782 8192000000 10129337552 10596225024 1126876336 12.092 98 35 70 2 1187251744 53092336 7283072
10:12:50 58876 8192000000 10166214816 10642399232 1144079632 12.254 99 18 36 0 1232074016 84224064 3770320
10:15:08 239037 8192000000 10120691648 10611687424 1104402112 11.879 96 9 18 0 1135379264 29406288 1570864
10:20:07 51070 8192000000 10088293392 10591137792 1132286816 12.143 99 27 54 1 1219003312 81274512 5441984
10:21:58 67000 8192000000 10071307008 10529202176 1143744768 12.251 100 10 20 0 1190838832 45014384 2079680
10:24:08 50356 8192000000 10076381808 10536095744 1124352704 12.068 98 17 34 0 1164317136 36683584 3280848
10:26:01 48985 8192000000 10085089808 10544181248 1141792224 12.232 100 14 28 1 1193508080 48636112 3079744
10:28:00 97133 8192000000 10104298752 10571063296 1116220208 11.991 98 11 22 0 1171378048 52930896 2226944
10:32:07 51583 8192000000 10057838448 10531041280 1119364672 12.021 99 14 28 1 1165652096 43461552 2825872
10:34:04 42144 8192000000 10109553920 10572570624 1118527824 12.013 99 33 66 1 1173928384 48869856 6530704
10:35:53 44594 8192000000 10075766672 10512060416 1094115840 11.782 96 25 50 1 1146788128 47800464 4871824

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 10:38:23 to 29-12-2021 10:38:38.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 93 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 10:38:39 to 29-12-2021 10:38:41.

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          229105
MON$OLDEST_ACTIVE               231491
MON$OLDEST_SNAPSHOT             231491
MON$NEXT_TRANSACTION            231491
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                       2073056
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             793
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 ---
:63: 
:64: mscs-test-161.budzdorov.ru    Wed Dec 29 10:37:12 2021
:65:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:66: 
--- end of diff output ---

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

29.12.21 10:38:42. 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 10:38:44 No severe PSQL-related problems occured
29.12.21 10:38:45. End of report.