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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 16909752 kB
MemAvailable 31437676 kB
Buffers 20 kB
Cached 14664584 kB
SwapCached 0 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-03-26 04:26:58
DTS_END                         2021-03-26 04:26:58.5430
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/52020
TRN_ID                          226957
ATT_ID                          619
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: 8634.45 23836 1036134
customer order: creation 159.14 418 19097
customer order: refuse 13.74 338 1649
order to supplier: creation 43.04 1405 5165
order to supplier: removal 3.37 114293 404
invoice (draft): creation 43.68 3710 5241
invoice (draft): removal 49.58 952 5949
invoice accept: apply 38.65 17046 4638
invoice accept: cancel 44.34 776 5321
customer reserve: creation 3580.23 165 429627
customer reserve: removal 4381.02 69 525722
realization accept: apply 78.49 198 9419
realization accept: cancel 79.36 119 9523
payment from customer: creation 55.16 122 6619
payment from customer: removal 5.67 101 680
payment to supplier: creation 45.71 143 5485
payment to supplier: removal 3.07 60 368
service: total inventory turnovers 3.73 3167 448
service: total monetary turnovers 5.59 559 671
service: aggregate perf. data 0.86 16445 103
service: refresh index statistics 0.04 316631 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 26-03-2021 04:27:40 to 26-03-2021 04:27:42.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2035
TEST_TIME 2 4283
TEST_TIME 3 4799
TEST_TIME 4 4524
TEST_TIME 5 4156
TEST_TIME 6 4315
TEST_TIME 7 4800
TEST_TIME 8 4991
TEST_TIME 9 5088
TEST_TIME 10 5339
TEST_TIME 11 5360
TEST_TIME 12 5839
TEST_TIME 13 5933
TEST_TIME 14 6148
TEST_TIME 15 6376
TEST_TIME 16 6699
TEST_TIME 17 6881
TEST_TIME 18 7045
TEST_TIME 19 7687
TEST_TIME 20 8327
TEST_TIME 21 8504
TEST_TIME 22 8721
TEST_TIME 23 8795
TEST_TIME 24 9225
TEST_TIME 25 9020
TEST_TIME 26 9353
TEST_TIME 27 9984
TEST_TIME 28 11006
TEST_TIME 29 10810
TEST_TIME 30 10478
TEST_TIME 31 10547
TEST_TIME 32 10467
TEST_TIME 33 10239
TEST_TIME 34 10025
TEST_TIME 35 9861
TEST_TIME 36 9815
TEST_TIME 37 9693
TEST_TIME 38 9904
TEST_TIME 39 9758
TEST_TIME 40 9629
TEST_TIME 41 9563
TEST_TIME 42 9477
TEST_TIME 43 9387
TEST_TIME 44 9279
TEST_TIME 45 9192
TEST_TIME 46 9017
TEST_TIME 47 9024
TEST_TIME 48 8906
TEST_TIME 49 8930
TEST_TIME 50 8909
TEST_TIME 51 8810
TEST_TIME 52 8704
TEST_TIME 53 8696
TEST_TIME 54 8616
TEST_TIME 55 8663
TEST_TIME 56 8710
TEST_TIME 57 8691
TEST_TIME 58 8785
TEST_TIME 59 8780
TEST_TIME 60 8875
TEST_TIME 61 8854
TEST_TIME 62 8900
TEST_TIME 63 8947
TEST_TIME 64 9014
TEST_TIME 65 8973
TEST_TIME 66 9050
TEST_TIME 67 9038
TEST_TIME 68 8911
TEST_TIME 69 8889
TEST_TIME 70 8883
TEST_TIME 71 8907
TEST_TIME 72 8847
TEST_TIME 73 8852
TEST_TIME 74 8834
TEST_TIME 75 8852
TEST_TIME 76 8841
TEST_TIME 77 8790
TEST_TIME 78 8847
TEST_TIME 79 8919
TEST_TIME 80 8867
TEST_TIME 81 8945
TEST_TIME 82 8941
TEST_TIME 83 8981
TEST_TIME 84 8953
TEST_TIME 85 8849
TEST_TIME 86 8768
TEST_TIME 87 8745
TEST_TIME 88 8713
TEST_TIME 89 8700
TEST_TIME 90 8675
TEST_TIME 91 8620
TEST_TIME 92 8611
TEST_TIME 93 8593
TEST_TIME 94 8583
TEST_TIME 95 8684
TEST_TIME 96 8710
TEST_TIME 97 8732
TEST_TIME 98 8710
TEST_TIME 99 8784
TEST_TIME 100 8781
TEST_TIME 101 8836
TEST_TIME 102 8888
TEST_TIME 103 8850
TEST_TIME 104 8859
TEST_TIME 105 8851
TEST_TIME 106 8778
TEST_TIME 107 8729
TEST_TIME 108 8706
TEST_TIME 109 8655
TEST_TIME 110 8599
TEST_TIME 111 8571
TEST_TIME 112 8578
TEST_TIME 113 8566
TEST_TIME 114 8536
TEST_TIME 115 8563
TEST_TIME 116 8561
TEST_TIME 117 8584
TEST_TIME 118 8582
TEST_TIME 119 8639
TEST_TIME 120 8636

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 26-03-2021 04:27:44 to 26-03-2021 04:27:47.

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 1434037 1434037 0 0.00 0 17757 6 0 0
doc_list_biud 1434037 1434037 0 0.00 0 51578 32 0 0
sp_add_invoice_to_stock 4762 4638 124 2.60 45 175212 17046 0 124
sp_cancel_adding_invoice 5321 5321 0 0.00 5 46657 776 0 0
sp_cancel_client_order 1649 1649 0 0.00 4 11098 338 0 0
sp_cancel_customer_reserve 525722 525722 0 0.00 1 23124 69 0 0
sp_cancel_pay_from_customer 680 680 0 0.00 3 7028 101 0 0
sp_cancel_pay_to_supplier 368 368 0 0.00 3 1855 61 0 0
sp_cancel_supplier_invoice 5960 5949 11 0.18 2 51581 953 0 11
sp_cancel_supplier_order 404 404 0 0.00 17 1137378 114294 0 0
sp_cancel_write_off 9523 9523 0 0.00 1 13242 119 0 0
sp_client_order 19097 19097 0 0.00 4 38050 418 0 0
sp_customer_reserve 429627 429627 0 0.00 0 48034 165 0 0
sp_fill_shopping_cart 29490 29490 0 0.00 1 29922 52 0 0
sp_fill_shopping_cart_clo_res 429625 429625 0 0.00 0 20750 19 0 0
sp_get_clo_for_invoice 4636 4636 0 0.00 0 41264 501 0 0
sp_kill_cost_storno 15892 15892 0 0.00 0 6709 57 0 0
sp_kill_qstorno_ret_qs2qd 1067320 1067320 0 0.00 0 51577 37 0 0
sp_kill_qty_storno 562623 562623 0 0.00 0 51578 80 0 0
sp_lock_selected_doc 19508 19508 0 0.00 0 8719 5 0 0
sp_make_cost_storno 26159 26159 0 0.00 0 16562 63 0 0
sp_multiply_rows_for_pdistr 26159 26159 0 0.00 0 11335 32 0 0
sp_multiply_rows_for_qdistr 19097 19097 0 0.00 0 38036 237 0 0
sp_pay_from_customer 6619 6619 0 0.00 4 17318 123 0 0
sp_pay_to_supplier 5485 5485 0 0.00 5 17931 144 0 0
sp_payment_common 12104 12104 0 0.00 1 14194 31 0 0
sp_qd_handle_on_cancel_clo 1649 1649 0 0.00 0 11090 304 0 0
sp_qd_handle_on_invoice_upd_sts 9957 9957 0 0.00 0 12419 342 0 0
sp_qd_handle_on_reserve_upd_sts 9419 9419 0 0.00 0 11364 62 0 0
sp_reserve_write_off 9419 9419 0 0.00 7 15832 199 0 0
sp_supplier_invoice 5241 5241 0 0.00 7 95370 3710 0 0
sp_supplier_order 5165 5165 0 0.00 5 48263 1405 0 0
srv_aggregate_perf_data 103 103 0 0.00 2690 199278 16446 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1845 139857 46942 0 0
srv_find_qd_qs_mism 991760 991760 0 0.00 0 14523 5 0 0
srv_make_invnt_saldo 448 448 0 0.00 54 112319 3167 0 0
srv_make_money_saldo 671 671 0 0.00 1 17397 559 0 0
srv_recalc_idx_stat 5 5 0 0.00 116771 586365 316631 0 0
t$perf-abend:sp_add_invoice_to_stock 124 124 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4638 4638 0 0.00 0 16326 168 0 0
t$perf-norm:sp_cancel_adding_invoice 375 375 0 0.00 1 5882 77 0 0
t$perf-norm:sp_cancel_client_order 1649 1649 0 0.00 0 8430 19 0 0
t$perf-norm:sp_cancel_customer_reserve 668 668 0 0.00 0 1306 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 680 680 0 0.00 0 1794 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 368 368 0 0.00 0 1085 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 371 371 0 0.00 0 1420 15 0 0
t$perf-norm:sp_cancel_supplier_order 404 404 0 0.00 0 17094 1413 0 0
t$perf-norm:sp_cancel_write_off 938 938 0 0.00 0 511 3 0 0
t$perf-norm:sp_client_order 19097 19097 0 0.00 0 9239 19 0 0
t$perf-norm:sp_customer_reserve 914 914 0 0.00 0 1070 4 0 0
t$perf-norm:sp_pay_from_customer 6619 6619 0 0.00 0 9125 12 0 0
t$perf-norm:sp_pay_to_supplier 5485 5485 0 0.00 0 5037 6 0 0
t$perf-norm:sp_reserve_write_off 9419 9419 0 0.00 0 9095 10 0 0
t$perf-norm:sp_supplier_invoice 5241 5241 0 0.00 0 5938 19 0 0
t$perf-norm:sp_supplier_order 5165 5165 0 0.00 0 25208 23 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 230 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 1911 34 0 0
t$perf-norm:srv_make_invnt_saldo 448 448 0 0.00 0 335 6 0 0
t$perf-norm:srv_make_money_saldo 671 671 0 0.00 0 9287 19 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 11 4 0 0
v_add_invoice_to_stock 9520 9272 248 2.60 0 11473 17 0 248
v_all_customers 34706 34706 0 0.00 0 23 1 0 0
v_all_suppliers 28758 28758 0 0.00 0 13 1 0 0
v_all_wares 38194 38194 0 0.00 0 15 1 0 0
v_cancel_adding_invoice 750 750 0 0.00 0 1462 9 0 0
v_cancel_client_order 3298 3298 0 0.00 0 4476 9 0 0
v_cancel_customer_prepayment 1360 1360 0 0.00 0 6932 13 0 0
v_cancel_customer_reserve 1336 1336 0 0.00 0 1491 5 0 0
v_cancel_payment_to_supplier 736 736 0 0.00 0 1058 5 0 0
v_cancel_supplier_invoice 764 742 22 2.87 0 7940 30 0 22
v_cancel_supplier_order 808 808 0 0.00 0 317 3 0 0
v_cancel_write_off 1876 1876 0 0.00 0 6852 21 0 0
v_max_id_clo_ord 5163 5163 0 0.00 0 4642 2 0 0
v_max_id_clo_res 914 914 0 0.00 0 2261 13 0 0
v_max_id_ord_sup 5229 5229 0 0.00 0 5755 6 0 0
v_max_non_paid_invoice 1498 1498 0 0.00 0 371 2 0 0
v_max_non_paid_realizn 3486 3486 0 0.00 0 2842 5 0 0
v_min_id_clo_ord 5163 5163 0 0.00 0 5501 13 0 0
v_min_id_clo_res 914 914 0 0.00 0 3789 10 0 0
v_min_id_ord_sup 5229 5229 0 0.00 0 5180 9 0 0
v_min_non_paid_invoice 5485 5485 0 0.00 0 10929 19 0 0
v_min_non_paid_realizn 6619 6619 0 0.00 0 14190 26 0 0
v_our_firm 9754 9754 0 0.00 0 31 1 0 0
v_reserve_write_off 18838 18838 0 0.00 0 4566 6 0 0
x_lock_depdocs_on_canc_invoice 10899 10899 0 0.00 0 18261 120 0 0
x_lock_depdocs_on_canc_sup_ord 404 404 0 0.00 0 18416 392 0 0
x_make_qstorno_1000_1200 5165 5165 0 0.00 2 48228 1322 0 0
x_make_qstorno_1200_2000 5241 5241 0 0.00 3 88497 3509 0 0
x_make_qstorno_2100_3300 429627 429627 0 0.00 0 48031 144 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-03-2021 04:27:48 to 26-03-2021 04:27:52.

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
01:57:02 1 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
01:58:04 27396 8192000000 9720114624 10015215616 798401888 8.880 100 89 178 0 828697472 8413792 21881792
01:59:38 22920 8192000000 9778647600 10085179392 851650208 9.417 100 92 184 0 886298512 13451888 21196416
02:01:07 20069 8192000000 9818105232 10136825856 879283248 9.693 100 79 158 0 919801072 23063648 17454176
02:02:30 15576 8192000000 9894765808 10232528896 926651552 10.162 100 77 154 2 1000102832 57017088 16434192
02:03:50 7369 8192000000 9930655120 10279596032 975474432 10.640 100 71 140 1 1042941088 53144976 14321680
02:05:00 5295 8192000000 9966468944 10321252352 998288672 10.862 100 79 156 1 1082286192 67772528 16224992
02:06:09 2624 8192000000 9991612672 10351673344 1020333184 11.075 100 83 166 3 1103993264 65832368 17827712
02:07:13 1672 8192000000 10025786848 10393886720 1044909152 11.312 100 88 176 1 1139333808 77030048 17394608
02:08:18 24500 8192000000 9992478704 10374758400 1056628240 11.424 100 63 127 0 1130688416 59842304 14217872
02:09:43 3835 8192000000 10012126032 10396393472 1065498784 11.509 100 60 120 1 1137822848 60648816 11675248
02:10:49 7915 8192000000 9986111856 10375409664 1071419072 11.566 100 46 90 3 1101077984 20365984 9292928
02:11:58 2961 8192000000 10022307808 10412273664 1077661280 11.625 100 56 109 2 1137153936 47705936 11786720
02:13:02 3117 8192000000 9994755552 10384793600 1084682864 11.692 100 54 108 0 1108081808 11654608 11744336
02:14:08 45034 8192000000 10012076944 10402422784 1090321968 11.746 100 52 105 1 1128159568 26899072 10938528
02:15:54 5685 8192000000 10007704368 10414317568 1094014528 11.781 100 46 92 0 1125238272 20586448 10637296
02:17:00 5641 8192000000 9997892576 10387841024 1059006064 11.447 97 49 100 3 1090392960 19475536 11911360
02:18:09 11319 8192000000 9963462672 10363756544 1027869920 11.148 96 21 42 2 1048572832 16339840 4363072
02:19:23 28082 8192000000 9949418784 10349555712 1000526432 10.884 95 43 86 1 1032028400 22446480 9055488
02:20:56 5318 8192000000 9929454288 10308976640 1010120304 10.977 100 52 104 2 1031562096 9922608 11519184
02:22:08 6000 8192000000 9943451392 10326601728 1004560192 10.923 98 44 86 3 1040089536 26274816 9254528
02:23:19 11612 8192000000 9941777968 10337345536 1036100336 11.227 100 29 58 0 1067931232 25129536 6701360
02:24:31 32381 8192000000 9973409456 10375929856 1046707280 11.329 100 50 100 1 1078129904 20180080 11242544
02:26:04 3296 8192000000 9975494096 10372956160 1060385312 11.460 100 50 98 3 1088604784 14737072 13482400
02:27:11 33981 8192000000 9998596336 10392813568 1077653952 11.625 100 41 79 1 1099888720 14353744 7881024
02:28:52 4459 8192000000 10059140016 10457239552 1102412832 11.861 100 56 112 0 1166270240 52486432 11370976
02:30:00 53444 8192000000 10069735152 10478366720 1118423088 12.012 100 38 76 2 1167487296 40243984 8820224
02:32:04 10759 8192000000 10130630736 10530566144 1126857872 12.092 100 55 110 0 1234920208 96039152 12023184
02:33:21 3698 8192000000 10141611472 10559373312 1141627296 12.231 100 56 108 2 1260198848 106912768 11658784
02:34:30 5689 8192000000 10188368896 10599886848 1155705408 12.363 100 62 122 1 1300119680 130893168 13521104
02:35:38 5524 8192000000 10207480048 10621796352 1167541920 12.474 100 46 90 3 1316713264 138561424 10609920
02:36:48 12715 8192000000 10267270544 10690146304 1165393280 12.454 100 55 110 1 1384098784 206080576 12624928
02:38:10 7676 8192000000 10284392400 10732855296 1174802384 12.542 100 56 109 0 1387775248 200552000 12420864
02:39:27 1844 8192000000 10301976800 10712145920 1188031104 12.665 100 75 147 0 1402960624 198639504 16290016
02:40:35 2531 8192000000 10335095472 10751397888 1181171936 12.601 100 71 141 1 1438402176 239831200 17399040
02:41:41 4853 8192000000 10378147472 10798321664 1167281456 12.471 99 68 136 2 1471091472 288098576 15711440
02:42:50 2624 8192000000 10399528288 10801025024 1159590288 12.399 99 79 158 0 1492220944 314344432 18286224
02:43:58 2725 8192000000 10394488704 10801209344 1163537152 12.436 100 77 153 1 1496283456 313883648 18862656
02:45:07 5046 8192000000 10415964464 10836836352 1150505632 12.314 99 51 102 0 1517722064 353701040 13515392
02:46:19 15931 8192000000 10323662224 10736308224 1142322944 12.237 99 48 93 2 1413060832 259156192 11581696
02:47:37 19885 8192000000 10326693824 10760003584 1111918832 11.951 97 34 66 1 1399430528 278848160 8663536
02:49:00 7375 8192000000 10353673968 10774884352 1148456640 12.295 100 51 103 2 1447498720 285431280 13610800
02:50:08 24340 8192000000 10315388832 10793000960 1132681728 12.147 100 25 50 1 1452294432 312840896 6771808
02:51:36 18847 8192000000 10390208208 10830868480 1109425088 11.927 99 28 56 1 1466015552 349318720 7271744
02:52:58 15452 8192000000 10308540704 10783408128 1110478384 11.937 100 27 54 1 1406534736 289047024 7009328
02:54:16 21983 8192000000 10118618448 10621472768 1095076688 11.791 99 13 26 1 1245490160 147453184 2960288
02:55:42 77361 8192000000 10065290976 10516516864 1095718144 11.797 100 23 44 1 1165618160 65047808 4852208
02:58:04 64760 8192000000 10058143728 10533294080 1094243296 11.783 98 13 26 1 1151752496 55177248 2331952
03:00:12 80374 8192000000 10094524880 10536108032 1113114864 11.962 99 20 41 1 1196407840 79468144 3824832
03:02:32 22615 8192000000 10097521920 10534518784 1118349072 12.011 99 31 61 1 1195270688 70022352 6899264
03:03:59 107279 8192000000 10058445152 10533478400 1108419264 11.917 98 21 42 0 1194747648 82069840 4258544
03:06:48 22910 8192000000 10057615584 10481790976 1119175456 12.019 99 37 74 1 1149100896 22293888 7631552
03:08:13 60811 8192000000 10032203392 10485764096 1116031792 11.989 99 20 40 1 1150228240 29839152 4357296
03:10:14 51061 8192000000 10100989264 10554814464 1136973440 12.187 100 41 83 1 1192293360 46724448 8595472
03:12:12 139857 8192000000 10094747728 10599784448 1129035504 12.112 99 26 52 1 1208928640 74297680 5595456
03:15:43 59783 8192000000 10062984848 10526932992 1122817632 12.054 98 13 27 0 1154979664 29622176 2539856
03:17:43 58443 8192000000 10072603168 10539008000 1141664864 12.231 100 11 19 2 1181903680 38019360 2219456
03:19:55 51377 8192000000 10087865024 10543026176 1139408816 12.210 99 13 28 0 1185981824 43347344 3225664
03:21:52 58127 8192000000 10096086752 10550804480 1143116176 12.245 99 15 30 1 1200642864 54562736 2963952
03:24:19 76660 8192000000 10102561072 10599858176 1129323280 12.115 97 13 26 0 1198815904 66968592 2524032
03:26:41 42121 8192000000 10105532624 10546962432 1129071328 12.113 98 25 51 1 1195126848 60932384 5123136
03:28:23 41151 8192000000 10110662032 10549854208 1140247232 12.218 99 27 54 0 1191274080 45923504 5103344
03:30:08 69403 8192000000 10062601152 10492604416 1106764176 11.902 97 28 56 3 1131748464 18793248 6191040
03:32:19 89037 8192000000 10117212976 10583674880 1078408064 11.632 94 6 12 1 1121812656 42366368 1038224
03:35:16 80531 8192000000 10106649712 10602860544 1141355216 12.228 100 9 18 0 1210592704 67329264 1908224
03:37:37 56803 8192000000 10059796912 10533748736 1112667920 11.958 98 9 18 0 1157483536 42973936 1841680
03:39:36 99136 8192000000 10072308608 10502070272 1080982464 11.657 95 26 53 0 1125905936 39174240 5749232
03:42:25 66005 8192000000 10112854560 10560057344 1116293552 11.992 98 21 42 0 1165157904 44285408 4578944
03:44:41 94317 8192000000 10040490832 10486407168 1022722368 11.098 90 7 14 0 1096998272 72567168 1708736
03:47:17 89136 8192000000 10039488448 10476838912 1023939152 11.110 92 15 30 1 1047048080 20180560 2928368
03:49:47 85053 8192000000 10030849152 10507968512 1060247968 11.459 96 18 37 1 1111632896 48091568 3293360
03:52:15 63847 8192000000 10058054864 10506362880 1089355024 11.737 99 29 58 1 1137740336 42333456 6051856
03:54:26 77728 8192000000 10060854720 10550599680 1103820736 11.874 99 10 20 0 1176162640 69863712 2478192
03:56:48 46832 8192000000 10076211184 10535432192 1104786640 11.883 99 10 20 0 1160893120 53938448 2168032
03:58:38 54618 8192000000 10107417584 10544013312 1101771376 11.854 99 25 50 0 1198187248 91391936 5023936
04:00:40 48799 8192000000 10096557520 10581475328 1132900144 12.149 100 20 40 0 1234287088 97107472 4279472
04:02:38 70387 8192000000 10071184416 10544107520 1123668432 12.062 99 17 33 1 1179839728 52605616 3565680
04:04:56 39600 8192000000 10155720608 10596384768 1144578368 12.259 100 45 90 1 1230603904 76574112 9451424
04:06:35 88302 8192000000 10100076272 10596933632 1121737024 12.043 98 12 24 0 1166288272 42385296 2165952
04:09:15 45366 8192000000 10093873024 10535596032 1124051088 12.065 98 33 66 0 1163557664 33166096 6340480
04:11:02 93587 8192000000 10071221040 10509381632 1132803312 12.148 99 20 40 0 1172255104 35308448 4143344
04:14:51 37751 8192000000 10068387712 10544168960 1137592624 12.193 99 8 17 0 1158537632 19321616 1623392
04:17:22 73161 8192000000 10067110496 10576109568 1132210672 12.142 98 7 14 0 1186414960 52539520 1664768
04:19:44 37809 8192000000 10122217792 10575159296 1145361440 12.266 99 24 48 0 1227393504 76590512 5441552
04:21:22 63872 8192000000 10150319664 10603433984 1145486416 12.267 99 31 62 3 1236661744 84551440 6623888
04:23:31 62021 8192000000 10128404192 10571948032 1116373536 11.993 97 16 32 1 1185935072 66156064 3405472
04:25:33 28018 8192000000 10064849456 10507939840 1131734816 12.138 99 21 42 1 1152970560 16220400 5015344

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 26-03-2021 04:27:54 to 26-03-2021 04:28:09.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 124 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 26-03-2021 04:28:10 to 26-03-2021 04:28:12.

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          224484
MON$OLDEST_ACTIVE               227065
MON$OLDEST_SNAPSHOT             227065
MON$NEXT_TRANSACTION            227065
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                       2084512
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.0.2390 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2390 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2390 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.2390 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 ---
:345: 
:346: mscs-test-161.budzdorov.ru    Fri Mar 26 04:27:03 2021
:347:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:348: 
--- end of diff output ---

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

26.03.21 04:28:13. 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-03-26 04:28:15 No severe PSQL-related problems occured
26.03.21 04:28:17. End of report.