Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.08.2021 07:51
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 16271668 kB
MemAvailable 31063160 kB
Buffers 0 kB
Cached 15454124 kB
SwapCached 2616 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-13 07:49:32
DTS_END                         2021-08-13 07:49:32.6810
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/56142
TRN_ID                          233777
ATT_ID                          682
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: 8955.58 20748 1074669
customer order: creation 175.58 438 21069
customer order: refuse 14.95 367 1794
order to supplier: creation 47.00 1343 5640
order to supplier: removal 3.41 109428 409
invoice (draft): creation 46.17 3575 5540
invoice (draft): removal 48.51 871 5821
invoice accept: apply 41.87 18921 5024
invoice accept: cancel 43.53 848 5224
customer reserve: creation 3955.01 183 474601
customer reserve: removal 4290.45 70 514854
realization accept: apply 83.39 180 10007
realization accept: cancel 75.98 131 9118
payment from customer: creation 60.53 102 7264
payment from customer: removal 5.58 82 669
payment to supplier: creation 49.28 114 5914
payment to supplier: removal 3.09 65 371
service: total inventory turnovers 3.93 3413 471
service: total monetary turnovers 6.36 539 763
service: aggregate perf. data 0.93 13761 112
service: refresh index statistics 0.03 260537 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-08-2021 07:51:42 to 13-08-2021 07:51:44.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4614
TEST_TIME 2 5459
TEST_TIME 3 5845
TEST_TIME 4 5475
TEST_TIME 5 5595
TEST_TIME 6 5491
TEST_TIME 7 5413
TEST_TIME 8 5673
TEST_TIME 9 5586
TEST_TIME 10 5669
TEST_TIME 11 5806
TEST_TIME 12 6544
TEST_TIME 13 7277
TEST_TIME 14 8138
TEST_TIME 15 8161
TEST_TIME 16 8621
TEST_TIME 17 8965
TEST_TIME 18 9198
TEST_TIME 19 9177
TEST_TIME 20 9395
TEST_TIME 21 9632
TEST_TIME 22 9706
TEST_TIME 23 10002
TEST_TIME 24 9847
TEST_TIME 25 10258
TEST_TIME 26 10984
TEST_TIME 27 11202
TEST_TIME 28 11294
TEST_TIME 29 11085
TEST_TIME 30 10745
TEST_TIME 31 10484
TEST_TIME 32 10416
TEST_TIME 33 10324
TEST_TIME 34 10704
TEST_TIME 35 10635
TEST_TIME 36 10486
TEST_TIME 37 10611
TEST_TIME 38 10443
TEST_TIME 39 10418
TEST_TIME 40 10281
TEST_TIME 41 10309
TEST_TIME 42 10143
TEST_TIME 43 9961
TEST_TIME 44 10086
TEST_TIME 45 9959
TEST_TIME 46 9894
TEST_TIME 47 9793
TEST_TIME 48 9746
TEST_TIME 49 9773
TEST_TIME 50 9833
TEST_TIME 51 9758
TEST_TIME 52 9799
TEST_TIME 53 9835
TEST_TIME 54 9814
TEST_TIME 55 9875
TEST_TIME 56 9969
TEST_TIME 57 9930
TEST_TIME 58 9950
TEST_TIME 59 9865
TEST_TIME 60 9755
TEST_TIME 61 9656
TEST_TIME 62 9657
TEST_TIME 63 9601
TEST_TIME 64 9546
TEST_TIME 65 9454
TEST_TIME 66 9438
TEST_TIME 67 9503
TEST_TIME 68 9495
TEST_TIME 69 9456
TEST_TIME 70 9556
TEST_TIME 71 9538
TEST_TIME 72 9511
TEST_TIME 73 9513
TEST_TIME 74 9561
TEST_TIME 75 9552
TEST_TIME 76 9549
TEST_TIME 77 9522
TEST_TIME 78 9446
TEST_TIME 79 9404
TEST_TIME 80 9366
TEST_TIME 81 9349
TEST_TIME 82 9319
TEST_TIME 83 9286
TEST_TIME 84 9235
TEST_TIME 85 9207
TEST_TIME 86 9167
TEST_TIME 87 9223
TEST_TIME 88 9178
TEST_TIME 89 9220
TEST_TIME 90 9249
TEST_TIME 91 9320
TEST_TIME 92 9322
TEST_TIME 93 9422
TEST_TIME 94 9395
TEST_TIME 95 9429
TEST_TIME 96 9376
TEST_TIME 97 9338
TEST_TIME 98 9294
TEST_TIME 99 9271
TEST_TIME 100 9228
TEST_TIME 101 9169
TEST_TIME 102 9137
TEST_TIME 103 9113
TEST_TIME 104 9124
TEST_TIME 105 9096
TEST_TIME 106 9062
TEST_TIME 107 9055
TEST_TIME 108 9064
TEST_TIME 109 9061
TEST_TIME 110 9166
TEST_TIME 111 9223
TEST_TIME 112 9221
TEST_TIME 113 9205
TEST_TIME 114 9215
TEST_TIME 115 9173
TEST_TIME 116 9133
TEST_TIME 117 9118
TEST_TIME 118 9070
TEST_TIME 119 9021
TEST_TIME 120 8964

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 13-08-2021 07:51:46 to 13-08-2021 07:51:49.

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 1511474 1511474 0 0.00 0 15162 6 0 0
doc_list_biud 1511474 1511474 0 0.00 0 39903 30 0 0
sp_add_invoice_to_stock 5128 5024 104 2.02 33 199705 18922 0 104
sp_cancel_adding_invoice 5224 5224 0 0.00 4 71145 848 0 0
sp_cancel_client_order 1794 1794 0 0.00 4 14073 368 0 0
sp_cancel_customer_reserve 514854 514854 0 0.00 1 39905 71 0 0
sp_cancel_pay_from_customer 669 669 0 0.00 3 3840 82 0 0
sp_cancel_pay_to_supplier 371 371 0 0.00 3 2254 66 0 0
sp_cancel_supplier_invoice 5830 5821 9 0.15 2 36348 872 0 9
sp_cancel_supplier_order 409 409 0 0.00 7 1185796 109428 0 0
sp_cancel_write_off 9118 9118 0 0.00 1 22371 132 0 0
sp_client_order 21069 21069 0 0.00 4 24116 439 0 0
sp_customer_reserve 474601 474601 0 0.00 0 78070 183 0 0
sp_fill_shopping_cart 32240 32240 0 0.00 1 19326 44 0 0
sp_fill_shopping_cart_clo_res 474590 474590 0 0.00 0 32283 17 0 0
sp_get_clo_for_invoice 5010 5010 0 0.00 0 34794 347 0 0
sp_kill_cost_storno 15382 15382 0 0.00 0 10988 58 0 0
sp_kill_qstorno_ret_qs2qd 1045056 1045056 0 0.00 0 38448 38 0 0
sp_kill_qty_storno 552237 552237 0 0.00 0 39902 81 0 0
sp_lock_selected_doc 20675 20675 0 0.00 0 12082 6 0 0
sp_make_cost_storno 28195 28195 0 0.00 0 14949 50 0 0
sp_multiply_rows_for_pdistr 28195 28195 0 0.00 0 5582 30 0 0
sp_multiply_rows_for_qdistr 21069 21069 0 0.00 0 15428 258 0 0
sp_pay_from_customer 7264 7264 0 0.00 4 10438 103 0 0
sp_pay_to_supplier 5914 5914 0 0.00 5 12885 115 0 0
sp_payment_common 13178 13178 0 0.00 1 10884 21 0 0
sp_qd_handle_on_cancel_clo 1794 1794 0 0.00 0 14066 329 0 0
sp_qd_handle_on_invoice_upd_sts 10234 10234 0 0.00 0 20770 339 0 0
sp_qd_handle_on_reserve_upd_sts 10007 10007 0 0.00 0 13064 54 0 0
sp_reserve_write_off 10007 10007 0 0.00 6 13485 181 0 0
sp_supplier_invoice 5540 5540 0 0.00 7 93254 3575 0 0
sp_supplier_order 5640 5640 0 0.00 6 63876 1343 0 0
srv_aggregate_perf_data 112 112 0 0.00 2378 158916 13762 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1873 130913 43224 0 0
srv_find_qd_qs_mism 1024756 1024756 0 0.00 0 15161 6 0 0
srv_make_invnt_saldo 471 471 0 0.00 50 94693 3413 0 0
srv_make_money_saldo 763 763 0 0.00 1 22877 540 0 0
srv_recalc_idx_stat 4 4 0 0.00 144889 408963 260538 0 0
t$perf-abend:sp_add_invoice_to_stock 104 104 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5024 5024 0 0.00 0 26957 181 0 0
t$perf-norm:sp_cancel_adding_invoice 392 392 0 0.00 1 30406 180 0 0
t$perf-norm:sp_cancel_client_order 1794 1794 0 0.00 0 9600 19 0 0
t$perf-norm:sp_cancel_customer_reserve 605 605 0 0.00 0 530 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 669 669 0 0.00 0 1520 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 371 371 0 0.00 0 266 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 380 380 0 0.00 0 6968 35 0 0
t$perf-norm:sp_cancel_supplier_order 409 409 0 0.00 0 44619 1511 0 0
t$perf-norm:sp_cancel_write_off 1038 1038 0 0.00 0 2560 8 0 0
t$perf-norm:sp_client_order 21070 21070 0 0.00 0 9382 19 0 0
t$perf-norm:sp_customer_reserve 1015 1015 0 0.00 0 3449 9 0 0
t$perf-norm:sp_pay_from_customer 7264 7264 0 0.00 0 10053 9 0 0
t$perf-norm:sp_pay_to_supplier 5914 5914 0 0.00 0 3864 8 0 0
t$perf-norm:sp_reserve_write_off 10007 10007 0 0.00 0 13526 12 0 0
t$perf-norm:sp_supplier_invoice 5540 5540 0 0.00 0 8916 18 0 0
t$perf-norm:sp_supplier_order 5640 5640 0 0.00 0 8877 18 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 2278 28 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 109 3 0 0
t$perf-norm:srv_make_invnt_saldo 471 471 0 0.00 0 3536 13 0 0
t$perf-norm:srv_make_money_saldo 763 763 0 0.00 0 684 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10228 10020 208 2.03 0 3507 14 0 208
v_all_customers 38526 38526 0 0.00 0 100 1 0 0
v_all_suppliers 31126 31126 0 0.00 0 80 1 0 0
v_all_wares 42138 42138 0 0.00 0 35 1 0 0
v_cancel_adding_invoice 784 784 0 0.00 0 694 8 0 0
v_cancel_client_order 3588 3588 0 0.00 0 3286 5 0 0
v_cancel_customer_prepayment 1338 1338 0 0.00 0 2996 8 0 0
v_cancel_customer_reserve 1210 1210 0 0.00 0 461 3 0 0
v_cancel_payment_to_supplier 742 742 0 0.00 0 472 4 0 0
v_cancel_supplier_invoice 778 760 18 2.31 0 908 13 0 18
v_cancel_supplier_order 818 818 0 0.00 0 746 4 0 0
v_cancel_write_off 2076 2076 0 0.00 0 4355 15 0 0
v_max_id_clo_ord 5640 5640 0 0.00 0 296 1 0 0
v_max_id_clo_res 1015 1015 0 0.00 0 6896 36 0 0
v_max_id_ord_sup 5531 5531 0 0.00 0 17341 7 0 0
v_max_non_paid_invoice 1522 1522 0 0.00 0 388 2 0 0
v_max_non_paid_realizn 3773 3773 0 0.00 0 1037 3 0 0
v_min_id_clo_ord 5640 5640 0 0.00 0 1440 4 0 0
v_min_id_clo_res 1015 1015 0 0.00 0 8262 19 0 0
v_min_id_ord_sup 5531 5531 0 0.00 0 5309 9 0 0
v_min_non_paid_invoice 5914 5914 0 0.00 0 4797 14 0 0
v_min_non_paid_realizn 7264 7264 0 0.00 0 4626 14 0 0
v_our_firm 10594 10594 0 0.00 0 200 1 0 0
v_reserve_write_off 20014 20014 0 0.00 0 3224 5 0 0
x_lock_depdocs_on_canc_invoice 10665 10665 0 0.00 0 13192 104 0 0
x_lock_depdocs_on_canc_sup_ord 409 409 0 0.00 0 7987 299 0 0
x_make_qstorno_1000_1200 5640 5640 0 0.00 2 63870 1264 0 0
x_make_qstorno_1200_2000 5540 5540 0 0.00 3 89925 3400 0 0
x_make_qstorno_2100_3300 474601 474601 0 0.00 0 78069 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-08-2021 07:51:50 to 13-08-2021 07:51:54.

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:19:36 2 8192000000 8796668944 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:20:37 20096 8192000000 9713907696 10010988544 785394368 8.748 100 83 166 0 812969184 7008800 20566016
05:21:59 22383 8192000000 9755137888 10061107200 827456640 9.174 100 90 180 1 861365872 12447264 21461968
05:23:29 17702 8192000000 9792488480 10108641280 857410944 9.474 100 83 166 0 897636304 20740032 19485328
05:25:09 9159 8192000000 9880488304 10216566784 921546768 10.111 100 78 156 1 991462928 53274272 16641888
05:26:23 9891 8192000000 9924726224 10272083968 966684128 10.554 100 69 138 3 1025625792 44100208 14841456
05:27:34 4445 8192000000 9964430592 10323304448 1006179248 10.938 100 73 147 1 1078511680 57577296 14755136
05:28:41 2225 8192000000 10010671584 10382110720 1035228176 11.219 100 81 161 0 1122315280 70875344 16211760
05:29:46 2908 8192000000 10029451408 10403946496 1057857760 11.436 100 84 166 1 1150450656 74671936 17920960
05:30:51 1788 8192000000 10039515168 10411888640 1068333712 11.536 100 83 165 1 1146894112 61658464 16901936
05:31:54 1589 8192000000 10035092656 10412929024 1071204784 11.564 100 86 171 2 1145010080 56512976 17292320
05:32:56 3476 8192000000 9987681888 10380365824 1078743344 11.635 100 37 74 0 1112087072 22383280 10960448
05:34:03 5815 8192000000 9995451872 10398527488 1086960464 11.714 100 31 61 1 1115515872 22184512 6370896
05:35:14 4870 8192000000 10002817504 10407530496 1094051536 11.781 100 29 58 2 1127686032 27904032 5730464
05:36:19 18872 8192000000 10018037056 10411622400 1094649056 11.787 100 56 112 3 1120428944 12862432 12917456
05:37:38 5685 8192000000 10014454608 10417680384 1099655936 11.834 100 49 98 1 1126908928 16299584 10953408
05:38:46 5015 8192000000 10007680512 10398900224 1087982864 11.723 100 46 92 0 1116380976 17768832 10629280
05:39:52 33977 8192000000 10002956336 10407698432 1079035904 11.638 99 27 54 2 1104762560 19793936 5932720
05:41:27 8687 8192000000 9990752000 10387439616 1036152624 11.228 97 42 84 3 1065525008 20077568 9294816
05:42:39 6617 8192000000 9930327728 10307604480 1009104880 10.967 98 29 58 1 1032194288 16366320 6723088
05:43:51 19051 8192000000 9933075376 10336923648 1010224752 10.978 98 46 92 1 1032956384 12197488 10534144
05:45:38 4869 8192000000 9962316432 10347167744 1044109520 11.304 100 57 111 4 1071516032 15598656 11807856
05:46:46 1816 8192000000 9966361792 10349572096 1041512304 11.279 99 51 104 1 1063677616 10874112 11291200
05:47:49 59067 8192000000 9990788112 10399096832 1068496960 11.538 100 30 60 1 1096636928 21733184 6406784
05:49:49 19658 8192000000 10014280320 10415624192 1091363184 11.756 100 44 88 1 1115165840 14647776 9154880
05:51:15 22302 8192000000 10057417424 10467971072 1110558912 11.938 100 42 84 0 1174863616 55455104 8849600
05:52:47 19243 8192000000 10096047696 10505842688 1133935840 12.158 100 38 74 3 1196481136 54305456 8239840
05:54:08 3766 8192000000 10132930144 10538164224 1146112160 12.273 100 56 110 1 1239977248 81835168 12029920
05:55:17 4337 8192000000 10191998320 10603704320 1156309120 12.369 100 58 114 2 1290776048 122840096 11626832
05:56:27 2948 8192000000 10229926624 10638258176 1169151296 12.489 100 73 146 0 1334724224 150050624 15522304
05:57:33 3172 8192000000 10247792528 10658877440 1174588064 12.540 100 61 119 1 1354867776 167147216 13132496
05:58:42 9757 8192000000 10266974544 10684301312 1169003344 12.488 99 53 102 2 1371471840 190756512 11711984
05:59:57 6428 8192000000 10327511856 10741751808 1190491664 12.688 100 55 109 0 1421492976 218450416 12550896
06:01:07 3874 8192000000 10346948432 10778910720 1181136224 12.601 99 79 156 2 1440818816 241955840 17726752
06:02:16 2315 8192000000 10383224480 10793152512 1178218304 12.574 100 84 168 1 1478802288 281216400 19367584
06:03:23 1872 8192000000 10317971696 10734751744 1169634544 12.493 100 68 135 1 1424378208 239281856 15461808
06:04:32 6737 8192000000 10313038064 10739544064 1167592464 12.474 99 43 83 1 1406962368 228831216 10538688
06:05:42 7828 8192000000 10345472176 10770763776 1162156528 12.423 100 65 129 0 1441851952 264812240 14883184
06:06:54 4172 8192000000 10280340176 10723213312 1146911728 12.280 99 53 106 1 1383642288 224080240 12650320
06:08:00 3490 8192000000 10278375280 10696568832 1140565296 12.221 100 47 95 1 1379955136 228459216 10930624
06:09:10 5763 8192000000 10334720096 10754940928 1157920272 12.384 100 50 100 1 1434108432 264404096 11784064
06:10:19 20164 8192000000 10315755952 10781339648 1162212624 12.424 100 30 60 0 1412426992 242773600 7440768
06:11:42 14141 8192000000 10315624160 10778624000 1145817152 12.270 99 30 57 3 1408799648 255616672 7365824
06:13:06 13666 8192000000 10298661376 10732060672 1149733408 12.307 100 28 56 0 1386213600 229419824 7060368
06:14:21 14686 8192000000 10263664912 10748149760 1119024864 12.018 100 17 34 0 1393082976 268992736 5065376
06:15:48 23462 8192000000 10087107872 10570031104 1083600480 11.682 97 14 28 1 1212915232 125893456 3421296
06:17:59 6730 8192000000 10075586560 10488664064 1110815440 11.940 100 70 138 1 1167105008 39205264 17084304
06:19:15 109484 8192000000 10199466928 10643644416 1126527248 12.089 100 35 67 2 1274236832 140050880 7658704
06:22:08 77965 8192000000 10169874624 10609491968 1120359120 12.030 99 25 50 1 1296306336 170490080 5457136
06:24:46 37694 8192000000 10066554816 10501058560 1100674944 11.844 99 31 61 1 1161262160 53849232 6737984
06:26:34 78244 8192000000 10078424672 10515021824 1105892096 11.894 99 13 26 1 1157340880 48808000 2640784
06:28:56 80159 8192000000 10059991904 10532454400 1129886576 12.120 100 15 29 0 1194487232 61160608 3440048
06:31:21 88678 8192000000 10129891216 10582024192 1115149424 11.981 98 22 44 0 1193285360 73169152 4966784
06:34:06 84526 8192000000 10061007216 10545065984 1121426480 12.040 99 21 42 2 1154690624 28873456 4390688
06:36:35 76473 8192000000 10095073056 10555760640 1136663696 12.184 99 13 26 0 1203551280 64210576 2677008
06:38:54 25600 8192000000 10068547648 10548391936 1136489184 12.182 99 31 61 1 1188175680 44736688 6949808
06:40:24 19709 8192000000 10112390944 10556829696 1155181808 12.358 100 46 92 1 1203973968 39610240 9181920
06:41:44 62050 8192000000 10105026688 10568925184 1148109552 12.292 99 20 40 1 1185950112 33927872 3912688
06:43:54 42086 8192000000 10178177248 10622062592 1167698912 12.475 100 34 69 0 1265425184 90474608 7251664
06:45:36 69875 8192000000 10118476848 10557485056 1149510400 12.305 99 23 46 0 1189690368 35357552 4822416
06:47:50 33258 8192000000 10096537856 10530258944 1118603824 12.014 97 39 75 1 1170490672 44135616 7751232
06:49:23 59148 8192000000 10077103664 10517987328 1113456288 11.965 97 33 65 2 1154987264 35461744 6069232
06:51:27 60490 8192000000 10057758480 10529636352 1123646288 12.061 99 17 34 0 1179242800 52281696 3314816
06:53:34 32150 8192000000 10069589648 10549522432 1128434656 12.107 99 7 14 0 1177213440 47347024 1431760
06:55:07 67062 8192000000 10078920384 10575728640 1098735984 11.826 97 17 35 0 1179603680 76775312 4092384
06:57:15 75947 8192000000 10096122784 10544717824 1089369344 11.737 96 40 79 1 1168092720 70266400 8456976
06:59:32 50649 8192000000 10055379264 10556866560 1092241440 11.764 98 18 36 1 1159331072 63080976 4008656
07:01:28 36157 8192000000 10094858224 10515234816 1098978944 11.828 98 44 87 1 1155280912 47770400 8531568
07:03:05 83223 8192000000 10070482464 10526695424 1095122624 11.791 98 33 66 0 1164550736 62913168 6514944
07:05:29 35303 8192000000 10059018448 10480951296 1095489744 11.795 98 43 87 2 1143984544 38804240 9690560
07:07:06 120131 8192000000 10026878624 10463793152 1091779552 11.760 99 35 69 3 1148562512 49833744 6949216
07:10:11 50396 8192000000 10043211776 10508414976 1113936336 11.970 100 19 38 0 1177613648 59314112 4363200
07:12:01 35844 8192000000 10065358352 10546757632 1121543296 12.042 100 16 34 0 1167966256 42952352 3470608
07:13:39 42954 8192000000 10090287184 10532405248 1097011920 11.809 97 8 16 0 1152148832 53444704 1692208
07:15:22 30799 8192000000 10142644816 10577793024 1124677200 12.071 99 23 46 0 1240470544 110319168 5474176
07:16:57 53755 8192000000 10162619088 10601160704 1117107152 12.000 98 18 36 0 1230793392 109686848 3999392
07:18:51 72659 8192000000 10130379904 10568466432 1116577824 11.995 98 17 34 0 1207636912 87704912 3354176
07:21:12 45500 8192000000 10133858400 10587422720 1141502000 12.230 99 35 70 0 1217355808 68848368 7005440
07:23:07 54468 8192000000 10109874512 10582634496 1150356528 12.313 100 18 36 0 1199992336 45833584 3802224
07:25:07 130913 8192000000 10113800912 10581102592 1121230336 12.039 97 33 65 1 1181982560 54396048 6356176
07:28:34 46034 8192000000 10077781808 10566004736 1143323024 12.247 99 10 20 0 1179155040 33879872 1952144
07:30:33 73148 8192000000 10093444816 10558930944 1114013536 11.970 97 22 42 5 1167216928 48722272 4481120
07:32:46 73489 8192000000 10092225792 10580119552 1124670112 12.071 98 19 38 3 1188048224 59270208 4107904
07:35:00 27266 8192000000 10117396528 10563321856 1125211216 12.076 98 25 49 1 1215656176 84610512 5834448
07:36:29 54409 8192000000 10186471264 10630033408 1144154208 12.255 100 25 50 0 1278553360 128436896 5962256
07:38:23 66398 8192000000 10178997616 10664255488 1115540672 11.985 97 17 34 1 1267015392 147640592 3834128
07:40:32 90933 8192000000 10063218352 10489634816 1085539008 11.700 95 23 40 3 1137112864 46696176 4877680
07:43:07 59882 8192000000 10069172480 10509762560 1090178448 11.744 97 31 62 0 1156755456 60622208 5954800
07:45:21 54005 8192000000 10025006960 10488111104 1082597456 11.672 97 22 44 0 1123671520 36134176 4939888
07:48:03 63286 8192000000 10051792288 10505183232 1081089248 11.658 97 13 26 0 1137246640 53486288 2671104

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 13-08-2021 07:51:56 to 13-08-2021 07:52:11.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 104 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 13-08-2021 07:52:12 to 13-08-2021 07:52:14.

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          231160
MON$OLDEST_ACTIVE               233886
MON$OLDEST_SNAPSHOT             233886
MON$NEXT_TRANSACTION            233886
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                       2042720
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             798
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2569 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2569 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2569 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2569 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Fri Aug 13 07:49:34 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Fri Aug 13 07:49:34 2021
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Fri Aug 13 07:49:42 2021
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:17: 
:18: 
:19: mscs-test-161.budzdorov.ru    Fri Aug 13 07:49:46 2021
:20:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:21: 
:22: 
:23: mscs-test-161.budzdorov.ru    Fri Aug 13 07:49:47 2021
:24:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:25: 
--- end of diff output ---

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

13.08.21 07:52:15. 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-13 07:52:18 No severe PSQL-related problems occured
13.08.21 07:52:19. End of report.