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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 16584500 kB
MemAvailable 31197564 kB
Buffers 0 kB
Cached 15060828 kB
SwapCached 4876 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-04 07:37:36
DTS_END                         2021-02-04 07:37:36.8300
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/60916
TRN_ID                          229614
ATT_ID                          625
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: 9110.27 23580 1093232
customer order: creation 165.35 459 19842
customer order: refuse 14.55 315 1746
order to supplier: creation 42.98 1437 5158
order to supplier: removal 3.44 125053 413
invoice (draft): creation 44.41 3298 5329
invoice (draft): removal 52.57 928 6308
invoice accept: apply 40.38 17517 4845
invoice accept: cancel 47.58 916 5709
customer reserve: creation 3764.35 169 451722
customer reserve: removal 4651.39 75 558167
realization accept: apply 80.80 174 9696
realization accept: cancel 79.70 132 9564
payment from customer: creation 55.63 98 6676
payment from customer: removal 5.63 109 675
payment to supplier: creation 47.45 134 5694
payment to supplier: removal 3.08 125 370
service: total inventory turnovers 3.96 3101 475
service: total monetary turnovers 5.91 568 709
service: aggregate perf. data 1.08 11006 130
service: refresh index statistics 0.03 305991 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 04-02-2021 07:38:32 to 04-02-2021 07:38:34.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2848
TEST_TIME 2 2372
TEST_TIME 3 3635
TEST_TIME 4 3685
TEST_TIME 5 4217
TEST_TIME 6 4247
TEST_TIME 7 4476
TEST_TIME 8 4591
TEST_TIME 9 5076
TEST_TIME 10 5081
TEST_TIME 11 5305
TEST_TIME 12 5650
TEST_TIME 13 5809
TEST_TIME 14 5821
TEST_TIME 15 5873
TEST_TIME 16 6397
TEST_TIME 17 6688
TEST_TIME 18 6881
TEST_TIME 19 7402
TEST_TIME 20 7703
TEST_TIME 21 7821
TEST_TIME 22 7869
TEST_TIME 23 8104
TEST_TIME 24 8745
TEST_TIME 25 9411
TEST_TIME 26 10482
TEST_TIME 27 11342
TEST_TIME 28 11312
TEST_TIME 29 11097
TEST_TIME 30 11093
TEST_TIME 31 10881
TEST_TIME 32 10569
TEST_TIME 33 10607
TEST_TIME 34 10472
TEST_TIME 35 10373
TEST_TIME 36 10269
TEST_TIME 37 10178
TEST_TIME 38 10115
TEST_TIME 39 10139
TEST_TIME 40 10006
TEST_TIME 41 9883
TEST_TIME 42 9946
TEST_TIME 43 9977
TEST_TIME 44 10010
TEST_TIME 45 10035
TEST_TIME 46 10034
TEST_TIME 47 10054
TEST_TIME 48 10115
TEST_TIME 49 10159
TEST_TIME 50 10205
TEST_TIME 51 10219
TEST_TIME 52 10231
TEST_TIME 53 10068
TEST_TIME 54 9913
TEST_TIME 55 9865
TEST_TIME 56 9743
TEST_TIME 57 9649
TEST_TIME 58 9582
TEST_TIME 59 9562
TEST_TIME 60 9532
TEST_TIME 61 9534
TEST_TIME 62 9509
TEST_TIME 63 9592
TEST_TIME 64 9567
TEST_TIME 65 9678
TEST_TIME 66 9702
TEST_TIME 67 9664
TEST_TIME 68 9754
TEST_TIME 69 9731
TEST_TIME 70 9764
TEST_TIME 71 9830
TEST_TIME 72 9798
TEST_TIME 73 9684
TEST_TIME 74 9610
TEST_TIME 75 9554
TEST_TIME 76 9450
TEST_TIME 77 9440
TEST_TIME 78 9404
TEST_TIME 79 9401
TEST_TIME 80 9354
TEST_TIME 81 9319
TEST_TIME 82 9273
TEST_TIME 83 9353
TEST_TIME 84 9368
TEST_TIME 85 9395
TEST_TIME 86 9543
TEST_TIME 87 9515
TEST_TIME 88 9538
TEST_TIME 89 9478
TEST_TIME 90 9407
TEST_TIME 91 9396
TEST_TIME 92 9352
TEST_TIME 93 9313
TEST_TIME 94 9336
TEST_TIME 95 9280
TEST_TIME 96 9318
TEST_TIME 97 9286
TEST_TIME 98 9287
TEST_TIME 99 9320
TEST_TIME 100 9263
TEST_TIME 101 9325
TEST_TIME 102 9332
TEST_TIME 103 9318
TEST_TIME 104 9335
TEST_TIME 105 9322
TEST_TIME 106 9373
TEST_TIME 107 9400
TEST_TIME 108 9340
TEST_TIME 109 9266
TEST_TIME 110 9248
TEST_TIME 111 9194
TEST_TIME 112 9157
TEST_TIME 113 9145
TEST_TIME 114 9130
TEST_TIME 115 9122
TEST_TIME 116 9123
TEST_TIME 117 9096
TEST_TIME 118 9136
TEST_TIME 119 9121
TEST_TIME 120 9110

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 04-02-2021 07:38:36 to 04-02-2021 07:38:39.

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 1512586 1512586 0 0.00 0 23017 6 0 0
doc_list_biud 1512586 1512586 0 0.00 0 78975 34 0 0
sp_add_invoice_to_stock 4947 4845 102 2.06 40 162581 17518 0 102
sp_cancel_adding_invoice 5709 5709 0 0.00 6 80467 917 0 0
sp_cancel_client_order 1746 1746 0 0.00 4 7120 316 0 0
sp_cancel_customer_reserve 558167 558167 0 0.00 1 78975 75 0 0
sp_cancel_pay_from_customer 675 675 0 0.00 3 13363 110 0 0
sp_cancel_pay_to_supplier 370 370 0 0.00 3 12892 125 0 0
sp_cancel_supplier_invoice 6311 6308 3 0.04 2 23465 929 0 3
sp_cancel_supplier_order 413 413 0 0.00 9 1102651 125053 0 0
sp_cancel_write_off 9564 9564 0 0.00 1 8909 132 0 0
sp_client_order 19842 19842 0 0.00 4 20723 459 0 0
sp_customer_reserve 451722 451722 0 0.00 0 115917 170 0 0
sp_fill_shopping_cart 30312 30312 0 0.00 1 19204 42 0 0
sp_fill_shopping_cart_clo_res 451720 451720 0 0.00 0 33121 19 0 0
sp_get_clo_for_invoice 4843 4843 0 0.00 0 59254 434 0 0
sp_kill_cost_storno 16318 16318 0 0.00 0 23009 65 0 0
sp_kill_qstorno_ret_qs2qd 1132619 1132619 0 0.00 0 78973 40 0 0
sp_kill_qty_storno 596446 596446 0 0.00 0 78974 85 0 0
sp_lock_selected_doc 20243 20243 0 0.00 0 10766 7 0 0
sp_make_cost_storno 26909 26909 0 0.00 0 12827 51 0 0
sp_multiply_rows_for_pdistr 26909 26909 0 0.00 0 6332 30 0 0
sp_multiply_rows_for_qdistr 19842 19842 0 0.00 0 18120 273 0 0
sp_pay_from_customer 6676 6676 0 0.00 4 11897 99 0 0
sp_pay_to_supplier 5694 5694 0 0.00 5 16969 134 0 0
sp_payment_common 12370 12370 0 0.00 2 7676 19 0 0
sp_qd_handle_on_cancel_clo 1746 1746 0 0.00 0 7018 283 0 0
sp_qd_handle_on_invoice_upd_sts 10552 10552 0 0.00 0 28450 354 0 0
sp_qd_handle_on_reserve_upd_sts 9696 9696 0 0.00 0 19144 52 0 0
sp_reserve_write_off 9696 9696 0 0.00 7 19187 175 0 0
sp_supplier_invoice 5329 5329 0 0.00 6 102325 3298 0 0
sp_supplier_order 5158 5158 0 0.00 6 67183 1438 0 0
srv_aggregate_perf_data 130 130 0 0.00 2852 130429 11006 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1854 129511 47890 0 0
srv_find_qd_qs_mism 1047815 1047815 0 0.00 0 21038 5 0 0
srv_make_invnt_saldo 475 475 0 0.00 23 117968 3102 0 0
srv_make_money_saldo 709 709 0 0.00 1 23478 569 0 0
srv_recalc_idx_stat 4 4 0 0.00 135236 461145 305992 0 0
t$perf-abend:sp_add_invoice_to_stock 102 102 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 3 3 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4845 4845 0 0.00 0 21097 158 0 0
t$perf-norm:sp_cancel_adding_invoice 434 434 0 0.00 0 2738 69 0 0
t$perf-norm:sp_cancel_client_order 1746 1746 0 0.00 0 7484 11 0 0
t$perf-norm:sp_cancel_customer_reserve 656 656 0 0.00 0 2875 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 675 675 0 0.00 0 7864 18 0 0
t$perf-norm:sp_cancel_pay_to_supplier 370 370 0 0.00 0 2195 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 375 375 0 0.00 0 298 6 0 0
t$perf-norm:sp_cancel_supplier_order 413 413 0 0.00 0 15765 1331 0 0
t$perf-norm:sp_cancel_write_off 1035 1035 0 0.00 0 556 3 0 0
t$perf-norm:sp_client_order 19842 19842 0 0.00 0 5448 14 0 0
t$perf-norm:sp_customer_reserve 921 921 0 0.00 0 1025 6 0 0
t$perf-norm:sp_pay_from_customer 6676 6676 0 0.00 0 7865 6 0 0
t$perf-norm:sp_pay_to_supplier 5694 5694 0 0.00 0 17814 10 0 0
t$perf-norm:sp_reserve_write_off 9696 9696 0 0.00 0 7982 9 0 0
t$perf-norm:sp_supplier_invoice 5329 5329 0 0.00 0 6615 14 0 0
t$perf-norm:sp_supplier_order 5158 5158 0 0.00 0 7353 18 0 0
t$perf-norm:srv_aggregate_perf_data 130 130 0 0.00 0 94 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 10 1 0 0
t$perf-norm:srv_make_invnt_saldo 475 475 0 0.00 0 2015 14 0 0
t$perf-norm:srv_make_money_saldo 709 709 0 0.00 0 901 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 9890 9686 204 2.06 0 6913 17 0 204
v_all_customers 36004 36004 0 0.00 0 16 1 0 0
v_all_suppliers 29534 29534 0 0.00 0 16 1 0 0
v_all_wares 39684 39684 0 0.00 0 15 1 0 0
v_cancel_adding_invoice 868 868 0 0.00 0 1345 9 0 0
v_cancel_client_order 3492 3492 0 0.00 0 6931 10 0 0
v_cancel_customer_prepayment 1350 1350 0 0.00 0 4278 12 0 0
v_cancel_customer_reserve 1312 1312 0 0.00 0 4562 10 0 0
v_cancel_payment_to_supplier 740 740 0 0.00 0 377 5 0 0
v_cancel_supplier_invoice 756 750 6 0.79 0 1061 13 0 6
v_cancel_supplier_order 826 826 0 0.00 0 2522 7 0 0
v_cancel_write_off 2070 2070 0 0.00 0 6999 19 0 0
v_max_id_clo_ord 5157 5157 0 0.00 0 1716 2 0 0
v_max_id_clo_res 921 921 0 0.00 0 1960 14 0 0
v_max_id_ord_sup 5312 5312 0 0.00 0 5353 6 0 0
v_max_non_paid_invoice 1396 1396 0 0.00 0 1685 3 0 0
v_max_non_paid_realizn 3488 3488 0 0.00 0 3650 3 0 0
v_min_id_clo_ord 5157 5157 0 0.00 0 4119 6 0 0
v_min_id_clo_res 921 921 0 0.00 0 6282 14 0 0
v_min_id_ord_sup 5312 5312 0 0.00 0 17330 12 0 0
v_min_non_paid_invoice 5694 5694 0 0.00 0 4778 11 0 0
v_min_non_paid_realizn 6676 6676 0 0.00 0 7029 14 0 0
v_our_firm 10056 10056 0 0.00 0 15 1 0 0
v_reserve_write_off 19392 19392 0 0.00 0 6989 8 0 0
x_lock_depdocs_on_canc_invoice 11642 11642 0 0.00 0 17977 107 0 0
x_lock_depdocs_on_canc_sup_ord 413 413 0 0.00 0 21648 423 0 0
x_make_qstorno_1000_1200 5158 5158 0 0.00 2 67082 1368 0 0
x_make_qstorno_1200_2000 5329 5329 0 0.00 3 96548 3132 0 0
x_make_qstorno_2100_3300 451722 451722 0 0.00 0 115916 148 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 04-02-2021 07:38:40 to 04-02-2021 07:38:44.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
05:07:40 7 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
05:08:41 33833 8192000000 9730392320 10029408256 803673520 8.934 100 83 166 1 832496512 7669168 21153824
05:10:20 27367 8192000000 9773093328 10081730560 847974256 9.380 100 86 172 0 881341104 13206080 20160768
05:11:58 10926 8192000000 9816299696 10140688384 889879104 9.798 100 80 161 0 929848704 22068384 17901216
05:13:14 15470 8192000000 9894220160 10229309440 928757264 10.182 100 84 168 0 1004616288 57664416 18194608
05:14:38 8606 8192000000 9943501760 10287439872 970897696 10.595 100 79 157 2 1049730832 62816352 16016784
05:15:50 3332 8192000000 9986475472 10341425152 1003415280 10.912 100 87 174 0 1102738048 81398688 17924080
05:16:55 3910 8192000000 9993282608 10354581504 1024412064 11.115 100 71 141 2 1107758528 69607616 13738848
05:18:01 1604 8192000000 10018116368 10384801792 1050939520 11.370 100 90 180 0 1123049648 53718016 18392112
05:19:04 30402 8192000000 10019275344 10396725248 1062972000 11.485 100 74 146 1 1131995696 53525056 15498640
05:20:36 5283 8192000000 9993306928 10385489920 1074630672 11.596 100 35 72 1 1122016336 39981760 7403904
05:21:42 5615 8192000000 10009231056 10404159488 1090590272 11.748 100 38 74 1 1115190784 16688288 7912224
05:22:54 8394 8192000000 10008352912 10398523392 1093332864 11.774 100 35 69 4 1125786992 24673456 7780672
05:24:03 31915 8192000000 10030840816 10415443968 1095293872 11.793 100 71 140 5 1124343264 14440304 14609088
05:25:35 3712 8192000000 10025654032 10431401984 1099362736 11.832 100 30 62 0 1131097488 24953248 6781504
05:26:39 6142 8192000000 10027306048 10430820352 1096644848 11.806 100 41 82 2 1148606848 42712784 9249216
05:27:47 29657 8192000000 10017747760 10418196480 1085448544 11.699 99 51 102 2 1114664560 17192512 12023504
05:29:21 9045 8192000000 9981878240 10389532672 1060094736 11.457 98 35 70 3 1089728784 21593072 8040976
05:30:30 5607 8192000000 9950794528 10338205696 1029174640 11.160 98 36 72 2 1052775824 15889888 7711296
05:31:37 7645 8192000000 9920989792 10303160320 955230240 10.442 94 41 83 1 980045328 14585504 10229584
05:32:47 40763 8192000000 9952901376 10361573376 1025849696 11.128 99 29 57 1 1059288224 27493824 5944704
05:34:30 8899 8192000000 9969265856 10366971904 1038145168 11.247 99 42 84 0 1063111680 15846192 9120320
05:35:43 8465 8192000000 9974432864 10377564160 1059157136 11.448 100 33 66 0 1085228864 19009360 7062368
05:36:53 13043 8192000000 9999567472 10404450304 1073199872 11.583 100 45 88 2 1100777200 17802336 9774992
05:38:08 42464 8192000000 10022457696 10421317632 1096577184 11.805 100 43 85 2 1132109264 26180064 9352016
05:39:59 39995 8192000000 10078795648 10490970112 1124083536 12.066 100 41 82 2 1193751440 60684592 8983312
05:41:42 9887 8192000000 10122679024 10538987520 1142993744 12.244 100 52 102 0 1234747616 80027056 11726816
05:42:58 11412 8192000000 10175910544 10585985024 1158385344 12.388 100 51 101 2 1280342896 110396528 11561024
05:44:13 15267 8192000000 10226705920 10638008320 1171166624 12.508 100 48 95 0 1326438736 144896032 10376080
05:45:34 5411 8192000000 10268634032 10682548224 1178852832 12.579 100 56 111 1 1376645680 185209632 12583216
05:46:42 5850 8192000000 10285123440 10710085632 1193656880 12.717 100 53 104 0 1389858368 183865552 12335936
05:47:54 2575 8192000000 10325141568 10736406528 1199589488 12.773 100 77 153 0 1429520576 212381472 17549616
05:48:59 3745 8192000000 10350571728 10758955008 1193445984 12.715 100 73 146 2 1438058752 228130208 16482560
05:50:08 7751 8192000000 10375790944 10799767552 1210422400 12.873 100 53 105 1 1482649504 259842832 12384272
05:51:20 2429 8192000000 10461907952 10873823232 1218530576 12.948 100 82 161 2 1556232240 318849088 18852576
05:52:29 5498 8192000000 10458450080 10872127488 1194762896 12.728 99 69 138 2 1547574496 336524528 16287072
05:53:46 1872 8192000000 10435901952 10841399296 1167522288 12.474 98 86 169 1 1523320880 335603072 20195520
05:54:52 1850 8192000000 10463832832 10888364032 1183320752 12.621 100 80 158 0 1565802448 364334736 18146960
05:56:00 1900 8192000000 10487294256 10914701312 1192954192 12.711 100 79 157 0 1587418688 375908480 18556016
05:57:07 10731 8192000000 10471384960 10920615936 1170206560 12.499 99 49 98 1 1581879616 399057824 12615232
05:58:23 8850 8192000000 10474662160 10932707328 1151433312 12.323 100 40 79 0 1569829760 407718096 10678352
05:59:35 11039 8192000000 10533459808 10980130816 1157313408 12.378 99 46 92 0 1636730096 467218016 12198672
06:00:49 12484 8192000000 10567371632 11047178240 1160876800 12.411 100 39 78 1 1681718368 509842864 10998704
06:02:02 20961 8192000000 10457213536 10979577856 1130922224 12.130 100 25 50 0 1559303200 420853056 7527920
06:03:27 54097 8192000000 10083997056 10636939264 1121462736 12.041 99 17 34 2 1323339328 197408960 4467632
06:05:22 41211 8192000000 10012192048 10467557376 1066339920 11.517 97 4 8 1 1118298256 50756976 1201360
06:07:03 73444 8192000000 10070849728 10500104192 1100123872 11.839 98 34 68 0 1145283456 38308192 6851392
06:09:27 88856 8192000000 10063866000 10553716736 1105546672 11.890 99 30 60 0 1204832720 92585968 6700080
06:12:17 22288 8192000000 10048863088 10481491968 1091487776 11.757 98 27 54 0 1133034528 36354960 5191792
06:13:40 39718 8192000000 10089140080 10521370624 1107218832 11.906 99 25 49 1 1170849360 58040592 5589936
06:15:22 105009 8192000000 10085857024 10565169152 1093817968 11.779 97 13 26 0 1169704048 72983600 2902480
06:18:08 105321 8192000000 10152623248 10620182528 1115627120 11.986 98 28 56 1 1237714624 115378864 6708640
06:21:04 42673 8192000000 10102370832 10545422336 1112452096 11.956 98 24 48 0 1176608496 58756960 5399440
06:23:00 80749 8192000000 10128036848 10555604992 1125400112 12.078 99 18 36 0 1198596352 69397792 3798448
06:25:31 52566 8192000000 10089731200 10612609024 1141683696 12.231 100 18 36 0 1236280432 90783168 3813568
06:27:38 86498 8192000000 10073941200 10534912000 1110378192 11.936 97 9 18 0 1160196128 48066352 1751584
06:30:10 129511 8192000000 10086823344 10576117760 1132762208 12.147 99 20 40 1 1172465824 35328960 4374656
06:33:27 22317 8192000000 10087973024 10572820480 1144456400 12.257 100 3 6 0 1182973648 37963472 553776
06:34:50 84555 8192000000 10110604784 10577272832 1143531152 12.249 99 22 44 2 1184857680 37196464 4130064
06:37:20 65365 8192000000 10098070640 10555994112 1145700992 12.269 99 15 30 1 1192557584 43800672 3055920
06:39:26 57971 8192000000 10111019568 10602823680 1115206064 11.982 96 17 34 1 1191706688 73370256 3130368
06:41:32 56522 8192000000 10140722880 10622885888 1098448432 11.823 96 22 44 0 1191037232 87970816 4617984
06:43:29 48435 8192000000 10090683728 10557042688 1102506560 11.861 98 22 44 1 1160127200 52458368 5162272
06:45:21 69788 8192000000 10070100096 10509279232 1099610448 11.834 97 19 36 2 1157424688 54592192 3222048
06:47:39 36722 8192000000 10180841008 10657193984 1135503360 12.173 100 30 60 2 1231532080 89836400 6192320
06:49:16 74775 8192000000 10063146896 10569793536 1119490800 12.022 100 8 16 0 1174183520 53275504 1417216
06:51:32 82473 8192000000 10047962208 10518827008 1105368416 11.889 98 11 17 4 1154113712 46658912 2086384
06:53:57 60995 8192000000 10072887808 10558083072 1097058544 11.810 98 5 10 0 1134471392 36464592 948256
06:55:59 71463 8192000000 10076047376 10522316800 1091988240 11.762 98 12 24 1 1145343328 50667184 2687904
06:58:12 35753 8192000000 10160679200 10597298176 1141367408 12.228 100 32 63 0 1236226544 87795024 7064112
06:59:48 61784 8192000000 10216685824 10717769728 1134571664 12.164 99 20 40 0 1302641760 163274224 4795872
07:02:00 100549 8192000000 10070926144 10578796544 1070689744 11.559 94 20 41 1 1197904464 122251440 4963280
07:04:43 32046 8192000000 10114316016 10583576576 1122619680 12.052 99 37 74 0 1191990656 62373072 6997904
07:06:17 26915 8192000000 10116487088 10553425920 1140258864 12.218 100 59 116 3 1194279168 41455680 12564624
07:08:35 66339 8192000000 10086128272 10551177216 1148697200 12.297 100 8 16 0 1180502128 30385680 1419248
07:10:50 111917 8192000000 10098001280 10557374464 1076116112 11.610 94 10 20 1 1106314016 28244352 1953552
07:14:43 56691 8192000000 10112519456 10563256320 1120014416 12.027 98 29 58 0 1162016688 35713152 6289120
07:16:42 58065 8192000000 10073671376 10507464704 1104348288 11.879 97 32 63 3 1162793872 51682832 6762752
07:18:41 58304 8192000000 10101596624 10602835968 1128518784 12.107 99 17 34 0 1196595072 64652592 3423696
07:20:40 87783 8192000000 10130532528 10595811328 1147192672 12.283 100 18 36 1 1204181504 53516848 3471984
07:23:13 52349 8192000000 10115313040 10558410752 1135468864 12.173 99 10 20 1 1175923296 38462304 1992128
07:25:10 126087 8192000000 10088763472 10546552832 1146852032 12.280 100 23 46 1 1182213664 30172608 5189024
07:28:19 56238 8192000000 10052979776 10524008448 1117251632 12.001 98 7 14 0 1151330512 32617312 1461568
07:30:15 80463 8192000000 10081548096 10538602496 1128406400 12.106 99 9 18 0 1149609984 19817440 1386144
07:32:41 41182 8192000000 10083961424 10522734592 1101859632 11.855 97 21 42 0 1143151808 36552400 4739776
07:34:25 68502 8192000000 10119704736 10591961088 1136304144 12.181 100 22 44 0 1223229104 82046224 4878736
07:37:01 20701 8192000000 10072913456 10511634432 1097279888 11.812 98 47 95 0 1149845696 42971856 9593952

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 04-02-2021 07:38:46 to 04-02-2021 07:39:01.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 102 v_add_invoice_to_stock 335544517
except 3 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 04-02-2021 07:39:01 to 04-02-2021 07:39:03.

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          227334
MON$OLDEST_ACTIVE               229804
MON$OLDEST_SNAPSHOT             229804
MON$NEXT_TRANSACTION            229804
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                       2094208
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             788
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2361 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2361 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2361 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.2361 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Thu Feb  4 07:37:37 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Thu Feb  4 07:37:41 2021
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Thu Feb  4 07:37:44 2021
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:17: 
:18: 
:19: mscs-test-161.budzdorov.ru    Thu Feb  4 07:37:52 2021
:20:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:21: 
--- end of diff output ---

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

04.02.21 07:39:04. 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-04 07:39:07 No severe PSQL-related problems occured
04.02.21 07:39:08. End of report.