Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 31.12.2021 10:39
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16603268 kB
MemAvailable 31387900 kB
Buffers 0 kB
Cached 15123680 kB
SwapCached 1796 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-31 10:37:12
DTS_END                         2021-12-31 10:37:12.7650
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/42990
TRN_ID                          232882
ATT_ID                          603
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: 8883.13 21754 1065976
customer order: creation 174.75 408 20970
customer order: refuse 14.89 330 1787
order to supplier: creation 45.69 1461 5483
order to supplier: removal 3.28 118936 394
invoice (draft): creation 47.00 3496 5640
invoice (draft): removal 46.30 1015 5556
invoice accept: apply 42.98 20734 5158
invoice accept: cancel 42.15 989 5058
customer reserve: creation 3964.75 205 475770
customer reserve: removal 4210.88 74 505305
realization accept: apply 84.05 208 10086
realization accept: cancel 75.82 139 9098
payment from customer: creation 61.12 133 7334
payment from customer: removal 5.43 87 651
payment to supplier: creation 49.38 133 5926
payment to supplier: removal 3.71 77 445
service: total inventory turnovers 3.96 3585 475
service: total monetary turnovers 6.04 574 725
service: aggregate perf. data 0.93 14619 111
service: refresh index statistics 0.03 267874 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 31-12-2021 10:39:43 to 31-12-2021 10:39:45.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4845
TEST_TIME 2 5670
TEST_TIME 3 5483
TEST_TIME 4 6255
TEST_TIME 5 6162
TEST_TIME 6 5876
TEST_TIME 7 6286
TEST_TIME 8 6195
TEST_TIME 9 6208
TEST_TIME 10 6594
TEST_TIME 11 6994
TEST_TIME 12 7149
TEST_TIME 13 6961
TEST_TIME 14 7100
TEST_TIME 15 7291
TEST_TIME 16 7597
TEST_TIME 17 7536
TEST_TIME 18 7859
TEST_TIME 19 7996
TEST_TIME 20 8090
TEST_TIME 21 8356
TEST_TIME 22 8498
TEST_TIME 23 8620
TEST_TIME 24 8532
TEST_TIME 25 8762
TEST_TIME 26 9533
TEST_TIME 27 10239
TEST_TIME 28 10506
TEST_TIME 29 10321
TEST_TIME 30 10079
TEST_TIME 31 10014
TEST_TIME 32 10153
TEST_TIME 33 10095
TEST_TIME 34 9890
TEST_TIME 35 9854
TEST_TIME 36 9791
TEST_TIME 37 9763
TEST_TIME 38 9755
TEST_TIME 39 9786
TEST_TIME 40 9654
TEST_TIME 41 9577
TEST_TIME 42 9457
TEST_TIME 43 9507
TEST_TIME 44 9632
TEST_TIME 45 9550
TEST_TIME 46 9446
TEST_TIME 47 9536
TEST_TIME 48 9480
TEST_TIME 49 9568
TEST_TIME 50 9712
TEST_TIME 51 9653
TEST_TIME 52 9781
TEST_TIME 53 9696
TEST_TIME 54 9879
TEST_TIME 55 9791
TEST_TIME 56 9648
TEST_TIME 57 9574
TEST_TIME 58 9473
TEST_TIME 59 9467
TEST_TIME 60 9454
TEST_TIME 61 9390
TEST_TIME 62 9286
TEST_TIME 63 9269
TEST_TIME 64 9302
TEST_TIME 65 9291
TEST_TIME 66 9294
TEST_TIME 67 9294
TEST_TIME 68 9267
TEST_TIME 69 9232
TEST_TIME 70 9199
TEST_TIME 71 9311
TEST_TIME 72 9322
TEST_TIME 73 9379
TEST_TIME 74 9345
TEST_TIME 75 9349
TEST_TIME 76 9451
TEST_TIME 77 9415
TEST_TIME 78 9315
TEST_TIME 79 9234
TEST_TIME 80 9285
TEST_TIME 81 9228
TEST_TIME 82 9183
TEST_TIME 83 9161
TEST_TIME 84 9197
TEST_TIME 85 9146
TEST_TIME 86 9155
TEST_TIME 87 9135
TEST_TIME 88 9157
TEST_TIME 89 9149
TEST_TIME 90 9134
TEST_TIME 91 9194
TEST_TIME 92 9266
TEST_TIME 93 9269
TEST_TIME 94 9225
TEST_TIME 95 9301
TEST_TIME 96 9299
TEST_TIME 97 9208
TEST_TIME 98 9189
TEST_TIME 99 9195
TEST_TIME 100 9146
TEST_TIME 101 9083
TEST_TIME 102 9043
TEST_TIME 103 9023
TEST_TIME 104 8986
TEST_TIME 105 8990
TEST_TIME 106 9016
TEST_TIME 107 9001
TEST_TIME 108 8995
TEST_TIME 109 9000
TEST_TIME 110 9035
TEST_TIME 111 9019
TEST_TIME 112 9011
TEST_TIME 113 9058
TEST_TIME 114 9044
TEST_TIME 115 9078
TEST_TIME 116 9071
TEST_TIME 117 9011
TEST_TIME 118 8974
TEST_TIME 119 8948
TEST_TIME 120 8891

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 31-12-2021 10:39:47 to 31-12-2021 10:39:50.

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 1505338 1505338 0 0.00 0 19341 8 0 0
doc_list_biud 1505338 1505338 0 0.00 0 53689 31 0 0
sp_add_invoice_to_stock 5279 5158 121 2.29 34 218958 20734 0 121
sp_cancel_adding_invoice 5058 5058 0 0.00 4 54386 990 0 0
sp_cancel_client_order 1787 1787 0 0.00 4 7071 331 0 0
sp_cancel_customer_reserve 505305 505305 0 0.00 1 53690 74 0 0
sp_cancel_pay_from_customer 651 651 0 0.00 3 5213 88 0 0
sp_cancel_pay_to_supplier 445 445 0 0.00 3 3080 77 0 0
sp_cancel_supplier_invoice 5574 5556 18 0.32 2 16175 1016 0 18
sp_cancel_supplier_order 394 394 0 0.00 5 1054549 118936 0 0
sp_cancel_write_off 9098 9098 0 0.00 1 16897 139 0 0
sp_client_order 20970 20970 0 0.00 4 24357 409 0 0
sp_customer_reserve 475770 475770 0 0.00 0 92662 206 0 0
sp_fill_shopping_cart 32088 32088 0 0.00 1 13376 44 0 0
sp_fill_shopping_cart_clo_res 475760 475760 0 0.00 0 16409 19 0 0
sp_get_clo_for_invoice 5144 5144 0 0.00 0 26883 330 0 0
sp_kill_cost_storno 15252 15252 0 0.00 0 8756 61 0 0
sp_kill_qstorno_ret_qs2qd 1025658 1025658 0 0.00 0 53685 40 0 0
sp_kill_qty_storno 542428 542428 0 0.00 0 53688 86 0 0
sp_lock_selected_doc 21017 21017 0 0.00 0 8246 7 0 0
sp_make_cost_storno 28490 28490 0 0.00 0 15182 69 0 0
sp_multiply_rows_for_pdistr 28490 28490 0 0.00 0 7664 35 0 0
sp_multiply_rows_for_qdistr 20970 20970 0 0.00 0 24348 225 0 0
sp_pay_from_customer 7334 7334 0 0.00 4 14737 134 0 0
sp_pay_to_supplier 5926 5926 0 0.00 4 17883 134 0 0
sp_payment_common 13260 13260 0 0.00 1 14731 32 0 0
sp_qd_handle_on_cancel_clo 1787 1787 0 0.00 0 5853 302 0 0
sp_qd_handle_on_invoice_upd_sts 10202 10202 0 0.00 0 50715 364 0 0
sp_qd_handle_on_reserve_upd_sts 10086 10086 0 0.00 0 18428 54 0 0
sp_reserve_write_off 10086 10086 0 0.00 7 18808 209 0 0
sp_supplier_invoice 5640 5640 0 0.00 6 88352 3496 0 0
sp_supplier_order 5483 5483 0 0.00 7 64216 1462 0 0
srv_aggregate_perf_data 111 111 0 0.00 2655 163781 14620 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1192 127266 43983 0 0
srv_find_qd_qs_mism 1016708 1016708 0 0.00 0 19340 7 0 0
srv_make_invnt_saldo 475 475 0 0.00 51 110145 3585 0 0
srv_make_money_saldo 725 725 0 0.00 1 17098 575 0 0
srv_recalc_idx_stat 4 4 0 0.00 160733 404531 267874 0 0
t$perf-abend:sp_add_invoice_to_stock 121 121 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 18 18 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5158 5158 0 0.00 0 16149 177 0 0
t$perf-norm:sp_cancel_adding_invoice 404 404 0 0.00 0 6886 128 0 0
t$perf-norm:sp_cancel_client_order 1787 1787 0 0.00 0 4639 16 0 0
t$perf-norm:sp_cancel_customer_reserve 712 712 0 0.00 0 3456 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 651 651 0 0.00 0 5150 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 445 445 0 0.00 0 721 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 369 369 0 0.00 0 667 13 0 0
t$perf-norm:sp_cancel_supplier_order 394 394 0 0.00 0 24282 1665 0 0
t$perf-norm:sp_cancel_write_off 1025 1025 0 0.00 0 2527 7 0 0
t$perf-norm:sp_client_order 20970 20970 0 0.00 0 9393 21 0 0
t$perf-norm:sp_customer_reserve 1044 1044 0 0.00 0 1911 10 0 0
t$perf-norm:sp_pay_from_customer 7334 7334 0 0.00 0 8990 11 0 0
t$perf-norm:sp_pay_to_supplier 5926 5926 0 0.00 0 4923 11 0 0
t$perf-norm:sp_reserve_write_off 10086 10086 0 0.00 0 18599 10 0 0
t$perf-norm:sp_supplier_invoice 5640 5640 0 0.00 0 4939 20 0 0
t$perf-norm:sp_supplier_order 5483 5483 0 0.00 0 2410 16 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 818 26 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 99 2 0 0
t$perf-norm:srv_make_invnt_saldo 475 475 0 0.00 0 2712 25 0 0
t$perf-norm:srv_make_money_saldo 725 725 0 0.00 0 5646 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 8 4 0 0
v_add_invoice_to_stock 10530 10288 242 2.29 0 6121 15 0 242
v_all_customers 38638 38638 0 0.00 0 65 1 0 0
v_all_suppliers 30390 30390 0 0.00 0 32 1 0 0
v_all_wares 41940 41940 0 0.00 0 24 1 0 0
v_cancel_adding_invoice 808 808 0 0.00 0 281 6 0 0
v_cancel_client_order 3574 3574 0 0.00 0 2023 5 0 0
v_cancel_customer_prepayment 1302 1302 0 0.00 0 2582 7 0 0
v_cancel_customer_reserve 1424 1424 0 0.00 0 1356 5 0 0
v_cancel_payment_to_supplier 890 890 0 0.00 0 2999 8 0 0
v_cancel_supplier_invoice 774 738 36 4.65 0 599 12 0 36
v_cancel_supplier_order 788 788 0 0.00 0 327 3 0 0
v_cancel_write_off 2050 2050 0 0.00 0 4887 16 0 0
v_max_id_clo_ord 5483 5483 0 0.00 0 2978 2 0 0
v_max_id_clo_res 1044 1044 0 0.00 0 2834 17 0 0
v_max_id_ord_sup 5635 5635 0 0.00 0 7032 8 0 0
v_max_non_paid_invoice 1849 1849 0 0.00 0 824 3 0 0
v_max_non_paid_realizn 3848 3848 0 0.00 0 5150 5 0 0
v_min_id_clo_ord 5483 5483 0 0.00 0 3498 5 0 0
v_min_id_clo_res 1044 1044 0 0.00 0 6888 20 0 0
v_min_id_ord_sup 5635 5635 0 0.00 0 5517 5 0 0
v_min_non_paid_invoice 5926 5926 0 0.00 0 5440 16 0 0
v_min_non_paid_realizn 7334 7334 0 0.00 0 14727 27 0 0
v_our_firm 10274 10274 0 0.00 0 12 1 0 0
v_reserve_write_off 20172 20172 0 0.00 0 2733 5 0 0
x_lock_depdocs_on_canc_invoice 10245 10245 0 0.00 0 26876 125 0 0
x_lock_depdocs_on_canc_sup_ord 394 394 0 0.00 0 6092 341 0 0
x_make_qstorno_1000_1200 5483 5483 0 0.00 2 63586 1378 0 0
x_make_qstorno_1200_2000 5640 5640 0 0.00 3 88336 3326 0 0
x_make_qstorno_2100_3300 475770 475770 0 0.00 0 92662 184 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 31-12-2021 10:39:51 to 31-12-2021 10:39:55.

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:16 1034 8192000000 8966459264 9032310784 3900128 0.047 2 1 2 0 3997744 61840 35776
08:08:19 19152 8192000000 9713661888 10009116672 793468000 8.830 100 86 170 1 821542928 7061696 21013232
08:09:41 25934 8192000000 9756337360 10057031680 827740784 9.176 100 85 170 0 858870704 11193312 19936608
08:11:11 21363 8192000000 9791815808 10108071936 862720016 9.527 100 87 174 0 903925856 21188160 20017680
08:12:36 12868 8192000000 9849637280 10181914624 914883232 10.046 100 79 158 0 959072944 27204976 16984736
08:13:55 10015 8192000000 9916851248 10258849792 952451536 10.415 100 84 168 0 1031223056 61506208 17265312
08:15:11 8416 8192000000 9959824080 10313748480 986028592 10.743 100 80 160 1 1072390992 70091360 16271040
08:16:21 4669 8192000000 9992893920 10350637056 1019600464 11.068 100 88 175 0 1103004096 65299904 18103728
08:17:27 7189 8192000000 10027717328 10391572480 1040454608 11.269 100 86 172 0 1148545728 90840864 17250256
08:18:35 2135 8192000000 10051174288 10421018624 1060799168 11.464 100 93 188 1 1160509840 80335072 19375600
08:19:38 15377 8192000000 10038375904 10414391296 1070582672 11.558 100 88 176 0 1146955936 57669024 18704240
08:20:55 37286 8192000000 9999120704 10382876672 1075873472 11.608 100 53 107 2 1112714688 25941840 10899376
08:22:33 4044 8192000000 10001505328 10407301120 1080525728 11.652 100 32 64 0 1117776960 30318128 6933104
08:23:40 6193 8192000000 10015410896 10420219904 1093983408 11.781 100 40 80 0 1140442976 37964336 8495232
08:24:49 8253 8192000000 10003263312 10409603072 1091966864 11.761 100 31 60 3 1128429040 29696016 6766160
08:25:59 46045 8192000000 10026899728 10422661120 1096576592 11.805 100 53 103 0 1128118592 20895648 10646352
08:27:47 4379 8192000000 10003493568 10400854016 1087975136 11.723 99 50 97 2 1115457520 17268880 10213504
08:28:54 7923 8192000000 9988493872 10388336640 1061592336 11.472 98 39 77 2 1099711296 28980176 9138784
08:30:02 6757 8192000000 9968395792 10352713728 1031215440 11.180 97 41 82 2 1073201344 33951552 8034352
08:31:09 38747 8192000000 9940339104 10319515648 951536016 10.406 91 46 90 0 975725472 15532000 8657456
08:32:48 7568 8192000000 9933684512 10319142912 991814240 10.799 97 33 66 0 1020069088 21333344 6921504
08:33:57 5444 8192000000 9950303072 10325110784 1031924176 11.187 99 54 106 2 1058978592 14286976 12767440
08:35:04 7443 8192000000 9958822864 10340192256 1047274640 11.335 100 35 70 0 1066940288 11954288 7711360
08:36:15 38545 8192000000 9978165504 10383405056 1055509904 11.413 100 39 78 3 1089338256 25625488 8202864
08:38:02 16976 8192000000 9998369600 10395631616 1087246640 11.716 100 38 75 1 1113899168 18510560 8141968
08:39:32 16261 8192000000 10092120752 10501337088 1107877008 11.912 100 50 100 2 1202915328 84492944 10545376
08:40:58 11449 8192000000 10118159664 10519482368 1119949488 12.027 100 47 94 0 1226603392 96049040 10604864
08:42:12 26926 8192000000 10150006416 10558480384 1131829984 12.139 100 47 92 2 1265371808 123368368 10173456
08:44:06 9050 8192000000 10152682256 10564857856 1143304448 12.247 100 43 86 2 1263211072 110678624 9228000
08:45:18 11309 8192000000 10199292224 10606903296 1148447616 12.295 100 56 110 2 1306021792 145656848 11917328
08:46:34 1702 8192000000 10218904784 10624602112 1163359120 12.435 100 77 153 2 1330524944 150031040 17134784
08:47:38 8410 8192000000 10222030224 10630406144 1167055808 12.469 100 71 142 0 1323021312 140590672 15374832
08:48:56 2958 8192000000 10252573120 10666926080 1178061744 12.572 100 79 156 2 1360126432 164646192 17418496
08:50:03 4382 8192000000 10255198656 10669121536 1175947680 12.552 100 75 150 0 1359860576 167479808 16433088
08:51:13 4774 8192000000 10296178912 10713931776 1179053664 12.581 100 62 121 2 1406957440 213888816 14014960
08:52:22 4409 8192000000 10324469952 10729832448 1169112544 12.489 100 78 155 1 1428034784 240560640 18361600
08:53:34 5563 8192000000 10310022352 10719350784 1164878064 12.449 99 75 147 1 1404655696 222361312 17416320
08:54:48 1190 8192000000 10334834400 10735087616 1166566896 12.465 100 87 173 0 1427655184 240387760 20700528
08:55:56 1552 8192000000 10359838608 10763104256 1186246784 12.648 100 85 167 0 1459546384 253509856 19789744
08:57:03 6801 8192000000 10371667840 10801627136 1173844256 12.533 100 50 100 0 1478459872 292242960 12372656
08:58:16 6891 8192000000 10375517312 10813288448 1170938560 12.506 100 54 108 2 1482199792 297902016 13359216
08:59:35 6115 8192000000 10341347488 10784022528 1150881632 12.318 100 40 80 0 1447181312 286328784 9970896
09:00:59 18103 8192000000 10352278160 10784784384 1128947888 12.111 98 29 58 0 1444683888 308241712 7494288
09:02:19 7586 8192000000 10343343040 10767712256 1126668512 12.090 100 42 84 0 1444091536 306765856 10657168
09:03:30 16779 8192000000 10256532896 10730770432 1106242960 11.897 99 24 48 0 1361684944 248810608 6631376
09:04:49 9108 8192000000 10079509920 10537304064 1108711152 11.920 100 20 40 0 1213552240 100343136 4497952
09:05:59 43008 8192000000 10042664304 10469212160 1088462128 11.728 98 24 47 1 1127371088 33382320 5526640
09:07:42 56193 8192000000 10091094128 10502189056 1106598032 11.900 100 51 102 2 1156967648 40375440 9994176
09:09:47 127266 8192000000 10042193088 10473832448 1074707344 11.597 96 31 60 3 1128152992 47276080 6169568
09:12:57 31409 8192000000 10100829360 10525806592 1113467072 11.965 99 40 80 1 1196556640 74589392 8500176
09:14:30 33882 8192000000 10101181056 10554494976 1104597424 11.881 98 38 76 0 1204708800 91294176 8817200
09:16:09 36944 8192000000 10095343712 10550120448 1121286336 12.039 100 15 30 0 1204555264 79033568 4235360
09:17:51 98326 8192000000 10096035792 10546991104 1105724720 11.892 98 12 24 1 1176640176 68512560 2402896
09:20:32 51346 8192000000 10056371920 10517057536 1090935456 11.752 97 22 42 0 1162280768 66266992 5078320
09:22:50 43646 8192000000 10110841376 10545577984 1133259536 12.152 100 20 40 1 1214901616 77077504 4564576
09:24:39 26489 8192000000 10100740128 10535964672 1147052608 12.282 100 13 26 0 1210592384 60710656 2829120
09:26:06 74435 8192000000 10164562656 10619432960 1143906288 12.252 99 15 30 0 1253200016 106136784 3156944
09:28:49 50029 8192000000 10089974208 10557063168 1120107184 12.028 98 22 44 1 1213040144 88148944 4784016
09:30:51 47379 8192000000 10079120752 10597527552 1123866064 12.063 99 18 36 0 1190426064 62992608 3567392
09:32:45 99775 8192000000 10093517408 10548281344 1120470544 12.031 99 26 51 2 1174889904 48686944 5732416
09:35:29 35697 8192000000 10066125792 10501062656 1122248784 12.048 100 36 72 0 1163487136 33953072 7285280
09:37:06 63784 8192000000 10044361904 10493599744 1105981408 11.894 98 20 40 0 1146934864 37033424 3920032
09:39:15 37886 8192000000 10081955200 10532233216 1136918416 12.187 100 12 24 0 1193201280 53502048 2780816
09:40:58 59117 8192000000 10045800976 10524852224 1094956448 11.790 98 13 25 2 1157760016 60180416 2623152
09:42:58 17036 8192000000 10063893840 10488700928 1101742880 11.854 98 36 71 0 1143054704 33303744 8008080
09:44:18 34242 8192000000 10043736592 10477539328 1088250656 11.726 98 34 68 0 1142994032 47769024 6974352
09:45:56 41888 8192000000 10089788656 10526056448 1092866832 11.770 97 34 67 3 1163710688 63453696 7390160
09:47:38 96768 8192000000 10094498272 10562617344 1099884064 11.837 98 19 38 2 1178231648 74466576 3881008
09:50:16 47905 8192000000 10032893760 10485764096 1080355616 11.651 98 27 54 1 1149855792 64118560 5381616
09:52:04 50741 8192000000 10107263872 10554449920 1117505904 12.003 100 35 70 0 1217572496 92951904 7114688
09:53:58 35243 8192000000 10094520032 10502447104 1121520480 12.041 100 37 74 1 1176381408 47009776 7851152
09:55:34 112183 8192000000 10089979728 10546810880 1115855664 11.988 99 34 68 0 1196557360 74169232 6532464
09:58:30 82116 8192000000 10107775072 10570018816 1130246848 12.124 100 12 23 0 1196689136 64007088 2435200
10:00:54 82902 8192000000 10061235056 10526748672 1116854160 11.997 99 10 20 0 1154935744 36106848 1974736
10:03:19 67821 8192000000 10109489520 10560425984 1140082688 12.216 100 29 59 0 1224883936 77998752 6802496
10:05:37 84998 8192000000 10144550816 10607902720 1110419648 11.936 97 17 34 0 1205635936 91257680 3958608
10:08:10 75483 8192000000 10073773328 10545233920 1138482512 12.201 99 17 34 0 1247321088 105491616 3346960
10:10:27 68512 8192000000 10126259952 10565332992 1138372448 12.200 98 23 45 0 1206993984 63205872 5415664
10:12:42 124963 8192000000 10142662736 10629672960 1149854528 12.308 100 8 16 0 1240862048 89335376 1672144
10:16:19 62432 8192000000 10069281120 10529271808 1150685952 12.316 100 14 28 1 1173555488 20352768 2516768
10:18:27 34353 8192000000 10084451072 10544386048 1128370368 12.106 98 10 20 0 1166942416 36502176 2069872
10:20:03 41152 8192000000 10062816608 10536439808 1128168448 12.104 99 9 18 0 1184860336 54757328 1934560
10:21:48 61317 8192000000 10091477808 10556239872 1132001696 12.140 99 32 63 0 1211602192 72836592 6763904
10:23:50 55580 8192000000 10084632464 10527948800 1100560560 11.843 96 25 49 0 1131678768 26105168 5013040
10:25:49 71432 8192000000 10083719104 10544136192 1118751056 12.015 98 16 32 1 1192241696 70056288 3434352
10:28:02 43041 8192000000 10071517040 10500943872 1075603488 11.606 94 35 70 1 1109420976 27000192 6817296
10:30:04 72217 8192000000 10106354320 10595020800 1133372240 12.153 100 28 56 1 1218496208 79273200 5850768
10:32:17 84807 8192000000 10063523760 10525978624 1089027088 11.733 96 17 34 1 1140494704 48294784 3172832
10:34:41 94768 8192000000 10048952528 10499186688 1115385696 11.983 99 24 48 1 1165761040 45303600 5071744

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 31-12-2021 10:39:57 to 31-12-2021 10:40:12.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 121 v_add_invoice_to_stock 335544517
except 18 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 31-12-2021 10:40:13 to 31-12-2021 10:40:15.

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          232038
MON$OLDEST_ACTIVE               232999
MON$OLDEST_SNAPSHOT             232999
MON$NEXT_TRANSACTION            232999
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                       2030240
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             796
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 ---
:175: 
:176: mscs-test-161.budzdorov.ru    Fri Dec 31 10:37:14 2021
:177:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:178: 
:179: 
:180: mscs-test-161.budzdorov.ru    Fri Dec 31 10:37:17 2021
:181:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:182: 
:183: 
:184: mscs-test-161.budzdorov.ru    Fri Dec 31 10:37:27 2021
:185:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:186: 
:187: 
:188: mscs-test-161.budzdorov.ru    Fri Dec 31 10:37:33 2021
:189:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:190: 
:191: 
:192: mscs-test-161.budzdorov.ru    Fri Dec 31 10:37:34 2021
:193:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:194: 
--- end of diff output ---

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

31.12.21 10:40:16. 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-31 10:40:18 No severe PSQL-related problems occured
31.12.21 10:40:20. End of report.