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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 15859812 kB
MemAvailable 30630068 kB
Buffers 0 kB
Cached 15822308 kB
SwapCached 3704 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-10-18 07:37:08
DTS_END                         2021-10-18 07:37:08.2380
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_FROM_CUSTOMER(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/45806
TRN_ID                          227664
ATT_ID                          684
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: 8739.56 20334 1048747
customer order: creation 164.50 428 19740
customer order: refuse 14.29 331 1715
order to supplier: creation 43.56 1470 5227
order to supplier: removal 3.23 127295 388
invoice (draft): creation 42.98 3713 5157
invoice (draft): removal 50.30 1082 6036
invoice accept: apply 38.61 20111 4633
invoice accept: cancel 46.28 866 5553
customer reserve: creation 3562.75 198 427530
customer reserve: removal 4486.54 72 538385
realization accept: apply 79.68 195 9561
realization accept: cancel 82.51 109 9901
payment from customer: creation 57.57 107 6908
payment from customer: removal 5.33 78 640
payment to supplier: creation 47.72 137 5726
payment to supplier: removal 3.36 82 403
service: total inventory turnovers 3.57 3900 428
service: total monetary turnovers 5.94 453 713
service: aggregate perf. data 0.82 17749 98
service: refresh index statistics 0.04 228304 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-10-2021 07:38:26 to 18-10-2021 07:38:28.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5239
TEST_TIME 2 6045
TEST_TIME 3 5811
TEST_TIME 4 5731
TEST_TIME 5 6249
TEST_TIME 6 6019
TEST_TIME 7 5767
TEST_TIME 8 6312
TEST_TIME 9 6086
TEST_TIME 10 6260
TEST_TIME 11 6696
TEST_TIME 12 7158
TEST_TIME 13 7396
TEST_TIME 14 7654
TEST_TIME 15 7761
TEST_TIME 16 8272
TEST_TIME 17 8540
TEST_TIME 18 8344
TEST_TIME 19 8495
TEST_TIME 20 8436
TEST_TIME 21 8449
TEST_TIME 22 8653
TEST_TIME 23 8723
TEST_TIME 24 8823
TEST_TIME 25 8787
TEST_TIME 26 9275
TEST_TIME 27 9741
TEST_TIME 28 11107
TEST_TIME 29 10977
TEST_TIME 30 10857
TEST_TIME 31 10811
TEST_TIME 32 10783
TEST_TIME 33 10527
TEST_TIME 34 10341
TEST_TIME 35 10200
TEST_TIME 36 10130
TEST_TIME 37 9981
TEST_TIME 38 9867
TEST_TIME 39 9869
TEST_TIME 40 9736
TEST_TIME 41 9698
TEST_TIME 42 9546
TEST_TIME 43 9447
TEST_TIME 44 9355
TEST_TIME 45 9296
TEST_TIME 46 9267
TEST_TIME 47 9304
TEST_TIME 48 9193
TEST_TIME 49 9087
TEST_TIME 50 9019
TEST_TIME 51 8915
TEST_TIME 52 8945
TEST_TIME 53 8801
TEST_TIME 54 8965
TEST_TIME 55 8988
TEST_TIME 56 9092
TEST_TIME 57 9015
TEST_TIME 58 8995
TEST_TIME 59 9134
TEST_TIME 60 9183
TEST_TIME 61 9239
TEST_TIME 62 9165
TEST_TIME 63 9254
TEST_TIME 64 9288
TEST_TIME 65 9276
TEST_TIME 66 9224
TEST_TIME 67 9143
TEST_TIME 68 9090
TEST_TIME 69 9054
TEST_TIME 70 9097
TEST_TIME 71 9055
TEST_TIME 72 9003
TEST_TIME 73 8952
TEST_TIME 74 8878
TEST_TIME 75 8855
TEST_TIME 76 8803
TEST_TIME 77 8772
TEST_TIME 78 8916
TEST_TIME 79 8924
TEST_TIME 80 8892
TEST_TIME 81 8918
TEST_TIME 82 8890
TEST_TIME 83 8928
TEST_TIME 84 9177
TEST_TIME 85 9142
TEST_TIME 86 9117
TEST_TIME 87 9169
TEST_TIME 88 9146
TEST_TIME 89 9090
TEST_TIME 90 9039
TEST_TIME 91 9020
TEST_TIME 92 8964
TEST_TIME 93 8948
TEST_TIME 94 8873
TEST_TIME 95 8912
TEST_TIME 96 8863
TEST_TIME 97 8879
TEST_TIME 98 8885
TEST_TIME 99 8857
TEST_TIME 100 8861
TEST_TIME 101 8856
TEST_TIME 102 8834
TEST_TIME 103 8833
TEST_TIME 104 8825
TEST_TIME 105 8824
TEST_TIME 106 8940
TEST_TIME 107 8953
TEST_TIME 108 8940
TEST_TIME 109 8968
TEST_TIME 110 8900
TEST_TIME 111 8896
TEST_TIME 112 8910
TEST_TIME 113 8855
TEST_TIME 114 8848
TEST_TIME 115 8829
TEST_TIME 116 8803
TEST_TIME 117 8790
TEST_TIME 118 8771
TEST_TIME 119 8813
TEST_TIME 120 8741

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 18-10-2021 07:38:29 to 18-10-2021 07:38:33.

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 1446034 1446034 0 0.00 0 15454 7 0 0
doc_list_biud 1446034 1446034 0 0.00 0 28115 34 0 0
sp_add_invoice_to_stock 4782 4633 149 3.11 43 215437 20111 0 149
sp_cancel_adding_invoice 5553 5553 0 0.00 3 69960 866 0 0
sp_cancel_client_order 1715 1715 0 0.00 4 11496 331 0 0
sp_cancel_customer_reserve 538385 538385 0 0.00 1 28120 72 0 0
sp_cancel_pay_from_customer 640 640 0 0.00 3 5744 79 0 0
sp_cancel_pay_to_supplier 403 403 0 0.00 4 5138 83 0 0
sp_cancel_supplier_invoice 6051 6036 15 0.24 2 27785 1082 0 15
sp_cancel_supplier_order 388 388 0 0.00 6 1097233 127295 0 0
sp_cancel_write_off 9901 9901 0 0.00 1 18419 110 0 0
sp_client_order 19740 19740 0 0.00 4 37842 428 0 0
sp_customer_reserve 427530 427530 0 0.00 0 36448 199 0 0
sp_fill_shopping_cart 30112 30112 0 0.00 1 25521 49 0 0
sp_fill_shopping_cart_clo_res 427522 427522 0 0.00 0 14943 18 0 0
sp_get_clo_for_invoice 4625 4625 0 0.00 0 26623 461 0 0
sp_kill_cost_storno 16497 16497 0 0.00 0 7650 58 0 0
sp_kill_qstorno_ret_qs2qd 1093095 1093095 0 0.00 0 28095 39 0 0
sp_kill_qty_storno 576164 576164 0 0.00 0 28115 84 0 0
sp_lock_selected_doc 19683 19683 0 0.00 0 9599 5 0 0
sp_make_cost_storno 26820 26820 0 0.00 0 15451 63 0 0
sp_multiply_rows_for_pdistr 26820 26820 0 0.00 0 7985 33 0 0
sp_multiply_rows_for_qdistr 19740 19740 0 0.00 0 37794 227 0 0
sp_pay_from_customer 6908 6908 0 0.00 4 14269 108 0 0
sp_pay_to_supplier 5726 5726 0 0.00 5 14148 138 0 0
sp_payment_common 12634 12634 0 0.00 1 10582 26 0 0
sp_qd_handle_on_cancel_clo 1715 1715 0 0.00 0 11490 305 0 0
sp_qd_handle_on_invoice_upd_sts 10178 10178 0 0.00 0 22935 360 0 0
sp_qd_handle_on_reserve_upd_sts 9561 9561 0 0.00 0 10957 57 0 0
sp_reserve_write_off 9561 9561 0 0.00 7 18608 196 0 0
sp_supplier_invoice 5157 5157 0 0.00 7 99715 3713 0 0
sp_supplier_order 5227 5227 0 0.00 6 44697 1471 0 0
srv_aggregate_perf_data 98 98 0 0.00 2120 182829 17749 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 2105 141387 44051 0 0
srv_find_qd_qs_mism 1004269 1004269 0 0.00 0 12377 6 0 0
srv_make_invnt_saldo 428 428 0 0.00 43 75434 3901 0 0
srv_make_money_saldo 713 713 0 0.00 1 9065 454 0 0
srv_recalc_idx_stat 5 5 0 0.00 140847 359147 228305 0 0
t$perf-abend:sp_add_invoice_to_stock 149 149 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4633 4633 0 0.00 0 10534 172 0 0
t$perf-norm:sp_cancel_adding_invoice 396 396 0 0.00 1 2081 71 0 0
t$perf-norm:sp_cancel_client_order 1715 1715 0 0.00 0 4703 12 0 0
t$perf-norm:sp_cancel_customer_reserve 626 626 0 0.00 0 2379 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 640 640 0 0.00 0 2579 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 403 403 0 0.00 0 2441 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 380 380 0 0.00 0 1232 20 0 0
t$perf-norm:sp_cancel_supplier_order 388 388 0 0.00 0 38318 1832 0 0
t$perf-norm:sp_cancel_write_off 952 952 0 0.00 0 2362 5 0 0
t$perf-norm:sp_client_order 19740 19740 0 0.00 0 11159 19 0 0
t$perf-norm:sp_customer_reserve 922 922 0 0.00 0 3746 12 0 0
t$perf-norm:sp_pay_from_customer 6908 6908 0 0.00 0 5369 10 0 0
t$perf-norm:sp_pay_to_supplier 5726 5726 0 0.00 0 6858 10 0 0
t$perf-norm:sp_reserve_write_off 9561 9561 0 0.00 0 9333 13 0 0
t$perf-norm:sp_supplier_invoice 5157 5157 0 0.00 0 4562 17 0 0
t$perf-norm:sp_supplier_order 5227 5227 0 0.00 0 9268 19 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 722 18 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 1017 49 0 0
t$perf-norm:srv_make_invnt_saldo 428 428 0 0.00 0 1602 10 0 0
t$perf-norm:srv_make_money_saldo 713 713 0 0.00 0 4002 16 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 153 35 0 0
v_add_invoice_to_stock 9544 9246 298 3.12 0 7742 19 0 298
v_all_customers 36146 36146 0 0.00 0 20 1 0 0
v_all_suppliers 28948 28948 0 0.00 0 22 1 0 0
v_all_wares 39480 39480 0 0.00 0 124 1 0 0
v_cancel_adding_invoice 792 792 0 0.00 0 705 8 0 0
v_cancel_client_order 3430 3430 0 0.00 0 2937 5 0 0
v_cancel_customer_prepayment 1280 1280 0 0.00 0 5713 11 0 0
v_cancel_customer_reserve 1252 1252 0 0.00 0 761 3 0 0
v_cancel_payment_to_supplier 806 806 0 0.00 0 2078 9 0 0
v_cancel_supplier_invoice 790 760 30 3.79 0 799 16 0 30
v_cancel_supplier_order 776 776 0 0.00 0 4800 10 0 0
v_cancel_write_off 1904 1904 0 0.00 0 5933 19 0 0
v_max_id_clo_ord 5225 5225 0 0.00 0 1775 1 0 0
v_max_id_clo_res 922 922 0 0.00 0 1158 11 0 0
v_max_id_ord_sup 5147 5147 0 0.00 0 7976 7 0 0
v_max_non_paid_invoice 1624 1624 0 0.00 0 8388 7 0 0
v_max_non_paid_realizn 3656 3656 0 0.00 0 5240 6 0 0
v_min_id_clo_ord 5225 5225 0 0.00 0 4872 7 0 0
v_min_id_clo_res 922 922 0 0.00 0 866 3 0 0
v_min_id_ord_sup 5147 5147 0 0.00 0 6844 7 0 0
v_min_non_paid_invoice 5726 5726 0 0.00 0 7033 20 0 0
v_min_non_paid_realizn 6908 6908 0 0.00 0 7702 15 0 0
v_our_firm 9838 9838 0 0.00 0 15 1 0 0
v_reserve_write_off 19120 19120 0 0.00 0 6596 6 0 0
x_lock_depdocs_on_canc_invoice 11209 11209 0 0.00 0 22929 128 0 0
x_lock_depdocs_on_canc_sup_ord 388 388 0 0.00 0 34536 470 0 0
x_make_qstorno_1000_1200 5227 5227 0 0.00 2 44685 1389 0 0
x_make_qstorno_1200_2000 5157 5157 0 0.00 4 99183 3524 0 0
x_make_qstorno_2100_3300 427530 427530 0 0.00 0 34639 178 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-10-2021 07:38:34 to 18-10-2021 07:38:38.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
05:07:12 3 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:12 22630 8192000000 9717083264 10009808896 790832976 8.803 100 89 178 1 820284384 7848432 21602976
05:09:51 20887 8192000000 9758689136 10064683008 837339776 9.273 100 89 176 1 870625952 12336656 20949520
05:11:20 11657 8192000000 9790880512 10107772928 864284096 9.543 100 78 156 0 903281440 20878096 18119248
05:12:36 15232 8192000000 9865725264 10199113728 919615904 10.092 100 75 150 1 970999168 34658400 16724864
05:13:53 13628 8192000000 9913678976 10259267584 955207504 10.442 100 80 158 0 1027797600 55493824 17096272
05:15:12 5845 8192000000 9956061232 10310336512 996068160 10.840 100 70 140 1 1067418320 57121568 14228592
05:16:20 2768 8192000000 9992360464 10353881088 1026963024 11.139 100 88 173 0 1106847936 62306720 17578192
05:17:24 2749 8192000000 10027019280 10391019520 1044456144 11.307 100 93 187 1 1135930432 73141408 18332880
05:18:28 1061 8192000000 10062502960 10432446464 1059151584 11.448 100 92 184 0 1172221184 94841136 18228464
05:19:31 2518 8192000000 10049583616 10427355136 1065337728 11.508 100 75 148 1 1165789168 85872048 14579392
05:20:34 8312 8192000000 9974684912 10374471680 1071430160 11.566 100 35 70 0 1092465008 13432384 7602464
05:21:46 5340 8192000000 9990633168 10383937536 1075907088 11.608 100 32 62 1 1115072096 32403856 6761152
05:22:54 7928 8192000000 10002767248 10397270016 1083355888 11.679 100 30 60 0 1102744640 13437216 5951536
05:24:03 60144 8192000000 9997623632 10400120832 1088398576 11.727 100 32 64 0 1127011456 31683904 6928976
05:26:22 4555 8192000000 10012134096 10415194112 1094640656 11.787 100 54 106 0 1122277920 14994352 12642912
05:27:29 11877 8192000000 10029944496 10436825088 1082468256 11.671 99 33 67 1 1128683520 39358560 6856704
05:28:44 5528 8192000000 9962754816 10360881152 1041532896 11.279 97 33 63 0 1063913024 15616880 6763248
05:29:52 3997 8192000000 9939299632 10323079168 1022985136 11.101 98 41 79 0 1036793200 5340640 8467424
05:30:59 22345 8192000000 9910437776 10300682240 978943744 10.674 95 41 83 2 1007526976 19145392 9437840
05:32:22 5177 8192000000 9964575808 10348044288 1023502784 11.106 99 48 96 0 1050293600 16368448 10422368
05:33:31 7809 8192000000 9966697952 10361794560 1038050672 11.246 99 34 67 3 1057002016 11415712 7535632
05:34:44 10039 8192000000 9990636224 10391150592 1054585136 11.405 100 36 70 1 1080836272 18900704 7350432
05:35:57 12594 8192000000 9971718048 10375475200 1061297696 11.469 100 25 50 1 1095350896 28756336 5296864
05:37:12 25720 8192000000 10003968080 10387402752 1083958432 11.685 100 50 100 1 1105608848 10546304 11104112
05:38:45 4275 8192000000 10068781584 10457604096 1100404032 11.841 100 63 126 1 1167864976 54602560 12858384
05:39:53 10248 8192000000 10088384704 10486517760 1112091328 11.952 100 56 111 1 1189845536 66739072 11015136
05:41:24 32915 8192000000 10112960064 10518876160 1125345392 12.077 100 43 85 0 1198635744 64435360 8854992
05:43:02 24884 8192000000 10113590768 10523103232 1140310112 12.218 100 49 98 0 1233471440 82724032 10437296
05:44:33 5699 8192000000 10154726304 10561761280 1137543824 12.192 99 53 105 0 1268933072 120067264 11321984
05:45:41 8299 8192000000 10188578736 10617376768 1144810672 12.261 100 45 88 0 1304952208 150158080 9983456
05:46:52 6712 8192000000 10242934992 10651348992 1158229664 12.387 100 63 126 1 1344414144 172573152 13611328
05:48:03 4695 8192000000 10259320208 10672300032 1169232688 12.490 100 69 138 2 1363777440 179014608 15530144
05:49:16 2576 8192000000 10294695344 10699276288 1176341024 12.556 100 73 146 0 1385051840 192622912 16087904
05:50:23 4784 8192000000 10285927296 10696675328 1168329552 12.481 99 75 150 1 1387004368 201861696 16813120
05:51:30 3409 8192000000 10265814224 10676043776 1157774144 12.382 99 74 142 2 1357946432 183875408 16296880
05:52:38 2179 8192000000 10309957120 10712252416 1156176592 12.367 100 72 142 0 1406611648 234684800 15750256
05:53:48 2239 8192000000 10307157232 10708324352 1147229152 12.283 100 73 145 0 1403958128 239860656 16868320
05:54:55 27369 8192000000 10302641392 10717827072 1143888640 12.252 100 55 110 1 1403123760 245106096 14129024
05:56:28 2105 8192000000 10397770080 10802274304 1171629968 12.512 100 75 148 2 1493981408 304587504 17763936
05:57:33 12626 8192000000 10421670768 10847092736 1170861968 12.505 100 34 68 0 1522507648 342730576 8915104
05:58:55 9737 8192000000 10464378928 10882883584 1157047536 12.376 99 50 99 1 1546846064 378033136 11765392
06:00:12 8386 8192000000 10426616976 10848256000 1161097840 12.414 100 44 87 0 1522952880 350876144 10978896
06:01:22 18648 8192000000 10460723408 10885988352 1118540144 12.013 98 32 63 2 1537833616 410552384 8741088
06:02:42 15760 8192000000 10479962944 10918629376 1106395344 11.898 99 29 58 2 1560903504 446123488 8384672
06:03:59 18687 8192000000 10366002672 10817634304 1088929472 11.732 98 19 38 0 1443760368 349254384 5576512
06:05:21 26524 8192000000 10150312880 10727759872 1126624656 12.090 100 21 42 0 1375051536 242927296 5499584
06:06:49 77471 8192000000 10083858368 10565378048 1107454416 11.908 99 24 48 0 1214176544 101596608 5125520
06:09:50 39783 8192000000 10035429280 10512293888 1116102608 11.990 100 5 10 0 1144681104 28006064 572432
06:11:31 86081 8192000000 10080570352 10514149376 1117448480 12.003 98 18 36 0 1167017680 46207552 3361648
06:14:06 73116 8192000000 10054385360 10501980160 1072398448 11.575 94 19 38 1 1124407680 48353520 3655712
06:16:23 41561 8192000000 10050346960 10525921280 1104342608 11.879 98 13 26 0 1148261440 41278288 2640544
06:18:16 64933 8192000000 10052689248 10491273216 1113761552 11.968 99 8 16 0 1132952960 17597408 1594000
06:20:29 61532 8192000000 10106559680 10571251712 1126565488 12.089 99 17 34 1 1187646288 57291120 3789680
06:22:35 70133 8192000000 10100897184 10572763136 1132265664 12.143 100 42 85 0 1242958912 101094768 9598480
06:24:51 64625 8192000000 10085818832 10523287552 1143117648 12.245 100 20 40 1 1184936208 37592848 4225712
06:27:11 84758 8192000000 10171685616 10621165568 1144216976 12.255 99 22 44 0 1250392896 100835312 5340608
06:29:52 59844 8192000000 10174054704 10647683072 1156115136 12.367 100 17 34 1 1278810928 118717664 3978128
06:32:00 67376 8192000000 10123887152 10617503744 1153453888 12.342 100 7 14 0 1267414464 112090016 1870560
06:34:09 33105 8192000000 10146383680 10586832896 1166056224 12.460 100 42 80 1 1242663040 68176288 8430528
06:35:45 108730 8192000000 10124630544 10603565056 1113013840 11.961 96 10 20 0 1241610832 126167968 2429024
06:38:34 36878 8192000000 10052577168 10475950080 1105240016 11.887 97 26 49 1 1126055632 15581552 5234064
06:40:18 27829 8192000000 10146913216 10585812992 1130330560 12.124 99 32 64 0 1229889328 93375200 6183568
06:41:56 98161 8192000000 10073754720 10508062720 1119675008 12.024 98 19 38 0 1152890208 29291088 3924112
06:44:38 121874 8192000000 10135809536 10612383744 1145573536 12.268 100 43 85 1 1239129200 84806160 8749504
06:47:57 22091 8192000000 10062733792 10538717184 1125343376 12.077 99 13 26 0 1158552560 30404912 2804272
06:49:19 64512 8192000000 10080816288 10523303936 1112253504 11.954 97 15 30 0 1139176288 24176224 2746560
06:51:24 67719 8192000000 10112197488 10568314880 1104444912 11.880 96 17 34 0 1164697072 55739744 4512416
06:53:50 55741 8192000000 10138255536 10591494144 1093076672 11.772 96 17 34 0 1195393072 98665152 3651248
06:55:52 63476 8192000000 10082297472 10525827072 1080171056 11.649 95 19 38 0 1158946592 73991424 4784112
06:57:58 28364 8192000000 10117537760 10578128896 1138117664 12.198 100 27 54 0 1212650912 68900768 5632480
06:59:27 55907 8192000000 10166288032 10606903296 1118772288 12.015 99 23 46 1 1264039632 139619744 5647600
07:01:24 77546 8192000000 10063725552 10544779264 1100303216 11.841 98 11 22 0 1138488400 36101472 2083712
07:03:41 35118 8192000000 10098167408 10514219008 1079899360 11.647 97 38 76 0 1155164384 67594288 7670736
07:05:20 70702 8192000000 10045267600 10496393216 1105414736 11.889 99 18 36 1 1150888048 41496976 3976336
07:07:36 27617 8192000000 10063133776 10496995328 1106251824 11.897 99 31 60 0 1138406912 25772800 6382288
07:09:05 64299 8192000000 10056408000 10502901760 1110381312 11.936 99 11 21 2 1155246608 42969856 1895440
07:11:21 38798 8192000000 10074648512 10527551488 1125421680 12.078 100 6 12 0 1182511952 56048528 1041744
07:13:30 82424 8192000000 10074565248 10551685120 1135869776 12.177 100 17 34 0 1180386944 41035120 3482048
07:15:55 35529 8192000000 10047768544 10484428800 1107505392 11.909 97 32 63 1 1141237392 26977408 6754592
07:17:31 44539 8192000000 10066020112 10502115328 1106404160 11.898 97 20 40 0 1146386224 36275648 3706416
07:19:16 34488 8192000000 10088918240 10516738048 1143193104 12.246 100 32 64 0 1181818896 32645584 5980208
07:20:55 46188 8192000000 10105905200 10547920896 1142007600 12.234 100 38 76 0 1198266192 48578528 7680064
07:22:42 141387 8192000000 10073605312 10587316224 1092601936 11.767 96 13 26 0 1161925904 67092048 2231920
07:26:04 49511 8192000000 10077220800 10522853376 1110738960 11.939 99 24 48 0 1166426224 51232544 4454720
07:27:54 93693 8192000000 10115853840 10616504320 1112125552 11.953 98 13 26 0 1207783664 92946512 2711600
07:30:29 55943 8192000000 10064702128 10537414656 1111156384 11.943 98 12 24 0 1163946960 50498400 2292176
07:32:29 29257 8192000000 10045233200 10551013376 1125475168 12.079 100 3 6 0 1173598368 47558144 565056
07:34:00 76194 8192000000 10104958336 10571300864 1124254672 12.067 98 11 21 1 1180091792 53322768 2514352
07:36:17 49832 8192000000 10097206544 10560020480 1119974256 12.027 97 19 38 1 1214590672 90432528 4183888

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 18-10-2021 07:38:40 to 18-10-2021 07:38:55.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 149 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 18-10-2021 07:38:56 to 18-10-2021 07:38:58.

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          225092
MON$OLDEST_ACTIVE               227777
MON$OLDEST_SNAPSHOT             227777
MON$NEXT_TRANSACTION            227777
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                       2037664
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             784
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2631 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2631 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2631 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2631 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 ---
:270: 
:271: mscs-test-161.budzdorov.ru    Mon Oct 18 07:37:16 2021
:272:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:273: 
:274: 
:275: mscs-test-161.budzdorov.ru    Mon Oct 18 07:37:16 2021
:276:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:277: 
:278: 
:279: mscs-test-161.budzdorov.ru    Mon Oct 18 07:37:16 2021
:280:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:281: 
--- end of diff output ---

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

18.10.21 07:38:59. 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-10-18 07:39:01 No severe PSQL-related problems occured
18.10.21 07:39:03. End of report.