Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.12.2020 13:28
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 17040856 kB
MemAvailable 31527676 kB
Buffers 0 kB
Cached 14587628 kB
SwapCached 1980 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-06 13:26:53
DTS_END                         2020-12-06 13:26:53.6550
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/37080
TRN_ID                          225654
ATT_ID                          601
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: 8706.11 19797 1044733
customer order: creation 165.88 413 19905
customer order: refuse 13.73 327 1648
order to supplier: creation 42.48 1369 5097
order to supplier: removal 3.36 104921 403
invoice (draft): creation 44.53 3776 5344
invoice (draft): removal 49.33 904 5920
invoice accept: apply 40.24 16466 4829
invoice accept: cancel 45.10 847 5412
customer reserve: creation 3644.53 162 437344
customer reserve: removal 4373.35 64 524802
realization accept: apply 78.38 179 9406
realization accept: cancel 82.58 118 9909
payment from customer: creation 57.59 102 6911
payment from customer: removal 5.17 124 620
payment to supplier: creation 46.63 128 5595
payment to supplier: removal 3.01 85 361
service: total inventory turnovers 3.47 3465 416
service: total monetary turnovers 5.81 573 697
service: aggregate perf. data 0.92 16307 110
service: refresh index statistics 0.03 245618 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 06-12-2020 13:28:24 to 06-12-2020 13:28:26.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3552
TEST_TIME 2 3725
TEST_TIME 3 4782
TEST_TIME 4 5277
TEST_TIME 5 5345
TEST_TIME 6 5676
TEST_TIME 7 5818
TEST_TIME 8 5940
TEST_TIME 9 6248
TEST_TIME 10 6593
TEST_TIME 11 7137
TEST_TIME 12 7069
TEST_TIME 13 7418
TEST_TIME 14 7661
TEST_TIME 15 8501
TEST_TIME 16 8721
TEST_TIME 17 8638
TEST_TIME 18 9320
TEST_TIME 19 9206
TEST_TIME 20 9362
TEST_TIME 21 9515
TEST_TIME 22 9721
TEST_TIME 23 10221
TEST_TIME 24 10302
TEST_TIME 25 10419
TEST_TIME 26 10808
TEST_TIME 27 11526
TEST_TIME 28 11148
TEST_TIME 29 10946
TEST_TIME 30 10959
TEST_TIME 31 10681
TEST_TIME 32 10582
TEST_TIME 33 10392
TEST_TIME 34 10280
TEST_TIME 35 10091
TEST_TIME 36 9975
TEST_TIME 37 9966
TEST_TIME 38 9947
TEST_TIME 39 9782
TEST_TIME 40 9816
TEST_TIME 41 9780
TEST_TIME 42 9657
TEST_TIME 43 9521
TEST_TIME 44 9467
TEST_TIME 45 9284
TEST_TIME 46 9194
TEST_TIME 47 9197
TEST_TIME 48 9275
TEST_TIME 49 9218
TEST_TIME 50 9144
TEST_TIME 51 9063
TEST_TIME 52 9205
TEST_TIME 53 9307
TEST_TIME 54 9231
TEST_TIME 55 9382
TEST_TIME 56 9308
TEST_TIME 57 9378
TEST_TIME 58 9295
TEST_TIME 59 9282
TEST_TIME 60 9428
TEST_TIME 61 9381
TEST_TIME 62 9242
TEST_TIME 63 9151
TEST_TIME 64 9065
TEST_TIME 65 9049
TEST_TIME 66 9110
TEST_TIME 67 9031
TEST_TIME 68 8964
TEST_TIME 69 8939
TEST_TIME 70 9058
TEST_TIME 71 9135
TEST_TIME 72 9202
TEST_TIME 73 9186
TEST_TIME 74 9209
TEST_TIME 75 9184
TEST_TIME 76 9124
TEST_TIME 77 9203
TEST_TIME 78 9155
TEST_TIME 79 9049
TEST_TIME 80 8998
TEST_TIME 81 8944
TEST_TIME 82 8883
TEST_TIME 83 8845
TEST_TIME 84 8795
TEST_TIME 85 8771
TEST_TIME 86 8732
TEST_TIME 87 8714
TEST_TIME 88 8746
TEST_TIME 89 8750
TEST_TIME 90 8842
TEST_TIME 91 8903
TEST_TIME 92 8882
TEST_TIME 93 8969
TEST_TIME 94 8976
TEST_TIME 95 9082
TEST_TIME 96 9098
TEST_TIME 97 9029
TEST_TIME 98 8993
TEST_TIME 99 8938
TEST_TIME 100 8887
TEST_TIME 101 8838
TEST_TIME 102 8832
TEST_TIME 103 8835
TEST_TIME 104 8824
TEST_TIME 105 8813
TEST_TIME 106 8806
TEST_TIME 107 8783
TEST_TIME 108 8787
TEST_TIME 109 8782
TEST_TIME 110 8806
TEST_TIME 111 8905
TEST_TIME 112 8905
TEST_TIME 113 8869
TEST_TIME 114 8928
TEST_TIME 115 8853
TEST_TIME 116 8805
TEST_TIME 117 8780
TEST_TIME 118 8763
TEST_TIME 119 8735
TEST_TIME 120 8712

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 06-12-2020 13:28:28 to 06-12-2020 13:28: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 1448997 1448997 0 0.00 0 21074 6 0 0
doc_list_biud 1448997 1448997 0 0.00 0 88129 30 0 0
sp_add_invoice_to_stock 4938 4829 109 2.20 18 193436 16467 0 109
sp_cancel_adding_invoice 5412 5412 0 0.00 4 60503 848 0 0
sp_cancel_client_order 1648 1648 0 0.00 4 5417 328 0 0
sp_cancel_customer_reserve 524802 524802 0 0.00 1 21879 64 0 0
sp_cancel_pay_from_customer 620 620 0 0.00 3 6423 125 0 0
sp_cancel_pay_to_supplier 361 361 0 0.00 3 2850 86 0 0
sp_cancel_supplier_invoice 5927 5920 7 0.11 2 92108 905 0 7
sp_cancel_supplier_order 403 403 0 0.00 12 1012123 104921 0 0
sp_cancel_write_off 9909 9909 0 0.00 1 15413 118 0 0
sp_client_order 19905 19905 0 0.00 4 41327 414 0 0
sp_customer_reserve 437344 437344 0 0.00 0 37099 163 0 0
sp_fill_shopping_cart 30324 30324 0 0.00 1 16836 53 0 0
sp_fill_shopping_cart_clo_res 437340 437340 0 0.00 0 24983 17 0 0
sp_get_clo_for_invoice 4821 4821 0 0.00 0 30312 432 0 0
sp_kill_cost_storno 16302 16302 0 0.00 0 7387 58 0 0
sp_kill_qstorno_ret_qs2qd 1065836 1065836 0 0.00 0 88127 35 0 0
sp_kill_qty_storno 562321 562321 0 0.00 0 88129 75 0 0
sp_lock_selected_doc 19656 19656 0 0.00 0 9573 5 0 0
sp_make_cost_storno 26733 26733 0 0.00 0 14581 53 0 0
sp_multiply_rows_for_pdistr 26733 26733 0 0.00 0 7731 28 0 0
sp_multiply_rows_for_qdistr 19905 19905 0 0.00 0 37584 242 0 0
sp_pay_from_customer 6911 6911 0 0.00 4 10588 102 0 0
sp_pay_to_supplier 5595 5595 0 0.00 5 19111 129 0 0
sp_payment_common 12506 12506 0 0.00 1 7737 24 0 0
sp_qd_handle_on_cancel_clo 1648 1648 0 0.00 0 5411 304 0 0
sp_qd_handle_on_invoice_upd_sts 10233 10233 0 0.00 0 23425 358 0 0
sp_qd_handle_on_reserve_upd_sts 9406 9406 0 0.00 0 11147 60 0 0
sp_reserve_write_off 9406 9406 0 0.00 7 18771 180 0 0
sp_supplier_invoice 5344 5344 0 0.00 8 109077 3776 0 0
sp_supplier_order 5097 5097 0 0.00 5 91826 1370 0 0
srv_aggregate_perf_data 110 110 0 0.00 2538 150458 16308 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1869 178340 52046 0 0
srv_find_qd_qs_mism 998928 998928 0 0.00 0 21074 5 0 0
srv_make_invnt_saldo 416 416 0 0.00 48 92839 3466 0 0
srv_make_money_saldo 697 697 0 0.00 1 12976 574 0 0
srv_recalc_idx_stat 4 4 0 0.00 166536 349822 245618 0 0
t$perf-abend:sp_add_invoice_to_stock 109 109 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 4829 4829 0 0.00 0 14001 152 0 0
t$perf-norm:sp_cancel_adding_invoice 387 387 0 0.00 0 2220 80 0 0
t$perf-norm:sp_cancel_client_order 1648 1648 0 0.00 0 8388 18 0 0
t$perf-norm:sp_cancel_customer_reserve 663 663 0 0.00 0 1113 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 620 620 0 0.00 0 4048 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 361 361 0 0.00 0 2499 17 0 0
t$perf-norm:sp_cancel_supplier_invoice 407 407 0 0.00 0 792 11 0 0
t$perf-norm:sp_cancel_supplier_order 403 403 0 0.00 0 15483 1235 0 0
t$perf-norm:sp_cancel_write_off 940 940 0 0.00 0 1170 7 0 0
t$perf-norm:sp_client_order 19905 19905 0 0.00 0 15302 18 0 0
t$perf-norm:sp_customer_reserve 982 982 0 0.00 0 1050 5 0 0
t$perf-norm:sp_pay_from_customer 6911 6911 0 0.00 0 5814 11 0 0
t$perf-norm:sp_pay_to_supplier 5595 5595 0 0.00 0 2974 9 0 0
t$perf-norm:sp_reserve_write_off 9406 9406 0 0.00 0 10893 10 0 0
t$perf-norm:sp_supplier_invoice 5344 5344 0 0.00 0 8686 20 0 0
t$perf-norm:sp_supplier_order 5097 5097 0 0.00 0 6902 19 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 810 19 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 136 4 0 0
t$perf-norm:srv_make_invnt_saldo 416 416 0 0.00 0 2650 16 0 0
t$perf-norm:srv_make_money_saldo 697 697 0 0.00 0 6873 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 9860 9642 218 2.21 0 4966 14 0 218
v_all_customers 36720 36720 0 0.00 0 104 1 0 0
v_all_suppliers 28596 28596 0 0.00 0 21 1 0 0
v_all_wares 39810 39810 0 0.00 0 15 1 0 0
v_cancel_adding_invoice 774 774 0 0.00 0 71 5 0 0
v_cancel_client_order 3296 3296 0 0.00 0 3023 5 0 0
v_cancel_customer_prepayment 1240 1240 0 0.00 0 3149 12 0 0
v_cancel_customer_reserve 1326 1326 0 0.00 0 373 3 0 0
v_cancel_payment_to_supplier 722 722 0 0.00 0 1374 9 0 0
v_cancel_supplier_invoice 828 814 14 1.69 0 314 11 0 14
v_cancel_supplier_order 806 806 0 0.00 0 723 4 0 0
v_cancel_write_off 1880 1880 0 0.00 0 1391 13 0 0
v_max_id_clo_ord 5097 5097 0 0.00 0 228 1 0 0
v_max_id_clo_res 982 982 0 0.00 0 3103 13 0 0
v_max_id_ord_sup 5322 5322 0 0.00 0 3751 3 0 0
v_max_non_paid_invoice 1716 1716 0 0.00 0 304 2 0 0
v_max_non_paid_realizn 3493 3493 0 0.00 0 4552 6 0 0
v_min_id_clo_ord 5097 5097 0 0.00 0 4302 6 0 0
v_min_id_clo_res 982 982 0 0.00 0 1603 8 0 0
v_min_id_ord_sup 5322 5322 0 0.00 0 8282 13 0 0
v_min_non_paid_invoice 5595 5595 0 0.00 0 7731 16 0 0
v_min_non_paid_realizn 6911 6911 0 0.00 0 7435 16 0 0
v_our_firm 9926 9926 0 0.00 0 12 1 0 0
v_reserve_write_off 18812 18812 0 0.00 0 7952 5 0 0
x_lock_depdocs_on_canc_invoice 10925 10925 0 0.00 0 10675 102 0 0
x_lock_depdocs_on_canc_sup_ord 403 403 0 0.00 0 4698 289 0 0
x_make_qstorno_1000_1200 5097 5097 0 0.00 2 86931 1291 0 0
x_make_qstorno_1200_2000 5344 5344 0 0.00 4 109055 3555 0 0
x_make_qstorno_2100_3300 437344 437344 0 0.00 0 37097 143 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-12-2020 13:28:32 to 06-12-2020 13:28: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
10:56:57 6 8192000000 8796890384 8807436288 3168704 0.038 1 1 2 0 3266400 61840 35856
10:57:58 13076 8192000000 9709571664 10005372928 790096032 8.796 100 85 170 0 818082864 7429184 20557648
10:59:14 23297 8192000000 9749914048 10053566464 824628656 9.145 100 90 180 1 857958864 11364576 21965632
11:00:41 17305 8192000000 9783133520 10100121600 851639168 9.416 100 76 152 1 886210480 16411216 18160096
11:02:06 10697 8192000000 9859822624 10189660160 914234240 10.039 100 80 160 1 969443792 37256672 17952880
11:03:22 8821 8192000000 9922380336 10264879104 956396272 10.454 100 79 158 2 1030733360 57802816 16534272
11:04:33 8008 8192000000 9952654160 10305024000 986695424 10.749 100 75 149 0 1071933520 70542464 14695632
11:05:43 4338 8192000000 9989045552 10348662784 1012360720 10.998 100 84 166 1 1109012944 79489632 17162592
11:06:49 2340 8192000000 10002615232 10369089536 1029846112 11.167 100 86 171 3 1119029008 71578768 17604128
11:07:53 1267 8192000000 10022492768 10390994944 1050135376 11.362 100 90 180 2 1138060640 68946384 18978880
11:08:55 18710 8192000000 9983221392 10370519040 1055986160 11.418 100 46 92 0 1109786064 42975536 10824368
11:10:15 6931 8192000000 9967836144 10363916288 1064472816 11.499 100 30 61 2 1090626416 19834976 6318624
11:11:23 8326 8192000000 9986718896 10389913600 1068304464 11.536 100 28 56 1 1109235328 34997296 5933568
11:12:34 6401 8192000000 9980558352 10374832128 1076231856 11.612 100 28 56 0 1106965056 24855664 5877536
11:13:42 21901 8192000000 10010752960 10393174016 1080754240 11.655 100 71 145 3 1112436848 16268576 15414032
11:15:05 8363 8192000000 9987095216 10402074624 1079404416 11.642 100 25 48 2 1107391808 22517008 5470384
11:16:15 9506 8192000000 9990946864 10389708800 1056104480 11.419 98 33 66 2 1092066864 28905328 7057056
11:17:27 34332 8192000000 9988025872 10399166464 1053067808 11.390 98 22 44 2 1088859584 31393152 4398624
11:19:01 4372 8192000000 9971847664 10356023296 1044087152 11.304 98 39 76 3 1074955408 22507456 8360800
11:20:07 6249 8192000000 9939083504 10319147008 993017200 10.811 96 49 97 0 1032911808 28363696 11530912
11:21:14 6705 8192000000 9920048032 10298871808 988171696 10.764 98 56 110 3 1014473472 13761008 12540768
11:22:21 27107 8192000000 9932052512 10324979712 995111888 10.831 97 28 56 1 1026547856 25573168 5862800
11:23:48 5292 8192000000 9962621760 10353922048 1037810176 11.244 100 35 69 1 1070993056 25752080 7430800
11:24:54 7314 8192000000 9972991984 10365816832 1050908896 11.369 100 37 73 1 1091501504 32218560 8374048
11:26:02 78646 8192000000 10001752592 10392170496 1070097024 11.553 100 46 90 2 1098887184 18818032 9972128
11:28:29 6381 8192000000 10033454976 10438148096 1096409312 11.804 100 40 80 1 1149822144 44706192 8706640
11:29:38 39095 8192000000 10079823680 10490585088 1114650352 11.976 100 43 85 1 1196252464 72504640 9097472
11:31:22 2843 8192000000 10124441808 10524631040 1132462048 12.145 100 77 151 3 1229138144 80070800 16605296
11:32:27 6944 8192000000 10155372448 10564956160 1137952096 12.196 100 51 101 1 1263170768 114360208 10858464
11:33:41 10240 8192000000 10200690688 10606469120 1147266848 12.284 100 62 124 2 1308422480 147575312 13580320
11:34:53 6434 8192000000 10193650768 10614013952 1156509904 12.371 100 62 123 0 1308393952 138272512 13611536
11:36:03 2455 8192000000 10250251488 10652033024 1155989312 12.366 100 74 146 1 1350698880 179598528 15111040
11:37:08 2027 8192000000 10286849520 10697072640 1155116784 12.358 100 90 180 0 1399049840 223727296 20205760
11:38:15 5185 8192000000 10289874720 10699317248 1156716384 12.372 100 63 126 3 1387405696 216721760 13967552
11:39:26 3053 8192000000 10336530192 10734419968 1156010224 12.366 100 87 172 0 1437533856 262121200 19402432
11:40:33 3219 8192000000 10323926912 10727309312 1135040096 12.169 98 77 153 1 1425630208 273420176 17169936
11:41:43 1866 8192000000 10319430368 10746654720 1144545600 12.258 100 77 154 1 1423887520 262330752 17011168
11:42:51 5024 8192000000 10290220736 10692780032 1133587536 12.155 98 63 124 1 1375568192 227840448 14140208
11:44:03 13511 8192000000 10251325168 10676613120 1120325216 12.030 97 35 70 0 1349249168 220233776 8690176
11:45:21 10537 8192000000 10260271008 10689544192 1125447760 12.078 98 33 65 0 1338955632 206171360 7336512
11:46:35 4310 8192000000 10276430688 10704818176 1131531712 12.136 99 42 85 1 1372878256 231686384 9660160
11:47:45 4805 8192000000 10288307024 10716598272 1131107072 12.132 100 45 90 3 1382589536 240944240 10538224
11:48:59 18090 8192000000 10292683232 10744172544 1137729552 12.194 100 34 68 2 1410712896 264679344 8304000
11:50:26 27921 8192000000 10262041328 10719510528 1133066928 12.150 99 29 58 1 1368916960 228953264 6896768
11:52:08 26185 8192000000 10231083136 10668621824 1113757344 11.968 99 28 56 1 1306647488 185550800 7339344
11:53:35 178339 8192000000 10077014384 10500792320 1068016416 11.533 95 23 50 1 1140206736 67117472 5072848
11:57:39 50115 8192000000 10068378256 10506022912 1094060544 11.781 97 22 44 0 1134452320 36091824 4299952
11:59:46 66402 8192000000 10086863792 10544021504 1119438816 12.022 99 17 34 0 1190470416 67556000 3475600
12:02:02 77270 8192000000 10123352992 10597117952 1125072128 12.075 98 20 40 2 1229503776 99578208 4853440
12:04:46 44214 8192000000 10111843408 10537746432 1142232128 12.237 100 35 67 3 1214323712 64385872 7705712
12:06:31 68438 8192000000 10059049680 10537123840 1136427888 12.182 100 16 32 3 1173782640 33831248 3523504
12:08:39 48768 8192000000 10065844400 10523193344 1140795504 12.223 100 15 30 2 1167171376 22884080 3491792
12:10:29 54622 8192000000 10054470256 10498658304 1127560112 12.098 99 15 30 0 1156067552 25407680 3099760
12:12:24 97441 8192000000 10096025184 10594340864 1127897072 12.102 99 10 20 0 1202525584 72698656 1929856
12:15:42 67197 8192000000 10127459520 10575687680 1141012832 12.225 99 15 30 1 1206212896 62307440 2892624
12:17:55 91097 8192000000 10123624544 10577674240 1149812336 12.308 100 18 36 2 1230882928 77154112 3916480
12:20:27 93861 8192000000 10068221120 10541121536 1108313296 11.916 96 16 31 0 1192370528 80810592 3246640
12:23:01 75258 8192000000 10089406688 10520670208 1075909312 11.608 94 20 40 0 1140867376 60830944 4127120
12:25:19 135997 8192000000 10053535312 10559258624 1051605200 11.376 93 20 40 1 1119558832 63682912 4270720
12:28:37 64579 8192000000 10061047680 10502881280 1093228608 11.773 97 16 32 2 1130384000 33544064 3611328
12:30:42 59165 8192000000 10096585728 10576777216 1118193424 12.010 99 10 20 2 1189587152 69297744 2095984
12:32:43 47455 8192000000 10050988976 10475413504 1050777072 11.368 92 18 35 2 1074680832 19486928 4416832
12:34:31 47911 8192000000 10176344464 10608197632 1144474048 12.258 100 30 60 2 1240954832 89678784 6802000
12:36:23 77091 8192000000 10102084512 10629677056 1084505488 11.690 96 11 22 1 1226991392 139765920 2719984
12:38:48 42263 8192000000 10063006656 10507591680 1102902208 11.865 98 22 44 3 1128849520 20980496 4966816
12:40:31 88817 8192000000 10058173648 10483167232 1120407984 12.031 100 31 62 2 1174826928 48219552 6199392
12:43:01 50433 8192000000 10083070512 10566234112 1125094512 12.075 100 37 74 1 1201617440 69447584 7075344
12:44:52 115381 8192000000 10039980608 10484895744 1117533248 12.004 100 17 35 0 1160846048 40263616 3049184
12:47:56 53370 8192000000 10024426048 10465042432 1060060288 11.457 95 8 16 0 1081693648 19684864 1948496
12:50:00 63251 8192000000 10029312352 10486308864 1095488032 11.795 98 9 19 1 1129609296 32315072 1806192
12:52:05 74009 8192000000 10132610144 10580611072 1126152400 12.085 99 27 54 0 1206006656 73821968 6032288
12:54:21 65027 8192000000 10137560160 10603487232 1124323008 12.068 99 20 40 0 1246549744 117400480 4826256
12:56:29 84333 8192000000 10060472064 10482544640 1081339744 11.660 96 17 34 1 1123847520 39073424 3434352
12:58:58 47614 8192000000 10067415728 10559684608 1074046096 11.591 96 11 22 0 1137111392 61146528 1918768
13:00:46 38867 8192000000 10105732976 10617729024 1120892384 12.035 100 17 34 2 1238150736 113547648 3710704
13:02:26 67635 8192000000 10068344416 10493161472 1104377936 11.879 98 18 34 2 1132044448 24294384 3372128
13:04:38 144547 8192000000 10097443936 10565095424 1122539680 12.051 100 21 40 1 1207133984 80415056 4179248
13:08:08 45497 8192000000 10076361920 10551365632 1122382256 12.049 100 12 24 1 1172911168 47786992 2741920
13:09:56 74981 8192000000 10060188672 10531524608 1104869200 11.884 98 16 32 0 1166697040 58328480 3499360
13:12:20 44559 8192000000 10097472064 10545598464 1112026176 11.952 98 18 36 0 1159930528 44012000 3892352
13:14:11 55492 8192000000 10141599536 10581426176 1145297376 12.265 100 21 43 2 1222923776 72777040 4849360
13:16:11 82691 8192000000 10123921600 10647916544 1113103008 11.962 97 12 25 0 1202289936 86331344 2855584
13:18:35 39396 8192000000 10084791264 10524786688 1103962512 11.875 97 16 32 1 1133802384 26287056 3552816
13:20:16 89132 8192000000 10069576880 10564276224 1116290608 11.992 99 16 32 0 1169183056 49748688 3143760
13:22:45 63762 8192000000 10049781728 10502201344 1105464128 11.889 98 17 35 0 1143913088 34389200 4059760
13:25:00 71735 8192000000 10098231056 10550837248 1110147648 11.934 98 13 25 0 1174310016 61127088 3035280

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 06-12-2020 13:28:38 to 06-12-2020 13:28:53.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 109 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 06-12-2020 13:28:54 to 06-12-2020 13:28: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          223234
MON$OLDEST_ACTIVE               226312
MON$OLDEST_SNAPSHOT             226312
MON$NEXT_TRANSACTION            226312
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                       2083232
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             782
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2290 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2290 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2290 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.2290 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 ---
:64: 
:65: mscs-test-161.budzdorov.ru    Sun Dec  6 13:26:55 2020
:66:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:67: 
:68: 
:69: mscs-test-161.budzdorov.ru    Sun Dec  6 13:26:59 2020
:70:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:71: 
--- end of diff output ---

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

06.12.20 13:28: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-06 13:28:59 No severe PSQL-related problems occured
06.12.20 13:29:01. End of report.