Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.08.2021 13: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 58e1ddde69c44dad93d327777e5becec
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-05-09 08:45
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4793.92
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16292992 kB
MemAvailable 31018008 kB
Buffers 0 kB
Cached 15438188 kB
SwapCached 2604 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.1
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-08-20 13:37:04
DTS_END                         2021-08-20 13:37:04.9820
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/57264
TRN_ID                          227353
ATT_ID                          602
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: 8705.71 18651 1044685
customer order: creation 166.03 432 19923
customer order: refuse 13.80 323 1656
order to supplier: creation 43.18 1398 5181
order to supplier: removal 3.27 117280 392
invoice (draft): creation 43.72 3689 5246
invoice (draft): removal 49.85 1063 5982
invoice accept: apply 39.33 19441 4719
invoice accept: cancel 44.75 831 5370
customer reserve: creation 3618.72 192 434246
customer reserve: removal 4404.97 67 528596
realization accept: apply 80.29 175 9635
realization accept: cancel 76.61 124 9193
payment from customer: creation 55.78 120 6694
payment from customer: removal 5.40 82 648
payment to supplier: creation 46.44 124 5573
payment to supplier: removal 3.48 58 417
service: total inventory turnovers 3.69 3570 443
service: total monetary turnovers 5.64 633 677
service: aggregate perf. data 0.75 20769 90
service: refresh index statistics 0.03 202646 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-08-2021 13:38:51 to 20-08-2021 13:38:53.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9521
TEST_TIME 2 8274
TEST_TIME 3 7184
TEST_TIME 4 7002
TEST_TIME 5 6770
TEST_TIME 6 6422
TEST_TIME 7 6781
TEST_TIME 8 6420
TEST_TIME 9 6409
TEST_TIME 10 6868
TEST_TIME 11 6659
TEST_TIME 12 6742
TEST_TIME 13 6756
TEST_TIME 14 6852
TEST_TIME 15 7514
TEST_TIME 16 7456
TEST_TIME 17 7561
TEST_TIME 18 7681
TEST_TIME 19 7784
TEST_TIME 20 8130
TEST_TIME 21 8571
TEST_TIME 22 8777
TEST_TIME 23 8793
TEST_TIME 24 8825
TEST_TIME 25 8936
TEST_TIME 26 9785
TEST_TIME 27 11118
TEST_TIME 28 10925
TEST_TIME 29 10568
TEST_TIME 30 10333
TEST_TIME 31 10107
TEST_TIME 32 10175
TEST_TIME 33 10109
TEST_TIME 34 10029
TEST_TIME 35 9924
TEST_TIME 36 9768
TEST_TIME 37 9646
TEST_TIME 38 9549
TEST_TIME 39 9458
TEST_TIME 40 9472
TEST_TIME 41 9419
TEST_TIME 42 9329
TEST_TIME 43 9132
TEST_TIME 44 9114
TEST_TIME 45 9098
TEST_TIME 46 9112
TEST_TIME 47 9055
TEST_TIME 48 9140
TEST_TIME 49 9116
TEST_TIME 50 9094
TEST_TIME 51 9016
TEST_TIME 52 9085
TEST_TIME 53 9136
TEST_TIME 54 9212
TEST_TIME 55 9225
TEST_TIME 56 9272
TEST_TIME 57 9365
TEST_TIME 58 9296
TEST_TIME 59 9150
TEST_TIME 60 9109
TEST_TIME 61 9058
TEST_TIME 62 9106
TEST_TIME 63 9008
TEST_TIME 64 8957
TEST_TIME 65 8973
TEST_TIME 66 8946
TEST_TIME 67 8975
TEST_TIME 68 9058
TEST_TIME 69 9015
TEST_TIME 70 8983
TEST_TIME 71 9084
TEST_TIME 72 9097
TEST_TIME 73 9101
TEST_TIME 74 9156
TEST_TIME 75 9149
TEST_TIME 76 9074
TEST_TIME 77 9031
TEST_TIME 78 8979
TEST_TIME 79 8956
TEST_TIME 80 8880
TEST_TIME 81 8838
TEST_TIME 82 8823
TEST_TIME 83 8854
TEST_TIME 84 8809
TEST_TIME 85 8817
TEST_TIME 86 8844
TEST_TIME 87 8823
TEST_TIME 88 8869
TEST_TIME 89 8877
TEST_TIME 90 8889
TEST_TIME 91 8864
TEST_TIME 92 8901
TEST_TIME 93 8915
TEST_TIME 94 8981
TEST_TIME 95 8984
TEST_TIME 96 8956
TEST_TIME 97 8924
TEST_TIME 98 8857
TEST_TIME 99 8840
TEST_TIME 100 8790
TEST_TIME 101 8786
TEST_TIME 102 8777
TEST_TIME 103 8766
TEST_TIME 104 8818
TEST_TIME 105 8794
TEST_TIME 106 8806
TEST_TIME 107 8793
TEST_TIME 108 8814
TEST_TIME 109 8853
TEST_TIME 110 8843
TEST_TIME 111 8882
TEST_TIME 112 8878
TEST_TIME 113 8950
TEST_TIME 114 8976
TEST_TIME 115 8910
TEST_TIME 116 8874
TEST_TIME 117 8827
TEST_TIME 118 8772
TEST_TIME 119 8783
TEST_TIME 120 8712

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 20-08-2021 13:38:54 to 20-08-2021 13:38:58.

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 1448410 1448410 0 0.00 0 19709 7 0 0
doc_list_biud 1448409 1448409 0 0.00 0 33335 31 0 0
sp_add_invoice_to_stock 4838 4719 119 2.45 25 198198 19442 0 119
sp_cancel_adding_invoice 5370 5370 0 0.00 5 62426 832 0 0
sp_cancel_client_order 1656 1656 0 0.00 4 8466 324 0 0
sp_cancel_customer_reserve 528596 528596 0 0.00 1 33348 67 0 0
sp_cancel_pay_from_customer 648 648 0 0.00 3 4799 82 0 0
sp_cancel_pay_to_supplier 417 417 0 0.00 3 1897 59 0 0
sp_cancel_supplier_invoice 5990 5982 8 0.13 2 23283 1064 0 8
sp_cancel_supplier_order 392 392 0 0.00 11 1151657 117280 0 0
sp_cancel_write_off 9193 9193 0 0.00 1 23422 125 0 0
sp_client_order 19923 19923 0 0.00 4 23494 432 0 0
sp_customer_reserve 434246 434246 0 0.00 0 77206 192 0 0
sp_fill_shopping_cart 30333 30333 0 0.00 1 28529 53 0 0
sp_fill_shopping_cart_clo_res 434224 434224 0 0.00 0 18358 18 0 0
sp_get_clo_for_invoice 4694 4694 0 0.00 0 52078 430 0 0
sp_kill_cost_storno 15628 15628 0 0.00 0 19708 59 0 0
sp_kill_qstorno_ret_qs2qd 1072759 1072759 0 0.00 0 33331 37 0 0
sp_kill_qty_storno 565518 565518 0 0.00 0 33334 79 0 0
sp_lock_selected_doc 19735 19735 0 0.00 0 8587 4 0 0
sp_make_cost_storno 26596 26596 0 0.00 0 15862 52 0 0
sp_multiply_rows_for_pdistr 26596 26596 0 0.00 0 10349 33 0 0
sp_multiply_rows_for_qdistr 19923 19923 0 0.00 0 21810 231 0 0
sp_pay_from_customer 6694 6694 0 0.00 4 12449 121 0 0
sp_pay_to_supplier 5573 5573 0 0.00 5 16617 124 0 0
sp_payment_common 12267 12267 0 0.00 1 12370 25 0 0
sp_qd_handle_on_cancel_clo 1656 1656 0 0.00 0 8459 297 0 0
sp_qd_handle_on_invoice_upd_sts 10064 10064 0 0.00 0 28858 362 0 0
sp_qd_handle_on_reserve_upd_sts 9635 9635 0 0.00 0 22158 54 0 0
sp_reserve_write_off 9635 9635 0 0.00 6 22240 176 0 0
sp_supplier_invoice 5246 5246 0 0.00 7 80555 3689 0 0
sp_supplier_order 5181 5181 0 0.00 6 45249 1399 0 0
srv_aggregate_perf_data 90 90 0 0.00 2682 471721 20769 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1343 116866 46191 0 0
srv_find_qd_qs_mism 1000308 1000308 0 0.00 0 15513 6 0 0
srv_make_invnt_saldo 443 443 0 0.00 67 90521 3571 0 0
srv_make_money_saldo 677 677 0 0.00 1 14090 633 0 0
srv_recalc_idx_stat 4 4 0 0.00 140765 288895 202647 0 0
t$perf-abend:sp_add_invoice_to_stock 120 120 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4719 4719 0 0.00 0 18058 167 0 0
t$perf-norm:sp_cancel_adding_invoice 374 374 0 0.00 0 15992 132 0 0
t$perf-norm:sp_cancel_client_order 1656 1656 0 0.00 0 8717 22 0 0
t$perf-norm:sp_cancel_customer_reserve 652 652 0 0.00 0 2881 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 648 648 0 0.00 0 884 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 417 417 0 0.00 0 408 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 352 352 0 0.00 0 1564 11 0 0
t$perf-norm:sp_cancel_supplier_order 392 392 0 0.00 0 19850 1661 0 0
t$perf-norm:sp_cancel_write_off 916 916 0 0.00 0 4548 17 0 0
t$perf-norm:sp_client_order 19923 19923 0 0.00 0 8259 20 0 0
t$perf-norm:sp_customer_reserve 971 971 0 0.00 0 1240 6 0 0
t$perf-norm:sp_pay_from_customer 6694 6694 0 0.00 0 5795 11 0 0
t$perf-norm:sp_pay_to_supplier 5573 5573 0 0.00 0 7965 8 0 0
t$perf-norm:sp_reserve_write_off 9635 9635 0 0.00 0 12314 12 0 0
t$perf-norm:sp_supplier_invoice 5246 5246 0 0.00 0 9903 21 0 0
t$perf-norm:sp_supplier_order 5181 5181 0 0.00 0 4548 17 0 0
t$perf-norm:srv_aggregate_perf_data 90 90 0 0.00 0 3967 50 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 473 8 0 0
t$perf-norm:srv_make_invnt_saldo 443 443 0 0.00 0 859 8 0 0
t$perf-norm:srv_make_money_saldo 677 677 0 0.00 0 1254 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 6 4 0 0
v_add_invoice_to_stock 9624 9386 238 2.47 0 5134 16 0 238
v_all_customers 35722 35722 0 0.00 0 17 1 0 0
v_all_suppliers 28916 28916 0 0.00 0 72 1 0 0
v_all_wares 39846 39846 0 0.00 0 69 1 0 0
v_cancel_adding_invoice 748 748 0 0.00 0 180 6 0 0
v_cancel_client_order 3312 3312 0 0.00 0 6569 6 0 0
v_cancel_customer_prepayment 1296 1296 0 0.00 0 1416 6 0 0
v_cancel_customer_reserve 1304 1304 0 0.00 0 2762 7 0 0
v_cancel_payment_to_supplier 834 834 0 0.00 0 1123 4 0 0
v_cancel_supplier_invoice 720 704 16 2.22 0 1480 20 0 16
v_cancel_supplier_order 784 784 0 0.00 0 227 3 0 0
v_cancel_write_off 1832 1832 0 0.00 0 5627 18 0 0
v_max_id_clo_ord 5175 5175 0 0.00 0 167 1 0 0
v_max_id_clo_res 971 971 0 0.00 0 3553 19 0 0
v_max_id_ord_sup 5235 5235 0 0.00 0 8815 7 0 0
v_max_non_paid_invoice 1525 1525 0 0.00 0 330 2 0 0
v_max_non_paid_realizn 3776 3776 0 0.00 0 5356 6 0 0
v_min_id_clo_ord 5175 5175 0 0.00 0 3818 6 0 0
v_min_id_clo_res 971 971 0 0.00 0 1638 6 0 0
v_min_id_ord_sup 5235 5235 0 0.00 0 14023 12 0 0
v_min_non_paid_invoice 5573 5573 0 0.00 0 6705 12 0 0
v_min_non_paid_realizn 6694 6694 0 0.00 0 6974 18 0 0
v_our_firm 9960 9960 0 0.00 0 16 1 0 0
v_reserve_write_off 19270 19270 0 0.00 0 5468 5 0 0
x_lock_depdocs_on_canc_invoice 11000 11000 0 0.00 0 19919 123 0 0
x_lock_depdocs_on_canc_sup_ord 392 392 0 0.00 0 9982 375 0 0
x_make_qstorno_1000_1200 5181 5181 0 0.00 2 45242 1322 0 0
x_make_qstorno_1200_2000 5246 5246 0 0.00 4 80542 3467 0 0
x_make_qstorno_2100_3300 434246 434246 0 0.00 0 77202 171 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-08-2021 13:38:59 to 20-08-2021 13:39:03.

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
11:07:08 2 8192000000 8796668944 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:09 25784 8192000000 9739859952 10038407168 813240640 9.030 100 84 168 0 840213536 7355168 19617728
11:09:41 17462 8192000000 9776421488 10086674432 851371488 9.414 100 88 177 0 883696144 12259936 20064720
11:11:05 14429 8192000000 9810557200 10127290368 878425552 9.684 100 85 170 0 918328640 21037808 18865280
11:12:29 12816 8192000000 9873698992 10205220864 919923120 10.095 100 81 162 1 987383984 50576352 16884512
11:13:45 8983 8192000000 9921157248 10264424448 949582864 10.387 100 86 173 4 1035824720 68005968 18235888
11:14:59 5428 8192000000 9960985680 10312593408 992205120 10.803 100 82 164 1 1072186576 63440064 16541392
11:16:07 2625 8192000000 9984604880 10343374848 1016246112 11.036 100 93 186 1 1101177792 66038064 18893616
11:17:11 2587 8192000000 10010134048 10376544256 1042907744 11.293 100 86 173 0 1119724848 59779264 17037840
11:18:15 1316 8192000000 10050117536 10421723136 1071402736 11.565 100 94 188 0 1160451872 70207936 18841200
11:19:16 21019 8192000000 10001289408 10389565440 1068750672 11.540 100 34 67 0 1134045920 58690720 6604528
11:20:38 7860 8192000000 10006745200 10404757504 1078730176 11.635 100 44 87 0 1113029888 24486880 9812832
11:21:49 10323 8192000000 10012997424 10421407744 1084907664 11.694 100 21 40 1 1114350544 25889168 3553712
11:22:59 10183 8192000000 10018422992 10429276160 1094448480 11.785 100 25 51 2 1126010416 26423712 5138224
11:24:12 38752 8192000000 10017959104 10411229184 1101401680 11.851 100 65 134 1 1127241168 11750464 14089024
11:25:52 6921 8192000000 9998408592 10394165248 1084304384 11.688 98 25 50 1 1115910496 25123984 6482128
11:27:00 8023 8192000000 10010907712 10411352064 1066599008 11.520 98 48 94 1 1096428496 19307728 10521760
11:28:09 10155 8192000000 9963195296 10359316480 1029029472 11.159 97 22 44 1 1052149264 18241776 4878016
11:29:20 58588 8192000000 9925911632 10314182656 975981888 10.645 92 27 52 2 993809056 12199696 5627472
11:31:23 5107 8192000000 9950532640 10337538048 1018646880 11.059 99 48 97 0 1072831504 44330736 9853888
11:32:29 6069 8192000000 9949939408 10335236096 1037868800 11.244 100 52 103 2 1062680000 12887984 11923216
11:33:39 11435 8192000000 9963721216 10376949760 1046019440 11.322 99 29 59 2 1077034256 24287408 6727408
11:34:51 52496 8192000000 9971591056 10384683008 1065181088 11.506 100 32 64 0 1087534832 15021360 7332384
11:36:44 6844 8192000000 9978416912 10388381696 1072904992 11.580 100 23 46 2 1099907808 21456128 5546688
11:37:59 5828 8192000000 10066937168 10463707136 1103719680 11.873 100 65 130 2 1173942896 57660192 12563024
11:39:19 10270 8192000000 10077357840 10480214016 1117604144 12.004 100 50 99 2 1177421616 49837008 9980464
11:40:34 54119 8192000000 10092315216 10522345472 1132193568 12.142 100 36 72 1 1202194096 61079984 8920544
11:42:33 8585 8192000000 10128729296 10559496192 1144274384 12.256 100 42 84 2 1254255328 100818000 9162944
11:43:45 12665 8192000000 10172882288 10591608832 1149045856 12.301 100 58 108 1 1271396256 109730976 12619424
11:45:01 22070 8192000000 10167144528 10585210880 1147418864 12.285 99 50 100 2 1277558848 119059056 11080928
11:46:26 7310 8192000000 10226704464 10640580608 1164815440 12.448 100 60 118 1 1327236336 149425424 12995472
11:47:38 6187 8192000000 10239890528 10655715328 1174854848 12.542 100 59 118 1 1344189888 156609616 12725424
11:48:47 4429 8192000000 10308307696 10725187584 1186377280 12.650 100 64 128 0 1417816048 217771568 13667200
11:49:55 13354 8192000000 10316890896 10735452160 1163365264 12.435 99 69 138 1 1415310752 235975104 15970384
11:51:14 8164 8192000000 10328970816 10741493760 1168007984 12.478 100 61 122 1 1426639648 244835152 13796512
11:52:26 1508 8192000000 10346239616 10751447040 1172670384 12.522 100 89 180 0 1443659200 250476448 20512368
11:53:31 1343 8192000000 10370056784 10772250624 1161910368 12.421 99 87 169 0 1469529536 288463792 19155376
11:54:38 2750 8192000000 10395769648 10798161920 1160094208 12.404 100 69 138 0 1495231136 318205600 16931328
11:55:47 3138 8192000000 10431578064 10840178688 1154684816 12.353 99 76 150 1 1526500416 354054864 17760736
11:56:54 3143 8192000000 10506468400 10914488320 1170877824 12.505 99 76 152 1 1605572256 416290496 18403936
11:58:03 5834 8192000000 10415054000 10881798144 1172864608 12.524 100 57 113 3 1520763088 333410320 14488160
11:59:12 6811 8192000000 10421154592 10868748288 1168300960 12.481 100 34 68 0 1537099488 359464528 9334000
12:00:30 27346 8192000000 10436701520 10906988544 1158745408 12.392 100 32 64 0 1543046992 375355504 8946080
12:02:04 19753 8192000000 10449548192 10904805376 1126520208 12.089 100 28 56 0 1542335008 407496960 8317840
12:03:26 23917 8192000000 10321116240 10791055360 1101153776 11.849 98 28 56 0 1418104592 309572192 7378624
12:04:56 30621 8192000000 10050804512 10473992192 1097176208 11.811 99 41 80 2 1138598272 33541520 7880544
12:06:27 51563 8192000000 10065010768 10493628416 1109063280 11.924 99 48 98 0 1160169856 41063648 10042928
12:08:51 36814 8192000000 10081219904 10519121920 1099079344 11.829 97 29 58 0 1181071168 76183248 5808576
12:10:29 36919 8192000000 10073882224 10537648128 1115373152 11.983 99 16 32 2 1178303648 59651408 3279088
12:12:06 46574 8192000000 10077424080 10548301824 1140832672 12.223 100 16 32 1 1193712256 49267424 3612160
12:13:53 45038 8192000000 10126372608 10585485312 1139401936 12.210 99 17 35 0 1211594592 68587040 3605616
12:15:46 116866 8192000000 10097060672 10579587072 1153331408 12.341 100 27 55 1 1249879296 90626992 5920896
12:18:52 55380 8192000000 10077106816 10554802176 1146236560 12.274 99 16 32 1 1191102848 41285808 3580480
12:21:08 72885 8192000000 10117469984 10633367552 1150394784 12.313 99 10 20 1 1223262016 70844080 2023152
12:23:36 55160 8192000000 10098425488 10553184256 1151835328 12.327 100 15 31 1 1191025440 35951456 3238656
12:25:31 91666 8192000000 10076668752 10516451328 1080495888 11.652 94 16 31 2 1111960448 28037728 3426832
12:28:16 78618 8192000000 10160857552 10623926272 1153835408 12.345 100 28 55 2 1252991456 93548320 5607728
12:30:45 90278 8192000000 10084262800 10595631104 1117800000 12.006 97 14 28 0 1177844208 57235632 2808576
12:33:24 110196 8192000000 10096320528 10589151232 1140687632 12.222 99 16 32 0 1215129392 70939248 3502512
12:36:15 97096 8192000000 10090379296 10534387712 1132528048 12.145 98 40 78 2 1180437232 40037536 7871648
12:40:15 26658 8192000000 10078450912 10573582336 1149158672 12.302 100 6 12 0 1186885424 36604752 1122000
12:41:43 77517 8192000000 10101951520 10591440896 1124189648 12.067 97 12 24 0 1165404464 38687808 2527008
12:44:01 74288 8192000000 10119775776 10575396864 1108237104 11.916 96 15 30 0 1190366592 78833184 3296304
12:46:18 34531 8192000000 10080796016 10531770368 1091822560 11.760 96 23 44 2 1142652976 45923888 4906528
12:47:55 91589 8192000000 10019248544 10463559680 1006464720 10.941 90 13 26 2 1073678384 64420224 2793440
12:50:32 62917 8192000000 10066498640 10560458752 1090557488 11.748 98 21 42 0 1162836560 67906016 4373056
12:52:43 23899 8192000000 10070513984 10521907200 1097154336 11.811 99 49 98 0 1164725472 57161408 10409728
12:54:15 69198 8192000000 10053610992 10500321280 1116106000 11.990 100 29 58 1 1159027024 37137280 5783744
12:56:25 28011 8192000000 10029256192 10499334144 1090083664 11.743 98 4 8 0 1114741360 24011152 646544
12:57:59 65034 8192000000 10051321600 10504937472 1106026960 11.895 99 11 22 1 1137758512 29710288 2021264
13:00:34 68028 8192000000 10036295872 10467229696 1043086256 11.294 94 23 46 0 1087250960 39169712 4994992
13:02:45 42407 8192000000 10042329200 10492084224 1114007408 11.970 100 20 40 1 1170304448 51947904 4349136
13:04:30 42002 8192000000 10098552976 10533060608 1085537952 11.700 97 33 66 1 1142729872 50820944 6370976
13:06:25 40562 8192000000 10044206944 10485276672 1084575648 11.691 97 17 34 0 1126788384 38556800 3655936
13:08:07 41355 8192000000 10094431504 10532601856 1112570656 11.957 99 43 86 0 1166872608 45643136 8658816
13:09:56 72417 8192000000 10108059152 10573258752 1124200224 12.067 99 15 30 0 1210586080 83165424 3220432
13:12:34 104388 8192000000 10043263504 10515292160 1122733504 12.053 100 12 24 0 1189913728 64487680 2692544
13:15:19 48680 8192000000 10053718608 10513125376 1114075824 11.971 99 8 16 0 1155719792 39873120 1770848
13:17:17 66990 8192000000 10055397152 10505928704 1107074304 11.905 98 23 46 1 1139201808 26825280 5302224
13:19:29 73595 8192000000 10100478704 10555473920 1136155232 12.179 100 13 26 1 1183673008 44656880 2860896
13:21:45 72817 8192000000 10078436304 10522030080 1131215936 12.133 99 24 48 2 1183384016 47050896 5117184
13:24:02 75340 8192000000 10148802400 10598879232 1138403376 12.201 99 33 66 0 1219134256 73693984 7036896
13:26:18 70287 8192000000 10157920464 10635206656 1147017488 12.281 99 22 44 2 1237941968 86224928 4699552
13:28:30 43288 8192000000 10088397648 10538520576 1140554560 12.221 99 38 75 3 1191808544 43035136 8218848
13:30:18 62903 8192000000 10109069568 10699685888 1141952144 12.234 100 10 20 0 1259240752 114766800 2521808
13:32:25 91819 8192000000 10049393216 10506862592 1094864080 11.789 96 17 34 0 1134490464 36491472 3134912
13:34:57 75244 8192000000 10097169120 10556305408 1110898368 11.941 98 8 16 0 1179729152 67283888 1546896

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 20-08-2021 13:39:05 to 20-08-2021 13:39:20.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 20-08-2021 13:39:21 to 20-08-2021 13:39:23.

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          225689
MON$OLDEST_ACTIVE               227537
MON$OLDEST_SNAPSHOT             227537
MON$NEXT_TRANSACTION            227537
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                       2037216
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             778
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2571 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2571 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2571 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2571 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
--- end of diff output ---

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

20.08.21 13:39:24. 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-08-20 13:39:26 No severe PSQL-related problems occured
20.08.21 13:39:27. End of report.