Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.04.2021 13: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 df9f488d08424df294c459d6c283e9c3
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-03-04 16:07
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.26
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 17011604 kB
MemAvailable 31463908 kB
Buffers 0 kB
Cached 14677828 kB
SwapCached 76 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-04-06 13:26:58
DTS_END                         2021-04-06 13:26:58.8810
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/60030
TRN_ID                          220636
ATT_ID                          615
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: 8598.78 30536 1031853
customer order: creation 154.12 434 18494
customer order: refuse 13.59 375 1631
order to supplier: creation 42.24 1511 5069
order to supplier: removal 3.30 138408 396
invoice (draft): creation 40.68 3824 4882
invoice (draft): removal 48.70 1201 5844
invoice accept: apply 37.63 20625 4515
invoice accept: cancel 44.54 978 5345
customer reserve: creation 3509.54 201 421145
customer reserve: removal 4428.34 80 531401
realization accept: apply 75.47 188 9056
realization accept: cancel 83.52 137 10022
payment from customer: creation 54.03 135 6484
payment from customer: removal 5.04 95 605
payment to supplier: creation 44.60 147 5352
payment to supplier: removal 3.28 143 394
service: total inventory turnovers 3.60 3789 432
service: total monetary turnovers 5.80 666 696
service: aggregate perf. data 0.72 24200 86
service: refresh index statistics 0.03 413573 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 06-04-2021 13:29:19 to 06-04-2021 13:29:21.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1709
TEST_TIME 2 3325
TEST_TIME 3 3190
TEST_TIME 4 3775
TEST_TIME 5 3700
TEST_TIME 6 3573
TEST_TIME 7 3657
TEST_TIME 8 3695
TEST_TIME 9 3947
TEST_TIME 10 4512
TEST_TIME 11 4804
TEST_TIME 12 5011
TEST_TIME 13 5141
TEST_TIME 14 5227
TEST_TIME 15 5739
TEST_TIME 16 6032
TEST_TIME 17 6330
TEST_TIME 18 6366
TEST_TIME 19 6367
TEST_TIME 20 6685
TEST_TIME 21 6946
TEST_TIME 22 7009
TEST_TIME 23 7145
TEST_TIME 24 7548
TEST_TIME 25 7823
TEST_TIME 26 8274
TEST_TIME 27 8853
TEST_TIME 28 8970
TEST_TIME 29 10323
TEST_TIME 30 10158
TEST_TIME 31 10158
TEST_TIME 32 9952
TEST_TIME 33 9769
TEST_TIME 34 9622
TEST_TIME 35 9586
TEST_TIME 36 9487
TEST_TIME 37 9425
TEST_TIME 38 9338
TEST_TIME 39 9269
TEST_TIME 40 9163
TEST_TIME 41 9091
TEST_TIME 42 8986
TEST_TIME 43 9047
TEST_TIME 44 8939
TEST_TIME 45 8845
TEST_TIME 46 8753
TEST_TIME 47 8780
TEST_TIME 48 8850
TEST_TIME 49 9066
TEST_TIME 50 8997
TEST_TIME 51 9099
TEST_TIME 52 9133
TEST_TIME 53 9089
TEST_TIME 54 9223
TEST_TIME 55 9208
TEST_TIME 56 9174
TEST_TIME 57 9359
TEST_TIME 58 9344
TEST_TIME 59 9222
TEST_TIME 60 9070
TEST_TIME 61 9033
TEST_TIME 62 8958
TEST_TIME 63 8859
TEST_TIME 64 8761
TEST_TIME 65 8694
TEST_TIME 66 8621
TEST_TIME 67 8768
TEST_TIME 68 8754
TEST_TIME 69 8786
TEST_TIME 70 8894
TEST_TIME 71 8933
TEST_TIME 72 8875
TEST_TIME 73 8893
TEST_TIME 74 8969
TEST_TIME 75 8992
TEST_TIME 76 8973
TEST_TIME 77 8939
TEST_TIME 78 9006
TEST_TIME 79 9052
TEST_TIME 80 9045
TEST_TIME 81 8955
TEST_TIME 82 8888
TEST_TIME 83 8876
TEST_TIME 84 8834
TEST_TIME 85 8751
TEST_TIME 86 8758
TEST_TIME 87 8756
TEST_TIME 88 8743
TEST_TIME 89 8726
TEST_TIME 90 8766
TEST_TIME 91 8742
TEST_TIME 92 8722
TEST_TIME 93 8716
TEST_TIME 94 8806
TEST_TIME 95 8774
TEST_TIME 96 8779
TEST_TIME 97 8838
TEST_TIME 98 8834
TEST_TIME 99 8821
TEST_TIME 100 8823
TEST_TIME 101 8770
TEST_TIME 102 8755
TEST_TIME 103 8720
TEST_TIME 104 8656
TEST_TIME 105 8638
TEST_TIME 106 8592
TEST_TIME 107 8625
TEST_TIME 108 8669
TEST_TIME 109 8660
TEST_TIME 110 8626
TEST_TIME 111 8675
TEST_TIME 112 8655
TEST_TIME 113 8681
TEST_TIME 114 8695
TEST_TIME 115 8690
TEST_TIME 116 8683
TEST_TIME 117 8702
TEST_TIME 118 8721
TEST_TIME 119 8650
TEST_TIME 120 8602

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 06-04-2021 13:29:22 to 06-04-2021 13:29:26.

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 1421835 1421835 0 0.00 0 19257 8 0 0
doc_list_biud 1421835 1421835 0 0.00 0 29339 38 0 0
sp_add_invoice_to_stock 4683 4515 168 3.58 25 213893 20626 0 168
sp_cancel_adding_invoice 5345 5345 0 0.00 5 66819 979 0 0
sp_cancel_client_order 1631 1631 0 0.00 4 11545 376 0 0
sp_cancel_customer_reserve 531401 531401 0 0.00 1 18831 80 0 0
sp_cancel_pay_from_customer 605 605 0 0.00 3 6410 96 0 0
sp_cancel_pay_to_supplier 394 394 0 0.00 2 7181 144 0 0
sp_cancel_supplier_invoice 5856 5844 12 0.20 2 15101 1201 0 12
sp_cancel_supplier_order 396 396 0 0.00 24 1371931 138408 0 0
sp_cancel_write_off 10022 10022 0 0.00 1 15101 137 0 0
sp_client_order 18494 18494 0 0.00 4 22250 434 0 0
sp_customer_reserve 421145 421145 0 0.00 0 41594 201 0 0
sp_fill_shopping_cart 28426 28426 0 0.00 1 13853 47 0 0
sp_fill_shopping_cart_clo_res 421143 421143 0 0.00 0 20042 19 0 0
sp_get_clo_for_invoice 4510 4510 0 0.00 0 36701 476 0 0
sp_kill_cost_storno 16366 16366 0 0.00 0 15096 75 0 0
sp_kill_qstorno_ret_qs2qd 1079064 1079064 0 0.00 0 27078 44 0 0
sp_kill_qty_storno 568205 568205 0 0.00 0 29339 94 0 0
sp_lock_selected_doc 18930 18930 0 0.00 0 8778 4 0 0
sp_make_cost_storno 25402 25402 0 0.00 0 18712 62 0 0
sp_multiply_rows_for_pdistr 25402 25402 0 0.00 0 16773 41 0 0
sp_multiply_rows_for_qdistr 18494 18494 0 0.00 0 21347 236 0 0
sp_pay_from_customer 6484 6484 0 0.00 4 16870 135 0 0
sp_pay_to_supplier 5352 5352 0 0.00 4 16188 148 0 0
sp_payment_common 11836 11836 0 0.00 1 10968 23 0 0
sp_qd_handle_on_cancel_clo 1631 1631 0 0.00 0 11539 343 0 0
sp_qd_handle_on_invoice_upd_sts 9855 9855 0 0.00 0 29338 437 0 0
sp_qd_handle_on_reserve_upd_sts 9056 9056 0 0.00 0 6875 52 0 0
sp_reserve_write_off 9056 9056 0 0.00 6 16405 189 0 0
sp_supplier_invoice 4882 4882 0 0.00 6 84351 3824 0 0
sp_supplier_order 5069 5069 0 0.00 6 56192 1511 0 0
srv_aggregate_perf_data 86 86 0 0.00 2417 664181 24200 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1427 131735 43882 0 0
srv_find_qd_qs_mism 988611 988611 0 0.00 0 19257 7 0 0
srv_make_invnt_saldo 432 432 0 0.00 46 114774 3790 0 0
srv_make_money_saldo 696 696 0 0.00 1 11170 667 0 0
srv_recalc_idx_stat 4 4 0 0.00 165220 658725 413573 0 0
t$perf-abend:sp_add_invoice_to_stock 168 168 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4515 4515 0 0.00 0 10959 172 0 0
t$perf-norm:sp_cancel_adding_invoice 370 370 0 0.00 0 2162 80 0 0
t$perf-norm:sp_cancel_client_order 1631 1631 0 0.00 0 1496 10 0 0
t$perf-norm:sp_cancel_customer_reserve 647 647 0 0.00 0 1179 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 605 605 0 0.00 0 4160 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 394 394 0 0.00 0 1522 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 366 366 0 0.00 0 1548 16 0 0
t$perf-norm:sp_cancel_supplier_order 396 396 0 0.00 0 20260 1758 0 0
t$perf-norm:sp_cancel_write_off 955 955 0 0.00 0 495 4 0 0
t$perf-norm:sp_client_order 18494 18494 0 0.00 0 6598 20 0 0
t$perf-norm:sp_customer_reserve 916 916 0 0.00 0 1066 5 0 0
t$perf-norm:sp_pay_from_customer 6484 6484 0 0.00 0 4366 9 0 0
t$perf-norm:sp_pay_to_supplier 5352 5352 0 0.00 0 5128 10 0 0
t$perf-norm:sp_reserve_write_off 9056 9056 0 0.00 0 6583 9 0 0
t$perf-norm:sp_supplier_invoice 4882 4882 0 0.00 0 4815 18 0 0
t$perf-norm:sp_supplier_order 5069 5069 0 0.00 0 4980 17 0 0
t$perf-norm:srv_aggregate_perf_data 86 86 0 0.00 0 307 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 114 4 0 0
t$perf-norm:srv_make_invnt_saldo 432 432 0 0.00 0 2125 11 0 0
t$perf-norm:srv_make_money_saldo 696 696 0 0.00 0 2215 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 7 5 0 0
v_add_invoice_to_stock 9356 9020 336 3.59 0 10812 20 0 336
v_all_customers 33800 33800 0 0.00 0 203 1 0 0
v_all_suppliers 27816 27816 0 0.00 0 108 1 0 0
v_all_wares 36988 36988 0 0.00 0 200 1 0 0
v_cancel_adding_invoice 740 740 0 0.00 0 4177 13 0 0
v_cancel_client_order 3262 3262 0 0.00 0 2884 5 0 0
v_cancel_customer_prepayment 1210 1210 0 0.00 0 826 5 0 0
v_cancel_customer_reserve 1294 1294 0 0.00 0 3526 8 0 0
v_cancel_payment_to_supplier 788 788 0 0.00 0 620 4 0 0
v_cancel_supplier_invoice 756 732 24 3.17 0 7068 35 0 24
v_cancel_supplier_order 792 792 0 0.00 0 1485 8 0 0
v_cancel_write_off 1910 1910 0 0.00 0 4555 17 0 0
v_max_id_clo_ord 5067 5067 0 0.00 0 842 1 0 0
v_max_id_clo_res 916 916 0 0.00 0 4378 20 0 0
v_max_id_ord_sup 4865 4865 0 0.00 0 3885 3 0 0
v_max_non_paid_invoice 1376 1376 0 0.00 0 901 2 0 0
v_max_non_paid_realizn 3481 3481 0 0.00 0 559 2 0 0
v_min_id_clo_ord 5067 5067 0 0.00 0 6080 10 0 0
v_min_id_clo_res 916 916 0 0.00 0 8351 23 0 0
v_min_id_ord_sup 4865 4865 0 0.00 0 4577 6 0 0
v_min_non_paid_invoice 5352 5352 0 0.00 0 7582 18 0 0
v_min_non_paid_realizn 6484 6484 0 0.00 0 10010 15 0 0
v_our_firm 9194 9194 0 0.00 0 109 1 0 0
v_reserve_write_off 18112 18112 0 0.00 0 6171 6 0 0
x_lock_depdocs_on_canc_invoice 10823 10823 0 0.00 0 11967 121 0 0
x_lock_depdocs_on_canc_sup_ord 396 396 0 0.00 0 2887 353 0 0
x_make_qstorno_1000_1200 5069 5069 0 0.00 2 56172 1413 0 0
x_make_qstorno_1200_2000 4882 4882 0 0.00 3 84268 3652 0 0
x_make_qstorno_2100_3300 421145 421145 0 0.00 0 38440 179 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-04-2021 13:29:27 to 06-04-2021 13:29:31.

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:02 3 8192000000 8798580832 8809799680 4478880 0.054 2 1 2 0 4578272 63616 35776
10:58:04 35044 8192000000 9742163552 10041626624 820825968 9.107 100 90 180 0 850362480 7931664 21604848
10:59:43 19992 8192000000 9770027952 10075115520 841871728 9.319 100 88 176 0 875773616 12956352 20945536
11:01:06 18306 8192000000 9805136688 10123710464 869486144 9.595 100 87 172 1 910025856 20825728 19713984
11:02:29 13498 8192000000 9868835120 10200305664 912397680 10.021 100 79 158 1 977384656 47635072 17351904
11:03:45 13173 8192000000 9921427520 10262892544 949504368 10.386 100 78 157 0 1034832352 68810848 16517136
11:05:01 8227 8192000000 9961993872 10313338880 988547760 10.767 100 83 166 1 1074752592 69258656 16946176
11:06:12 4051 8192000000 9998115952 10363453440 1013770672 11.012 100 81 160 2 1117068096 87489840 15807584
11:07:18 2090 8192000000 10017663136 10382848000 1045845536 11.321 100 92 183 1 1135056416 70624160 18586720
11:08:21 1915 8192000000 10051350976 10423349248 1065184624 11.506 100 95 188 1 1167604544 83019632 19400288
11:09:23 1683 8192000000 9995429520 10377904128 1071429824 11.566 100 58 117 0 1104934544 20976448 12528272
11:10:25 5796 8192000000 10001066016 10388369408 1076982400 11.619 100 47 93 2 1124138800 37631568 9524832
11:11:32 7580 8192000000 10001902656 10404429824 1088861712 11.732 100 40 80 1 1118000608 19832880 9306016
11:12:45 9606 8192000000 10011872240 10420015104 1090214896 11.745 100 21 42 1 1129079600 34647760 4216944
11:14:03 41889 8192000000 10014075376 10434371584 1099402752 11.832 100 36 70 2 1135333184 28247744 7682688
11:15:52 3617 8192000000 10025903200 10433097728 1105882640 11.893 100 52 104 3 1149038400 31925760 11230000
11:16:58 8414 8192000000 10012250384 10420531200 1078056832 11.629 98 32 64 2 1109209744 24711712 6441200
11:18:07 4218 8192000000 9989126768 10384879616 1073324448 11.584 99 33 64 0 1096949104 15611536 8013120
11:19:13 62036 8192000000 9957875536 10361790464 1001416880 10.892 94 29 58 0 1030616528 23214384 5985264
11:21:15 6980 8192000000 9953052304 10330513408 1002559552 10.903 96 33 64 1 1042517552 32751968 7206032
11:22:24 8456 8192000000 9932848496 10321186816 983275312 10.716 97 38 76 1 1011791248 19898240 8617696
11:23:33 5596 8192000000 9964640112 10349637632 1050485408 11.365 100 54 107 2 1070961680 7830560 12645712
11:24:41 45002 8192000000 9965182784 10358231040 1043122048 11.295 99 29 56 0 1063431856 14036640 6273168
11:26:26 2997 8192000000 9980524224 10366988288 1062461136 11.480 100 54 109 1 1085514368 11407728 11645504
11:27:35 26225 8192000000 10013412304 10403647488 1081862320 11.665 100 47 93 1 1109414144 18347776 9204048
11:29:08 27025 8192000000 10051998976 10450898944 1105982192 11.894 100 48 96 2 1154161664 38043088 10136384
11:30:49 21214 8192000000 10090417936 10501664768 1127609408 12.099 100 42 82 3 1202592992 65664928 9318656
11:32:18 36558 8192000000 10139834528 10557833216 1142371680 12.238 100 34 67 1 1264195776 114053776 7770320
11:34:07 14532 8192000000 10187988880 10601209856 1148099024 12.292 100 43 86 0 1289878800 132461072 9318704
11:35:29 6506 8192000000 10249035712 10654523392 1159035008 12.394 100 72 144 0 1348693728 174398144 15260576
11:36:41 14316 8192000000 10248444304 10663481344 1167653712 12.475 100 52 106 1 1363453280 183100512 12699056
11:38:05 2623 8192000000 10297308960 10705293312 1184603392 12.633 100 76 151 0 1403387072 201555632 17228048
11:39:11 6362 8192000000 10319008736 10731900928 1175411568 12.547 100 75 150 0 1419364768 226944400 17008800
11:40:22 3207 8192000000 10329285152 10751012864 1173735472 12.532 99 78 155 0 1429307760 237638288 17934000
11:41:33 1425 8192000000 10361167392 10774327296 1196431728 12.743 100 78 155 1 1463178416 248768144 17978544
11:42:39 2274 8192000000 10408407216 10820304896 1204375168 12.817 100 79 158 0 1515551072 293276192 17899712
11:43:48 3389 8192000000 10412688736 10830880768 1203861744 12.812 100 71 140 1 1518624624 298455360 16307520
11:44:59 5397 8192000000 10457849936 10871238656 1204038752 12.814 100 70 140 0 1554759072 334462528 16257792
11:46:10 2199 8192000000 10507363344 10909876224 1188579088 12.670 98 90 179 2 1590645120 381170416 20895616
11:47:15 6402 8192000000 10520444688 10953826304 1198511200 12.763 100 50 100 0 1616672912 405435504 12726208
11:48:28 5736 8192000000 10518503824 10945273856 1171999040 12.516 100 52 103 1 1618451088 433789648 12662400
11:49:42 5264 8192000000 10553042336 11002982400 1169453856 12.492 100 48 96 0 1669276976 486853056 12970064
11:50:56 11534 8192000000 10492041856 10985283584 1174403616 12.538 100 36 72 3 1613816736 429179408 10233712
11:52:10 20447 8192000000 10427397216 10872815616 1153212224 12.340 99 26 52 0 1529606400 369151920 7242256
11:53:47 24740 8192000000 10357336784 10809012224 1142604624 12.240 100 22 44 1 1490917536 342067280 6245632
11:55:24 20937 8192000000 10093526192 10636947456 1130322160 12.124 99 22 44 0 1302958256 167604640 5031456
11:56:46 38225 8192000000 10064404304 10512039936 1126485088 12.088 99 20 39 0 1169006080 38514304 4006688
11:58:31 103484 8192000000 10118046496 10591248384 1144749664 12.260 100 22 44 0 1224694352 74779808 5164880
12:01:36 56328 8192000000 10079712176 10507698176 1108278320 11.916 97 41 79 1 1136521808 20351824 7891664
12:03:49 62060 8192000000 10071490208 10510749696 1048508160 11.346 93 28 56 1 1098627440 44447616 5671664
12:05:59 77148 8192000000 10104725184 10539429888 1103434224 11.870 97 22 44 0 1166595952 58498288 4663440
12:08:42 24180 8192000000 10140614976 10573508608 1119378384 12.021 99 44 87 1 1224452128 95723776 9349968
12:10:06 60860 8192000000 10125217504 10587226112 1122445072 12.050 99 29 58 2 1217444896 88036464 6963360
12:12:57 80644 8192000000 10187231920 10665795584 1118650880 12.014 99 13 26 0 1292202864 170103712 3448272
12:15:26 64574 8192000000 10103613792 10547326976 1126332832 12.087 99 30 61 1 1195911296 62502912 7075552
12:17:31 92859 8192000000 10084644480 10567143424 1109586976 11.929 98 7 14 1 1150767232 39683376 1496880
12:20:05 76510 8192000000 10089305072 10612981760 1131551712 12.136 99 9 18 0 1208181552 74850192 1779648
12:22:23 93633 8192000000 10136902992 10681823232 1140928224 12.224 100 11 22 1 1232278304 88747616 2602464
12:25:20 128643 8192000000 10078088800 10532388864 1136055920 12.178 99 28 56 2 1167151440 24956400 6139120
12:30:27 38969 8192000000 10091923568 10572406784 1127984640 12.102 99 6 12 0 1200680432 71537344 1158448
12:32:20 52435 8192000000 10222441200 10680143872 1144988464 12.262 100 13 26 0 1280805344 132704112 3112768
12:34:27 55554 8192000000 10128643056 10610860032 1131616160 12.137 98 15 30 1 1230923984 96161440 3146384
12:36:28 47641 8192000000 10068917968 10531344384 1071007728 11.562 94 13 25 1 1115516400 42038528 2470144
12:38:17 78668 8192000000 10100444864 10571550720 1113715696 11.968 97 18 36 1 1170548800 52712880 4120224
12:40:41 39859 8192000000 10092210448 10538856448 1121668048 12.043 99 22 44 0 1161793360 35822784 4302528
12:42:23 25051 8192000000 10082892176 10533675008 1111623776 11.948 98 22 44 0 1155680864 39354256 4702832
12:43:52 53102 8192000000 10102174736 10587226112 1124517120 12.070 98 29 58 1 1212372832 82314672 5541040
12:45:53 77432 8192000000 10091214736 10519347200 1097527120 11.814 96 28 56 1 1140737632 37828144 5382368
12:48:11 86149 8192000000 10113339952 10561822720 1105547232 11.890 97 32 64 1 1182433152 70467648 6418272
12:50:47 48703 8192000000 10070955552 10561466368 1103470640 11.871 98 8 16 1 1156384464 51412832 1500992
12:52:36 55449 8192000000 10087568336 10574946304 1087278400 11.717 97 10 20 0 1152057600 62774144 2005056
12:54:33 46614 8192000000 10068943824 10519830528 1102396912 11.860 98 22 40 4 1150166880 42984368 4785600
12:56:27 61060 8192000000 10055149392 10490007552 1087127184 11.715 97 15 30 0 1149631840 59808560 2696096
12:58:42 45969 8192000000 10120863856 10567983104 1100851328 11.846 97 24 48 0 1197219664 91292272 5076064
13:00:29 35723 8192000000 10044673488 10492530688 1081969968 11.666 97 17 34 0 1118554688 33109280 3475440
13:02:06 68240 8192000000 10107867152 10563883008 1118147184 12.009 100 11 22 1 1185461680 65082128 2232368
13:04:14 29727 8192000000 10064519456 10502373376 1093415440 11.775 97 38 75 1 1130440288 29189760 7835088
13:05:44 23972 8192000000 10127117648 10552320000 1129315072 12.115 100 60 120 0 1210147040 68984000 11847968
13:07:10 131734 8192000000 10041188128 10505633792 1109338752 11.926 98 16 32 0 1159902160 47110560 3452848
13:10:31 47049 8192000000 10069751568 10523394048 1085974144 11.704 96 10 20 0 1125510256 37414448 2121664
13:12:24 78091 8192000000 10095576192 10585989120 1109443632 11.927 98 22 44 0 1196108016 81532496 5131888
13:14:44 60523 8192000000 10056040000 10509381632 1101894960 11.856 98 12 24 1 1138315888 33725968 2694960
13:16:48 53305 8192000000 10079844800 10557030400 1135226656 12.171 100 29 58 1 1194066368 52648288 6191424
13:18:54 64109 8192000000 10107845968 10585628672 1138539168 12.202 100 29 58 0 1219458576 75115904 5803504
13:20:58 26190 8192000000 10088771840 10525687808 1123270160 12.058 99 55 111 3 1169144320 34152416 11721744
13:22:35 26783 8192000000 10109338592 10549903360 1115810288 11.987 98 34 67 1 1177385776 54902944 6672544
13:24:13 78882 8192000000 10060994672 10549465088 1121349248 12.040 99 9 18 1 1169272704 46041184 1882272

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 06-04-2021 13:29:33 to 06-04-2021 13:29:47.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 168 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 06-04-2021 13:29:48 to 06-04-2021 13:29:50.

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          219762
MON$OLDEST_ACTIVE               220752
MON$OLDEST_SNAPSHOT             220752
MON$NEXT_TRANSACTION            220752
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                       2074784
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             771
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2406 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2406 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2406 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.2406 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 ---
:40: 
:41: mscs-test-161.budzdorov.ru    Tue Apr  6 13:27:08 2021
:42:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:43: 
--- end of diff output ---

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

06.04.21 13:29:51. 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-04-06 13:29:54 No severe PSQL-related problems occured
06.04.21 13:29:55. End of report.