Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.06.2021 13:27
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 16433580 kB
MemAvailable 31336100 kB
Buffers 0 kB
Cached 15179888 kB
SwapCached 2420 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-06-21 13:26:57
DTS_END                         2021-06-21 13:26:57.1250
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/45004
TRN_ID                          229736
ATT_ID                          582
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: 9320.89 24124 1118507
customer order: creation 167.48 431 20098
customer order: refuse 14.61 290 1753
order to supplier: creation 46.01 1242 5521
order to supplier: removal 3.27 138995 392
invoice (draft): creation 45.84 3297 5501
invoice (draft): removal 53.75 1042 6450
invoice accept: apply 40.96 16964 4915
invoice accept: cancel 48.93 848 5872
customer reserve: creation 3832.04 163 459845
customer reserve: removal 4773.65 74 572838
realization accept: apply 82.68 186 9921
realization accept: cancel 86.09 123 10331
payment from customer: creation 57.45 122 6894
payment from customer: removal 5.74 70 689
payment to supplier: creation 47.96 131 5755
payment to supplier: removal 3.39 62 407
service: total inventory turnovers 4.06 3003 487
service: total monetary turnovers 6.16 445 739
service: aggregate perf. data 0.79 15333 95
service: refresh index statistics 0.03 299650 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-06-2021 13:27:59 to 21-06-2021 13:28:01.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2262
TEST_TIME 2 3213
TEST_TIME 3 3879
TEST_TIME 4 3756
TEST_TIME 5 4045
TEST_TIME 6 4677
TEST_TIME 7 4919
TEST_TIME 8 4999
TEST_TIME 9 5239
TEST_TIME 10 5322
TEST_TIME 11 5446
TEST_TIME 12 5407
TEST_TIME 13 5402
TEST_TIME 14 5521
TEST_TIME 15 5741
TEST_TIME 16 6186
TEST_TIME 17 6468
TEST_TIME 18 6492
TEST_TIME 19 7225
TEST_TIME 20 7456
TEST_TIME 21 7477
TEST_TIME 22 7748
TEST_TIME 23 7826
TEST_TIME 24 8442
TEST_TIME 25 8548
TEST_TIME 26 9109
TEST_TIME 27 9451
TEST_TIME 28 11417
TEST_TIME 29 11533
TEST_TIME 30 11490
TEST_TIME 31 11304
TEST_TIME 32 11122
TEST_TIME 33 11259
TEST_TIME 34 11241
TEST_TIME 35 11133
TEST_TIME 36 11003
TEST_TIME 37 10707
TEST_TIME 38 10476
TEST_TIME 39 10460
TEST_TIME 40 10454
TEST_TIME 41 10394
TEST_TIME 42 10403
TEST_TIME 43 10438
TEST_TIME 44 10315
TEST_TIME 45 10214
TEST_TIME 46 10154
TEST_TIME 47 10086
TEST_TIME 48 10181
TEST_TIME 49 10161
TEST_TIME 50 10085
TEST_TIME 51 10155
TEST_TIME 52 10105
TEST_TIME 53 10218
TEST_TIME 54 10213
TEST_TIME 55 10198
TEST_TIME 56 10117
TEST_TIME 57 10215
TEST_TIME 58 10300
TEST_TIME 59 10274
TEST_TIME 60 10205
TEST_TIME 61 10070
TEST_TIME 62 10003
TEST_TIME 63 9912
TEST_TIME 64 9849
TEST_TIME 65 9747
TEST_TIME 66 9786
TEST_TIME 67 9763
TEST_TIME 68 9731
TEST_TIME 69 9713
TEST_TIME 70 9684
TEST_TIME 71 9754
TEST_TIME 72 9733
TEST_TIME 73 9663
TEST_TIME 74 9690
TEST_TIME 75 9746
TEST_TIME 76 9802
TEST_TIME 77 9828
TEST_TIME 78 9744
TEST_TIME 79 9643
TEST_TIME 80 9616
TEST_TIME 81 9562
TEST_TIME 82 9558
TEST_TIME 83 9536
TEST_TIME 84 9506
TEST_TIME 85 9490
TEST_TIME 86 9483
TEST_TIME 87 9435
TEST_TIME 88 9432
TEST_TIME 89 9449
TEST_TIME 90 9426
TEST_TIME 91 9523
TEST_TIME 92 9638
TEST_TIME 93 9596
TEST_TIME 94 9526
TEST_TIME 95 9477
TEST_TIME 96 9483
TEST_TIME 97 9473
TEST_TIME 98 9463
TEST_TIME 99 9461
TEST_TIME 100 9441
TEST_TIME 101 9477
TEST_TIME 102 9504
TEST_TIME 103 9518
TEST_TIME 104 9508
TEST_TIME 105 9495
TEST_TIME 106 9553
TEST_TIME 107 9560
TEST_TIME 108 9489
TEST_TIME 109 9411
TEST_TIME 110 9414
TEST_TIME 111 9392
TEST_TIME 112 9364
TEST_TIME 113 9312
TEST_TIME 114 9316
TEST_TIME 115 9291
TEST_TIME 116 9304
TEST_TIME 117 9275
TEST_TIME 118 9272
TEST_TIME 119 9248
TEST_TIME 120 9325

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 21-06-2021 13:28:03 to 21-06-2021 13:28:06.

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 1543352 1543352 0 0.00 0 18830 6 0 0
doc_list_biud 1543348 1543348 0 0.00 0 84807 34 0 0
sp_add_invoice_to_stock 5033 4915 118 2.34 19 196895 16964 0 118
sp_cancel_adding_invoice 5872 5872 0 0.00 4 103965 849 0 0
sp_cancel_client_order 1753 1753 0 0.00 4 14537 291 0 0
sp_cancel_customer_reserve 572838 572838 0 0.00 1 37779 75 0 0
sp_cancel_pay_from_customer 689 689 0 0.00 3 5580 70 0 0
sp_cancel_pay_to_supplier 407 407 0 0.00 3 3509 63 0 0
sp_cancel_supplier_invoice 6458 6450 8 0.12 2 84813 1042 0 8
sp_cancel_supplier_order 392 392 0 0.00 7 1267806 138995 0 0
sp_cancel_write_off 10331 10331 0 0.00 1 7945 123 0 0
sp_client_order 20098 20098 0 0.00 4 29484 432 0 0
sp_customer_reserve 459845 459845 0 0.00 0 77048 164 0 0
sp_fill_shopping_cart 31083 31083 0 0.00 1 11127 40 0 0
sp_fill_shopping_cart_clo_res 459838 459838 0 0.00 0 21106 18 0 0
sp_get_clo_for_invoice 4908 4908 0 0.00 0 40182 372 0 0
sp_kill_cost_storno 17299 17299 0 0.00 0 5717 57 0 0
sp_kill_qstorno_ret_qs2qd 1162849 1162849 0 0.00 0 84805 40 0 0
sp_kill_qty_storno 612465 612465 0 0.00 0 84807 86 0 0
sp_lock_selected_doc 20475 20475 0 0.00 0 11013 9 0 0
sp_make_cost_storno 27478 27478 0 0.00 0 18805 58 0 0
sp_multiply_rows_for_pdistr 27478 27478 0 0.00 0 15127 33 0 0
sp_multiply_rows_for_qdistr 20096 20096 0 0.00 0 15018 244 0 0
sp_pay_from_customer 6894 6894 0 0.00 5 18942 122 0 0
sp_pay_to_supplier 5755 5755 0 0.00 5 18877 132 0 0
sp_payment_common 12648 12648 0 0.00 1 18549 30 0 0
sp_qd_handle_on_cancel_clo 1753 1753 0 0.00 0 14528 263 0 0
sp_qd_handle_on_invoice_upd_sts 10780 10780 0 0.00 0 43819 375 0 0
sp_qd_handle_on_reserve_upd_sts 9921 9921 0 0.00 0 17763 46 0 0
sp_reserve_write_off 9921 9921 0 0.00 7 18884 187 0 0
sp_supplier_invoice 5501 5501 0 0.00 7 121623 3298 0 0
sp_supplier_order 5521 5521 0 0.00 6 44991 1243 0 0
srv_aggregate_perf_data 95 95 0 0.00 2929 140814 15333 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1523 132385 46700 0 0
srv_find_qd_qs_mism 1071055 1071055 0 0.00 0 11093 5 0 0
srv_make_invnt_saldo 487 487 0 0.00 74 146498 3003 0 0
srv_make_money_saldo 739 739 0 0.00 1 11324 445 0 0
srv_recalc_idx_stat 4 4 0 0.00 234945 438635 299650 0 0
t$perf-abend:sp_add_invoice_to_stock 118 118 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4915 4915 0 0.00 0 11851 182 0 0
t$perf-norm:sp_cancel_adding_invoice 390 390 0 0.00 0 10861 105 0 0
t$perf-norm:sp_cancel_client_order 1753 1753 0 0.00 0 5310 17 0 0
t$perf-norm:sp_cancel_customer_reserve 691 691 0 0.00 0 5606 28 0 0
t$perf-norm:sp_cancel_pay_from_customer 689 689 0 0.00 0 2312 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 407 407 0 0.00 0 2637 20 0 0
t$perf-norm:sp_cancel_supplier_invoice 366 366 0 0.00 0 718 9 0 0
t$perf-norm:sp_cancel_supplier_order 392 392 0 0.00 0 26294 1716 0 0
t$perf-norm:sp_cancel_write_off 958 958 0 0.00 0 612 3 0 0
t$perf-norm:sp_client_order 20098 20098 0 0.00 0 12363 18 0 0
t$perf-norm:sp_customer_reserve 994 994 0 0.00 0 3547 10 0 0
t$perf-norm:sp_pay_from_customer 6894 6894 0 0.00 0 4001 9 0 0
t$perf-norm:sp_pay_to_supplier 5755 5755 0 0.00 0 6461 8 0 0
t$perf-norm:sp_reserve_write_off 9921 9921 0 0.00 0 6212 11 0 0
t$perf-norm:sp_supplier_invoice 5502 5502 0 0.00 0 7474 13 0 0
t$perf-norm:sp_supplier_order 5521 5521 0 0.00 0 5959 17 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 319 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 3450 55 0 0
t$perf-norm:srv_make_invnt_saldo 487 487 0 0.00 0 6161 28 0 0
t$perf-norm:srv_make_money_saldo 739 739 0 0.00 0 1171 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10052 9816 236 2.34 0 8706 19 0 236
v_all_customers 37130 37130 0 0.00 0 47 1 0 0
v_all_suppliers 30408 30408 0 0.00 0 16 1 0 0
v_all_wares 40180 40180 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 780 780 0 0.00 0 1221 8 0 0
v_cancel_client_order 3506 3506 0 0.00 0 3356 4 0 0
v_cancel_customer_prepayment 1378 1378 0 0.00 0 5469 8 0 0
v_cancel_customer_reserve 1382 1382 0 0.00 0 1029 4 0 0
v_cancel_payment_to_supplier 814 814 0 0.00 0 3473 8 0 0
v_cancel_supplier_invoice 748 732 16 2.13 0 1492 13 0 16
v_cancel_supplier_order 784 784 0 0.00 0 3479 9 0 0
v_cancel_write_off 1916 1916 0 0.00 0 1780 13 0 0
v_max_id_clo_ord 5521 5521 0 0.00 0 734 1 0 0
v_max_id_clo_res 994 994 0 0.00 0 3925 17 0 0
v_max_id_ord_sup 5472 5472 0 0.00 0 5827 4 0 0
v_max_non_paid_invoice 1542 1542 0 0.00 0 61 2 0 0
v_max_non_paid_realizn 3459 3459 0 0.00 0 15045 9 0 0
v_min_id_clo_ord 5521 5521 0 0.00 0 6684 8 0 0
v_min_id_clo_res 994 994 0 0.00 0 3865 9 0 0
v_min_id_ord_sup 5472 5472 0 0.00 0 5030 7 0 0
v_min_non_paid_invoice 5754 5754 0 0.00 0 18257 20 0 0
v_min_non_paid_realizn 6894 6894 0 0.00 0 18325 20 0 0
v_our_firm 9920 9920 0 0.00 0 14 1 0 0
v_reserve_write_off 19842 19842 0 0.00 0 5040 5 0 0
x_lock_depdocs_on_canc_invoice 11956 11956 0 0.00 0 37288 119 0 0
x_lock_depdocs_on_canc_sup_ord 392 392 0 0.00 0 23313 488 0 0
x_make_qstorno_1000_1200 5521 5521 0 0.00 1 44978 1166 0 0
x_make_qstorno_1200_2000 5501 5501 0 0.00 3 121591 3152 0 0
x_make_qstorno_2100_3300 459845 459845 0 0.00 0 77047 143 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-06-2021 13:28:07 to 21-06-2021 13:28:11.

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
10:57:00 2 8192000000 8796668864 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
10:58:01 27721 8192000000 9728309040 10028740608 806561904 8.963 100 91 182 0 836136928 7537792 22037232
10:59:32 22192 8192000000 9759983952 10067419136 836710784 9.267 100 89 178 0 871530352 13493984 21325584
11:01:05 21569 8192000000 9827920992 10152869888 888426320 9.783 100 92 184 2 937571392 27903568 21241504
11:02:29 12272 8192000000 9874050704 10211635200 917888368 10.075 100 77 152 2 986261440 51632048 16741024
11:03:43 7136 8192000000 9926237728 10270793728 958264384 10.472 100 87 174 0 1044322944 67788144 18270416
11:04:55 3295 8192000000 9972904352 10329575424 997069168 10.850 100 73 145 0 1079317456 67437392 14810896
11:06:02 3310 8192000000 9995934608 10363219968 1022556512 11.097 100 74 148 0 1118008128 80315600 15136016
11:07:06 1423 8192000000 10022371600 10390016000 1061193456 11.468 100 91 182 0 1143257872 63320672 18743744
11:08:09 28640 8192000000 9990267104 10371584000 1068390576 11.537 100 59 120 0 1108580288 27281952 12907760
11:09:38 4202 8192000000 10001205728 10385444864 1074309504 11.593 100 40 81 1 1111665776 28817088 8539184
11:10:44 10181 8192000000 10005807040 10404024320 1085172992 11.697 100 23 46 0 1107841616 18190320 4478304
11:11:58 8976 8192000000 10013083072 10409529344 1094455520 11.785 100 39 76 0 1131497552 28601584 8440448
11:13:07 53139 8192000000 10016570256 10410766336 1097236320 11.811 100 49 101 0 1133626784 26117216 10273248
11:15:01 8235 8192000000 10017550896 10430132224 1100754800 11.845 100 41 83 0 1133538032 23856288 8926944
11:16:12 7666 8192000000 10007726096 10407288832 1095815120 11.798 100 39 76 1 1115247232 9616288 9815824
11:17:22 11184 8192000000 10015832448 10432724992 1096761232 11.807 100 23 46 1 1123428736 21271088 5396416
11:18:34 47512 8192000000 9958358048 10364465152 981201008 10.696 91 24 48 1 1010149984 23735920 5213056
11:20:22 11294 8192000000 9907570576 10304520192 961452912 10.503 95 45 90 4 996919456 25294240 10172304
11:21:34 7043 8192000000 9930426128 10313883648 1017176272 11.045 100 21 42 0 1039307808 17194992 4936544
11:22:44 12550 8192000000 9949312368 10347446272 1035056240 11.217 100 25 50 2 1059843216 19807408 4979568
11:23:57 44393 8192000000 9957584944 10364715008 1046079696 11.323 100 16 32 0 1068733056 19601024 3052336
11:25:42 6540 8192000000 9973864400 10365014016 1056915456 11.427 100 29 57 2 1080304016 17291072 6097488
11:26:49 7568 8192000000 9986457616 10382479360 1068536896 11.538 100 45 91 0 1095148704 15816912 10794896
11:28:03 38114 8192000000 10056215504 10461507584 1098249616 11.821 100 43 84 0 1150063040 43282736 8530688
11:29:45 46783 8192000000 10070033728 10478317568 1112540304 11.956 100 43 83 2 1178525136 57374688 8610144
11:31:39 16362 8192000000 10124121248 10536714240 1123882592 12.064 100 45 90 2 1221646800 88605616 9158592
11:33:04 8466 8192000000 10167168096 10573545472 1137710320 12.194 100 60 120 1 1272049952 121706736 12632896
11:34:19 9754 8192000000 10153583632 10569396224 1147637888 12.287 100 53 104 1 1261455712 102319264 11498560
11:35:33 9964 8192000000 10200884752 10623668224 1161584528 12.418 100 56 111 2 1320042992 145898048 12560416
11:36:45 5076 8192000000 10222714192 10634596352 1155028528 12.357 99 54 106 1 1332336832 165540000 11768304
11:37:58 3934 8192000000 10255075904 10669162496 1174136704 12.535 100 60 121 2 1357970288 169961360 13872224
11:39:07 7922 8192000000 10321130544 10737598464 1189605808 12.680 100 68 132 2 1422641584 217771008 15264768
11:40:27 1521 8192000000 10432611760 10838917120 1206274992 12.835 100 89 179 0 1529997824 302855040 20867792
11:41:34 3863 8192000000 10488117456 10915045376 1212951280 12.896 100 85 168 0 1587600784 355143280 19506224
11:42:46 1807 8192000000 10477581472 10895147008 1196687616 12.746 100 74 148 0 1578172240 363827104 17657520
11:43:56 4187 8192000000 10483086352 10902724608 1190951200 12.692 100 74 148 3 1584173664 374874336 18348128
11:45:06 2414 8192000000 10542816320 10964127744 1186312688 12.649 100 82 161 0 1636876336 431114848 19448800
11:46:10 10232 8192000000 10508309632 10951589888 1171110944 12.507 100 51 102 0 1594731008 410502608 13117456
11:47:31 8533 8192000000 10519121840 10964430848 1163909856 12.440 100 42 84 1 1627507104 452786368 10810880
11:48:42 15587 8192000000 10517823664 10942930944 1146629072 12.278 100 40 80 1 1622488864 465249584 10610208
11:50:07 16336 8192000000 10567096800 11038560256 1136522096 12.183 100 40 80 0 1660900992 513043280 11335616
11:51:35 11718 8192000000 10533814384 11002691584 1129612368 12.118 99 35 70 1 1645547280 505881872 10053040
11:52:55 30144 8192000000 10476867696 10953609216 1096197632 11.802 98 23 46 1 1591181776 488422720 6561424
11:54:36 132383 8192000000 10047024000 10534408192 1034926960 11.216 93 13 26 1 1171187328 132987232 3273136
11:57:49 45159 8192000000 10071335984 10493571072 1072779952 11.579 96 33 65 1 1121549888 42180992 6588944
11:59:36 55795 8192000000 10062875216 10509139968 1115715120 11.986 100 8 16 0 1169086496 52049072 1322304
12:01:33 31806 8192000000 10050704608 10472611840 1092853056 11.770 98 21 42 2 1129540784 32436416 4251312
12:03:08 79421 8192000000 10066296640 10490392576 1108730832 11.920 99 48 94 0 1142930944 24141952 10058160
12:05:29 14726 8192000000 10104713888 10542686208 1093491936 11.776 97 32 64 1 1187178544 86834224 6852384
12:06:53 81203 8192000000 10084035344 10596847616 1111422208 11.946 99 15 30 1 1200689312 85620112 3646992
12:09:18 45382 8192000000 10061630256 10549338112 1098425856 11.823 98 11 22 1 1149229024 48289392 2513776
12:11:04 28878 8192000000 10073228784 10520686592 1120252064 12.029 100 9 18 0 1176340128 54118896 1969168
12:13:22 39255 8192000000 10103266304 10590560256 1123492320 12.060 100 12 24 1 1213326528 87007824 2826384
12:15:20 85980 8192000000 10105209104 10563112960 1113515648 11.966 98 18 36 1 1155350288 37474624 4360016
12:17:46 66213 8192000000 10112219472 10562162688 1121609840 12.042 98 19 36 0 1196294720 70871200 3813680
12:19:53 51018 8192000000 10127297888 10575298560 1145418960 12.266 100 23 46 1 1221355776 70866320 5070496
12:21:48 17923 8192000000 10107001568 10544771072 1149778640 12.307 100 38 76 0 1192145696 34649488 7717568
12:23:10 47179 8192000000 10116435616 10592890880 1143617168 12.250 99 14 28 0 1243109712 95994832 3497712
12:25:02 64488 8192000000 10097136480 10550439936 1125035472 12.075 98 11 22 1 1160903568 33642640 2225456
12:27:09 109303 8192000000 10083495184 10554843136 1130760688 12.129 99 21 42 1 1189792624 54576992 4454944
12:30:51 63830 8192000000 10097494592 10578599936 1127036096 12.093 99 4 8 0 1175253376 47481040 736240
12:32:55 104476 8192000000 10080091648 10544271360 1054720896 11.406 92 12 24 0 1127238784 69496240 3021648
12:35:41 51798 8192000000 10108866208 10568638464 1113584416 11.966 98 26 52 2 1197617616 78083456 5949744
12:37:33 34710 8192000000 10097043552 10530004992 1093270128 11.774 97 35 70 1 1134333568 33588368 7475072
12:39:14 50175 8192000000 10139128640 10575572992 1088592192 11.729 96 36 71 2 1181699008 85935280 7171536
12:41:04 46194 8192000000 10165168544 10622062592 1083444048 11.680 96 29 58 0 1210662352 121083936 6134368
12:42:54 28529 8192000000 10065604048 10497167360 1056492256 11.423 96 37 74 0 1124918272 61086400 7339616
12:44:23 117621 8192000000 10078756624 10539462656 1097397568 11.813 99 37 74 0 1166285680 61143456 7744656
12:47:26 48902 8192000000 10058167184 10545201152 1094814992 11.788 99 9 18 0 1167438352 70750368 1872992
12:49:22 59855 8192000000 10036629312 10489139200 1077115120 11.620 98 23 45 3 1128308064 45824416 5368528
12:51:35 48288 8192000000 10058436384 10512302080 1084266816 11.688 98 28 56 2 1132539488 42020544 6252128
12:53:30 72004 8192000000 10125602720 10559303680 1099926464 11.837 98 23 46 1 1200241152 95132176 5182512
12:55:52 62283 8192000000 10193333568 10657959936 1095793920 11.798 97 23 44 2 1260667424 159896016 4977488
12:58:05 53222 8192000000 10045226272 10482520064 1084310192 11.689 97 10 20 0 1116036800 29968864 1757744
13:00:05 108505 8192000000 10104830272 10550382592 1105838816 11.893 98 32 64 1 1174953328 63000432 6114080
13:03:07 77206 8192000000 10079495664 10551623680 1133872096 12.158 100 15 22 3 1186447200 50373728 2201376
13:05:33 73217 8192000000 10123920016 10597601280 1123702112 12.062 98 28 56 1 1212684144 82734144 6247888
13:08:10 106294 8192000000 10097058016 10593415168 1119197824 12.019 98 6 12 0 1172208032 51895408 1114800
13:10:57 44499 8192000000 10093189488 10541817856 1141236192 12.227 100 32 64 1 1172840656 25525488 6078976
13:12:50 88253 8192000000 10088437968 10527600640 1141015008 12.225 99 18 36 0 1187825024 43061824 3748192
13:15:19 106133 8192000000 10060645968 10543710208 1102278864 11.859 96 28 56 0 1152978576 44460480 6239232
13:18:09 62139 8192000000 10060160000 10528661504 1125557360 12.079 98 13 25 2 1157436480 29539184 2339936
13:20:15 39718 8192000000 10103219488 10579640320 1131160320 12.132 99 11 23 0 1165547488 32248640 2138528
13:22:25 47264 8192000000 10106197424 10571476992 1131873120 12.139 98 18 36 0 1205257840 69238192 4146528
13:24:27 52883 8192000000 10151210640 10618425344 1096389392 11.803 95 24 48 1 1200168336 98385568 5393376
13:26:20 45176 8192000000 9286969344 9617641472 376187184 4.390 34 7 14 0 492688928 114805536 1696208

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 21-06-2021 13:28:13 to 21-06-2021 13:28:28.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 118 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-06-2021 13:28:29 to 21-06-2021 13:28:31.

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          235402
MON$OLDEST_ACTIVE               235403
MON$OLDEST_SNAPSHOT             235403
MON$NEXT_TRANSACTION            235403
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                       2120960
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             792
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2519 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2519 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2519 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2519 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16: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 ---
:152: 
:153: mscs-test-161.budzdorov.ru    Mon Jun 21 13:27:10 2021
:154:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:155: 
:156: 
:157: mscs-test-161.budzdorov.ru    Mon Jun 21 13:27:29 2021
:158:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:159: 
--- end of diff output ---

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

21.06.21 13:28:32. 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-06-21 13:28:35 No severe PSQL-related problems occured
21.06.21 13:28:36. End of report.