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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 15915920 kB
MemAvailable 30777960 kB
Buffers 20 kB
Cached 15705368 kB
SwapCached 4116 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-11-02 04:37:09
DTS_END                         2021-11-02 04:37:09.6330
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/52836
TRN_ID                          238919
ATT_ID                          612
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: 9266.53 22079 1111984
customer order: creation 172.19 374 20663
customer order: refuse 14.51 335 1741
order to supplier: creation 44.92 1341 5390
order to supplier: removal 3.44 122907 413
invoice (draft): creation 44.51 3617 5341
invoice (draft): removal 52.51 1010 6301
invoice accept: apply 41.86 18096 5023
invoice accept: cancel 47.12 831 5654
customer reserve: creation 3873.07 177 464768
customer reserve: removal 4685.65 70 562278
realization accept: apply 83.03 187 9963
realization accept: cancel 75.05 131 9006
payment from customer: creation 59.81 123 7177
payment from customer: removal 5.83 77 700
payment to supplier: creation 49.15 140 5898
payment to supplier: removal 3.57 75 428
service: total inventory turnovers 3.89 3367 467
service: total monetary turnovers 5.53 560 663
service: aggregate perf. data 0.88 15163 106
service: refresh index statistics 0.03 272998 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 02-11-2021 04:37:58 to 02-11-2021 04:38:00.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1689
TEST_TIME 2 3734
TEST_TIME 3 4381
TEST_TIME 4 4617
TEST_TIME 5 4913
TEST_TIME 6 5383
TEST_TIME 7 5433
TEST_TIME 8 5335
TEST_TIME 9 5822
TEST_TIME 10 6081
TEST_TIME 11 6305
TEST_TIME 12 6551
TEST_TIME 13 6923
TEST_TIME 14 7048
TEST_TIME 15 7458
TEST_TIME 16 7455
TEST_TIME 17 7617
TEST_TIME 18 7594
TEST_TIME 19 7787
TEST_TIME 20 8215
TEST_TIME 21 8466
TEST_TIME 22 8787
TEST_TIME 23 8858
TEST_TIME 24 9324
TEST_TIME 25 9805
TEST_TIME 26 10679
TEST_TIME 27 11671
TEST_TIME 28 11622
TEST_TIME 29 11404
TEST_TIME 30 11027
TEST_TIME 31 10828
TEST_TIME 32 10826
TEST_TIME 33 10585
TEST_TIME 34 10476
TEST_TIME 35 10280
TEST_TIME 36 10230
TEST_TIME 37 10019
TEST_TIME 38 9860
TEST_TIME 39 9735
TEST_TIME 40 9639
TEST_TIME 41 9630
TEST_TIME 42 9627
TEST_TIME 43 9530
TEST_TIME 44 9487
TEST_TIME 45 9545
TEST_TIME 46 9514
TEST_TIME 47 9476
TEST_TIME 48 9546
TEST_TIME 49 9499
TEST_TIME 50 9549
TEST_TIME 51 9571
TEST_TIME 52 9643
TEST_TIME 53 9630
TEST_TIME 54 9612
TEST_TIME 55 9831
TEST_TIME 56 9836
TEST_TIME 57 9703
TEST_TIME 58 9566
TEST_TIME 59 9521
TEST_TIME 60 9459
TEST_TIME 61 9403
TEST_TIME 62 9400
TEST_TIME 63 9369
TEST_TIME 64 9371
TEST_TIME 65 9366
TEST_TIME 66 9325
TEST_TIME 67 9316
TEST_TIME 68 9380
TEST_TIME 69 9391
TEST_TIME 70 9305
TEST_TIME 71 9308
TEST_TIME 72 9468
TEST_TIME 73 9627
TEST_TIME 74 9674
TEST_TIME 75 9675
TEST_TIME 76 9604
TEST_TIME 77 9538
TEST_TIME 78 9503
TEST_TIME 79 9466
TEST_TIME 80 9461
TEST_TIME 81 9394
TEST_TIME 82 9352
TEST_TIME 83 9254
TEST_TIME 84 9259
TEST_TIME 85 9324
TEST_TIME 86 9290
TEST_TIME 87 9351
TEST_TIME 88 9339
TEST_TIME 89 9328
TEST_TIME 90 9349
TEST_TIME 91 9363
TEST_TIME 92 9386
TEST_TIME 93 9362
TEST_TIME 94 9449
TEST_TIME 95 9489
TEST_TIME 96 9522
TEST_TIME 97 9545
TEST_TIME 98 9519
TEST_TIME 99 9446
TEST_TIME 100 9411
TEST_TIME 101 9463
TEST_TIME 102 9409
TEST_TIME 103 9388
TEST_TIME 104 9346
TEST_TIME 105 9349
TEST_TIME 106 9300
TEST_TIME 107 9277
TEST_TIME 108 9221
TEST_TIME 109 9216
TEST_TIME 110 9203
TEST_TIME 111 9191
TEST_TIME 112 9202
TEST_TIME 113 9175
TEST_TIME 114 9172
TEST_TIME 115 9265
TEST_TIME 116 9240
TEST_TIME 117 9253
TEST_TIME 118 9261
TEST_TIME 119 9340
TEST_TIME 120 9267

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 02-11-2021 04:38:02 to 02-11-2021 04:38:05.

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 1543011 1543011 0 0.00 0 18652 7 0 0
doc_list_biud 1542973 1542973 0 0.00 0 116571 32 0 0
sp_add_invoice_to_stock 5127 5023 104 2.02 26 195903 18096 0 104
sp_cancel_adding_invoice 5654 5654 0 0.00 6 66928 831 0 0
sp_cancel_client_order 1741 1741 0 0.00 5 18171 335 0 0
sp_cancel_customer_reserve 562278 562278 0 0.00 1 61058 71 0 0
sp_cancel_pay_from_customer 700 700 0 0.00 3 4291 77 0 0
sp_cancel_pay_to_supplier 428 428 0 0.00 3 6134 75 0 0
sp_cancel_supplier_invoice 6312 6301 11 0.17 2 116578 1011 0 11
sp_cancel_supplier_order 413 413 0 0.00 7 1248508 122907 0 0
sp_cancel_write_off 9006 9006 0 0.00 1 9942 131 0 0
sp_client_order 20663 20663 0 0.00 4 17681 374 0 0
sp_customer_reserve 464768 464768 0 0.00 0 49618 177 0 0
sp_fill_shopping_cart 31390 31390 0 0.00 1 19902 45 0 0
sp_fill_shopping_cart_clo_res 464768 464768 0 0.00 0 22299 17 0 0
sp_get_clo_for_invoice 5023 5023 0 0.00 0 31714 422 0 0
sp_kill_cost_storno 15788 15788 0 0.00 0 7017 58 0 0
sp_kill_qstorno_ret_qs2qd 1140276 1140276 0 0.00 0 116569 38 0 0
sp_kill_qty_storno 600358 600358 0 0.00 0 116570 82 0 0
sp_lock_selected_doc 20622 20622 0 0.00 0 9931 7 0 0
sp_make_cost_storno 28061 28061 0 0.00 0 18636 63 0 0
sp_multiply_rows_for_pdistr 28023 28023 0 0.00 0 10915 30 0 0
sp_multiply_rows_for_qdistr 20663 20663 0 0.00 0 16659 197 0 0
sp_pay_from_customer 7177 7177 0 0.00 4 23736 124 0 0
sp_pay_to_supplier 5898 5898 0 0.00 4 17661 141 0 0
sp_payment_common 13058 13058 0 0.00 1 8319 21 0 0
sp_qd_handle_on_cancel_clo 1741 1741 0 0.00 0 18150 294 0 0
sp_qd_handle_on_invoice_upd_sts 10671 10671 0 0.00 0 23959 351 0 0
sp_qd_handle_on_reserve_upd_sts 9948 9948 0 0.00 0 11315 47 0 0
sp_reserve_write_off 9963 9963 0 0.00 7 26004 188 0 0
sp_supplier_invoice 5341 5341 0 0.00 8 119261 3617 0 0
sp_supplier_order 5390 5390 0 0.00 5 25037 1341 0 0
srv_aggregate_perf_data 107 106 1 0.93 2755 144364 15163 0 1
srv_fill_mon_cache_memory 65 65 0 0.00 1802 119989 41414 0 0
srv_find_qd_qs_mism 1064574 1064574 0 0.00 0 13706 6 0 0
srv_make_invnt_saldo 467 467 0 0.00 81 82116 3367 0 0
srv_make_money_saldo 663 663 0 0.00 1 17561 561 0 0
srv_recalc_idx_stat 4 4 0 0.00 177778 359997 272999 0 0
t$perf-abend:sp_add_invoice_to_stock 104 104 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 5023 5023 0 0.00 0 8189 161 0 0
t$perf-norm:sp_cancel_adding_invoice 390 390 0 0.00 0 2527 84 0 0
t$perf-norm:sp_cancel_client_order 1741 1741 0 0.00 0 4584 18 0 0
t$perf-norm:sp_cancel_customer_reserve 685 685 0 0.00 0 1492 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 700 700 0 0.00 0 6609 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 428 428 0 0.00 0 509 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 377 377 0 0.00 0 3427 22 0 0
t$perf-norm:sp_cancel_supplier_order 413 413 0 0.00 0 19081 1659 0 0
t$perf-norm:sp_cancel_write_off 923 923 0 0.00 0 3160 7 0 0
t$perf-norm:sp_client_order 20663 20663 0 0.00 0 9271 19 0 0
t$perf-norm:sp_customer_reserve 998 998 0 0.00 0 637 3 0 0
t$perf-norm:sp_pay_from_customer 7177 7177 0 0.00 0 30060 16 0 0
t$perf-norm:sp_pay_to_supplier 5898 5898 0 0.00 0 6662 10 0 0
t$perf-norm:sp_reserve_write_off 9963 9963 0 0.00 0 9003 11 0 0
t$perf-norm:sp_supplier_invoice 5341 5341 0 0.00 0 2796 14 0 0
t$perf-norm:sp_supplier_order 5390 5390 0 0.00 0 15557 17 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 348 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 270 5 0 0
t$perf-norm:srv_make_invnt_saldo 467 467 0 0.00 0 4252 17 0 0
t$perf-norm:srv_make_money_saldo 663 663 0 0.00 0 525 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 3 0 0
v_add_invoice_to_stock 10242 10034 208 2.03 0 21905 20 0 208
v_all_customers 37786 37786 0 0.00 0 17 1 0 0
v_all_suppliers 29980 29980 0 0.00 0 17 1 0 0
v_all_wares 41326 41326 0 0.00 0 23 1 0 0
v_cancel_adding_invoice 780 780 0 0.00 0 21979 38 0 0
v_cancel_client_order 3482 3482 0 0.00 0 5981 12 0 0
v_cancel_customer_prepayment 1400 1400 0 0.00 0 4284 8 0 0
v_cancel_customer_reserve 1370 1370 0 0.00 0 5533 7 0 0
v_cancel_payment_to_supplier 856 856 0 0.00 0 6125 14 0 0
v_cancel_supplier_invoice 776 754 22 2.83 0 443 14 0 22
v_cancel_supplier_order 826 826 0 0.00 0 1713 8 0 0
v_cancel_write_off 1846 1846 0 0.00 0 9847 22 0 0
v_max_id_clo_ord 5387 5387 0 0.00 0 10170 4 0 0
v_max_id_clo_res 998 998 0 0.00 0 2471 14 0 0
v_max_id_ord_sup 5340 5340 0 0.00 0 7233 6 0 0
v_max_non_paid_invoice 1620 1620 0 0.00 0 412 2 0 0
v_max_non_paid_realizn 3785 3785 0 0.00 0 3119 4 0 0
v_min_id_clo_ord 5387 5387 0 0.00 0 4944 8 0 0
v_min_id_clo_res 998 998 0 0.00 0 2619 10 0 0
v_min_id_ord_sup 5340 5340 0 0.00 0 6772 12 0 0
v_min_non_paid_invoice 5889 5889 0 0.00 0 8311 15 0 0
v_min_non_paid_realizn 7168 7168 0 0.00 0 4601 13 0 0
v_our_firm 10306 10306 0 0.00 0 40 1 0 0
v_reserve_write_off 19896 19896 0 0.00 0 17612 7 0 0
x_lock_depdocs_on_canc_invoice 11578 11578 0 0.00 0 21885 126 0 0
x_lock_depdocs_on_canc_sup_ord 413 413 0 0.00 0 15570 373 0 0
x_make_qstorno_1000_1200 5390 5390 0 0.00 2 24954 1256 0 0
x_make_qstorno_1200_2000 5341 5341 0 0.00 5 119248 3449 0 0
x_make_qstorno_2100_3300 464768 464768 0 0.00 0 49617 158 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-11-2021 04:38:06 to 02-11-2021 04:38:10.

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
02:07:13 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:14 26726 8192000000 9728157056 10026102784 810526672 9.003 100 88 176 0 839528576 7721472 21280432
02:09:45 18684 8192000000 9759533152 10066157568 835610512 9.256 100 85 170 0 867972032 12404816 19956704
02:11:08 12081 8192000000 9794795536 10109173760 863743312 9.538 100 87 175 0 904653872 20894592 20015968
02:12:22 12295 8192000000 9861979904 10190942208 908425984 9.982 100 80 160 0 966603584 40819616 17357984
02:13:37 10389 8192000000 9926019984 10269175808 954702800 10.437 100 86 173 0 1035283392 62287056 18293536
02:14:51 4525 8192000000 9962289216 10314014720 992208352 10.803 100 84 166 0 1079653776 70725168 16720256
02:15:59 2088 8192000000 9998623744 10359443456 1020371744 11.076 100 82 164 2 1109921328 72417488 17132096
02:17:03 1006 8192000000 10005632640 10371768320 1048977376 11.351 100 92 184 0 1125615392 57912848 18725168
02:18:05 1194 8192000000 10029953632 10402107392 1063690704 11.492 100 88 177 1 1144548416 62320784 18536928
02:19:07 27001 8192000000 9976281360 10368978944 1066817072 11.522 100 27 54 0 1095859040 23603792 5438176
02:20:35 6960 8192000000 9984987600 10384789504 1079079280 11.639 100 36 71 1 1107662624 21167568 7415776
02:21:45 5399 8192000000 10007752704 10402754560 1085333936 11.698 100 40 78 2 1127840672 33817696 8689040
02:22:52 11379 8192000000 10001865104 10409672704 1089679200 11.740 100 23 44 2 1111057536 15607648 5770688
02:24:04 15316 8192000000 10012979536 10415857664 1095871760 11.798 100 40 80 1 1127577840 24126112 7579968
02:25:24 4457 8192000000 10015387200 10412404736 1085470832 11.700 99 53 104 2 1111969040 15495600 11002608
02:26:31 6038 8192000000 10012088752 10411626496 1073673712 11.587 99 44 89 1 1114359696 30647856 10038128
02:27:37 73708 8192000000 9955007056 10350321664 974741200 10.633 90 23 45 0 996799072 17740784 4317088
02:29:51 2784 8192000000 9942325648 10319060992 1010237232 10.978 97 50 100 0 1058014912 35916000 11861680
02:30:55 10295 8192000000 9940652656 10327846912 1004377808 10.921 98 29 59 0 1036616976 25738624 6500544
02:32:07 55376 8192000000 9931219824 10314244096 1019453952 11.067 98 25 50 0 1049381248 23512832 6414464
02:34:03 7319 8192000000 9973804896 10367877120 1051156096 11.372 100 40 80 1 1076512416 16371536 8984784
02:35:11 9168 8192000000 9987274000 10386006016 1064593392 11.500 100 44 89 1 1089380224 14523360 10263472
02:36:21 7191 8192000000 9990362656 10380365824 1073319488 11.584 100 41 81 1 1097127296 14747344 9060464
02:37:31 71021 8192000000 10015831952 10416181248 1084437648 11.690 100 27 55 0 1105984416 16216896 5329872
02:39:44 11414 8192000000 10035914272 10446020608 1105143728 11.886 100 45 90 0 1155962368 40963632 9855008
02:41:08 19026 8192000000 10089941424 10493292544 1123298400 12.058 100 46 90 1 1190071888 57404656 9368832
02:42:35 12068 8192000000 10130160832 10542489600 1142349568 12.238 100 53 105 1 1254336320 100405232 11581520
02:43:54 7675 8192000000 10167919248 10578345984 1150135552 12.311 100 58 114 2 1285834224 123319488 12379184
02:45:04 29134 8192000000 10216957056 10631532544 1146915344 12.281 100 51 102 1 1309334320 150998688 11420288
02:46:51 5204 8192000000 10238340832 10648285184 1158095552 12.385 99 58 116 1 1336683872 165668640 12919680
02:48:00 8502 8192000000 10289336960 10696667136 1180626848 12.596 100 67 133 1 1395045920 199320912 15098160
02:49:14 2291 8192000000 10347574240 10756435968 1183158752 12.620 100 76 150 0 1448774560 248568432 17047376
02:50:21 7745 8192000000 10372335296 10787397632 1167004032 12.469 100 73 146 1 1480510560 296277184 17229344
02:51:34 1796 8192000000 10375866896 10776887296 1173149616 12.526 100 87 174 0 1472047904 278587312 20310976
02:52:39 4656 8192000000 10416579072 10840653824 1167683568 12.475 100 77 153 0 1513630320 328409952 17536800
02:53:48 2589 8192000000 10424427280 10818502656 1149699792 12.307 100 87 173 2 1519507888 348792112 21015984
02:54:57 4030 8192000000 10372452752 10763771904 1104253504 11.878 98 70 140 1 1473814048 352868064 16692480
02:56:05 2373 8192000000 10449075024 10860843008 1144473280 12.258 100 87 173 1 1561883584 396632080 20778224
02:57:13 8037 8192000000 10455126752 10916360192 1152400128 12.332 100 40 80 1 1592008128 428819104 10788896
02:58:25 3996 8192000000 10497073120 10935980032 1152560144 12.334 100 46 92 0 1594841952 430182816 12098992
02:59:39 8957 8192000000 10489225952 10976034816 1152924672 12.337 100 42 84 0 1627476480 462702928 11848880
03:00:58 15310 8192000000 10498161024 10955948032 1145401504 12.266 100 35 70 1 1600852880 445580176 9871200
03:02:34 14090 8192000000 10433311376 10882199552 1120245472 12.029 99 28 56 0 1526264416 398470304 7548640
03:03:49 25044 8192000000 10120340160 10700320768 1098260752 11.821 98 26 52 0 1364966432 260030336 6675344
03:05:15 77964 8192000000 10092048400 10525663232 1094724448 11.788 97 27 54 1 1133436720 32697312 6014960
03:07:40 80351 8192000000 10092463328 10527289344 1115877872 11.988 99 33 63 1 1176966848 54845200 6243776
03:10:06 49879 8192000000 10085249504 10525831168 1103307744 11.869 97 32 64 0 1139726144 30021504 6396896
03:11:57 35484 8192000000 10094835712 10543898624 1134627296 12.165 100 12 22 1 1203499296 66922912 1949088
03:15:14 23034 8192000000 10080840640 10525286400 1133312944 12.153 99 22 45 0 1191632128 52915392 5403792
03:16:39 30056 8192000000 10092594240 10564993024 1124354176 12.068 99 9 18 0 1198905856 72626720 1924960
03:18:30 71303 8192000000 10045069952 10526994432 1126666512 12.090 100 12 24 0 1175380832 46215696 2498624
03:20:41 59223 8192000000 10105705440 10570960896 1128407472 12.106 99 24 48 0 1179307120 45620112 5279536
03:22:43 66417 8192000000 10070100240 10529226752 1131988640 12.140 99 17 34 1 1188830400 53252784 3588976
03:24:54 24457 8192000000 10096268848 10540720128 1143497840 12.248 100 30 59 2 1189746400 40159264 6089296
03:26:19 28793 8192000000 10092553168 10532892672 1139205488 12.208 99 33 65 1 1191163200 44485712 7472000
03:27:48 53828 8192000000 10117269280 10586050560 1114975856 11.980 97 13 26 1 1191713120 74065024 2672240
03:29:55 103389 8192000000 10151154784 10613108736 1139630560 12.212 99 28 56 1 1217952416 72459536 5862320
03:32:42 77960 8192000000 10056964032 10506489856 1045196640 11.315 91 10 20 1 1078970704 32148336 1625728
03:35:00 54066 8192000000 10047753776 10470625280 1098914272 11.827 97 46 92 3 1137734816 28671536 10149008
03:36:55 60068 8192000000 10050067760 10501382144 1096216336 11.802 97 17 34 0 1150396144 50800144 3379664
03:38:55 45234 8192000000 10015127680 10487078912 1089921248 11.742 98 5 10 0 1119577376 28913696 742432
03:40:40 44490 8192000000 10039158288 10499510272 1056337568 11.421 94 9 18 0 1093140656 35158944 1644144
03:42:25 31730 8192000000 10049784208 10493493248 1084519376 11.691 97 19 37 1 1127706784 38859984 4327424
03:44:28 77190 8192000000 10093432928 10526560256 1090675328 11.749 97 26 52 3 1192257280 95687984 5893968
03:46:46 42295 8192000000 10119926912 10530312192 1070542624 11.557 95 33 66 2 1143683232 66135312 7005296
03:48:45 83452 8192000000 10145110976 10676645888 1105891264 11.894 99 13 26 0 1268346400 159210448 3244688
03:51:09 39513 8192000000 10084590384 10548830208 1116002912 11.989 100 13 26 0 1182825408 64270240 2552256
03:52:54 104005 8192000000 10061325776 10491043840 1109438560 11.927 99 33 64 0 1137986160 21836208 6711392
03:55:49 22494 8192000000 10063918992 10503155712 1113977056 11.970 99 27 53 1 1148356064 28715504 5663504
03:57:13 46609 8192000000 10043630800 10501959680 1100982032 11.847 97 11 23 0 1134805392 31742448 2080912
03:58:59 41530 8192000000 10087009200 10542530560 1115856976 11.988 99 13 26 0 1168007840 49379216 2771648
04:00:42 49909 8192000000 10097643408 10561310720 1105804032 11.893 98 23 46 0 1174155152 63382448 4968672
04:03:10 35172 8192000000 10081920512 10529046528 1115897136 11.988 99 25 50 0 1161109936 39714624 5498176
04:04:49 50847 8192000000 10062436944 10503753728 1107817440 11.912 98 18 36 0 1153654352 42290736 3546176
04:06:46 32850 8192000000 10117161600 10551783424 1125990384 12.084 100 35 70 1 1192769984 59928224 6851376
04:08:20 55723 8192000000 10115903552 10550915072 1135963168 12.178 100 29 58 0 1207467200 65770992 5733040
04:10:22 35929 8192000000 10137121936 10567745536 1129722992 12.119 99 18 36 1 1220787840 87107200 3957648
04:11:59 51528 8192000000 10099393664 10581393408 1119267472 12.020 98 15 30 0 1163325328 41128880 2928976
04:14:09 119989 8192000000 10091271600 10565259264 1147366272 12.285 100 16 32 0 1209506624 58434560 3705792
04:17:55 59786 8192000000 10094492064 10620321792 1149295952 12.303 100 10 20 0 1215474816 63981984 2196880
04:20:19 60807 8192000000 10070764640 10526949376 1121714224 12.043 98 11 22 1 1163076704 39053328 2309152
04:22:20 81860 8192000000 10073521472 10514722816 1062665760 11.482 93 28 56 0 1090104704 21951056 5487888
04:24:54 71842 8192000000 10062049696 10536587264 1122513984 12.051 100 9 18 0 1149716080 25299104 1902992
04:27:13 43083 8192000000 10048509792 10483191808 1103687856 11.873 98 17 34 0 1134366352 27664048 3014448
04:28:57 75565 8192000000 10100666192 10540765184 1100617504 11.843 98 23 46 0 1174784928 69509376 4658048
04:31:28 75741 8192000000 10025253952 10503864320 1071726736 11.569 96 20 40 0 1172469760 96517872 4225152
04:33:44 29604 8192000000 10105920992 10524672000 1108993968 11.923 100 51 103 1 1185456880 65966128 10496784
04:35:18 21830 8192000000 10065301504 10491375616 1087467264 11.719 98 40 80 1 1136270992 40592400 8211328

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 02-11-2021 04:38:12 to 02-11-2021 04:38:27.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 104 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:01, from 02-11-2021 04:38:28 to 02-11-2021 04:38:29.

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          238494
MON$OLDEST_ACTIVE               239056
MON$OLDEST_SNAPSHOT             239056
MON$NEXT_TRANSACTION            239056
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                       2045536
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             812
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2649 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2649 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2649 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2649 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 ---
:137: 
:138: mscs-test-161.budzdorov.ru    Tue Nov  2 04:37:26 2021
:139:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:140: 
:141: 
:142: mscs-test-161.budzdorov.ru    Tue Nov  2 04:37:28 2021
:143:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:144: 
:145: 
:146: mscs-test-161.budzdorov.ru    Tue Nov  2 04:37:29 2021
:147:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:148: 
:149: 
:150: mscs-test-161.budzdorov.ru    Tue Nov  2 04:37:29 2021
:151:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:152: 
:153: 
:154: mscs-test-161.budzdorov.ru    Tue Nov  2 04:37:29 2021
:155:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:156: 
--- end of diff output ---

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

02.11.21 04:38:31. 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-11-02 04:38:33 No severe PSQL-related problems occured
02.11.21 04:38:34. End of report.