Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.05.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 16260828 kB
MemAvailable 31472356 kB
Buffers 0 kB
Cached 15409564 kB
SwapCached 2536 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-05-06 10:37:15
DTS_END                         2022-05-06 10:37:15.1970
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_MAKE_INVNT_SALDO(40:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/58188
TRN_ID                          208665
ATT_ID                          590
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: 8528.69 24567 1023443
customer order: creation 157.18 501 18862
customer order: refuse 13.98 451 1677
order to supplier: creation 40.33 1398 4839
order to supplier: removal 3.08 198730 369
invoice (draft): creation 43.10 3496 5172
invoice (draft): removal 48.44 1242 5813
invoice accept: apply 38.94 26603 4673
invoice accept: cancel 42.93 1253 5151
customer reserve: creation 3620.83 251 434499
customer reserve: removal 4248.19 110 509783
realization accept: apply 76.74 291 9209
realization accept: cancel 76.21 175 9145
payment from customer: creation 52.77 172 6332
payment from customer: removal 5.39 172 647
payment to supplier: creation 45.66 201 5479
payment to supplier: removal 3.18 110 382
service: total inventory turnovers 3.74 4671 449
service: total monetary turnovers 7.03 395 843
service: aggregate perf. data 0.95 15436 114
service: refresh index statistics 0.04 235675 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 06-05-2022 10:38:26 to 06-05-2022 10:38:28.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7252
TEST_TIME 2 5587
TEST_TIME 3 5168
TEST_TIME 4 5500
TEST_TIME 5 5473
TEST_TIME 6 5113
TEST_TIME 7 5174
TEST_TIME 8 5353
TEST_TIME 9 5653
TEST_TIME 10 5725
TEST_TIME 11 6107
TEST_TIME 12 6351
TEST_TIME 13 6325
TEST_TIME 14 6326
TEST_TIME 15 6443
TEST_TIME 16 6321
TEST_TIME 17 6591
TEST_TIME 18 6597
TEST_TIME 19 6577
TEST_TIME 20 6517
TEST_TIME 21 7014
TEST_TIME 22 7144
TEST_TIME 23 7151
TEST_TIME 24 7041
TEST_TIME 25 7086
TEST_TIME 26 7290
TEST_TIME 27 7541
TEST_TIME 28 7657
TEST_TIME 29 8223
TEST_TIME 30 8862
TEST_TIME 31 9356
TEST_TIME 32 9835
TEST_TIME 33 9945
TEST_TIME 34 10031
TEST_TIME 35 10163
TEST_TIME 36 10631
TEST_TIME 37 10683
TEST_TIME 38 10541
TEST_TIME 39 10334
TEST_TIME 40 10362
TEST_TIME 41 10182
TEST_TIME 42 10049
TEST_TIME 43 9958
TEST_TIME 44 9841
TEST_TIME 45 9811
TEST_TIME 46 9758
TEST_TIME 47 9650
TEST_TIME 48 9544
TEST_TIME 49 9474
TEST_TIME 50 9385
TEST_TIME 51 9457
TEST_TIME 52 9333
TEST_TIME 53 9199
TEST_TIME 54 9138
TEST_TIME 55 9061
TEST_TIME 56 8992
TEST_TIME 57 9125
TEST_TIME 58 8998
TEST_TIME 59 9003
TEST_TIME 60 8971
TEST_TIME 61 8862
TEST_TIME 62 8864
TEST_TIME 63 8802
TEST_TIME 64 8686
TEST_TIME 65 8675
TEST_TIME 66 8697
TEST_TIME 67 8646
TEST_TIME 68 8638
TEST_TIME 69 8651
TEST_TIME 70 8696
TEST_TIME 71 8686
TEST_TIME 72 8668
TEST_TIME 73 8737
TEST_TIME 74 8874
TEST_TIME 75 8841
TEST_TIME 76 8920
TEST_TIME 77 8871
TEST_TIME 78 8905
TEST_TIME 79 8836
TEST_TIME 80 8796
TEST_TIME 81 8723
TEST_TIME 82 8744
TEST_TIME 83 8684
TEST_TIME 84 8632
TEST_TIME 85 8614
TEST_TIME 86 8567
TEST_TIME 87 8559
TEST_TIME 88 8577
TEST_TIME 89 8550
TEST_TIME 90 8562
TEST_TIME 91 8549
TEST_TIME 92 8598
TEST_TIME 93 8566
TEST_TIME 94 8554
TEST_TIME 95 8632
TEST_TIME 96 8670
TEST_TIME 97 8641
TEST_TIME 98 8629
TEST_TIME 99 8679
TEST_TIME 100 8611
TEST_TIME 101 8593
TEST_TIME 102 8578
TEST_TIME 103 8573
TEST_TIME 104 8527
TEST_TIME 105 8485
TEST_TIME 106 8438
TEST_TIME 107 8438
TEST_TIME 108 8497
TEST_TIME 109 8484
TEST_TIME 110 8485
TEST_TIME 111 8444
TEST_TIME 112 8447
TEST_TIME 113 8456
TEST_TIME 114 8465
TEST_TIME 115 8479
TEST_TIME 116 8520
TEST_TIME 117 8615
TEST_TIME 118 8658
TEST_TIME 119 8622
TEST_TIME 120 8556

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 06-05-2022 10:38:29 to 06-05-2022 10:38:33.

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 1424223 1424223 0 0.00 0 21207 14 0 0
doc_list_biud 1424221 1424221 0 0.00 0 21821 46 0 0
sp_add_invoice_to_stock 4762 4673 89 1.86 42 289192 26604 0 89
sp_cancel_adding_invoice 5151 5151 0 0.00 6 92538 1253 0 0
sp_cancel_client_order 1677 1677 0 0.00 6 13556 452 0 0
sp_cancel_customer_reserve 509783 509783 0 0.00 1 21825 111 0 0
sp_cancel_pay_from_customer 647 647 0 0.00 4 14617 172 0 0
sp_cancel_pay_to_supplier 382 382 0 0.00 4 1235 110 0 0
sp_cancel_supplier_invoice 5824 5813 11 0.18 4 17049 1243 0 11
sp_cancel_supplier_order 369 369 0 0.00 25 1551606 198730 0 0
sp_cancel_write_off 9145 9145 0 0.00 2 10879 176 0 0
sp_client_order 18862 18862 0 0.00 6 27174 501 0 0
sp_customer_reserve 434499 434499 0 0.00 0 77227 251 0 0
sp_fill_shopping_cart 28863 28863 0 0.00 1 26497 91 0 0
sp_fill_shopping_cart_clo_res 434490 434490 0 0.00 0 17763 21 0 0
sp_get_clo_for_invoice 4628 4628 0 0.00 0 22195 351 0 0
sp_kill_cost_storno 15325 15325 0 0.00 0 12996 82 0 0
sp_kill_qstorno_ret_qs2qd 1034893 1034893 0 0.00 0 21815 49 0 0
sp_kill_qty_storno 545773 545773 0 0.00 0 21820 111 0 0
sp_lock_selected_doc 19172 19172 0 0.00 0 12987 9 0 0
sp_make_cost_storno 25648 25648 0 0.00 0 19253 82 0 0
sp_multiply_rows_for_pdistr 25647 25647 0 0.00 0 21148 40 0 0
sp_multiply_rows_for_qdistr 18862 18862 0 0.00 0 16157 199 0 0
sp_pay_from_customer 6332 6332 0 0.00 6 12412 173 0 0
sp_pay_to_supplier 5479 5479 0 0.00 7 21215 202 0 0
sp_payment_common 11811 11811 0 0.00 2 12190 47 0 0
sp_qd_handle_on_cancel_clo 1677 1677 0 0.00 0 7672 370 0 0
sp_qd_handle_on_invoice_upd_sts 9777 9777 0 0.00 0 19944 561 0 0
sp_qd_handle_on_reserve_upd_sts 9209 9209 0 0.00 0 10539 62 0 0
sp_reserve_write_off 9209 9209 0 0.00 8 27753 292 0 0
sp_supplier_invoice 5172 5172 0 0.00 8 124668 3497 0 0
sp_supplier_order 4839 4839 0 0.00 8 35581 1398 0 0
srv_aggregate_perf_data 115 114 1 0.86 2281 197778 15436 0 1
srv_fill_mon_cache_memory 69 69 0 0.00 906 135200 36342 0 0
srv_find_qd_qs_mism 978584 978584 0 0.00 0 16344 10 0 0
srv_make_invnt_saldo 449 449 0 0.00 67 70261 4672 0 0
srv_make_money_saldo 843 843 0 0.00 2 16578 396 0 0
srv_recalc_idx_stat 5 5 0 0.00 110657 450840 235676 0 0
t$perf-abend:sp_add_invoice_to_stock 89 89 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4679 4679 0 0.00 1 12839 923 0 0
t$perf-norm:sp_cancel_adding_invoice 364 364 0 0.00 1 14558 545 0 0
t$perf-norm:sp_cancel_client_order 1677 1677 0 0.00 0 6795 33 0 0
t$perf-norm:sp_cancel_customer_reserve 696 696 0 0.00 0 18100 54 0 0
t$perf-norm:sp_cancel_pay_from_customer 647 647 0 0.00 0 8473 25 0 0
t$perf-norm:sp_cancel_pay_to_supplier 382 382 0 0.00 0 5822 27 0 0
t$perf-norm:sp_cancel_supplier_invoice 358 358 0 0.00 0 1523 41 0 0
t$perf-norm:sp_cancel_supplier_order 369 369 0 0.00 0 109375 8453 0 0
t$perf-norm:sp_cancel_write_off 845 845 0 0.00 0 907 18 0 0
t$perf-norm:sp_client_order 18862 18862 0 0.00 0 20557 40 0 0
t$perf-norm:sp_customer_reserve 918 918 0 0.00 0 2258 15 0 0
t$perf-norm:sp_pay_from_customer 6332 6332 0 0.00 0 6220 22 0 0
t$perf-norm:sp_pay_to_supplier 5479 5479 0 0.00 0 17263 21 0 0
t$perf-norm:sp_reserve_write_off 9209 9209 0 0.00 0 17225 30 0 0
t$perf-norm:sp_supplier_invoice 5172 5172 0 0.00 0 10292 35 0 0
t$perf-norm:sp_supplier_order 4841 4841 0 0.00 0 9048 35 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 1158 28 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 36 3 0 0
t$perf-norm:srv_make_invnt_saldo 449 449 0 0.00 0 602 14 0 0
t$perf-norm:srv_make_money_saldo 843 843 0 0.00 0 1074 7 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 8 496 130 0 0
v_add_invoice_to_stock 9430 9252 178 1.88 0 4225 24 0 178
v_all_customers 34224 34224 0 0.00 0 87 3 0 0
v_all_suppliers 28232 28232 0 0.00 0 106 4 0 0
v_all_wares 37724 37724 0 0.00 0 166 4 0 0
v_cancel_adding_invoice 728 728 0 0.00 0 2593 17 0 0
v_cancel_client_order 3354 3354 0 0.00 0 4239 15 0 0
v_cancel_customer_prepayment 1294 1294 0 0.00 0 2285 13 0 0
v_cancel_customer_reserve 1392 1392 0 0.00 0 1696 10 0 0
v_cancel_payment_to_supplier 764 764 0 0.00 0 1163 14 0 0
v_cancel_supplier_invoice 738 716 22 2.98 0 2563 30 0 22
v_cancel_supplier_order 738 738 0 0.00 0 979 10 0 0
v_cancel_write_off 1690 1690 0 0.00 0 6964 31 0 0
v_max_id_clo_ord 4838 4838 0 0.00 0 4198 6 0 0
v_max_id_clo_res 918 918 0 0.00 0 10516 23 0 0
v_max_id_ord_sup 5163 5163 0 0.00 0 5230 8 0 0
v_max_non_paid_invoice 1364 1364 0 0.00 0 365 7 0 0
v_max_non_paid_realizn 3322 3322 0 0.00 0 753 7 0 0
v_min_id_clo_ord 4838 4838 0 0.00 0 3108 10 0 0
v_min_id_clo_res 918 918 0 0.00 0 814 10 0 0
v_min_id_ord_sup 5163 5163 0 0.00 0 8336 15 0 0
v_min_non_paid_invoice 5479 5479 0 0.00 0 8977 18 0 0
v_min_non_paid_realizn 6332 6332 0 0.00 0 7283 14 0 0
v_our_firm 9520 9520 0 0.00 0 80 4 0 0
v_reserve_write_off 18416 18416 0 0.00 0 4241 13 0 0
x_lock_depdocs_on_canc_invoice 10606 10606 0 0.00 0 40444 140 0 0
x_lock_depdocs_on_canc_sup_ord 369 369 0 0.00 0 8368 394 0 0
x_make_qstorno_1000_1200 4839 4839 0 0.00 2 35572 1224 0 0
x_make_qstorno_1200_2000 5172 5172 0 0.00 3 121122 3229 0 0
x_make_qstorno_2100_3300 434499 434499 0 0.00 0 77220 218 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-05-2022 10:38:34 to 06-05-2022 10:38:38.

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:18 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:19 10288 8192000000 9706010992 10003070976 793724736 8.833 100 89 178 0 822871216 7302656 21843824
08:09:35 16495 8192000000 9756929648 10062307328 826813568 9.167 100 93 186 0 861998448 12650016 22534864
08:11:06 14171 8192000000 9797394400 10115129344 860266128 9.503 100 83 166 1 899303808 20024272 19013408
08:12:24 7288 8192000000 9852464112 10182828032 899704176 9.895 100 81 163 0 962797312 45376976 17716160
08:13:38 5261 8192000000 9903437856 10245734400 948603008 10.377 100 90 170 0 1017931904 51751680 17577216
08:14:51 1700 8192000000 9947974576 10299473920 983691408 10.720 100 92 177 0 1068529584 66889760 17948416
08:15:56 2353 8192000000 9991861616 10351620096 1014962640 11.023 100 87 168 1 1105893792 74013312 16917840
08:17:00 1385 8192000000 10014332688 10381123584 1037138080 11.237 100 93 176 0 1130773360 76550320 17084960
08:18:08 1101 8192000000 10030091296 10403065856 1058687392 11.444 100 98 190 1 1151307280 72494048 20125840
08:19:10 1269 8192000000 10027939104 10405535744 1067431072 11.528 100 93 184 0 1152005984 66281840 18293072
08:20:13 5397 8192000000 10026507952 10408501248 1070712576 11.559 100 77 147 2 1158660512 73201408 14746528
08:21:20 727 8192000000 10082944448 10470465536 1080557056 11.653 100 89 178 0 1199060448 100507440 17995952
08:22:22 1190 8192000000 10054047584 10440192000 1079182256 11.640 100 95 185 3 1174359104 76905104 18271744
08:23:25 2238 8192000000 10032735584 10428604416 1085858816 11.703 100 71 134 0 1154320816 54721328 13740672
08:24:27 8578 8192000000 10047544544 10439634944 1089455344 11.737 100 66 127 1 1174170368 71238544 13476480
08:25:37 1608 8192000000 10040366784 10435538944 1093260224 11.774 100 67 122 4 1165767504 59998432 12508848
08:26:39 1938 8192000000 10051177520 10445434880 1095067696 11.791 100 65 124 2 1170501184 62279344 13154144
08:27:42 795 8192000000 10029379360 10421297152 1089812416 11.741 99 84 163 1 1151593888 45080224 16701248
08:28:44 1014 8192000000 10031822544 10416291840 1081882160 11.665 100 90 178 2 1152298608 52466624 17949824
08:29:46 826 8192000000 10054741008 10444709888 1091951424 11.761 100 96 183 0 1176930112 65951872 19026816
08:30:48 1226 8192000000 10045598384 10429054976 1088693216 11.730 100 93 183 0 1164897200 57742320 18461664
08:31:50 1325 8192000000 10015842112 10391744512 1047174336 11.334 99 94 182 3 1137035344 71029680 18831328
08:32:53 1366 8192000000 10002012048 10375258112 1041883968 11.283 100 97 188 1 1128974960 67558256 19532736
08:33:55 998 8192000000 9966911600 10351304704 1035372752 11.220 100 65 123 1 1090752480 41429376 13950352
08:34:58 999 8192000000 9946615824 10311950336 1014004848 11.014 100 89 170 1 1069371072 37827440 17538784
08:35:59 2801 8192000000 9988027600 10379345920 1036287504 11.229 100 75 146 1 1109461632 57319840 15854288
08:37:03 1012 8192000000 9994301552 10370064384 1048052560 11.342 100 93 172 0 1117513808 51973696 17487552
08:38:07 14627 8192000000 10000833808 10388004864 1064180000 11.496 100 55 102 0 1120232720 45921984 10130736
08:39:30 892 8192000000 10038391040 10420289536 1084308080 11.689 100 78 145 0 1161058176 60966576 15783520
08:40:37 16202 8192000000 10076884096 10464174080 1097938016 11.818 100 68 127 0 1199705232 88875312 12891904
08:42:01 5524 8192000000 10106358800 10502328320 1110249504 11.935 100 60 112 0 1222393168 100662544 11481120
08:43:13 21902 8192000000 10155017552 10555793408 1119781232 12.025 100 59 116 0 1261630112 129549072 12299808
08:44:52 6505 8192000000 10175707152 10573852672 1128555344 12.108 100 69 134 0 1292207344 148898784 14753216
08:46:12 1364 8192000000 10241529856 10633109504 1143923408 12.252 100 93 175 2 1356942432 194469920 18549104
08:47:17 1891 8192000000 10269465952 10663370752 1155961040 12.365 100 84 161 1 1386605824 212859744 17785040
08:48:22 1166 8192000000 10305160688 10716438528 1161247760 12.415 100 94 178 3 1419900192 237210624 21441808
08:49:28 1683 8192000000 10325050784 10741125120 1173620128 12.531 100 85 167 1 1436870944 243719712 19531104
08:50:34 1499 8192000000 10356911664 10758279168 1177705184 12.569 100 86 170 0 1472210128 275345216 19159728
08:51:42 2216 8192000000 10405727088 10813861888 1192044208 12.702 100 94 180 1 1516850464 302499216 22307040
08:52:48 1281 8192000000 10430567664 10838700032 1196894240 12.747 100 89 175 0 1542955456 324935536 21125680
08:53:54 919 8192000000 10480392848 10907279360 1209575856 12.865 100 96 188 1 1586423680 353964448 22883376
08:54:59 1545 8192000000 10515004960 10922827776 1219072672 12.953 100 91 180 0 1616452224 374941072 22438480
08:56:04 899 8192000000 10564341888 10974019584 1217883024 12.942 100 98 185 0 1667786496 427551152 22352320
08:57:07 902 8192000000 10576327904 11030827008 1222481008 12.985 100 98 194 3 1682901792 436351184 24069600
08:58:11 1136 8192000000 10594117984 11048120320 1219054864 12.953 100 96 188 1 1698268320 456619600 22593856
08:59:16 1186 8192000000 10604871552 11028418560 1201377632 12.789 100 92 180 2 1708850080 485608256 21864192
09:00:21 1094 8192000000 10649478800 11067367424 1213935744 12.906 100 79 154 0 1750974608 518142736 18896128
09:01:29 1063 8192000000 10705405968 11116544000 1204335024 12.817 100 90 180 0 1804362720 578423472 21604224
09:02:33 3913 8192000000 10726124736 11175272448 1202692976 12.801 100 81 159 1 1830760544 608266352 19801216
09:03:46 5268 8192000000 10709171552 11165470720 1180988496 12.599 99 57 112 0 1812162288 615893232 15280560
09:05:01 4972 8192000000 10669501552 11115581440 1159231440 12.396 99 58 115 0 1762760784 588538672 14990672
09:06:10 5491 8192000000 10594538272 11119804416 1158587168 12.390 100 40 80 0 1702344624 532213520 11543936
09:07:18 13287 8192000000 10392449520 10948898816 1136894464 12.186 98 40 80 0 1518786304 371423008 10468832
09:08:33 18204 8192000000 10365958272 10835193856 1159101680 12.395 100 32 64 1 1463773696 296446832 8225184
09:10:08 18137 8192000000 10276749200 10711986176 1163707008 12.438 100 39 76 0 1377420096 205316096 8396992
09:11:35 44256 8192000000 10208471024 10716823552 1132165680 12.142 98 24 48 0 1330551632 192738720 5647232
09:13:21 21550 8192000000 10146967872 10647343104 1141020256 12.225 99 47 90 4 1237943520 87568064 9355200
09:14:49 80337 8192000000 10073049936 10545598464 1112826992 11.959 97 14 28 0 1154889296 39099264 2963040
09:17:11 57592 8192000000 10063666368 10526339072 1125349872 12.078 99 14 29 0 1185705072 57166080 3189120
09:19:10 52943 8192000000 10079461712 10519326720 1101631280 11.853 97 16 32 0 1157036720 52273472 3131968
09:21:08 68345 8192000000 10082339824 10525999104 1122576992 12.051 99 19 38 1 1176988352 50194512 4216848
09:23:18 56487 8192000000 10049351952 10485645312 1087212624 11.716 96 17 34 1 1131119472 40749152 3157696
09:25:33 124222 8192000000 10100255808 10538999808 1103169824 11.868 98 39 79 2 1179114896 67992848 7952224
09:28:52 114002 8192000000 10040388160 10480402432 1098122192 11.820 98 29 55 1 1127372304 23520960 5729152
09:31:51 24313 8192000000 10079892336 10513141760 1126414240 12.088 100 42 84 1 1181765376 46457104 8894032
09:33:22 83978 8192000000 10131789840 10617303040 1127811264 12.101 99 19 38 2 1226462992 94333040 4318688
09:35:50 20213 8192000000 10064925696 10516897792 1115671488 11.986 99 53 105 0 1173592416 47388848 10532080
09:37:11 53296 8192000000 10052330800 10521018368 1116085040 11.990 100 12 24 0 1157843408 38917312 2841056
09:39:05 78342 8192000000 10049799584 10487734272 1114162608 11.972 99 35 66 1 1169560448 48473648 6924192
09:41:25 54551 8192000000 10078865120 10549649408 1108067248 11.914 98 15 30 0 1172763792 61254112 3442432
09:43:20 99877 8192000000 10098225792 10543116288 1103700064 11.873 97 13 26 0 1142614432 36136912 2777456
09:46:01 39935 8192000000 10123345200 10589634560 1132009728 12.140 99 34 68 1 1235551248 96525888 7015632
09:47:41 50700 8192000000 10128475600 10598830080 1107613520 11.910 98 23 45 1 1212064496 99536832 4914144
09:49:33 39069 8192000000 10174178176 10618568704 1120756352 12.034 99 31 62 1 1257511808 130004080 6751376
09:51:14 20394 8192000000 10083285776 10518941696 1120875408 12.035 99 34 66 2 1168705856 41486624 6343824
09:52:39 21673 8192000000 10113160944 10540007424 1117581504 12.004 99 38 74 0 1192231568 66971952 7678112
09:54:02 18180 8192000000 10055088368 10480017408 1113092000 11.962 99 59 116 6 1141849792 16459760 12298032
09:55:22 23988 8192000000 10086700144 10521137152 1120542080 12.032 100 41 81 0 1181371120 53133488 7695552
09:56:47 90521 8192000000 10077284400 10538774528 1117085056 11.999 99 31 63 0 1195539008 72366944 6087008
10:01:03 64798 8192000000 10042788176 10500485120 1095059584 11.791 97 5 10 1 1132879072 36759936 1059552
10:03:13 40559 8192000000 10070001584 10508206080 1108527760 11.918 98 18 36 1 1148520448 35581792 4410896
10:05:00 34031 8192000000 10089520496 10529443840 1126589488 12.089 99 28 56 1 1180717792 48203648 5924656
10:06:35 49266 8192000000 10112897920 10559807488 1110237168 11.935 97 33 65 1 1193721616 76178512 7305936
10:08:27 50973 8192000000 10134413568 10585493504 1124542624 12.070 99 29 58 3 1228907008 98094752 6269632
10:10:19 56295 8192000000 10125844576 10571792384 1105468832 11.889 97 29 58 1 1188052096 76960880 5622384
10:12:16 23931 8192000000 10051621760 10472775680 1073608400 11.587 97 42 84 2 1113898976 31706640 8583936
10:13:41 32388 8192000000 10084243872 10527776768 1116573680 11.995 100 31 62 0 1179902896 57303200 6026016
10:15:17 11902 8192000000 10083739216 10497773568 1117315760 12.002 100 60 112 1 1176488032 47580544 11591728
10:16:29 135199 8192000000 10054479200 10518908928 1097926656 11.818 98 32 61 2 1189629776 85289984 6413136
10:19:45 20940 8192000000 10042325120 10491924480 1093720960 11.778 98 40 79 0 1136796848 33350208 9725680
10:21:07 62092 8192000000 10042281936 10493739008 1106924592 11.903 99 14 27 0 1140491680 30379600 3187488
10:25:54 122482 8192000000 10081250400 10556084224 1102331024 11.860 97 15 30 1 1132976592 27250272 3395296
10:28:57 106538 8192000000 10159121200 10636046336 1108396192 11.917 96 33 65 1 1223047120 106721536 7929392
10:31:59 88521 8192000000 10150951168 10650124288 1146034512 12.272 100 18 36 0 1271785152 121601008 4149632
10:34:31 81906 8192000000 10105342576 10603229184 1143280288 12.246 100 17 34 2 1188516432 41537472 3698672
10:36:54 32013 8192000000 9051652048 9310277632 197171136 2.350 18 4 8 0 212106320 14310384 624800

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 06-05-2022 10:38:40 to 06-05-2022 10:38:56.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 89 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 06-05-2022 10:38:57 to 06-05-2022 10:38:59.

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          206938
MON$OLDEST_ACTIVE               208940
MON$OLDEST_SNAPSHOT             208940
MON$NEXT_TRANSACTION            208940
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                       2070176
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             755
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2763 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2763 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2763 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2763 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 May  6 10:37:30 2022
:13:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:14: 
:15: 
:16: mscs-test-161.budzdorov.ru    Fri May  6 10:37:30 2022
:17:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:18: 
:19: 
:20: mscs-test-161.budzdorov.ru    Fri May  6 10:37:30 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'

06.05.22 10:39:00. 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-05-06 10:39:02 No severe PSQL-related problems occured
06.05.22 10:39:03. End of report.