Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.02.2021 10: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 e0e7b46250ba4f9795ceb55e0face73f
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 2020-09-25 13:42
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.00
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 16748464 kB
MemAvailable 31171292 kB
Buffers 0 kB
Cached 14909928 kB
SwapCached 4872 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-02-09 10:26:56
DTS_END                         2021-02-09 10:26:56.3300
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/55720
TRN_ID                          224963
ATT_ID                          637
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: 8648.74 24925 1037849
customer order: creation 160.48 484 19257
customer order: refuse 13.15 313 1578
order to supplier: creation 42.73 1404 5128
order to supplier: removal 3.30 124479 396
invoice (draft): creation 44.23 3810 5308
invoice (draft): removal 49.52 892 5942
invoice accept: apply 38.79 18164 4655
invoice accept: cancel 44.41 959 5329
customer reserve: creation 3612.00 176 433440
customer reserve: removal 4359.10 76 523092
realization accept: apply 78.04 202 9365
realization accept: cancel 84.58 117 10149
payment from customer: creation 54.33 105 6519
payment from customer: removal 4.97 95 596
payment to supplier: creation 46.11 127 5533
payment to supplier: removal 3.08 94 369
service: total inventory turnovers 3.51 3613 421
service: total monetary turnovers 5.56 640 667
service: aggregate perf. data 0.84 14684 101
service: refresh index statistics 0.03 328065 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 09-02-2021 10:28:03 to 09-02-2021 10:28:06.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4201
TEST_TIME 2 4206
TEST_TIME 3 5339
TEST_TIME 4 5089
TEST_TIME 5 5054
TEST_TIME 6 4951
TEST_TIME 7 5033
TEST_TIME 8 4932
TEST_TIME 9 5375
TEST_TIME 10 5498
TEST_TIME 11 5632
TEST_TIME 12 5885
TEST_TIME 13 6125
TEST_TIME 14 6762
TEST_TIME 15 6815
TEST_TIME 16 7005
TEST_TIME 17 7288
TEST_TIME 18 7849
TEST_TIME 19 8259
TEST_TIME 20 8323
TEST_TIME 21 8355
TEST_TIME 22 8594
TEST_TIME 23 9247
TEST_TIME 24 9682
TEST_TIME 25 10078
TEST_TIME 26 11210
TEST_TIME 27 11182
TEST_TIME 28 10968
TEST_TIME 29 10719
TEST_TIME 30 10529
TEST_TIME 31 10568
TEST_TIME 32 10369
TEST_TIME 33 10144
TEST_TIME 34 10074
TEST_TIME 35 10050
TEST_TIME 36 9900
TEST_TIME 37 9854
TEST_TIME 38 9697
TEST_TIME 39 9727
TEST_TIME 40 9588
TEST_TIME 41 9580
TEST_TIME 42 9486
TEST_TIME 43 9353
TEST_TIME 44 9314
TEST_TIME 45 9278
TEST_TIME 46 9198
TEST_TIME 47 9170
TEST_TIME 48 9219
TEST_TIME 49 9372
TEST_TIME 50 9306
TEST_TIME 51 9376
TEST_TIME 52 9300
TEST_TIME 53 9290
TEST_TIME 54 9359
TEST_TIME 55 9295
TEST_TIME 56 9403
TEST_TIME 57 9328
TEST_TIME 58 9185
TEST_TIME 59 9084
TEST_TIME 60 9038
TEST_TIME 61 8966
TEST_TIME 62 8939
TEST_TIME 63 8855
TEST_TIME 64 8828
TEST_TIME 65 8817
TEST_TIME 66 8785
TEST_TIME 67 8762
TEST_TIME 68 8780
TEST_TIME 69 8775
TEST_TIME 70 8827
TEST_TIME 71 8899
TEST_TIME 72 8909
TEST_TIME 73 8850
TEST_TIME 74 8899
TEST_TIME 75 8930
TEST_TIME 76 8908
TEST_TIME 77 8966
TEST_TIME 78 8900
TEST_TIME 79 8845
TEST_TIME 80 8780
TEST_TIME 81 8721
TEST_TIME 82 8705
TEST_TIME 83 8696
TEST_TIME 84 8693
TEST_TIME 85 8663
TEST_TIME 86 8632
TEST_TIME 87 8594
TEST_TIME 88 8658
TEST_TIME 89 8664
TEST_TIME 90 8756
TEST_TIME 91 8736
TEST_TIME 92 8852
TEST_TIME 93 8945
TEST_TIME 94 8947
TEST_TIME 95 8913
TEST_TIME 96 8883
TEST_TIME 97 8861
TEST_TIME 98 8877
TEST_TIME 99 8850
TEST_TIME 100 8863
TEST_TIME 101 8931
TEST_TIME 102 8906
TEST_TIME 103 8907
TEST_TIME 104 8894
TEST_TIME 105 8948
TEST_TIME 106 8956
TEST_TIME 107 8934
TEST_TIME 108 8855
TEST_TIME 109 8830
TEST_TIME 110 8792
TEST_TIME 111 8740
TEST_TIME 112 8688
TEST_TIME 113 8691
TEST_TIME 114 8684
TEST_TIME 115 8660
TEST_TIME 116 8626
TEST_TIME 117 8625
TEST_TIME 118 8638
TEST_TIME 119 8635
TEST_TIME 120 8652

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 09-02-2021 10:28:07 to 09-02-2021 10:28:10.

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 1439101 1439101 0 0.00 0 32420 7 0 0
doc_list_biud 1439101 1439101 0 0.00 0 37858 34 0 0
sp_add_invoice_to_stock 4744 4655 89 1.87 41 164302 18164 0 89
sp_cancel_adding_invoice 5329 5329 0 0.00 5 76773 959 0 0
sp_cancel_client_order 1578 1578 0 0.00 5 11049 314 0 0
sp_cancel_customer_reserve 523092 523092 0 0.00 1 32271 76 0 0
sp_cancel_pay_from_customer 596 596 0 0.00 3 9526 96 0 0
sp_cancel_pay_to_supplier 369 369 0 0.00 3 5071 94 0 0
sp_cancel_supplier_invoice 5949 5942 7 0.11 3 37468 892 0 7
sp_cancel_supplier_order 396 396 0 0.00 10 1311992 124480 0 0
sp_cancel_write_off 10149 10149 0 0.00 1 10547 118 0 0
sp_client_order 19257 19257 0 0.00 4 57052 485 0 0
sp_customer_reserve 433440 433440 0 0.00 0 55715 176 0 0
sp_fill_shopping_cart 29680 29680 0 0.00 1 24257 54 0 0
sp_fill_shopping_cart_clo_res 433434 433434 0 0.00 0 20366 18 0 0
sp_get_clo_for_invoice 4648 4648 0 0.00 0 34294 429 0 0
sp_kill_cost_storno 16443 16443 0 0.00 0 22799 62 0 0
sp_kill_qstorno_ret_qs2qd 1062671 1062671 0 0.00 0 37452 40 0 0
sp_kill_qty_storno 560499 560499 0 0.00 0 37858 87 0 0
sp_lock_selected_doc 19188 19188 0 0.00 0 8785 5 0 0
sp_make_cost_storno 26065 26065 0 0.00 0 13092 61 0 0
sp_multiply_rows_for_pdistr 26065 26065 0 0.00 0 9868 34 0 0
sp_multiply_rows_for_qdistr 19257 19257 0 0.00 0 18063 267 0 0
sp_pay_from_customer 6519 6519 0 0.00 4 19235 106 0 0
sp_pay_to_supplier 5533 5533 0 0.00 4 13363 128 0 0
sp_payment_common 12052 12052 0 0.00 1 7892 20 0 0
sp_qd_handle_on_cancel_clo 1578 1578 0 0.00 0 11041 286 0 0
sp_qd_handle_on_invoice_upd_sts 9977 9977 0 0.00 0 26171 411 0 0
sp_qd_handle_on_reserve_upd_sts 9365 9365 0 0.00 0 37857 69 0 0
sp_reserve_write_off 9365 9365 0 0.00 7 37886 202 0 0
sp_supplier_invoice 5308 5308 0 0.00 8 115484 3810 0 0
sp_supplier_order 5128 5128 0 0.00 6 26535 1404 0 0
srv_aggregate_perf_data 101 101 0 0.00 3020 147822 14685 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2148 123015 47863 0 0
srv_find_qd_qs_mism 993299 993299 0 0.00 0 32417 5 0 0
srv_make_invnt_saldo 421 421 0 0.00 32 106390 3614 0 0
srv_make_money_saldo 667 667 0 0.00 1 17881 640 0 0
srv_recalc_idx_stat 4 4 0 0.00 144422 504675 328065 0 0
t$perf-abend:sp_add_invoice_to_stock 89 89 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4655 4655 0 0.00 0 10755 165 0 0
t$perf-norm:sp_cancel_adding_invoice 368 368 0 0.00 1 5347 101 0 0
t$perf-norm:sp_cancel_client_order 1578 1578 0 0.00 0 1057 8 0 0
t$perf-norm:sp_cancel_customer_reserve 596 596 0 0.00 0 2935 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 596 596 0 0.00 0 2834 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 369 369 0 0.00 0 1073 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 382 382 0 0.00 0 604 12 0 0
t$perf-norm:sp_cancel_supplier_order 396 396 0 0.00 0 24156 1339 0 0
t$perf-norm:sp_cancel_write_off 891 891 0 0.00 0 3836 12 0 0
t$perf-norm:sp_client_order 19257 19257 0 0.00 0 12407 16 0 0
t$perf-norm:sp_customer_reserve 908 908 0 0.00 0 2916 15 0 0
t$perf-norm:sp_pay_from_customer 6519 6519 0 0.00 0 5830 9 0 0
t$perf-norm:sp_pay_to_supplier 5533 5533 0 0.00 0 18336 10 0 0
t$perf-norm:sp_reserve_write_off 9365 9365 0 0.00 0 13712 12 0 0
t$perf-norm:sp_supplier_invoice 5308 5308 0 0.00 0 12181 21 0 0
t$perf-norm:sp_supplier_order 5128 5128 0 0.00 0 5255 16 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 33 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 413 10 0 0
t$perf-norm:srv_make_invnt_saldo 421 421 0 0.00 0 1040 10 0 0
t$perf-norm:srv_make_money_saldo 667 667 0 0.00 0 3251 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 9472 9294 178 1.87 0 8221 17 0 178
v_all_customers 35102 35102 0 0.00 0 21 1 0 0
v_all_suppliers 28878 28878 0 0.00 0 84 1 0 0
v_all_wares 38514 38514 0 0.00 0 53 1 0 0
v_cancel_adding_invoice 736 736 0 0.00 0 2111 16 0 0
v_cancel_client_order 3156 3156 0 0.00 0 5059 7 0 0
v_cancel_customer_prepayment 1192 1192 0 0.00 0 1817 7 0 0
v_cancel_customer_reserve 1192 1192 0 0.00 0 1862 6 0 0
v_cancel_payment_to_supplier 738 738 0 0.00 0 1201 7 0 0
v_cancel_supplier_invoice 778 764 14 1.79 0 8080 34 0 14
v_cancel_supplier_order 792 792 0 0.00 0 1818 9 0 0
v_cancel_write_off 1782 1782 0 0.00 0 2574 19 0 0
v_max_id_clo_ord 5125 5125 0 0.00 0 854 2 0 0
v_max_id_clo_res 908 908 0 0.00 0 4247 19 0 0
v_max_id_ord_sup 5298 5298 0 0.00 0 6239 4 0 0
v_max_non_paid_invoice 1517 1517 0 0.00 0 987 3 0 0
v_max_non_paid_realizn 3470 3470 0 0.00 0 3298 4 0 0
v_min_id_clo_ord 5125 5125 0 0.00 0 3623 5 0 0
v_min_id_clo_res 908 908 0 0.00 0 5035 14 0 0
v_min_id_ord_sup 5298 5298 0 0.00 0 6738 13 0 0
v_min_non_paid_invoice 5533 5533 0 0.00 0 7101 13 0 0
v_min_non_paid_realizn 6519 6519 0 0.00 0 7888 14 0 0
v_our_firm 9510 9510 0 0.00 0 24 1 0 0
v_reserve_write_off 18730 18730 0 0.00 0 4855 6 0 0
x_lock_depdocs_on_canc_invoice 10889 10889 0 0.00 0 17300 118 0 0
x_lock_depdocs_on_canc_sup_ord 396 396 0 0.00 0 17900 377 0 0
x_make_qstorno_1000_1200 5128 5128 0 0.00 2 26386 1330 0 0
x_make_qstorno_1200_2000 5308 5308 0 0.00 5 115176 3586 0 0
x_make_qstorno_2100_3300 433440 433440 0 0.00 0 55698 156 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-02-2021 10:28:11 to 09-02-2021 10:28:15.

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
07:57:00 2 8192000000 8797595008 8808484864 3189104 0.038 1 1 2 0 3286720 61840 35776
07:58:00 25311 8192000000 9713982016 10004635648 786957360 8.764 100 90 180 0 817883008 8332016 22593632
07:59:28 27499 8192000000 9763527168 10066780160 833583664 9.235 100 91 180 2 868746240 13766800 21395776
08:01:05 12632 8192000000 9815497616 10132160512 874655456 9.646 100 82 164 0 918361920 24718704 18987760
08:02:23 15536 8192000000 9861314864 10192207872 911095856 10.008 100 78 155 1 968136224 39912016 17128352
08:03:42 10671 8192000000 9906640816 10248392704 957103376 10.461 100 76 154 2 1014151280 41284288 15763616
08:04:56 4860 8192000000 9974096576 10325712896 996696640 10.846 100 81 160 0 1082724336 69834992 16192704
08:06:02 3956 8192000000 9999378400 10359627776 1020452960 11.076 100 82 163 0 1114640576 78152784 16034832
08:07:08 1485 8192000000 10029943776 10393325568 1043169248 11.295 100 94 188 1 1141410240 79254992 18986000
08:08:10 20773 8192000000 10009560080 10391547904 1058510816 11.442 100 63 128 1 1125305360 54630464 12164080
08:09:32 4584 8192000000 9980712352 10380951552 1072053152 11.572 100 24 48 1 1115580752 38361664 5165936
08:10:37 3978 8192000000 10005868208 10397089792 1084647648 11.692 100 56 108 1 1119925168 23058640 12218880
08:11:42 14207 8192000000 10026196640 10440728576 1091995904 11.762 100 31 60 0 1113045872 15042224 6007744
08:13:28 12482 8192000000 10004061936 10406891520 1094438864 11.785 100 19 37 1 1113996048 15884224 3672960
08:14:42 6794 8192000000 10023268848 10421862400 1102958544 11.866 100 27 54 2 1132580160 23980400 5641216
08:15:51 3502 8192000000 10033251920 10428190720 1094888672 11.789 99 43 87 1 1129690016 26320736 8480608
08:16:54 43553 8192000000 10033615584 10448162816 1081590672 11.663 98 25 50 0 1110441872 23316896 5534304
08:18:41 3989 8192000000 9965424560 10346168320 1002401536 10.902 93 41 82 1 1029132848 16862096 9869216
08:19:46 6214 8192000000 9964996096 10355122176 1040433600 11.269 99 37 74 1 1074586080 26138032 8014448
08:20:55 4001 8192000000 9950235616 10327203840 1021377392 11.085 99 50 98 1 1047176384 13124432 12674560
08:22:02 27506 8192000000 9939403856 10335350784 997847872 10.858 96 28 56 0 1029344656 25268720 6228064
08:23:30 6413 8192000000 9964147328 10353172480 1044517840 11.308 100 45 91 0 1074322832 18611376 11193616
08:24:39 12112 8192000000 9974274864 10382934016 1061407680 11.470 100 21 42 0 1085362224 19486048 4468496
08:25:56 54540 8192000000 9985983840 10397024256 1073225648 11.583 100 27 54 0 1110313760 31383168 5704944
08:27:57 9855 8192000000 10040978128 10443350016 1100358528 11.841 100 45 86 2 1150996368 41222464 9415376
08:29:12 14620 8192000000 10079348880 10480943104 1124676528 12.071 100 49 96 0 1189161760 55236496 9248736
08:30:30 15213 8192000000 10106642512 10507546624 1135959376 12.178 100 51 103 0 1215517568 68806560 10751632
08:31:49 14093 8192000000 10160170896 10572169216 1146758256 12.279 100 62 123 0 1273844352 113879504 13206592
08:33:12 8117 8192000000 10218369744 10630991872 1160643616 12.409 100 67 129 1 1315735792 140619936 14472240
08:34:30 19160 8192000000 10256422912 10682023936 1170007024 12.497 100 63 126 0 1370969872 186595360 14367488
08:35:54 4300 8192000000 10292234960 10702299136 1175428640 12.548 100 70 138 1 1398610560 207632448 15549472
08:37:02 6000 8192000000 10331576160 10748100608 1187038256 12.656 100 56 110 3 1432026144 232567104 12420784
08:38:14 2601 8192000000 10388152816 10806104064 1195840320 12.738 100 67 134 0 1495598624 283926224 15832080
08:39:24 4742 8192000000 10391670080 10815959040 1166036560 12.460 98 71 142 0 1472447264 289674240 16736464
08:40:36 2607 8192000000 10391537312 10823602176 1174479584 12.539 99 61 123 1 1500251744 310806576 14965584
08:41:46 2174 8192000000 10411121344 10818646016 1172904128 12.524 100 88 175 1 1511407952 317963808 20540016
08:42:55 6418 8192000000 10447506512 10869043200 1174698240 12.541 100 87 172 0 1561229888 366391584 20140064
08:44:07 2146 8192000000 10472683152 10895163392 1166243232 12.462 99 80 158 0 1573636864 388378784 19014848
08:45:13 7189 8192000000 10421290928 10851405824 1154465136 12.351 99 48 94 0 1507246400 340817856 11963408
08:46:21 11703 8192000000 10404744704 10834616320 1156740048 12.373 100 39 77 0 1513963536 346397232 10826256
08:47:42 5292 8192000000 10463942720 10881597440 1173425952 12.529 100 74 142 2 1553248608 362747248 17075408
08:48:50 10636 8192000000 10444943392 10910478336 1152815200 12.336 99 34 68 2 1566063808 403838608 9410000
08:50:10 22874 8192000000 10365104768 10848477184 1125379040 12.078 98 25 48 3 1480427520 348084880 6963600
08:51:48 51214 8192000000 10078924816 10678571008 1101070016 11.848 98 12 24 0 1298790608 194859616 2860976
08:53:40 51690 8192000000 10121715024 10546040832 1131699680 12.137 100 31 61 0 1179707152 41400048 6607424
08:55:37 81754 8192000000 10128040576 10587234304 1128475696 12.107 100 35 65 2 1233512912 97467824 7569392
08:58:23 123015 8192000000 10090207712 10554126336 1092998224 11.771 96 28 56 0 1155995248 57473872 5523152
09:01:28 82134 8192000000 10057912880 10518036480 1070892832 11.561 95 14 25 1 1159355664 85583216 2879616
09:04:20 74189 8192000000 10092241264 10549424128 1114454272 11.975 99 11 22 0 1198602000 81797872 2349856
09:06:35 56062 8192000000 10087022976 10562486272 1129434864 12.116 100 18 36 1 1199073168 65798608 3839696
09:08:44 56086 8192000000 10046599920 10539995136 1102149664 11.858 98 8 16 0 1153388544 49696880 1542000
09:10:41 38828 8192000000 10079893408 10504626176 1103714176 11.873 99 48 96 1 1151851712 37911968 10225568
09:12:35 63903 8192000000 10107580192 10567168000 1127419696 12.097 100 18 36 2 1198441840 67455936 3566208
09:14:40 63365 8192000000 10082512992 10553511936 1111616112 11.948 98 27 54 1 1213306768 95805808 5884848
09:16:47 22029 8192000000 10115204832 10542985216 1133795056 12.157 100 47 94 0 1195274416 52138656 9340704
09:18:15 25467 8192000000 10080394736 10520326144 1117255984 12.001 99 28 55 1 1153539744 31034064 5249696
09:19:41 90070 8192000000 10079258032 10552184832 1143604448 12.249 100 17 34 0 1209178400 62386672 3187280
09:22:12 120587 8192000000 10071631600 10578739200 1119116176 12.019 98 23 46 1 1194716416 70882544 4717696
09:25:32 56021 8192000000 10068457392 10520588288 1128717632 12.109 99 10 20 0 1151055280 19560800 2776848
09:27:31 53274 8192000000 10085747040 10569035776 1134598528 12.165 100 13 26 0 1191284704 53851056 2835120
09:29:33 39285 8192000000 10067267072 10525581312 1129790208 12.119 99 10 20 0 1158417488 27110912 1516368
09:31:22 49108 8192000000 10052798832 10495508480 1093388768 11.775 96 17 34 0 1117082528 20344144 3349616
09:33:12 63845 8192000000 10126132160 10582388736 1093474480 11.776 96 34 68 2 1159252352 58321360 7456512
09:35:33 69971 8192000000 10120169056 10567139328 1098055856 11.819 97 28 56 1 1179590240 75065328 6469056
09:37:49 94702 8192000000 10062003808 10518228992 1053798176 11.397 94 22 44 1 1087854992 29281600 4775216
09:40:24 71841 8192000000 10034719056 10501771264 1065052752 11.505 96 15 30 0 1133342064 65251984 3037328
09:42:43 44158 8192000000 10078616656 10511646720 1090238544 11.745 98 29 58 0 1153608240 57682912 5686784
09:44:35 121739 8192000000 10012595424 10477441024 1065288080 11.507 96 15 30 0 1106603712 37976128 3339504
09:47:41 50029 8192000000 10038409024 10497462272 1086351424 11.708 98 7 14 1 1122848160 34745040 1751696
09:49:37 35027 8192000000 10076230512 10518323200 1115868640 11.988 100 24 47 0 1157603568 37198720 4536208
09:51:19 47901 8192000000 10108556784 10549178368 1084879456 11.694 96 34 68 1 1169717280 77103184 7734640
09:53:12 51566 8192000000 10203586960 10640683008 1121389696 12.040 99 33 65 3 1271664336 142730592 7544048
09:55:04 54189 8192000000 10161552112 10607267840 1134697504 12.166 100 22 44 0 1262774048 123105616 4970928
09:57:06 102920 8192000000 10110069968 10627719168 1064856656 11.503 94 12 24 0 1177115440 109588576 2670208
09:59:50 65386 8192000000 10086813888 10552139776 1091997296 11.762 97 5 10 0 1141469904 48699312 773296
10:01:56 43069 8192000000 10080128496 10555072512 1103052464 11.867 98 9 18 0 1161597120 56977344 1567312
10:03:40 57799 8192000000 10081309472 10558799872 1107902272 11.913 98 35 70 1 1166409504 50527280 7979952
10:05:39 98985 8192000000 10135618704 10632421376 1109003264 11.923 98 23 46 3 1215250640 101446480 4800896
10:08:31 24215 8192000000 10066518160 10492985344 1100892912 11.846 97 24 48 0 1134180608 28726480 4561216
10:09:58 114476 8192000000 10071513536 10571870208 1098309520 11.822 97 15 30 0 1158227168 57026304 2891344
10:12:55 119876 8192000000 10100954464 10561277952 1137450512 12.192 100 22 44 1 1205740000 63621776 4667712
10:16:29 46333 8192000000 10080748832 10555715584 1144891008 12.262 100 5 10 0 1180911840 34527616 1493216
10:18:15 73249 8192000000 10086078416 10574708736 1122443520 12.050 98 8 15 0 1176298688 52520688 1334480
10:20:32 52427 8192000000 10080590480 10512416768 1144955376 12.262 100 27 54 1 1172005280 20995856 6054048
10:22:32 26076 8192000000 10093660288 10538991616 1139708560 12.213 99 26 50 1 1186139808 41339952 5091296
10:24:07 70916 8192000000 10094198528 10564444160 1118482304 12.013 97 22 44 0 1177204560 53890720 4831536
10:26:23 46702 8192000000 9197311840 9521922048 319978144 3.759 28 6 10 1 375929312 54656800 1294368

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 09-02-2021 10:28:17 to 09-02-2021 10:28:32.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 89 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 09-02-2021 10:28:33 to 09-02-2021 10:28:35.

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          225245
MON$OLDEST_ACTIVE               225246
MON$OLDEST_SNAPSHOT             225246
MON$NEXT_TRANSACTION            225246
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                       2076960
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             781
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2365 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2365 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2365 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.2365 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 ---
:151: 
:152: mscs-test-161.budzdorov.ru    Tue Feb  9 10:27:24 2021
:153:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:154: 
--- end of diff output ---

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

09.02.21 10:28:36. 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-02-09 10:28:39 No severe PSQL-related problems occured
09.02.21 10:28:40. End of report.