Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.02.2021 10:28
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 e0e7b46250ba4f9795ceb55e0face73f
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 2020-09-25 13:42
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.00
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 16417440 kB
MemAvailable 31213720 kB
Buffers 0 kB
Cached 15283340 kB
SwapCached 4776 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.0
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-02-10 10:26:56
DTS_END                         2021-02-10 10:26:56.1650
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/43196
TRN_ID                          240960
ATT_ID                          650
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: 9152.50 19921 1098300
customer order: creation 176.70 401 21204
customer order: refuse 14.85 309 1782
order to supplier: creation 48.38 1290 5805
order to supplier: removal 3.49 112347 419
invoice (draft): creation 48.27 3586 5792
invoice (draft): removal 49.66 1009 5959
invoice accept: apply 43.58 17117 5229
invoice accept: cancel 44.23 923 5308
customer reserve: creation 4022.03 166 482644
customer reserve: removal 4408.68 70 529042
realization accept: apply 86.52 162 10382
realization accept: cancel 75.77 122 9092
payment from customer: creation 59.80 105 7176
payment from customer: removal 5.88 96 706
payment to supplier: creation 50.05 132 6006
payment to supplier: removal 3.68 81 442
service: total inventory turnovers 4.03 3369 484
service: total monetary turnovers 5.98 646 717
service: aggregate perf. data 0.89 16188 107
service: refresh index statistics 0.03 240306 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 10-02-2021 10:28:22 to 10-02-2021 10:28:24.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1747
TEST_TIME 2 2256
TEST_TIME 3 2623
TEST_TIME 4 3432
TEST_TIME 5 3726
TEST_TIME 6 4309
TEST_TIME 7 4826
TEST_TIME 8 5147
TEST_TIME 9 5561
TEST_TIME 10 5548
TEST_TIME 11 5751
TEST_TIME 12 6300
TEST_TIME 13 6418
TEST_TIME 14 6637
TEST_TIME 15 7229
TEST_TIME 16 7612
TEST_TIME 17 8231
TEST_TIME 18 8622
TEST_TIME 19 8837
TEST_TIME 20 9068
TEST_TIME 21 9290
TEST_TIME 22 9350
TEST_TIME 23 9304
TEST_TIME 24 9182
TEST_TIME 25 9246
TEST_TIME 26 9606
TEST_TIME 27 9614
TEST_TIME 28 9824
TEST_TIME 29 10364
TEST_TIME 30 11266
TEST_TIME 31 11306
TEST_TIME 32 11219
TEST_TIME 33 11266
TEST_TIME 34 11230
TEST_TIME 35 10991
TEST_TIME 36 10904
TEST_TIME 37 10825
TEST_TIME 38 10592
TEST_TIME 39 10492
TEST_TIME 40 10398
TEST_TIME 41 10322
TEST_TIME 42 10313
TEST_TIME 43 10346
TEST_TIME 44 10293
TEST_TIME 45 10257
TEST_TIME 46 10187
TEST_TIME 47 10161
TEST_TIME 48 10034
TEST_TIME 49 10099
TEST_TIME 50 10081
TEST_TIME 51 10025
TEST_TIME 52 10019
TEST_TIME 53 10071
TEST_TIME 54 10034
TEST_TIME 55 10020
TEST_TIME 56 10073
TEST_TIME 57 10143
TEST_TIME 58 10124
TEST_TIME 59 10130
TEST_TIME 60 10102
TEST_TIME 61 10143
TEST_TIME 62 10029
TEST_TIME 63 9907
TEST_TIME 64 9831
TEST_TIME 65 9739
TEST_TIME 66 9664
TEST_TIME 67 9623
TEST_TIME 68 9575
TEST_TIME 69 9523
TEST_TIME 70 9500
TEST_TIME 71 9461
TEST_TIME 72 9475
TEST_TIME 73 9567
TEST_TIME 74 9617
TEST_TIME 75 9583
TEST_TIME 76 9596
TEST_TIME 77 9715
TEST_TIME 78 9716
TEST_TIME 79 9690
TEST_TIME 80 9582
TEST_TIME 81 9556
TEST_TIME 82 9495
TEST_TIME 83 9431
TEST_TIME 84 9412
TEST_TIME 85 9364
TEST_TIME 86 9361
TEST_TIME 87 9360
TEST_TIME 88 9343
TEST_TIME 89 9307
TEST_TIME 90 9312
TEST_TIME 91 9298
TEST_TIME 92 9371
TEST_TIME 93 9405
TEST_TIME 94 9393
TEST_TIME 95 9523
TEST_TIME 96 9519
TEST_TIME 97 9491
TEST_TIME 98 9455
TEST_TIME 99 9413
TEST_TIME 100 9371
TEST_TIME 101 9402
TEST_TIME 102 9368
TEST_TIME 103 9345
TEST_TIME 104 9319
TEST_TIME 105 9282
TEST_TIME 106 9324
TEST_TIME 107 9411
TEST_TIME 108 9410
TEST_TIME 109 9403
TEST_TIME 110 9427
TEST_TIME 111 9388
TEST_TIME 112 9453
TEST_TIME 113 9388
TEST_TIME 114 9334
TEST_TIME 115 9306
TEST_TIME 116 9263
TEST_TIME 117 9219
TEST_TIME 118 9203
TEST_TIME 119 9180
TEST_TIME 120 9154

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 10-02-2021 10:28:26 to 10-02-2021 10:28:29.

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 1544743 1544743 0 0.00 0 18448 6 0 0
doc_list_biud 1544743 1544743 0 0.00 0 28012 31 0 0
sp_add_invoice_to_stock 5323 5229 94 1.76 29 180400 17118 0 94
sp_cancel_adding_invoice 5308 5308 0 0.00 4 60919 923 0 0
sp_cancel_client_order 1782 1782 0 0.00 4 14245 309 0 0
sp_cancel_customer_reserve 529042 529042 0 0.00 1 22455 70 0 0
sp_cancel_pay_from_customer 706 706 0 0.00 2 8014 97 0 0
sp_cancel_pay_to_supplier 442 442 0 0.00 3 3482 82 0 0
sp_cancel_supplier_invoice 5963 5959 4 0.06 1 19669 1009 0 4
sp_cancel_supplier_order 419 419 0 0.00 7 918662 112347 0 0
sp_cancel_write_off 9092 9092 0 0.00 1 6724 122 0 0
sp_client_order 21204 21204 0 0.00 4 51434 402 0 0
sp_customer_reserve 482644 482644 0 0.00 0 70166 166 0 0
sp_fill_shopping_cart 32787 32787 0 0.00 1 18271 44 0 0
sp_fill_shopping_cart_clo_res 482637 482637 0 0.00 0 18804 17 0 0
sp_get_clo_for_invoice 5222 5222 0 0.00 0 36049 352 0 0
sp_kill_cost_storno 15548 15548 0 0.00 0 6714 62 0 0
sp_kill_qstorno_ret_qs2qd 1073554 1073554 0 0.00 0 22440 38 0 0
sp_kill_qty_storno 567201 567201 0 0.00 0 28012 82 0 0
sp_lock_selected_doc 21515 21515 0 0.00 0 8214 5 0 0
sp_make_cost_storno 28781 28781 0 0.00 0 18428 52 0 0
sp_multiply_rows_for_pdistr 28781 28781 0 0.00 0 7996 29 0 0
sp_multiply_rows_for_qdistr 21204 21204 0 0.00 0 16051 217 0 0
sp_pay_from_customer 7176 7176 0 0.00 4 10405 105 0 0
sp_pay_to_supplier 6006 6006 0 0.00 5 17080 133 0 0
sp_payment_common 13182 13182 0 0.00 1 7879 20 0 0
sp_qd_handle_on_cancel_clo 1782 1782 0 0.00 0 14238 274 0 0
sp_qd_handle_on_invoice_upd_sts 10525 10525 0 0.00 0 28010 399 0 0
sp_qd_handle_on_reserve_upd_sts 10382 10382 0 0.00 0 19846 56 0 0
sp_reserve_write_off 10382 10382 0 0.00 7 19855 162 0 0
sp_supplier_invoice 5792 5792 0 0.00 8 98045 3587 0 0
sp_supplier_order 5805 5805 0 0.00 5 52621 1291 0 0
srv_aggregate_perf_data 107 107 0 0.00 2483 112852 16189 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 1384 118327 40189 0 0
srv_find_qd_qs_mism 1048816 1048816 0 0.00 0 8461 5 0 0
srv_make_invnt_saldo 484 484 0 0.00 42 113814 3369 0 0
srv_make_money_saldo 717 717 0 0.00 1 17019 647 0 0
srv_recalc_idx_stat 4 4 0 0.00 197399 332419 240306 0 0
t$perf-abend:sp_add_invoice_to_stock 94 94 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5229 5229 0 0.00 0 23163 172 0 0
t$perf-norm:sp_cancel_adding_invoice 418 418 0 0.00 1 13673 109 0 0
t$perf-norm:sp_cancel_client_order 1782 1782 0 0.00 0 3808 15 0 0
t$perf-norm:sp_cancel_customer_reserve 719 719 0 0.00 0 602 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 706 706 0 0.00 0 3834 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 442 442 0 0.00 0 3527 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 439 439 0 0.00 0 8081 32 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 21594 1500 0 0
t$perf-norm:sp_cancel_write_off 991 991 0 0.00 0 2025 7 0 0
t$perf-norm:sp_client_order 21204 21204 0 0.00 0 8254 19 0 0
t$perf-norm:sp_customer_reserve 933 933 0 0.00 0 5158 20 0 0
t$perf-norm:sp_pay_from_customer 7176 7176 0 0.00 0 4745 7 0 0
t$perf-norm:sp_pay_to_supplier 6006 6006 0 0.00 0 10896 12 0 0
t$perf-norm:sp_reserve_write_off 10382 10382 0 0.00 0 32954 16 0 0
t$perf-norm:sp_supplier_invoice 5792 5792 0 0.00 0 12196 21 0 0
t$perf-norm:sp_supplier_order 5805 5805 0 0.00 0 4542 16 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 180 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 93 4 0 0
t$perf-norm:srv_make_invnt_saldo 484 484 0 0.00 0 1676 11 0 0
t$perf-norm:srv_make_money_saldo 717 717 0 0.00 0 608 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10622 10434 188 1.76 0 6550 20 0 188
v_all_customers 38022 38022 0 0.00 0 31 1 0 0
v_all_suppliers 31866 31866 0 0.00 0 19 1 0 0
v_all_wares 42406 42406 0 0.00 0 72 1 0 0
v_cancel_adding_invoice 836 836 0 0.00 0 672 7 0 0
v_cancel_client_order 3564 3564 0 0.00 0 1646 5 0 0
v_cancel_customer_prepayment 1412 1412 0 0.00 0 1795 8 0 0
v_cancel_customer_reserve 1438 1438 0 0.00 0 2942 6 0 0
v_cancel_payment_to_supplier 884 884 0 0.00 0 661 4 0 0
v_cancel_supplier_invoice 886 878 8 0.90 0 746 13 0 8
v_cancel_supplier_order 838 838 0 0.00 0 3842 13 0 0
v_cancel_write_off 1982 1982 0 0.00 0 1139 13 0 0
v_max_id_clo_ord 5805 5805 0 0.00 0 4675 3 0 0
v_max_id_clo_res 933 933 0 0.00 0 2187 13 0 0
v_max_id_ord_sup 5778 5778 0 0.00 0 6158 4 0 0
v_max_non_paid_invoice 1656 1656 0 0.00 0 114 2 0 0
v_max_non_paid_realizn 3912 3912 0 0.00 0 3745 3 0 0
v_min_id_clo_ord 5805 5805 0 0.00 0 4120 8 0 0
v_min_id_clo_res 933 933 0 0.00 0 21476 36 0 0
v_min_id_ord_sup 5778 5778 0 0.00 0 8315 7 0 0
v_min_non_paid_invoice 6006 6006 0 0.00 0 5710 11 0 0
v_min_non_paid_realizn 7176 7176 0 0.00 0 7876 14 0 0
v_our_firm 10912 10912 0 0.00 0 43 1 0 0
v_reserve_write_off 20764 20764 0 0.00 0 2815 5 0 0
x_lock_depdocs_on_canc_invoice 10828 10828 0 0.00 0 14107 116 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 12766 375 0 0
x_make_qstorno_1000_1200 5805 5805 0 0.00 2 52616 1208 0 0
x_make_qstorno_1200_2000 5792 5792 0 0.00 4 97835 3420 0 0
x_make_qstorno_2100_3300 482644 482644 0 0.00 0 70163 147 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-02-2021 10:28:30 to 10-02-2021 10:28:34.

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
07:56:59 6 8192000000 8798449744 8809664512 4478880 0.054 2 1 2 0 4578272 63616 35776
07:58:00 23523 8192000000 9720104304 10021388288 806517536 8.962 100 80 160 0 833535440 7425568 19592336
07:59:27 28423 8192000000 9776242800 10082852864 846400880 9.364 100 91 182 0 880521360 12860512 21259968
08:01:12 12977 8192000000 9817607648 10135732224 881525936 9.715 100 87 174 0 927341424 26648240 19167248
08:02:29 14625 8192000000 9874917056 10207248384 908579872 9.983 100 84 168 1 983260848 56595184 18085792
08:03:47 7649 8192000000 9913001312 10254688256 955474864 10.445 100 80 159 1 1021753904 49840432 16438608
08:05:00 3967 8192000000 9965502320 10318348288 996136032 10.841 100 78 154 1 1084158768 72092592 15930144
08:06:06 4548 8192000000 9982198048 10344505344 1025140448 11.122 100 93 185 0 1099597824 55247520 19209856
08:07:16 2110 8192000000 10021862656 10394439680 1047558992 11.337 100 83 165 0 1137690592 73186656 16944944
08:08:20 33445 8192000000 10013866704 10397044736 1061978848 11.475 100 62 125 0 1139997024 65116048 12902128
08:09:54 5028 8192000000 10000965440 10387492864 1070593536 11.558 100 46 93 0 1114681728 34790576 9297616
08:11:00 6080 8192000000 9988735056 10389839872 1077240192 11.621 100 33 66 1 1107036992 22015120 7781680
08:12:11 55629 8192000000 9990680848 10386538496 1081789056 11.665 100 40 78 0 1104084928 13434640 8861232
08:14:07 7180 8192000000 10007095456 10405699584 1087696656 11.721 100 41 80 2 1124242176 27282800 9262720
08:15:17 6351 8192000000 10013750848 10421432320 1092197584 11.764 100 32 63 0 1120049136 21123552 6728000
08:16:23 4314 8192000000 9999446848 10391744512 1069602928 11.548 98 35 66 1 1092803424 16354176 6846320
08:17:29 50641 8192000000 10007353296 10419982336 1011152512 10.987 93 33 65 1 1041995840 23883552 6959776
08:19:22 7857 8192000000 9958847920 10355556352 1025138768 11.122 98 37 75 1 1053735360 20309440 8287152
08:20:31 4485 8192000000 9922248224 10291478528 951113584 10.402 93 43 87 0 982540176 22215280 9211312
08:21:36 6548 8192000000 9922683728 10298621952 1014408832 11.018 100 35 72 1 1040474128 17065504 8999792
08:22:44 23915 8192000000 9937038384 10337157120 1009675152 10.972 98 25 48 1 1030677040 15556816 5445072
08:24:12 12353 8192000000 9953331776 10346192896 1036198112 11.228 100 32 64 1 1061866496 18078480 7589904
08:25:25 9507 8192000000 9979529936 10384216064 1050353296 11.364 100 30 60 1 1081287104 24563168 6370640
08:26:38 8957 8192000000 9977013584 10377527296 1066739776 11.521 100 39 78 1 1099084144 23015360 9329008
08:27:47 19619 8192000000 10002390064 10400661504 1084858112 11.694 100 34 65 3 1115183872 22588912 7736848
08:29:13 14662 8192000000 10039846752 10444627968 1108135424 11.915 100 38 76 1 1153355600 37167424 8052752
08:30:32 22468 8192000000 10087673664 10492719104 1121014016 12.037 100 40 80 3 1201063408 71375808 8673584
08:32:03 19519 8192000000 10130693568 10535542784 1136660352 12.184 100 44 86 0 1248465808 102216160 9589296
08:33:26 6707 8192000000 10183103264 10594140160 1147640544 12.287 100 62 124 3 1301412800 140007760 13764496
08:34:36 10891 8192000000 10190666000 10608029696 1154902928 12.355 100 48 94 1 1302810960 137523888 10384144
08:35:50 2226 8192000000 10224528064 10632916992 1162064880 12.423 100 74 147 1 1327321744 147922144 17334720
08:36:56 5901 8192000000 10254076080 10670776320 1160311504 12.406 100 57 114 0 1359245568 186626864 12307200
08:38:04 1608 8192000000 10304794912 10705670144 1153002720 12.338 99 87 172 0 1396167328 225395152 17769456
08:39:08 2141 8192000000 10332438224 10734260224 1163963712 12.440 100 82 164 2 1427730400 245836624 17930064
08:40:17 2637 8192000000 10303119232 10718208000 1152880800 12.337 99 54 107 0 1401836208 236738576 12216832
08:41:23 1382 8192000000 10317536064 10719424512 1146362656 12.275 100 87 174 0 1415501280 249654160 19484464
08:42:29 1872 8192000000 10317598496 10725199872 1142229840 12.237 100 79 155 0 1414569376 255094464 17245072
08:43:35 4246 8192000000 10261930848 10667470848 1133698976 12.156 100 86 164 1 1375249712 223803872 17746864
08:44:41 10603 8192000000 10248343232 10690310144 1127266352 12.096 100 39 78 0 1352993728 215268880 10458496
08:45:55 3758 8192000000 10291975984 10697064448 1127488112 12.098 100 74 147 1 1387059888 242906032 16665744
08:47:05 4301 8192000000 10264997104 10684538880 1122846352 12.054 100 72 141 0 1358605584 219782160 15977072
08:48:15 8111 8192000000 10249655248 10666348544 1132015312 12.140 100 46 93 0 1361191904 217911264 11265328
08:49:29 12255 8192000000 10288667184 10717249536 1122333552 12.049 99 32 63 3 1381545536 250997440 8214544
08:50:47 11923 8192000000 10367234384 10790313984 1135354704 12.172 100 44 88 0 1461414352 314769920 11289728
08:52:11 5669 8192000000 10418096528 10847211520 1148066000 12.291 100 50 100 0 1507772256 347217840 12488416
08:53:29 19023 8192000000 10389474688 10846400512 1139460656 12.210 100 30 60 1 1484444208 336882592 8100960
08:55:01 13134 8192000000 10375862576 10866565120 1146663440 12.278 100 25 50 0 1489689488 336104464 6921584
08:56:20 29894 8192000000 10174635280 10740207616 1100263104 11.840 97 12 24 2 1314437904 211442928 2731872
08:57:58 48474 8192000000 10097322144 10527830016 1109375776 11.927 98 17 36 0 1178177280 65227360 3574144
08:59:48 45523 8192000000 10075934736 10555584512 1130103936 12.122 99 11 22 0 1183223168 51151280 1967952
09:01:36 40645 8192000000 10069877520 10502004736 1116893488 11.998 98 42 84 0 1152564800 26047104 9624208
09:03:29 87641 8192000000 10088514176 10523332608 1141496496 12.230 100 34 66 0 1190760912 40571280 8693136
09:06:10 17595 8192000000 10081780048 10526371840 1145466944 12.267 100 39 78 0 1185379424 31980208 7932272
09:07:29 65711 8192000000 10147459056 10597015552 1162236368 12.424 100 20 40 2 1232987792 66356432 4394992
09:10:01 46183 8192000000 10120040448 10578452480 1165429744 12.454 100 21 42 1 1220964704 51122800 4412160
09:12:13 53489 8192000000 10127219152 10598612992 1157253728 12.378 99 17 34 1 1242503248 81499312 3750208
09:14:14 57654 8192000000 10128082720 10601627648 1167949280 12.478 100 21 42 1 1245380960 72438736 4992944
09:17:10 57179 8192000000 10103107120 10555723776 1154906416 12.356 99 16 32 0 1210994304 52202768 3885120
09:19:11 57643 8192000000 10089008528 10575233024 1136510288 12.183 98 14 28 1 1166625344 27231536 2883520
09:21:09 94764 8192000000 10124887936 10627637248 1134454096 12.163 97 14 28 0 1225458224 87665728 3338400
09:24:33 61609 8192000000 10063145968 10517843968 1112056912 11.952 97 27 54 2 1155395184 38040784 5297488
09:26:35 18374 8192000000 10084605072 10514305024 1119835696 12.025 99 34 68 1 1190538368 63785312 6917360
09:27:58 118327 8192000000 10036804784 10481672192 1082797728 11.674 96 14 28 0 1113521504 27936832 2786944
09:31:03 44350 8192000000 10036671856 10489503744 1111128224 11.943 99 5 10 0 1145433728 33346208 959296
09:32:48 36695 8192000000 10045110672 10509918208 1104994896 11.885 99 5 10 0 1149514816 43619424 900496
09:34:25 45953 8192000000 10007824128 10445008896 1037108416 11.237 93 5 10 1 1051861168 14125952 626800
09:36:11 44643 8192000000 10075943072 10515828736 1081895168 11.666 97 25 50 1 1161476368 74257488 5323712
09:38:07 66618 8192000000 10137548192 10575872000 1112597872 11.957 99 26 52 1 1234167184 115221536 6347776
09:40:17 59174 8192000000 10065942576 10547683328 1115946064 11.989 99 16 32 1 1205080288 85459088 3675136
09:42:19 57608 8192000000 10125980352 10589048832 1117784400 12.006 99 29 58 0 1207416640 83459552 6172688
09:44:28 33510 8192000000 10088691568 10516389888 1107784016 11.911 99 28 56 0 1145053152 31754672 5514464
09:46:05 96382 8192000000 10038335696 10496671744 1099872096 11.836 98 22 44 0 1152009728 47573984 4563648
09:48:43 40018 8192000000 10034343584 10489790464 1113922640 11.970 99 5 10 0 1137626240 22514576 1189024
09:50:23 28087 8192000000 10053653248 10532229120 1128134832 12.104 100 4 8 0 1158108624 29260800 712992
09:51:52 57247 8192000000 10050342096 10506268672 1130807536 12.129 100 18 36 1 1175637472 40753824 4076112
09:53:53 52588 8192000000 10059403936 10509045760 1114344544 11.974 98 24 46 1 1150206976 31222512 4639920
09:55:46 49949 8192000000 10146605952 10602385408 1146571408 12.277 100 37 74 2 1232044688 77579008 7894272
09:57:37 87824 8192000000 10106485264 10606436352 1147740064 12.288 99 19 38 1 1255743216 104372336 3630816
10:00:10 78044 8192000000 10176788176 10659155968 1148686464 12.297 99 18 36 1 1236050560 83540352 3823744
10:02:28 16891 8192000000 10077756560 10504011776 1129254352 12.114 98 35 70 0 1160052240 22797200 8000688
10:03:58 114399 8192000000 10065537872 10518376448 1137342336 12.191 99 15 30 1 1195284064 54972464 2969264
10:06:52 60586 8192000000 10093632736 10610135040 1152106768 12.329 100 7 14 3 1211061232 57491920 1462544
10:08:59 54540 8192000000 10115191728 10572984320 1137581968 12.193 98 40 77 4 1187741344 41731664 8427712
10:10:59 39700 8192000000 10131895328 10571481088 1129122976 12.113 98 29 58 1 1202511680 66896160 6492544
10:12:45 88234 8192000000 10097999296 10658824192 1139691840 12.213 100 10 20 1 1273170032 131166560 2311632
10:15:13 21399 8192000000 10088436400 10530226176 1130517792 12.126 99 48 95 0 1161836544 20357200 10961552
10:16:39 58719 8192000000 10111099488 10533093376 1117583472 12.004 98 32 64 1 1166778448 42357024 6837952
10:18:39 108545 8192000000 10069444224 10559070208 1108611760 11.919 99 14 28 1 1166564816 54889856 3063200
10:21:58 39659 8192000000 10033882704 10518257664 1107300512 11.907 99 7 14 0 1142055968 33192640 1562816
10:23:40 38761 8192000000 10053453232 10499440640 1114136256 11.972 99 18 36 0 1153260416 35333104 3791056
10:25:19 67101 8192000000 10070874928 10513457152 1089954128 11.742 97 12 24 1 1142209488 49668560 2586800

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 10-02-2021 10:28:36 to 10-02-2021 10:28:52.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 94 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 10-02-2021 10:28:53 to 10-02-2021 10:28:54.

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          236878
MON$OLDEST_ACTIVE               241118
MON$OLDEST_SNAPSHOT             241118
MON$NEXT_TRANSACTION            241118
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                       2118400
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             815
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2365 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2365 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2365 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2365 Firebird 4.0 Release Candidate 1/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 ---
:207: 
:208: mscs-test-161.budzdorov.ru    Wed Feb 10 10:26:57 2021
:209:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:210: 
:211: 
:212: mscs-test-161.budzdorov.ru    Wed Feb 10 10:26:59 2021
:213:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:214: 
:215: 
:216: mscs-test-161.budzdorov.ru    Wed Feb 10 10:27:01 2021
:217:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:218: 
:219: 
:220: mscs-test-161.budzdorov.ru    Wed Feb 10 10:27:01 2021
:221:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:222: 
:223: 
:224: mscs-test-161.budzdorov.ru    Wed Feb 10 10:27:01 2021
:225:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:226: 
--- end of diff output ---

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

10.02.21 10:28:56. 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-02-10 10:28:58 No severe PSQL-related problems occured
10.02.21 10:28:59. End of report.