Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 01.07.2022 10:38
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 15898276 kB
MemAvailable 31212640 kB
Buffers 0 kB
Cached 15837296 kB
SwapCached 2492 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.2
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-07-01 10:37:17
DTS_END                         2022-07-01 10:37:17.2140
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/42064
TRN_ID                          232800
ATT_ID                          623
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: 9023.89 22559 1082867
customer order: creation 187.67 485 22520
customer order: refuse 15.13 395 1815
order to supplier: creation 48.53 1249 5824
order to supplier: removal 3.47 161970 416
invoice (draft): creation 49.78 2931 5973
invoice (draft): removal 48.33 1000 5799
invoice accept: apply 44.90 27595 5388
invoice accept: cancel 42.88 1317 5145
customer reserve: creation 4127.88 267 495346
customer reserve: removal 4156.94 106 498833
realization accept: apply 89.22 259 10706
realization accept: cancel 69.26 181 8311
payment from customer: creation 63.60 174 7632
payment from customer: removal 6.38 122 766
payment to supplier: creation 52.22 185 6266
payment to supplier: removal 3.61 125 433
service: total inventory turnovers 4.72 3545 566
service: total monetary turnovers 8.39 330 1007
service: aggregate perf. data 0.97 14350 116
service: refresh index statistics 0.04 234591 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 01-07-2022 10:38:53 to 01-07-2022 10:38:55.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8135
TEST_TIME 2 6630
TEST_TIME 3 6514
TEST_TIME 4 6904
TEST_TIME 5 6984
TEST_TIME 6 7001
TEST_TIME 7 7392
TEST_TIME 8 7614
TEST_TIME 9 7729
TEST_TIME 10 8005
TEST_TIME 11 7914
TEST_TIME 12 7930
TEST_TIME 13 7993
TEST_TIME 14 8328
TEST_TIME 15 8237
TEST_TIME 16 8309
TEST_TIME 17 8268
TEST_TIME 18 8415
TEST_TIME 19 8294
TEST_TIME 20 8325
TEST_TIME 21 8411
TEST_TIME 22 8579
TEST_TIME 23 8478
TEST_TIME 24 8537
TEST_TIME 25 8730
TEST_TIME 26 8847
TEST_TIME 27 9121
TEST_TIME 28 9546
TEST_TIME 29 9760
TEST_TIME 30 10116
TEST_TIME 31 10424
TEST_TIME 32 10669
TEST_TIME 33 11189
TEST_TIME 34 11709
TEST_TIME 35 11571
TEST_TIME 36 11457
TEST_TIME 37 11461
TEST_TIME 38 11323
TEST_TIME 39 11231
TEST_TIME 40 11189
TEST_TIME 41 10985
TEST_TIME 42 10811
TEST_TIME 43 10685
TEST_TIME 44 10638
TEST_TIME 45 10591
TEST_TIME 46 10554
TEST_TIME 47 10412
TEST_TIME 48 10366
TEST_TIME 49 10369
TEST_TIME 50 10267
TEST_TIME 51 10233
TEST_TIME 52 10180
TEST_TIME 53 10230
TEST_TIME 54 10107
TEST_TIME 55 10132
TEST_TIME 56 10089
TEST_TIME 57 10027
TEST_TIME 58 10010
TEST_TIME 59 9979
TEST_TIME 60 9979
TEST_TIME 61 9950
TEST_TIME 62 9997
TEST_TIME 63 10124
TEST_TIME 64 10151
TEST_TIME 65 10104
TEST_TIME 66 10093
TEST_TIME 67 10148
TEST_TIME 68 10094
TEST_TIME 69 10136
TEST_TIME 70 10022
TEST_TIME 71 9947
TEST_TIME 72 9876
TEST_TIME 73 9823
TEST_TIME 74 9775
TEST_TIME 75 9797
TEST_TIME 76 9747
TEST_TIME 77 9689
TEST_TIME 78 9600
TEST_TIME 79 9589
TEST_TIME 80 9557
TEST_TIME 81 9537
TEST_TIME 82 9507
TEST_TIME 83 9511
TEST_TIME 84 9541
TEST_TIME 85 9515
TEST_TIME 86 9570
TEST_TIME 87 9569
TEST_TIME 88 9538
TEST_TIME 89 9543
TEST_TIME 90 9599
TEST_TIME 91 9607
TEST_TIME 92 9533
TEST_TIME 93 9460
TEST_TIME 94 9469
TEST_TIME 95 9418
TEST_TIME 96 9384
TEST_TIME 97 9336
TEST_TIME 98 9343
TEST_TIME 99 9335
TEST_TIME 100 9353
TEST_TIME 101 9306
TEST_TIME 102 9349
TEST_TIME 103 9350
TEST_TIME 104 9415
TEST_TIME 105 9415
TEST_TIME 106 9397
TEST_TIME 107 9434
TEST_TIME 108 9399
TEST_TIME 109 9340
TEST_TIME 110 9290
TEST_TIME 111 9344
TEST_TIME 112 9285
TEST_TIME 113 9224
TEST_TIME 114 9229
TEST_TIME 115 9190
TEST_TIME 116 9193
TEST_TIME 117 9160
TEST_TIME 118 9136
TEST_TIME 119 9114
TEST_TIME 120 9045

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 01-07-2022 10:38:56 to 01-07-2022 10:39:00.

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 1539830 1539830 0 0.00 0 19529 15 0 0
doc_list_biud 1539819 1539819 0 0.00 0 33333 40 0 0
sp_add_invoice_to_stock 5441 5388 53 0.97 28 198044 27595 0 53
sp_cancel_adding_invoice 5145 5145 0 0.00 6 73900 1317 0 0
sp_cancel_client_order 1815 1815 0 0.00 5 10435 396 0 0
sp_cancel_customer_reserve 498833 498833 0 0.00 1 27484 107 0 0
sp_cancel_pay_from_customer 766 766 0 0.00 3 6102 123 0 0
sp_cancel_pay_to_supplier 433 433 0 0.00 3 5694 126 0 0
sp_cancel_supplier_invoice 5804 5799 5 0.08 2 29173 1001 0 5
sp_cancel_supplier_order 416 416 0 0.00 14 1579374 161970 0 0
sp_cancel_write_off 8311 8311 0 0.00 2 6164 181 0 0
sp_client_order 22520 22520 0 0.00 6 19648 485 0 0
sp_customer_reserve 495346 495346 0 0.00 0 53495 268 0 0
sp_fill_shopping_cart 34307 34307 0 0.00 1 13603 81 0 0
sp_fill_shopping_cart_clo_res 495314 495314 0 0.00 0 17643 19 0 0
sp_get_clo_for_invoice 5342 5342 0 0.00 0 25770 255 0 0
sp_kill_cost_storno 14655 14655 0 0.00 0 6159 76 0 0
sp_kill_qstorno_ret_qs2qd 1012192 1012192 0 0.00 0 29157 45 0 0
sp_kill_qty_storno 536366 536366 0 0.00 0 33327 104 0 0
sp_lock_selected_doc 22136 22136 0 0.00 0 7777 7 0 0
sp_make_cost_storno 29946 29946 0 0.00 0 12171 77 0 0
sp_multiply_rows_for_pdistr 29944 29944 0 0.00 0 7809 34 0 0
sp_multiply_rows_for_qdistr 22520 22520 0 0.00 0 19613 194 0 0
sp_pay_from_customer 7632 7632 0 0.00 5 11066 175 0 0
sp_pay_to_supplier 6266 6266 0 0.00 6 18260 185 0 0
sp_payment_common 13897 13897 0 0.00 2 9134 49 0 0
sp_qd_handle_on_cancel_clo 1815 1815 0 0.00 0 9456 311 0 0
sp_qd_handle_on_invoice_upd_sts 10486 10486 0 0.00 0 33325 520 0 0
sp_qd_handle_on_reserve_upd_sts 10706 10706 0 0.00 0 14447 53 0 0
sp_reserve_write_off 10706 10706 0 0.00 8 14504 260 0 0
sp_supplier_invoice 5973 5973 0 0.00 8 73107 2932 0 0
sp_supplier_order 5824 5824 0 0.00 7 33370 1249 0 0
srv_aggregate_perf_data 116 116 0 0.00 2438 220948 14351 0 0
srv_fill_mon_cache_memory 75 75 0 0.00 678 126967 28496 0 0
srv_find_qd_qs_mism 1030559 1030559 0 0.00 0 19528 13 0 0
srv_make_invnt_saldo 566 566 0 0.00 43 113889 3546 0 0
srv_make_money_saldo 1007 1007 0 0.00 1 10029 331 0 0
srv_recalc_idx_stat 5 5 0 0.00 56649 436131 234592 0 0
t$perf-abend:sp_add_invoice_to_stock 53 53 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5391 5391 0 0.00 1 11625 940 0 0
t$perf-norm:sp_cancel_adding_invoice 450 450 0 0.00 1 5917 517 0 0
t$perf-norm:sp_cancel_client_order 1815 1815 0 0.00 0 1666 27 0 0
t$perf-norm:sp_cancel_customer_reserve 711 711 0 0.00 0 3072 36 0 0
t$perf-norm:sp_cancel_pay_from_customer 766 766 0 0.00 0 1096 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 433 433 0 0.00 0 3660 23 0 0
t$perf-norm:sp_cancel_supplier_invoice 429 429 0 0.00 0 7289 52 0 0
t$perf-norm:sp_cancel_supplier_order 416 416 0 0.00 0 78983 6677 0 0
t$perf-norm:sp_cancel_write_off 1071 1071 0 0.00 0 1669 23 0 0
t$perf-norm:sp_client_order 22520 22520 0 0.00 0 11908 36 0 0
t$perf-norm:sp_customer_reserve 1090 1090 0 0.00 0 972 14 0 0
t$perf-norm:sp_pay_from_customer 7632 7632 0 0.00 0 5469 18 0 0
t$perf-norm:sp_pay_to_supplier 6266 6266 0 0.00 0 6448 20 0 0
t$perf-norm:sp_reserve_write_off 10707 10707 0 0.00 0 6083 24 0 0
t$perf-norm:sp_supplier_invoice 5973 5973 0 0.00 0 9620 31 0 0
t$perf-norm:sp_supplier_order 5824 5824 0 0.00 0 20911 37 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 545 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 75 75 0 0.00 0 2264 74 0 0
t$perf-norm:srv_make_invnt_saldo 566 566 0 0.00 0 493 10 0 0
t$perf-norm:srv_make_money_saldo 1007 1007 0 0.00 0 1534 8 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 205 64 0 0
v_add_invoice_to_stock 10784 10678 106 0.98 0 7215 28 0 106
v_all_customers 41238 41238 0 0.00 0 281 4 0 0
v_all_suppliers 32858 32858 0 0.00 0 124 4 0 0
v_all_wares 45035 45035 0 0.00 0 65 4 0 0
v_cancel_adding_invoice 900 900 0 0.00 0 3310 20 0 0
v_cancel_client_order 3630 3630 0 0.00 0 4350 13 0 0
v_cancel_customer_prepayment 1532 1532 0 0.00 0 1491 12 0 0
v_cancel_customer_reserve 1422 1422 0 0.00 1 4290 17 0 0
v_cancel_payment_to_supplier 866 866 0 0.00 0 297 11 0 0
v_cancel_supplier_invoice 868 858 10 1.15 0 828 20 0 10
v_cancel_supplier_order 832 832 0 0.00 0 2158 17 0 0
v_cancel_write_off 2142 2142 0 0.00 0 1910 24 0 0
v_max_id_clo_ord 5823 5823 0 0.00 0 298 5 0 0
v_max_id_clo_res 1090 1090 0 0.00 0 1573 12 0 0
v_max_id_ord_sup 5965 5965 0 0.00 0 13445 10 0 0
v_max_non_paid_invoice 1622 1622 0 0.00 0 562 9 0 0
v_max_non_paid_realizn 4006 4006 0 0.00 0 2209 9 0 0
v_min_id_clo_ord 5823 5823 0 0.00 0 5401 8 0 0
v_min_id_clo_res 1090 1090 0 0.00 0 885 8 0 0
v_min_id_ord_sup 5964 5964 0 0.00 0 8924 14 0 0
v_min_non_paid_invoice 6264 6264 0 0.00 0 6634 18 0 0
v_min_non_paid_realizn 7631 7631 0 0.00 0 5487 18 0 0
v_our_firm 11042 11042 0 0.00 0 123 5 0 0
v_reserve_write_off 21412 21412 0 0.00 0 5132 12 0 0
x_lock_depdocs_on_canc_invoice 10515 10515 0 0.00 0 16115 119 0 0
x_lock_depdocs_on_canc_sup_ord 416 416 0 0.00 0 14233 394 0 0
x_make_qstorno_1000_1200 5824 5824 0 0.00 2 33334 1094 0 0
x_make_qstorno_1200_2000 5973 5973 0 0.00 3 73084 2710 0 0
x_make_qstorno_2100_3300 495344 495344 0 0.00 0 53462 236 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 01-07-2022 10:39:01 to 01-07-2022 10:39:05.

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:21 1 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:22 14106 8192000000 9713898704 10009657344 791937008 8.815 100 89 178 0 822004096 7738080 22329008
08:09:39 17138 8192000000 9756488576 10064240640 832910128 9.229 100 88 176 0 864627424 10435968 21281328
08:11:03 14998 8192000000 9797642560 10112917504 866260128 9.563 100 86 170 1 905001360 18919552 19821680
08:12:22 9784 8192000000 9845575328 10176540672 908452512 9.982 100 76 152 0 955638544 30723648 16462384
08:13:35 7037 8192000000 9910644192 10252849152 952441712 10.415 100 88 175 1 1030076944 58762224 18873008
08:14:45 2186 8192000000 9947162784 10294931456 990756512 10.789 100 89 174 0 1065758928 56255872 18746544
08:15:50 1338 8192000000 9987068672 10348068864 1021650384 11.088 100 86 171 0 1111864096 72378016 17835696
08:16:53 1437 8192000000 10033957312 10406092800 1043204608 11.295 100 94 181 4 1153098864 91705760 18188496
08:17:56 1072 8192000000 10035753552 10413293568 1060006128 11.457 100 98 190 3 1160830496 81304336 19520032
08:18:58 1036 8192000000 10031380256 10407723008 1070220832 11.554 100 95 177 1 1148480432 59924000 18335600
08:20:00 958 8192000000 10045410432 10428297216 1074712496 11.597 100 86 167 3 1170464240 77826528 17925216
08:21:01 1210 8192000000 10031276512 10422157312 1080383872 11.651 100 79 154 1 1157568352 60162448 17022032
08:22:04 1238 8192000000 10032225792 10419908608 1084456000 11.690 100 87 170 0 1151588272 49985488 17146784
08:23:06 1251 8192000000 10036861424 10427510784 1085850000 11.703 100 74 139 3 1161249216 61862048 13537168
08:24:08 1020 8192000000 10057713360 10446327808 1091047152 11.753 100 96 179 1 1181457664 72151408 18259104
08:25:09 1080 8192000000 10050118432 10439716864 1091356224 11.756 100 68 126 0 1168309344 64329024 12624096
08:26:11 1062 8192000000 10055531360 10445099008 1101011696 11.847 100 97 188 2 1178095024 57978160 19105168
08:27:13 1260 8192000000 10056780896 10443984896 1094247024 11.783 100 72 140 0 1177677744 69299872 14130848
08:28:15 995 8192000000 10057642928 10444070912 1087838016 11.722 99 90 167 1 1178724432 75001488 15884928
08:29:19 1335 8192000000 10050369904 10440925184 1090256896 11.745 100 77 145 2 1173655456 68102432 15296128
08:30:22 1238 8192000000 10005515776 10389581824 1071253664 11.564 100 84 163 1 1126295792 39381072 15661056
08:31:24 1751 8192000000 10010246976 10397097984 1055673376 11.415 100 75 127 3 1134968944 65081824 14213744
08:32:26 1893 8192000000 9992566928 10376658944 1050271056 11.363 100 70 127 1 1110957888 48155120 12531712
08:33:29 944 8192000000 9982717456 10356068352 1025326832 11.123 100 84 155 2 1102298848 60483952 16488064
08:34:31 1294 8192000000 9994603456 10373685248 1022329024 11.094 99 92 166 1 1119337360 80197616 16810720
08:35:33 1131 8192000000 9986016624 10360025088 1037056768 11.236 100 90 171 5 1109599904 53688272 18854864
08:36:35 1003 8192000000 9986793072 10360516608 1036328240 11.229 100 90 173 2 1115532656 60808832 18395584
08:37:41 11042 8192000000 9994747792 10373976064 1047123376 11.333 99 68 127 1 1104516224 44938128 12454720
08:39:01 1269 8192000000 10037466336 10418761728 1074980720 11.600 100 85 157 2 1152241360 62638864 14621776
08:40:09 8839 8192000000 10056263232 10451238912 1090521904 11.748 100 62 120 1 1181058320 78630368 11906048
08:41:22 1456 8192000000 10101884592 10484011008 1109912704 11.932 100 93 180 2 1216457104 88223168 18321232
08:42:28 4210 8192000000 10140312560 10530447360 1114772048 11.978 100 81 159 0 1258412720 127761600 15879072
08:43:44 1288 8192000000 10152439040 10549346304 1132369920 12.144 100 93 172 3 1266143072 116233760 17539392
08:44:49 2843 8192000000 10185527344 10598641664 1141468656 12.229 100 80 156 0 1302919504 144933696 16517152
08:45:56 1455 8192000000 10199545040 10596696064 1157445424 12.379 100 88 168 1 1314388800 138395392 18547984
08:47:00 1859 8192000000 10227172000 10630701056 1160874016 12.411 100 88 174 2 1341931968 161132496 19925456
08:48:05 1721 8192000000 10227018560 10633220096 1175110912 12.545 100 81 149 1 1346158176 154869440 16177824
08:49:11 1682 8192000000 10275225664 10675499008 1171115584 12.507 99 87 171 0 1382290560 192287056 18887920
08:50:15 812 8192000000 10313738864 10716905472 1181115888 12.601 100 97 189 0 1423670624 221071504 21483232
08:51:20 2987 8192000000 10326139920 10750693376 1195147584 12.731 100 76 148 0 1440413536 228826528 16439424
08:52:27 997 8192000000 10349629760 10763567104 1199376688 12.771 100 95 187 2 1457422048 236014976 22030384
08:53:32 1779 8192000000 10352326224 10766929920 1183116448 12.619 100 88 169 2 1456980448 254018640 19845360
08:54:37 1257 8192000000 10379220112 10796593152 1193876800 12.719 100 97 193 0 1485734384 269877600 21979984
08:55:42 765 8192000000 10428123472 10851221504 1197698256 12.755 100 96 188 0 1534049776 313727824 22623696
08:56:48 843 8192000000 10473378640 10876325888 1188935696 12.673 100 96 191 1 1580044624 368619168 22489760
08:57:52 904 8192000000 10503042320 10922999808 1193347520 12.715 100 97 187 0 1603607504 388273696 21986288
08:58:56 1016 8192000000 10499021664 10930147328 1195147472 12.731 100 98 189 2 1603397456 385702800 22547184
08:59:59 1059 8192000000 10522423952 10924085248 1175009024 12.544 100 93 175 0 1624843040 428554992 21279024
09:01:05 672 8192000000 10577484624 11000287232 1191238608 12.695 100 94 179 0 1675441808 461987600 22215600
09:02:10 724 8192000000 10569970880 11014455296 1188583488 12.670 100 99 195 2 1672281280 460529680 23168112
09:03:17 1790 8192000000 10568021632 11020910592 1177235808 12.564 99 77 150 0 1662759200 465985680 19537712
09:04:22 9297 8192000000 10570540176 11063070720 1195804128 12.737 100 58 114 1 1719918160 508899344 15214688
09:05:41 15665 8192000000 10568006608 11025920000 1194196112 12.722 100 48 94 0 1657044368 449947248 12901008
09:07:10 18368 8192000000 10475779152 10977579008 1163028608 12.432 99 29 58 0 1582594832 411714656 7851568
09:09:02 25341 8192000000 10330211280 10837282816 1127081104 12.094 98 19 36 1 1420909088 289096992 4730992
09:10:28 52775 8192000000 10135315296 10679472128 1101015280 11.847 97 21 42 0 1256118256 150331312 4771664
09:12:21 34517 8192000000 10070917344 10497179648 1114918848 11.979 98 37 66 0 1135867312 13887808 7060656
09:13:57 72794 8192000000 10050670224 10493812736 1059255776 11.449 93 28 56 0 1122586944 57582240 5748928
09:16:15 64196 8192000000 10078241456 10534821888 1097374048 11.813 97 24 46 0 1164099968 61924432 4801488
09:18:21 46927 8192000000 10060773840 10503036928 1117675008 12.005 100 38 70 1 1145794448 21674672 6444768
09:20:09 34527 8192000000 10103735952 10541514752 1131701680 12.137 100 42 85 0 1200500416 59752160 9046576
09:21:51 5587 8192000000 10086210352 10513010688 1133219888 12.152 100 68 122 4 1190408560 43086624 14102048
09:22:57 19916 8192000000 10065661312 10509778944 1118189952 12.010 99 24 47 0 1163494304 40673344 4631008
09:24:19 17104 8192000000 10129550896 10561495040 1148908512 12.299 100 68 136 1 1222452208 58421344 15122352
09:25:37 49339 8192000000 10092090192 10589982720 1126239952 12.086 98 23 44 0 1232128032 99983712 5904368
09:28:29 29967 8192000000 10124810128 10627710976 1148095888 12.292 100 12 23 0 1223669776 73043840 2530048
09:30:22 72858 8192000000 10067941248 10535403520 1130269792 12.124 99 20 30 0 1186773664 53586880 2916992
09:32:35 77164 8192000000 10061614576 10538913792 1126106176 12.085 99 8 13 0 1153204208 25912768 1185264
09:34:53 10130 8192000000 10099556544 10536984576 1147514976 12.286 100 42 84 1 1184499616 28694480 8290160
09:36:04 20483 8192000000 10160056896 10613280768 1142578576 12.240 99 25 50 0 1235869248 88236576 5054096
09:37:25 30570 8192000000 10139669968 10582114304 1132770912 12.147 98 25 50 0 1219037104 81367376 4898816
09:38:56 70665 8192000000 10170321472 10622881792 1126357472 12.087 98 24 48 0 1243266928 111710864 5198592
09:41:12 13499 8192000000 10099270656 10552197120 1127139472 12.094 99 37 74 0 1181413552 46267776 8006304
09:42:27 24395 8192000000 10143077040 10579136512 1144301504 12.256 100 54 106 1 1217711920 62499024 10911392
09:44:02 32833 8192000000 10093961536 10562621440 1101396160 11.851 97 32 62 0 1182514736 74665216 6453360
09:45:36 22395 8192000000 10131838176 10569121792 1133367776 12.153 100 55 109 0 1221088208 76714144 11006288
09:47:45 100984 8192000000 10072330640 10517311488 1108615760 11.919 98 21 41 0 1151994800 39303376 4075664
09:50:33 61690 8192000000 10104686400 10562310144 1089252048 11.736 96 23 46 0 1167983984 74308112 4423824
09:52:35 63025 8192000000 10028002992 10485297152 1093576816 11.777 97 11 20 0 1140429168 44501920 2350432
09:54:42 85568 8192000000 10086879968 10537766912 1126555088 12.089 100 25 51 0 1186516000 55046560 4914352
09:57:46 49901 8192000000 10040147696 10508292096 1109137312 11.924 98 15 24 0 1132651072 20258320 3255440
09:59:41 27649 8192000000 10043578672 10492829696 1058167344 11.439 94 37 74 0 1145261376 79525296 7568736
10:01:14 37357 8192000000 10053824128 10483572736 1106655440 11.901 99 42 78 0 1159109312 43618384 8835488
10:02:55 30972 8192000000 10130742400 10570760192 1099436848 11.832 97 46 91 1 1194365616 85903520 9025248
10:04:26 17276 8192000000 10084627296 10516475904 1122350208 12.049 100 42 84 0 1174530352 42904576 9275568
10:05:44 18244 8192000000 10136258864 10570547200 1129973248 12.121 100 49 96 0 1231143136 91718032 9451856
10:07:04 13567 8192000000 10153407808 10583171072 1136121824 12.179 100 51 102 0 1236239392 90099056 10018512
10:08:18 56701 8192000000 10107262224 10583195648 1115984992 11.989 97 39 78 0 1193671040 69906912 7779136
10:11:00 2310 8192000000 10114141824 10546941952 1139920368 12.215 100 54 107 0 1213950720 61941936 12088416
10:12:03 31376 8192000000 10093680752 10589753344 1145553504 12.268 100 8 16 0 1215211520 68238080 1419936
10:13:42 68770 8192000000 10088977232 10536001536 1127383888 12.097 98 23 46 0 1179936080 47880384 4671808
10:15:51 32628 8192000000 10099433664 10561957888 1135318720 12.171 99 23 46 0 1215112912 74523888 5270304
10:17:27 45223 8192000000 10097866208 10537152512 1098975296 11.828 96 24 48 0 1181397104 77074784 5347024
10:19:19 60814 8192000000 10120653072 10617171968 1118061440 12.009 98 20 40 0 1201404368 79003472 4339456
10:21:59 6501 8192000000 10078311824 10510987264 1135447296 12.173 100 65 122 2 1173971984 25927936 12596752
10:23:09 17951 8192000000 10110138704 10536587264 1135012976 12.169 100 61 120 0 1189716800 43333184 11370640
10:24:45 40617 8192000000 10099347808 10572283904 1130374624 12.125 99 25 50 0 1176413360 40671488 5367248
10:26:28 126967 8192000000 10055222128 10510827520 1110917168 11.941 98 33 65 0 1214991072 97812096 6261808
10:29:46 33611 8192000000 10040197264 10504421376 1104300048 11.878 99 6 12 0 1143888688 38385936 1202704
10:31:20 98712 8192000000 10086680736 10581966848 1130696112 12.128 100 19 36 0 1181550240 47368512 3485616
10:33:59 46316 8192000000 10065665008 10502414336 1086235200 11.707 96 22 44 0 1144206768 53222320 4749248
10:35:50 37496 8192000000 10112728688 10562940928 1113699456 11.967 99 32 64 1 1204282608 83918288 6664864

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 01-07-2022 10:39:07 to 01-07-2022 10:39:23.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 53 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 01-07-2022 10:39:24 to 01-07-2022 10:39:26.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          225925
MON$OLDEST_ACTIVE               232933
MON$OLDEST_SNAPSHOT             232933
MON$NEXT_TRANSACTION            232933
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                       2075808
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             797
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2796 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2796 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2796 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2796 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 ---
:11: 
:12: mscs-test-161.budzdorov.ru    Fri Jul  1 10:37:21 2022
:13:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:14: 
:15: 
:16: mscs-test-161.budzdorov.ru    Fri Jul  1 10:37:23 2022
:17:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:18: 
:19: 
:20: mscs-test-161.budzdorov.ru    Fri Jul  1 10:37:23 2022
:21:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:22: 
--- end of diff output ---

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

01.07.22 10:39:27. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-07-01 10:39:29 No severe PSQL-related problems occured
01.07.22 10:39:31. End of report.