Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.10.2021 07: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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 15778764 kB
MemAvailable 30715112 kB
Buffers 0 kB
Cached 15959208 kB
SwapCached 3780 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-10-15 07:37:06
DTS_END                         2021-10-15 07:37:06.2400
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/55162
TRN_ID                          236743
ATT_ID                          617
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: 9209.53 18492 1105144
customer order: creation 175.73 406 21088
customer order: refuse 14.71 286 1765
order to supplier: creation 46.55 1397 5586
order to supplier: removal 3.36 116287 403
invoice (draft): creation 46.50 3635 5580
invoice (draft): removal 51.64 946 6197
invoice accept: apply 41.63 18746 4995
invoice accept: cancel 46.47 871 5576
customer reserve: creation 3888.11 184 466573
customer reserve: removal 4601.23 68 552147
realization accept: apply 85.99 192 10319
realization accept: cancel 76.76 122 9211
payment from customer: creation 60.92 103 7310
payment from customer: removal 5.76 80 691
payment to supplier: creation 49.69 126 5963
payment to supplier: removal 3.32 75 398
service: total inventory turnovers 3.96 3498 475
service: total monetary turnovers 6.30 504 756
service: aggregate perf. data 0.89 17321 107
service: refresh index statistics 0.03 204994 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-10-2021 07:39:45 to 15-10-2021 07:39:47.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5270
TEST_TIME 2 6554
TEST_TIME 3 6971
TEST_TIME 4 6352
TEST_TIME 5 5899
TEST_TIME 6 5709
TEST_TIME 7 6190
TEST_TIME 8 6041
TEST_TIME 9 6315
TEST_TIME 10 6428
TEST_TIME 11 6774
TEST_TIME 12 7173
TEST_TIME 13 7814
TEST_TIME 14 7847
TEST_TIME 15 7741
TEST_TIME 16 7993
TEST_TIME 17 8220
TEST_TIME 18 8280
TEST_TIME 19 8311
TEST_TIME 20 8348
TEST_TIME 21 8455
TEST_TIME 22 8875
TEST_TIME 23 8936
TEST_TIME 24 9701
TEST_TIME 25 9825
TEST_TIME 26 10320
TEST_TIME 27 10214
TEST_TIME 28 10541
TEST_TIME 29 11496
TEST_TIME 30 11369
TEST_TIME 31 11192
TEST_TIME 32 11189
TEST_TIME 33 10877
TEST_TIME 34 10752
TEST_TIME 35 10730
TEST_TIME 36 10530
TEST_TIME 37 10472
TEST_TIME 38 10366
TEST_TIME 39 10304
TEST_TIME 40 10488
TEST_TIME 41 10379
TEST_TIME 42 10240
TEST_TIME 43 10174
TEST_TIME 44 10231
TEST_TIME 45 10280
TEST_TIME 46 10234
TEST_TIME 47 10144
TEST_TIME 48 10104
TEST_TIME 49 10289
TEST_TIME 50 10260
TEST_TIME 51 10329
TEST_TIME 52 10291
TEST_TIME 53 10304
TEST_TIME 54 10305
TEST_TIME 55 10339
TEST_TIME 56 10368
TEST_TIME 57 10209
TEST_TIME 58 10177
TEST_TIME 59 10062
TEST_TIME 60 10046
TEST_TIME 61 10018
TEST_TIME 62 9953
TEST_TIME 63 9862
TEST_TIME 64 9787
TEST_TIME 65 9708
TEST_TIME 66 9791
TEST_TIME 67 9807
TEST_TIME 68 9767
TEST_TIME 69 9748
TEST_TIME 70 9804
TEST_TIME 71 9848
TEST_TIME 72 9785
TEST_TIME 73 9869
TEST_TIME 74 9846
TEST_TIME 75 9808
TEST_TIME 76 9954
TEST_TIME 77 9924
TEST_TIME 78 9821
TEST_TIME 79 9803
TEST_TIME 80 9728
TEST_TIME 81 9710
TEST_TIME 82 9651
TEST_TIME 83 9609
TEST_TIME 84 9584
TEST_TIME 85 9591
TEST_TIME 86 9599
TEST_TIME 87 9579
TEST_TIME 88 9609
TEST_TIME 89 9640
TEST_TIME 90 9653
TEST_TIME 91 9660
TEST_TIME 92 9626
TEST_TIME 93 9676
TEST_TIME 94 9654
TEST_TIME 95 9660
TEST_TIME 96 9616
TEST_TIME 97 9585
TEST_TIME 98 9560
TEST_TIME 99 9499
TEST_TIME 100 9445
TEST_TIME 101 9402
TEST_TIME 102 9393
TEST_TIME 103 9342
TEST_TIME 104 9345
TEST_TIME 105 9302
TEST_TIME 106 9343
TEST_TIME 107 9339
TEST_TIME 108 9307
TEST_TIME 109 9325
TEST_TIME 110 9322
TEST_TIME 111 9368
TEST_TIME 112 9356
TEST_TIME 113 9442
TEST_TIME 114 9429
TEST_TIME 115 9489
TEST_TIME 116 9414
TEST_TIME 117 9343
TEST_TIME 118 9335
TEST_TIME 119 9289
TEST_TIME 120 9214

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 15-10-2021 07:39:48 to 15-10-2021 07:39:52.

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 1537062 1537062 0 0.00 0 31376 7 0 0
doc_list_biud 1537062 1537062 0 0.00 0 39521 31 0 0
sp_add_invoice_to_stock 5124 4995 129 2.51 48 239645 18746 0 129
sp_cancel_adding_invoice 5576 5576 0 0.00 6 118413 871 0 0
sp_cancel_client_order 1765 1765 0 0.00 4 8003 286 0 0
sp_cancel_customer_reserve 552147 552147 0 0.00 1 39049 68 0 0
sp_cancel_pay_from_customer 691 691 0 0.00 2 4232 81 0 0
sp_cancel_pay_to_supplier 398 398 0 0.00 3 4422 76 0 0
sp_cancel_supplier_invoice 6207 6197 10 0.16 2 39524 947 0 10
sp_cancel_supplier_order 403 403 0 0.00 9 991065 116288 0 0
sp_cancel_write_off 9211 9211 0 0.00 1 7230 122 0 0
sp_client_order 21088 21088 0 0.00 4 18758 406 0 0
sp_customer_reserve 466573 466573 0 0.00 0 76605 184 0 0
sp_fill_shopping_cart 32254 32254 0 0.00 1 15395 42 0 0
sp_fill_shopping_cart_clo_res 466569 466569 0 0.00 0 16392 17 0 0
sp_get_clo_for_invoice 4987 4987 0 0.00 0 15101 337 0 0
sp_kill_cost_storno 15876 15876 0 0.00 0 9958 59 0 0
sp_kill_qstorno_ret_qs2qd 1120105 1120105 0 0.00 0 39519 37 0 0
sp_kill_qty_storno 590605 590605 0 0.00 0 39519 79 0 0
sp_lock_selected_doc 21079 21079 0 0.00 0 7363 5 0 0
sp_make_cost_storno 28579 28579 0 0.00 0 31370 51 0 0
sp_multiply_rows_for_pdistr 28579 28579 0 0.00 0 10140 33 0 0
sp_multiply_rows_for_qdistr 21088 21088 0 0.00 0 16930 212 0 0
sp_pay_from_customer 7310 7310 0 0.00 4 9346 104 0 0
sp_pay_to_supplier 5963 5963 0 0.00 5 13460 127 0 0
sp_payment_common 13273 13273 0 0.00 1 9337 22 0 0
sp_qd_handle_on_cancel_clo 1765 1765 0 0.00 0 7994 261 0 0
sp_qd_handle_on_invoice_upd_sts 10563 10563 0 0.00 0 15292 329 0 0
sp_qd_handle_on_reserve_upd_sts 10319 10319 0 0.00 0 23084 66 0 0
sp_reserve_write_off 10319 10319 0 0.00 7 23103 193 0 0
sp_supplier_invoice 5580 5580 0 0.00 6 76039 3636 0 0
sp_supplier_order 5586 5586 0 0.00 6 22585 1397 0 0
srv_aggregate_perf_data 107 107 0 0.00 2703 193523 17321 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1373 135494 46252 0 0
srv_find_qd_qs_mism 1056654 1056654 0 0.00 0 16390 6 0 0
srv_make_invnt_saldo 475 475 0 0.00 46 95378 3498 0 0
srv_make_money_saldo 756 756 0 0.00 1 16981 504 0 0
srv_recalc_idx_stat 4 4 0 0.00 107137 355120 204995 0 0
t$perf-abend:sp_add_invoice_to_stock 129 129 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4995 4995 0 0.00 0 13718 140 0 0
t$perf-norm:sp_cancel_adding_invoice 396 396 0 0.00 1 4053 90 0 0
t$perf-norm:sp_cancel_client_order 1765 1765 0 0.00 0 2709 10 0 0
t$perf-norm:sp_cancel_customer_reserve 681 681 0 0.00 0 1037 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 691 691 0 0.00 0 4655 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 398 398 0 0.00 0 97 1 0 0
t$perf-norm:sp_cancel_supplier_invoice 429 429 0 0.00 0 1053 18 0 0
t$perf-norm:sp_cancel_supplier_order 403 403 0 0.00 0 23318 1508 0 0
t$perf-norm:sp_cancel_write_off 1010 1010 0 0.00 0 4409 16 0 0
t$perf-norm:sp_client_order 21088 21088 0 0.00 0 11464 18 0 0
t$perf-norm:sp_customer_reserve 1048 1048 0 0.00 0 2484 9 0 0
t$perf-norm:sp_pay_from_customer 7310 7310 0 0.00 0 10951 13 0 0
t$perf-norm:sp_pay_to_supplier 5963 5963 0 0.00 0 5962 9 0 0
t$perf-norm:sp_reserve_write_off 10319 10319 0 0.00 0 5430 10 0 0
t$perf-norm:sp_supplier_invoice 5580 5580 0 0.00 0 4340 14 0 0
t$perf-norm:sp_supplier_order 5586 5586 0 0.00 0 9137 14 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 551 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 6 1 0 0
t$perf-norm:srv_make_invnt_saldo 475 475 0 0.00 0 6580 22 0 0
t$perf-norm:srv_make_money_saldo 756 756 0 0.00 0 3527 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 21 7 0 0
v_add_invoice_to_stock 10232 9974 258 2.52 0 3868 18 0 258
v_all_customers 38720 38720 0 0.00 0 99 1 0 0
v_all_suppliers 31138 31138 0 0.00 0 116 1 0 0
v_all_wares 42176 42176 0 0.00 0 71 1 0 0
v_cancel_adding_invoice 792 792 0 0.00 0 1567 8 0 0
v_cancel_client_order 3530 3530 0 0.00 0 3008 4 0 0
v_cancel_customer_prepayment 1382 1382 0 0.00 0 4210 17 0 0
v_cancel_customer_reserve 1362 1362 0 0.00 0 3419 8 0 0
v_cancel_payment_to_supplier 796 796 0 0.00 0 162 4 0 0
v_cancel_supplier_invoice 878 858 20 2.27 0 4535 23 0 20
v_cancel_supplier_order 806 806 0 0.00 0 3813 13 0 0
v_cancel_write_off 2020 2020 0 0.00 0 1585 12 0 0
v_max_id_clo_ord 5585 5585 0 0.00 0 3917 3 0 0
v_max_id_clo_res 1048 1048 0 0.00 0 4450 13 0 0
v_max_id_ord_sup 5580 5580 0 0.00 0 6949 7 0 0
v_max_non_paid_invoice 1559 1559 0 0.00 0 1767 4 0 0
v_max_non_paid_realizn 3728 3728 0 0.00 0 2909 5 0 0
v_min_id_clo_ord 5585 5585 0 0.00 0 7228 8 0 0
v_min_id_clo_res 1048 1048 0 0.00 0 4926 22 0 0
v_min_id_ord_sup 5580 5580 0 0.00 0 6299 5 0 0
v_min_non_paid_invoice 5963 5963 0 0.00 0 7070 15 0 0
v_min_non_paid_realizn 7310 7310 0 0.00 0 5193 14 0 0
v_our_firm 10620 10620 0 0.00 0 17 1 0 0
v_reserve_write_off 20638 20638 0 0.00 0 4056 5 0 0
x_lock_depdocs_on_canc_invoice 11344 11344 0 0.00 0 15078 105 0 0
x_lock_depdocs_on_canc_sup_ord 403 403 0 0.00 0 4698 305 0 0
x_make_qstorno_1000_1200 5586 5586 0 0.00 2 22569 1320 0 0
x_make_qstorno_1200_2000 5580 5580 0 0.00 3 76031 3472 0 0
x_make_qstorno_2100_3300 466573 466573 0 0.00 0 76599 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-10-2021 07:39:53 to 15-10-2021 07:39:57.

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
05:07:10 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:11 26149 8192000000 9728905584 10027261952 801118576 8.908 100 82 164 0 828493440 7266336 20108528
05:09:42 12565 8192000000 9768136624 10077220864 847364240 9.374 100 88 176 0 879534432 11537264 20632928
05:10:58 17745 8192000000 9813268800 10133274624 882019552 9.720 100 83 166 0 919056144 18495280 18541312
05:12:23 13873 8192000000 9864580240 10197323776 923173568 10.127 100 80 160 1 978778176 38332800 17271808
05:13:40 12394 8192000000 9935505184 10277572608 957141328 10.461 100 88 178 0 1044611264 68248624 19221312
05:14:57 6076 8192000000 9958865888 10311819264 989835312 10.780 100 78 156 1 1077668160 71312096 16520752
05:16:05 4069 8192000000 9998517248 10361528320 1024874304 11.119 100 84 168 0 1117485104 76110064 16500736
05:17:11 2519 8192000000 10023464304 10389643264 1046495424 11.327 100 91 181 2 1134946464 69536256 18914784
05:18:15 2225 8192000000 10044708224 10420084736 1065379440 11.508 100 89 178 1 1159032352 75184016 18468896
05:19:18 3785 8192000000 10000033216 10384920576 1068464560 11.537 100 49 98 1 1108801872 29695936 10641376
05:20:25 7854 8192000000 10008208976 10404397056 1077569568 11.624 100 47 89 1 1117711536 29985824 10156144
05:21:35 7742 8192000000 9999121440 10395635712 1087271488 11.717 100 26 53 0 1117761056 25586864 4902704
05:22:43 35393 8192000000 9998642064 10406318080 1089028160 11.733 100 46 90 2 1116284400 16896512 10359728
05:24:20 4501 8192000000 10018972944 10407149568 1095899696 11.799 100 43 86 2 1124346384 19713232 8733456
05:25:25 9378 8192000000 10011844896 10432303104 1099611152 11.834 100 30 60 0 1131567008 25971408 5984448
05:26:35 27303 8192000000 10011368512 10435444736 1084021008 11.686 99 24 46 1 1108350368 19371072 4958288
05:28:22 7271 8192000000 10001298992 10404466688 1073022848 11.581 99 41 82 0 1099562224 17874016 8665360
05:29:30 5920 8192000000 9952973776 10333802496 1024115712 11.112 98 44 86 2 1046797984 13751488 8930784
05:30:40 13732 8192000000 9949800224 10344067072 1006472608 10.941 97 34 69 2 1040468848 26524768 7471472
05:31:54 22421 8192000000 9931801888 10332704768 1002177328 10.900 97 25 50 1 1040282896 32910720 5194848
05:33:16 4638 8192000000 9960130064 10346844160 1041228304 11.276 99 49 98 1 1066099840 13914720 10956816
05:34:22 7981 8192000000 9964960560 10347544576 1043733344 11.301 99 30 61 0 1076255280 25716848 6805088
05:35:30 38877 8192000000 9975163936 10367967232 1051314128 11.373 99 49 97 2 1079160576 16235952 11610496
05:37:28 4447 8192000000 10009006016 10400198656 1088937040 11.733 100 46 93 0 1119833648 21192304 9704304
05:38:46 16660 8192000000 10058353728 10458386432 1104794832 11.883 100 56 112 3 1168317440 52134400 11388208
05:40:06 20457 8192000000 10103028720 10504130560 1120630576 12.033 100 64 120 4 1200209376 66686352 12892448
05:41:30 46493 8192000000 10123851616 10534866944 1133959792 12.159 100 39 77 1 1220098400 77365920 8772688
05:43:35 16834 8192000000 10181635840 10588667904 1148237616 12.293 100 54 108 1 1283522048 122806512 12477920
05:44:53 18227 8192000000 10195534512 10611871744 1156005520 12.366 100 39 78 0 1298481680 133550336 8925824
05:46:16 25499 8192000000 10213620544 10622291968 1159584544 12.399 100 51 103 1 1327064448 155564096 11915808
05:47:45 9228 8192000000 10218879664 10634526720 1166311232 12.462 100 45 90 2 1324900992 147697440 10892320
05:48:57 1370 8192000000 10275109952 10681610240 1176058528 12.553 100 89 176 0 1375539728 179666608 19814592
05:50:03 2218 8192000000 10308308400 10724757504 1185359744 12.640 100 75 150 2 1402505120 199850464 17294912
05:51:11 5043 8192000000 10308970624 10725257216 1194477312 12.725 100 66 131 1 1408824624 198893632 15453680
05:52:28 8876 8192000000 10308002000 10724499456 1166405792 12.463 99 53 105 1 1401967520 223174960 12386768
05:53:40 2944 8192000000 10309010208 10718900224 1170065952 12.497 100 75 144 0 1405625696 218720528 16839216
05:54:47 13599 8192000000 10315709008 10752036864 1166562976 12.465 100 34 67 1 1433792704 259030400 8199328
05:56:25 2941 8192000000 10353667696 10757853184 1154135568 12.348 100 75 147 1 1447910816 275935328 17839920
05:57:32 2847 8192000000 10356912176 10777206784 1155557200 12.362 100 56 112 1 1463045728 293659264 13829264
05:58:43 2115 8192000000 10410230448 10824867840 1163425936 12.435 100 59 118 0 1508991568 330952624 14613008
06:00:00 5557 8192000000 10400300256 10818711552 1147445136 12.286 100 55 109 0 1488828320 327976896 13406288
06:01:15 13380 8192000000 10378754592 10839265280 1132599264 12.146 99 40 80 0 1495879456 353344560 9935632
06:02:31 16215 8192000000 10307774416 10751250432 1118169104 12.010 99 35 70 2 1395994448 268621024 9204320
06:03:50 31068 8192000000 10247533648 10726797312 1130132112 12.123 100 22 44 1 1371098480 235745376 5220992
06:05:25 22820 8192000000 10251089408 10786869248 1097680704 11.816 98 13 26 2 1361802464 260690416 3431344
06:06:55 77604 8192000000 10091338608 10525343744 1103044784 11.867 98 12 24 1 1165446592 60318992 2082816
06:09:13 135494 8192000000 10117770336 10555686912 1128317984 12.106 100 35 70 1 1179227728 43352816 7556928
06:12:38 84530 8192000000 10128399440 10575761408 1138025440 12.197 100 34 67 0 1226025648 80766592 7233616
06:15:03 46408 8192000000 10153327792 10594140160 1149116304 12.301 100 29 56 3 1248817920 93114480 6587136
06:16:55 46736 8192000000 10135505856 10648363008 1152181056 12.330 100 34 69 2 1248283200 88743456 7358688
06:18:43 44804 8192000000 10128107696 10589478912 1141836016 12.233 100 16 32 1 1233560352 88450528 3273808
06:20:44 40565 8192000000 10075301120 10610860032 1130550976 12.127 100 13 26 1 1219645648 86072000 3022672
06:22:33 58335 8192000000 10075196288 10545377280 1132441072 12.144 100 15 30 1 1173862704 37782272 3639360
06:24:32 41443 8192000000 10142014096 10577207296 1151283472 12.322 100 38 76 0 1215792816 56259120 8250224
06:26:13 76540 8192000000 10142053984 10607943680 1151004336 12.319 100 13 26 0 1232384912 78595280 2785296
06:28:30 45838 8192000000 10105248720 10565005312 1143051200 12.244 99 18 36 0 1179763968 32882976 3829792
06:30:24 62983 8192000000 10120849120 10603073536 1161049600 12.413 100 27 53 0 1232941472 66685424 5206448
06:32:33 73374 8192000000 10091340912 10589057024 1142845488 12.242 99 13 26 0 1209616432 64396240 2374704
06:34:46 115532 8192000000 10080564880 10534424576 1128999920 12.112 98 29 58 1 1169505744 34150480 6355344
06:37:42 60580 8192000000 10049885168 10518016000 1081560784 11.662 94 9 18 0 1144861984 61250944 2050256
06:39:43 32551 8192000000 10043214944 10497855488 1123715104 12.062 99 7 14 0 1147529664 22615824 1198736
06:41:29 61988 8192000000 10101274752 10541461504 1088256224 11.726 95 13 26 0 1142182928 51262112 2664592
06:43:32 38165 8192000000 10068552640 10510471168 1052016672 11.380 93 17 32 1 1131665616 76148160 3500784
06:45:16 96325 8192000000 10096606800 10550333440 1068820432 11.541 95 23 46 0 1132324416 58573712 4930272
06:47:52 72966 8192000000 10064742464 10500214784 1087368192 11.718 97 33 66 0 1133067904 38730880 6968832
06:50:06 40446 8192000000 10093971136 10575187968 1100860448 11.846 98 39 78 0 1198271136 89558064 7852624
06:51:52 16573 8192000000 10081294592 10495623168 1095691664 11.797 99 48 96 0 1159115264 54155824 9267776
06:53:14 38572 8192000000 10136087216 10594127872 1088030784 11.724 98 33 66 1 1249255744 154351040 6873920
06:54:53 79559 8192000000 10056843184 10489520128 1110614240 11.938 100 24 48 0 1172278352 56083776 5580336
06:57:22 34828 8192000000 10061982752 10539388928 1115795616 11.987 100 4 8 0 1166196048 49616192 784240
06:58:57 57465 8192000000 10033901840 10464124928 1095342848 11.793 98 11 22 0 1118227968 20022064 2863056
07:00:58 72619 8192000000 10071123760 10522521600 1079550928 11.643 96 25 50 0 1143146256 57976592 5618736
07:03:19 109737 8192000000 10110175728 10594242560 1108048032 11.914 98 16 32 1 1202452960 90977232 3427696
07:06:09 64304 8192000000 10074369520 10508201984 1081719456 11.664 96 12 24 0 1112664752 28684800 2260496
07:08:13 40115 8192000000 10075804864 10522689536 1099724976 11.835 98 23 46 0 1156179088 52177664 4276448
07:09:56 31695 8192000000 10145631504 10581843968 1128548464 12.108 99 46 92 1 1236764016 98968688 9246864
07:11:28 75498 8192000000 10099349952 10592808960 1124972096 12.074 99 18 36 0 1179705056 51253840 3479120
07:13:58 42043 8192000000 10070224704 10521329664 1138239264 12.199 100 18 36 0 1175483888 33808624 3436000
07:15:42 58038 8192000000 10058198800 10521559040 1139566944 12.211 100 13 26 0 1169690816 27330928 2792944
07:17:50 105458 8192000000 10065748208 10551341056 1115352624 11.983 98 21 41 0 1158855184 38851040 4651520
07:20:38 53720 8192000000 10050784336 10532737024 1128178976 12.104 100 22 44 0 1183776768 50910512 4687280
07:22:33 58653 8192000000 10098177600 10576121856 1150088800 12.310 100 33 66 2 1224485520 67605488 6791232
07:24:42 31741 8192000000 10080084208 10519674880 1139947504 12.215 99 27 51 2 1176620992 31539232 5134256
07:26:18 80178 8192000000 10119915568 10590797824 1128878640 12.111 98 22 44 0 1221440672 88195376 4366656
07:28:46 48395 8192000000 10104077392 10540265472 1118491168 12.013 97 27 54 1 1160887264 36800144 5595952
07:30:42 93557 8192000000 10098776560 10550054912 1062363104 11.479 93 23 46 1 1112963664 45889168 4711392
07:33:16 107373 8192000000 10050311120 10486218752 1091451024 11.756 97 20 41 0 1136713152 40448464 4813664
07:36:11 64106 8192000000 9546844704 9923702784 667201664 7.531 59 8 16 0 686117520 17191040 1724816

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 15-10-2021 07:39:59 to 15-10-2021 07:40:14.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 129 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

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

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          237080
MON$OLDEST_ACTIVE               237081
MON$OLDEST_SNAPSHOT             237081
MON$NEXT_TRANSACTION            237081
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                       2053664
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             801
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2631 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2631 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2631 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2631 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 ---
:122: 
:123: mscs-test-161.budzdorov.ru    Fri Oct 15 07:37:48 2021
:124:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:125: 
--- end of diff output ---

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

15.10.21 07:40:18. 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-10-15 07:40:20 No severe PSQL-related problems occured
15.10.21 07:40:21. End of report.