Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.12.2020 07:29
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 16783884 kB
MemAvailable 31424908 kB
Buffers 0 kB
Cached 14828276 kB
SwapCached 3696 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 2020-12-22 07:26:48
DTS_END                         2020-12-22 07:26:48.4920
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/59790
TRN_ID                          236157
ATT_ID                          667
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: 8671.86 19269 1040623
customer order: creation 171.58 427 20589
customer order: refuse 14.52 319 1742
order to supplier: creation 44.62 1407 5354
order to supplier: removal 3.28 113399 393
invoice (draft): creation 46.09 4337 5531
invoice (draft): removal 47.74 1061 5729
invoice accept: apply 40.94 16878 4913
invoice accept: cancel 42.43 933 5092
customer reserve: creation 3765.50 166 451860
customer reserve: removal 4212.77 69 505533
realization accept: apply 83.96 167 10075
realization accept: cancel 72.66 125 8719
payment from customer: creation 58.87 125 7064
payment from customer: removal 5.46 74 655
payment to supplier: creation 47.33 128 5679
payment to supplier: removal 3.28 86 393
service: total inventory turnovers 3.90 2905 468
service: total monetary turnovers 6.03 515 724
service: aggregate perf. data 0.88 11247 106
service: refresh index statistics 0.03 231018 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-12-2020 07:29:24 to 22-12-2020 07:29:26.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4927
TEST_TIME 2 3938
TEST_TIME 3 4025
TEST_TIME 4 4200
TEST_TIME 5 4707
TEST_TIME 6 4552
TEST_TIME 7 4857
TEST_TIME 8 4917
TEST_TIME 9 5744
TEST_TIME 10 6172
TEST_TIME 11 6678
TEST_TIME 12 6810
TEST_TIME 13 6779
TEST_TIME 14 6989
TEST_TIME 15 7166
TEST_TIME 16 7638
TEST_TIME 17 7777
TEST_TIME 18 8158
TEST_TIME 19 8264
TEST_TIME 20 8252
TEST_TIME 21 8272
TEST_TIME 22 8283
TEST_TIME 23 8344
TEST_TIME 24 8798
TEST_TIME 25 9273
TEST_TIME 26 9827
TEST_TIME 27 10226
TEST_TIME 28 11013
TEST_TIME 29 10658
TEST_TIME 30 10435
TEST_TIME 31 10404
TEST_TIME 32 10399
TEST_TIME 33 10136
TEST_TIME 34 10055
TEST_TIME 35 9920
TEST_TIME 36 9704
TEST_TIME 37 9618
TEST_TIME 38 9608
TEST_TIME 39 9453
TEST_TIME 40 9362
TEST_TIME 41 9352
TEST_TIME 42 9252
TEST_TIME 43 9197
TEST_TIME 44 9165
TEST_TIME 45 9013
TEST_TIME 46 9085
TEST_TIME 47 9194
TEST_TIME 48 9145
TEST_TIME 49 9095
TEST_TIME 50 9148
TEST_TIME 51 9282
TEST_TIME 52 9236
TEST_TIME 53 9215
TEST_TIME 54 9164
TEST_TIME 55 9123
TEST_TIME 56 9219
TEST_TIME 57 9231
TEST_TIME 58 9211
TEST_TIME 59 9300
TEST_TIME 60 9156
TEST_TIME 61 9037
TEST_TIME 62 8968
TEST_TIME 63 8932
TEST_TIME 64 8847
TEST_TIME 65 8799
TEST_TIME 66 8831
TEST_TIME 67 8790
TEST_TIME 68 8841
TEST_TIME 69 8794
TEST_TIME 70 8976
TEST_TIME 71 8930
TEST_TIME 72 8979
TEST_TIME 73 8986
TEST_TIME 74 8931
TEST_TIME 75 8998
TEST_TIME 76 9038
TEST_TIME 77 9080
TEST_TIME 78 9063
TEST_TIME 79 8959
TEST_TIME 80 8893
TEST_TIME 81 8837
TEST_TIME 82 8760
TEST_TIME 83 8720
TEST_TIME 84 8686
TEST_TIME 85 8671
TEST_TIME 86 8655
TEST_TIME 87 8623
TEST_TIME 88 8656
TEST_TIME 89 8655
TEST_TIME 90 8687
TEST_TIME 91 8662
TEST_TIME 92 8668
TEST_TIME 93 8681
TEST_TIME 94 8740
TEST_TIME 95 8759
TEST_TIME 96 8766
TEST_TIME 97 8770
TEST_TIME 98 8867
TEST_TIME 99 8815
TEST_TIME 100 8744
TEST_TIME 101 8724
TEST_TIME 102 8711
TEST_TIME 103 8661
TEST_TIME 104 8644
TEST_TIME 105 8652
TEST_TIME 106 8652
TEST_TIME 107 8631
TEST_TIME 108 8652
TEST_TIME 109 8668
TEST_TIME 110 8629
TEST_TIME 111 8650
TEST_TIME 112 8711
TEST_TIME 113 8770
TEST_TIME 114 8780
TEST_TIME 115 8801
TEST_TIME 116 8787
TEST_TIME 117 8765
TEST_TIME 118 8734
TEST_TIME 119 8693
TEST_TIME 120 8673

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 22-12-2020 07:29:28 to 22-12-2020 07:29:31.

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 1457890 1457890 0 0.00 0 14994 6 0 0
doc_list_biud 1457876 1457876 0 0.00 0 46597 31 0 0
sp_add_invoice_to_stock 5005 4913 92 1.83 44 150374 16878 0 92
sp_cancel_adding_invoice 5092 5092 0 0.00 4 71432 933 0 0
sp_cancel_client_order 1742 1742 0 0.00 4 7899 320 0 0
sp_cancel_customer_reserve 505533 505533 0 0.00 1 35832 70 0 0
sp_cancel_pay_from_customer 655 655 0 0.00 3 1971 74 0 0
sp_cancel_pay_to_supplier 393 393 0 0.00 3 5348 86 0 0
sp_cancel_supplier_invoice 5738 5729 9 0.15 2 22027 1061 0 9
sp_cancel_supplier_order 393 393 0 0.00 6 976319 113399 0 0
sp_cancel_write_off 8719 8719 0 0.00 1 13790 125 0 0
sp_client_order 20589 20589 0 0.00 4 12450 428 0 0
sp_customer_reserve 451860 451860 0 0.00 0 60408 166 0 0
sp_fill_shopping_cart 31467 31467 0 0.00 1 25204 51 0 0
sp_fill_shopping_cart_clo_res 451859 451859 0 0.00 0 37375 18 0 0
sp_get_clo_for_invoice 4912 4912 0 0.00 0 21240 378 0 0
sp_kill_cost_storno 14859 14859 0 0.00 0 8953 58 0 0
sp_kill_qstorno_ret_qs2qd 1025907 1025907 0 0.00 0 35829 38 0 0
sp_kill_qty_storno 542187 542187 0 0.00 0 46596 83 0 0
sp_lock_selected_doc 20621 20621 0 0.00 0 7856 5 0 0
sp_make_cost_storno 27730 27730 0 0.00 0 14144 53 0 0
sp_multiply_rows_for_pdistr 27722 27722 0 0.00 0 9097 28 0 0
sp_multiply_rows_for_qdistr 20589 20589 0 0.00 0 10255 249 0 0
sp_pay_from_customer 7064 7064 0 0.00 4 14190 126 0 0
sp_pay_to_supplier 5679 5679 0 0.00 5 13626 128 0 0
sp_payment_common 12738 12738 0 0.00 1 13671 23 0 0
sp_qd_handle_on_cancel_clo 1742 1742 0 0.00 0 7886 299 0 0
sp_qd_handle_on_invoice_upd_sts 10001 10001 0 0.00 0 46596 389 0 0
sp_qd_handle_on_reserve_upd_sts 10070 10070 0 0.00 0 12015 50 0 0
sp_reserve_write_off 10075 10075 0 0.00 7 14156 167 0 0
sp_supplier_invoice 5531 5531 0 0.00 7 113147 4338 0 0
sp_supplier_order 5354 5354 0 0.00 5 69055 1408 0 0
srv_aggregate_perf_data 106 106 0 0.00 2742 105780 11248 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 1579 153420 48702 0 0
srv_find_qd_qs_mism 993142 993142 0 0.00 0 14991 5 0 0
srv_make_invnt_saldo 468 468 0 0.00 77 87646 2905 0 0
srv_make_money_saldo 724 724 0 0.00 1 24311 515 0 0
srv_recalc_idx_stat 4 4 0 0.00 50520 345759 231019 0 0
t$perf-abend:sp_add_invoice_to_stock 92 92 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 4913 4913 0 0.00 0 30787 164 0 0
t$perf-norm:sp_cancel_adding_invoice 391 391 0 0.00 0 1403 63 0 0
t$perf-norm:sp_cancel_client_order 1742 1742 0 0.00 0 1710 12 0 0
t$perf-norm:sp_cancel_customer_reserve 710 710 0 0.00 0 1553 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 655 655 0 0.00 0 480 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 393 393 0 0.00 0 319 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 416 416 0 0.00 0 526 12 0 0
t$perf-norm:sp_cancel_supplier_order 393 393 0 0.00 0 14626 1308 0 0
t$perf-norm:sp_cancel_write_off 942 942 0 0.00 0 2727 8 0 0
t$perf-norm:sp_client_order 20589 20589 0 0.00 0 9174 16 0 0
t$perf-norm:sp_customer_reserve 952 952 0 0.00 0 950 4 0 0
t$perf-norm:sp_pay_from_customer 7064 7064 0 0.00 0 10444 9 0 0
t$perf-norm:sp_pay_to_supplier 5679 5679 0 0.00 0 10481 11 0 0
t$perf-norm:sp_reserve_write_off 10075 10075 0 0.00 0 6309 10 0 0
t$perf-norm:sp_supplier_invoice 5531 5531 0 0.00 0 6256 20 0 0
t$perf-norm:sp_supplier_order 5354 5354 0 0.00 0 6110 14 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 722 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 386 7 0 0
t$perf-norm:srv_make_invnt_saldo 468 468 0 0.00 0 471 6 0 0
t$perf-norm:srv_make_money_saldo 724 724 0 0.00 0 930 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10002 9818 184 1.83 0 4154 16 0 184
v_all_customers 36876 36876 0 0.00 0 92 1 0 0
v_all_suppliers 29724 29724 0 0.00 0 18 1 0 0
v_all_wares 41178 41178 0 0.00 0 158 1 0 0
v_cancel_adding_invoice 782 782 0 0.00 0 1722 8 0 0
v_cancel_client_order 3484 3484 0 0.00 0 2353 5 0 0
v_cancel_customer_prepayment 1310 1310 0 0.00 0 1887 11 0 0
v_cancel_customer_reserve 1420 1420 0 0.00 0 2846 7 0 0
v_cancel_payment_to_supplier 786 786 0 0.00 0 3449 11 0 0
v_cancel_supplier_invoice 850 832 18 2.11 0 2917 16 0 18
v_cancel_supplier_order 786 786 0 0.00 0 827 4 0 0
v_cancel_write_off 1884 1884 0 0.00 0 6552 21 0 0
v_max_id_clo_ord 5353 5353 0 0.00 0 4283 2 0 0
v_max_id_clo_res 952 952 0 0.00 0 2224 13 0 0
v_max_id_ord_sup 5525 5525 0 0.00 0 6139 8 0 0
v_max_non_paid_invoice 1690 1690 0 0.00 0 3182 3 0 0
v_max_non_paid_realizn 4014 4014 0 0.00 0 1599 4 0 0
v_min_id_clo_ord 5353 5353 0 0.00 0 5083 9 0 0
v_min_id_clo_res 952 952 0 0.00 0 2280 5 0 0
v_min_id_ord_sup 5525 5525 0 0.00 0 7957 14 0 0
v_min_non_paid_invoice 5674 5674 0 0.00 0 7237 11 0 0
v_min_non_paid_realizn 7063 7063 0 0.00 0 5918 17 0 0
v_our_firm 10400 10400 0 0.00 0 14 1 0 0
v_reserve_write_off 20140 20140 0 0.00 0 3806 6 0 0
x_lock_depdocs_on_canc_invoice 10405 10405 0 0.00 0 17523 111 0 0
x_lock_depdocs_on_canc_sup_ord 393 393 0 0.00 0 5942 292 0 0
x_make_qstorno_1000_1200 5354 5354 0 0.00 2 69002 1326 0 0
x_make_qstorno_1200_2000 5531 5531 0 0.00 3 113132 4134 0 0
x_make_qstorno_2100_3300 451860 451860 0 0.00 0 60406 146 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-12-2020 07:29:32 to 22-12-2020 07:29:36.

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
04:56:52 2 8192000000 8796637808 8807108608 3167696 0.038 1 1 2 0 3265392 61840 35856
04:57:52 27222 8192000000 9705585936 9997873152 783743712 8.731 100 88 176 0 814055088 7559792 22751584
04:59:25 20716 8192000000 9747049968 10045276160 817900880 9.077 100 91 182 0 853057696 12928688 22228128
05:00:55 14723 8192000000 9776820768 10093580288 850379040 9.404 100 79 158 0 889489200 20877984 18232176
05:02:22 15949 8192000000 9861677648 10192977920 917858800 10.075 100 81 162 0 973543056 37813152 17871104
05:03:49 8102 8192000000 9919079648 10269368320 966268544 10.550 100 68 136 0 1037274112 57135248 13870320
05:04:59 5348 8192000000 9976409600 10331660288 1004221040 10.919 100 79 158 0 1087917552 67899600 15796912
05:06:07 2799 8192000000 9988488400 10354847744 1033314880 11.200 100 77 154 1 1101911664 52839616 15757168
05:07:11 2710 8192000000 10022000864 10392256512 1053396576 11.393 100 87 175 1 1141246608 70304928 17545104
05:08:15 29047 8192000000 9982668624 10363437056 1062267152 11.478 100 66 133 3 1114300560 37553680 14479728
05:09:45 7819 8192000000 10005867552 10394968064 1070540224 11.557 100 34 68 1 1102747840 24734096 7473520
05:10:53 8708 8192000000 9989725776 10386276352 1081170896 11.659 100 39 78 0 1106275392 16627360 8477136
05:12:05 4838 8192000000 10000923936 10395353088 1092429072 11.766 100 47 95 0 1124615552 22015440 10171040
05:13:10 38022 8192000000 10012167824 10420850688 1090209552 11.745 100 38 76 1 1108671680 11114496 7347632
05:14:58 4942 8192000000 10020080192 10422079488 1097735568 11.816 100 49 100 0 1134190784 25485904 10969312
05:16:05 5458 8192000000 10014530752 10413752320 1096225232 11.802 100 48 97 2 1132178800 25185056 10768512
05:17:11 8452 8192000000 10000967296 10397388800 1061860448 11.474 98 46 93 3 1095767216 23328208 10578560
05:18:20 25206 8192000000 9984983088 10383523840 1043621504 11.299 98 25 50 2 1080596448 32218240 4756704
05:19:47 4219 8192000000 9943848880 10317000704 1003752192 10.915 99 48 94 1 1032321888 17907728 10661968
05:20:51 6337 8192000000 9925172880 10302234624 984846128 10.731 98 50 99 2 1014750304 18276640 11627536
05:21:58 69729 8192000000 9944972960 10349449216 1036152480 11.228 100 36 73 5 1060335808 16524816 7658512
05:24:08 6158 8192000000 9967362480 10353344512 1049625472 11.357 100 30 59 0 1071489712 14566672 7297568
05:25:18 8273 8192000000 9991860976 10387578880 1059309632 11.450 100 49 96 1 1087989424 17378064 11301728
05:26:28 3573 8192000000 9995111072 10388353024 1083613456 11.682 100 40 80 3 1104575984 11508272 9454256
05:27:36 103846 8192000000 10043415040 10448625664 1097795568 11.817 100 40 80 0 1145492528 38287360 9409600
05:30:26 29505 8192000000 10086711760 10490466304 1119208992 12.020 100 41 82 1 1192046352 63885936 8951424
05:32:11 5645 8192000000 10111920336 10516344832 1132433104 12.144 100 51 102 1 1217810768 74657152 10720512
05:33:20 10919 8192000000 10148158800 10555133952 1143461072 12.248 100 59 117 1 1262113984 105950336 12702576
05:34:36 19887 8192000000 10197471632 10610298880 1139914896 12.215 99 57 114 1 1288481248 136225648 12340704
05:36:00 5324 8192000000 10205135600 10608979968 1153646464 12.344 100 70 140 1 1300449312 131776272 15026576
05:37:08 5625 8192000000 10202896560 10612457472 1161430416 12.417 100 57 112 1 1304558752 131111088 12017248
05:38:20 3729 8192000000 10212728544 10615689216 1152978448 12.337 100 72 140 1 1316146224 147872016 15295760
05:39:27 5300 8192000000 10228472576 10633510912 1137919760 12.196 100 59 118 1 1330966192 179765504 13280928
05:40:37 5983 8192000000 10207752048 10596642816 1102632432 11.863 98 68 137 0 1297297520 177994160 16670928
05:41:45 5323 8192000000 10220459600 10636648448 1106844608 11.903 99 72 143 3 1313146800 189667712 16634480
05:42:56 1579 8192000000 10234992256 10628988928 1130728640 12.128 100 86 168 3 1333376752 182505616 20142496
05:44:06 9541 8192000000 10255126384 10679447552 1131495616 12.135 100 50 100 2 1368563456 224216992 12850848
05:45:22 9682 8192000000 10269777904 10685349888 1125322992 12.077 99 43 86 0 1356469104 220625920 10520192
05:46:34 12328 8192000000 10297630016 10703138816 1122186736 12.048 100 50 97 0 1388413568 254510608 11716224
05:47:54 9796 8192000000 10387600784 10805387264 1141331680 12.228 100 43 83 1 1474887952 323024064 10532208
05:49:09 9333 8192000000 10446663536 10866671616 1146306816 12.275 100 48 96 0 1538930864 380280400 12343648
05:50:21 8724 8192000000 10403782368 10899664896 1132268000 12.143 100 38 76 0 1547680288 404859840 10552448
05:51:35 15186 8192000000 10317945712 10765426688 1102195360 11.858 98 34 68 0 1411620304 299821056 9603888
05:52:58 23061 8192000000 10271757504 10771951616 1089955616 11.742 98 23 46 3 1374044880 278092640 5996624
05:54:23 87777 8192000000 10046114864 10463342592 1103154864 11.868 99 52 102 1 1164827792 50782656 10890272
05:56:51 52785 8192000000 10066711552 10539393024 1121571968 12.042 100 11 22 1 1189412320 65619280 2221072
05:58:44 149698 8192000000 10056415328 10494464000 1096187024 11.801 97 13 26 0 1137036512 38331856 2517632
06:02:15 133495 8192000000 10073510464 10574778368 1123700688 12.062 99 24 48 1 1187213568 57986560 5526320
06:05:30 19842 8192000000 10084917632 10520948736 1124893408 12.073 99 24 47 0 1186282096 56047296 5341392
06:06:50 27417 8192000000 10112096320 10550370304 1129678960 12.118 99 34 67 0 1208599216 71268128 7652128
06:08:18 46807 8192000000 10117675200 10549026816 1118519808 12.013 98 30 60 1 1192744528 67575648 6649072
06:10:07 86582 8192000000 10131867696 10589523968 1142899296 12.243 100 18 36 1 1235077872 88515952 3662624
06:12:41 48061 8192000000 10093461008 10564501504 1135524528 12.173 99 12 24 0 1222353296 83935616 2893152
06:14:34 38721 8192000000 10094373040 10540158976 1140626176 12.221 99 33 66 2 1172385328 24557088 7202064
06:16:17 63836 8192000000 10127375904 10591526912 1155717584 12.363 100 19 38 2 1238785456 79271888 3795984
06:18:35 44079 8192000000 10090120720 10522914816 1156910288 12.374 100 34 68 0 1175848992 11717120 7221584
06:20:20 120792 8192000000 10089375568 10547933184 1027830432 11.148 88 28 56 0 1098791216 65287088 5673696
06:23:28 26202 8192000000 10065186624 10488176640 1132601680 12.146 100 40 78 1 1155321904 14457168 8263056
06:24:56 110957 8192000000 10081768992 10571489280 1125044640 12.075 99 13 26 0 1184269152 56547872 2676640
06:28:37 38887 8192000000 10050162224 10517921792 1111761920 11.949 98 16 32 0 1156771680 41737520 3272240
06:30:17 30593 8192000000 10078911152 10523103232 1106035232 11.895 98 7 14 0 1149182272 41912480 1234560
06:31:52 39641 8192000000 10073837056 10517209088 1084999248 11.695 95 19 38 1 1149125888 59823968 4302672
06:33:33 24836 8192000000 10142982240 10578071552 1129111744 12.113 99 33 64 0 1224616608 88326784 7178080
06:35:06 87923 8192000000 10142837968 10626772992 1084472256 11.690 95 25 50 0 1192604288 102386816 5745216
06:37:36 42216 8192000000 10135183936 10575958016 1111440160 11.946 98 43 86 1 1196625888 75927920 9257808
06:39:31 43033 8192000000 10097703088 10525134848 1114166672 11.972 98 16 32 0 1163434752 45916544 3351536
06:41:17 42929 8192000000 10099493280 10509459456 1083765568 11.683 96 49 98 1 1130930768 37557360 9607840
06:43:04 41158 8192000000 10086676464 10575454208 1107939856 11.913 98 12 24 1 1183750144 73052160 2758128
06:47:31 53809 8192000000 10038177616 10488619008 1095598368 11.796 97 6 12 0 1129481360 32514976 1368016
06:49:56 63124 8192000000 10062377616 10520682496 1122412560 12.050 100 13 27 0 1170551952 45209424 2929968
06:52:06 48077 8192000000 10086616736 10529869824 1114173824 11.972 98 19 38 2 1164426816 46408176 3844816
06:53:58 65411 8192000000 10068251504 10505359360 1114470496 11.975 99 19 37 1 1182249856 63427472 4351888
06:56:03 65964 8192000000 10049485536 10465697792 1077627920 11.625 96 19 38 2 1118602192 36918432 4055840
06:58:13 60462 8192000000 10121575504 10578567168 1120360912 12.030 99 24 48 2 1190567216 65534960 4671344
07:00:24 115008 8192000000 10093241600 10571927552 1112793840 11.959 98 15 30 0 1154971536 39227072 2950624
07:03:25 58280 8192000000 10103229888 10567802880 1127715872 12.100 99 35 70 2 1215543504 80755760 7071872
07:05:42 153420 8192000000 10085568160 10583347200 1136427728 12.182 99 24 48 0 1218546032 76564400 5553904
07:10:45 63307 8192000000 10113968000 10579283968 1132037264 12.141 99 25 50 1 1226299504 88789952 5472288
07:12:49 56078 8192000000 10100723216 10537443328 1081476640 11.662 95 18 36 0 1144432368 59064176 3891552
07:15:05 68755 8192000000 10122640752 10553462784 1108977920 11.923 97 21 42 2 1179048976 65400032 4671024
07:17:27 52843 8192000000 10206888128 10683592704 1144500272 12.258 100 23 46 0 1276316768 126896960 4919536
07:20:02 89671 8192000000 10088971520 10535309312 1105270160 11.888 97 12 24 2 1134141408 26405120 2466128
07:22:40 104275 8192000000 10097182336 10558406656 1114748336 11.977 98 18 36 2 1180411536 62064752 3598448
07:25:41 54928 8192000000 10046875568 10543693824 1114276880 11.973 99 10 20 1 1166914384 50763600 1873904

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 22-12-2020 07:29:38 to 22-12-2020 07:29:53.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 92 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 22-12-2020 07:29:54 to 22-12-2020 07:29:56.

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          235004
MON$OLDEST_ACTIVE               236376
MON$OLDEST_SNAPSHOT             236376
MON$NEXT_TRANSACTION            236376
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                       2101696
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                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             800
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2300 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2300 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2300 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.2300 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 ---
:178: 
:179: mscs-test-161.budzdorov.ru    Tue Dec 22 07:27:24 2020
:180:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:181: 
--- end of diff output ---

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

22.12.20 07:29:57. 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
2020-12-22 07:29:59 No severe PSQL-related problems occured
22.12.20 07:30:00. End of report.