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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 58e1ddde69c44dad93d327777e5becec
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-05-09 08:45
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4793.92
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16520616 kB
MemAvailable 31365444 kB
Buffers 0 kB
Cached 15107284 kB
SwapCached 2408 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.1
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-06-18 07:38:30
DTS_END                         2021-06-18 07:38:30.0560
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/50024
TRN_ID                          227712
ATT_ID                          593
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: 9196.82 21371 1103618
customer order: creation 161.02 420 19322
customer order: refuse 14.03 312 1684
order to supplier: creation 42.53 1525 5104
order to supplier: removal 3.41 117658 409
invoice (draft): creation 43.45 3556 5214
invoice (draft): removal 53.77 1003 6452
invoice accept: apply 39.69 16677 4763
invoice accept: cancel 48.93 814 5872
customer reserve: creation 3718.18 161 446182
customer reserve: removal 4782.57 65 573908
realization accept: apply 80.78 175 9694
realization accept: cancel 87.56 110 10507
payment from customer: creation 55.92 109 6710
payment from customer: removal 5.63 75 676
payment to supplier: creation 46.23 122 5548
payment to supplier: removal 3.13 69 376
service: total inventory turnovers 3.65 3585 438
service: total monetary turnovers 5.46 631 655
service: aggregate perf. data 0.83 17128 100
service: refresh index statistics 0.03 263224 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 18-06-2021 07:39:45 to 18-06-2021 07:39:48.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5952
TEST_TIME 2 5423
TEST_TIME 3 5313
TEST_TIME 4 4930
TEST_TIME 5 5135
TEST_TIME 6 5145
TEST_TIME 7 5249
TEST_TIME 8 5322
TEST_TIME 9 5574
TEST_TIME 10 5564
TEST_TIME 11 6175
TEST_TIME 12 6318
TEST_TIME 13 6835
TEST_TIME 14 7255
TEST_TIME 15 7680
TEST_TIME 16 8025
TEST_TIME 17 8258
TEST_TIME 18 8599
TEST_TIME 19 8989
TEST_TIME 20 9195
TEST_TIME 21 9474
TEST_TIME 22 9546
TEST_TIME 23 9794
TEST_TIME 24 10190
TEST_TIME 25 11027
TEST_TIME 26 10690
TEST_TIME 27 10630
TEST_TIME 28 10743
TEST_TIME 29 10758
TEST_TIME 30 10958
TEST_TIME 31 10680
TEST_TIME 32 10533
TEST_TIME 33 10507
TEST_TIME 34 10611
TEST_TIME 35 10508
TEST_TIME 36 10462
TEST_TIME 37 10347
TEST_TIME 38 10432
TEST_TIME 39 10226
TEST_TIME 40 10351
TEST_TIME 41 10431
TEST_TIME 42 10457
TEST_TIME 43 10440
TEST_TIME 44 10309
TEST_TIME 45 10144
TEST_TIME 46 10009
TEST_TIME 47 9825
TEST_TIME 48 9880
TEST_TIME 49 9753
TEST_TIME 50 9635
TEST_TIME 51 9646
TEST_TIME 52 9703
TEST_TIME 53 9672
TEST_TIME 54 9717
TEST_TIME 55 9661
TEST_TIME 56 9671
TEST_TIME 57 9860
TEST_TIME 58 9791
TEST_TIME 59 9729
TEST_TIME 60 9826
TEST_TIME 61 9822
TEST_TIME 62 9859
TEST_TIME 63 9911
TEST_TIME 64 9877
TEST_TIME 65 9839
TEST_TIME 66 9810
TEST_TIME 67 9945
TEST_TIME 68 9929
TEST_TIME 69 9823
TEST_TIME 70 9691
TEST_TIME 71 9766
TEST_TIME 72 9679
TEST_TIME 73 9630
TEST_TIME 74 9551
TEST_TIME 75 9525
TEST_TIME 76 9445
TEST_TIME 77 9476
TEST_TIME 78 9436
TEST_TIME 79 9417
TEST_TIME 80 9410
TEST_TIME 81 9460
TEST_TIME 82 9565
TEST_TIME 83 9539
TEST_TIME 84 9569
TEST_TIME 85 9607
TEST_TIME 86 9617
TEST_TIME 87 9582
TEST_TIME 88 9532
TEST_TIME 89 9431
TEST_TIME 90 9436
TEST_TIME 91 9448
TEST_TIME 92 9410
TEST_TIME 93 9386
TEST_TIME 94 9364
TEST_TIME 95 9332
TEST_TIME 96 9366
TEST_TIME 97 9386
TEST_TIME 98 9449
TEST_TIME 99 9434
TEST_TIME 100 9440
TEST_TIME 101 9439
TEST_TIME 102 9433
TEST_TIME 103 9503
TEST_TIME 104 9511
TEST_TIME 105 9544
TEST_TIME 106 9593
TEST_TIME 107 9515
TEST_TIME 108 9435
TEST_TIME 109 9387
TEST_TIME 110 9322
TEST_TIME 111 9293
TEST_TIME 112 9267
TEST_TIME 113 9255
TEST_TIME 114 9289
TEST_TIME 115 9262
TEST_TIME 116 9246
TEST_TIME 117 9219
TEST_TIME 118 9202
TEST_TIME 119 9190
TEST_TIME 120 9202

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 18-06-2021 07:39:49 to 18-06-2021 07:39:52.

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 1516766 1516766 0 0.00 0 19701 6 0 0
doc_list_biud 1516765 1516765 0 0.00 0 81571 32 0 0
sp_add_invoice_to_stock 4875 4763 112 2.29 39 212327 16678 0 112
sp_cancel_adding_invoice 5872 5872 0 0.00 5 95160 815 0 0
sp_cancel_client_order 1684 1684 0 0.00 4 12998 312 0 0
sp_cancel_customer_reserve 573908 573908 0 0.00 1 81573 66 0 0
sp_cancel_pay_from_customer 676 676 0 0.00 2 5739 75 0 0
sp_cancel_pay_to_supplier 376 376 0 0.00 3 3615 70 0 0
sp_cancel_supplier_invoice 6456 6452 4 0.06 2 65763 1003 0 4
sp_cancel_supplier_order 409 409 0 0.00 14 999585 117658 0 0
sp_cancel_write_off 10507 10507 0 0.00 1 7580 110 0 0
sp_client_order 19322 19322 0 0.00 4 13894 420 0 0
sp_customer_reserve 446182 446182 0 0.00 0 75284 161 0 0
sp_fill_shopping_cart 29625 29625 0 0.00 1 20289 41 0 0
sp_fill_shopping_cart_clo_res 446162 446162 0 0.00 0 21616 15 0 0
sp_get_clo_for_invoice 4743 4743 0 0.00 0 41451 365 0 0
sp_kill_cost_storno 17431 17431 0 0.00 0 7576 55 0 0
sp_kill_qstorno_ret_qs2qd 1165184 1165184 0 0.00 0 81568 36 0 0
sp_kill_qty_storno 613269 613269 0 0.00 0 81571 78 0 0
sp_lock_selected_doc 19983 19983 0 0.00 0 7755 5 0 0
sp_make_cost_storno 26695 26695 0 0.00 0 18316 56 0 0
sp_multiply_rows_for_pdistr 26695 26695 0 0.00 0 7100 32 0 0
sp_multiply_rows_for_qdistr 19322 19322 0 0.00 0 13885 233 0 0
sp_pay_from_customer 6710 6710 0 0.00 4 9286 109 0 0
sp_pay_to_supplier 5548 5548 0 0.00 5 21704 123 0 0
sp_payment_common 12258 12258 0 0.00 1 21685 22 0 0
sp_qd_handle_on_cancel_clo 1684 1684 0 0.00 0 12989 284 0 0
sp_qd_handle_on_invoice_upd_sts 10615 10615 0 0.00 0 31564 362 0 0
sp_qd_handle_on_reserve_upd_sts 9694 9694 0 0.00 0 9108 56 0 0
sp_reserve_write_off 9694 9694 0 0.00 6 10202 176 0 0
sp_supplier_invoice 5214 5214 0 0.00 7 91292 3556 0 0
sp_supplier_order 5104 5104 0 0.00 5 99959 1526 0 0
srv_aggregate_perf_data 100 100 0 0.00 2837 156181 17128 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1570 164237 47101 0 0
srv_find_qd_qs_mism 1058380 1058380 0 0.00 0 19700 5 0 0
srv_make_invnt_saldo 438 438 0 0.00 28 116066 3585 0 0
srv_make_money_saldo 655 655 0 0.00 1 16113 631 0 0
srv_recalc_idx_stat 4 4 0 0.00 74152 402080 263225 0 0
t$perf-abend:sp_add_invoice_to_stock 112 112 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4763 4763 0 0.00 0 10876 160 0 0
t$perf-norm:sp_cancel_adding_invoice 401 401 0 0.00 1 10458 165 0 0
t$perf-norm:sp_cancel_client_order 1684 1684 0 0.00 0 3566 12 0 0
t$perf-norm:sp_cancel_customer_reserve 648 648 0 0.00 0 788 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 676 676 0 0.00 0 4089 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 376 376 0 0.00 0 89 1 0 0
t$perf-norm:sp_cancel_supplier_invoice 372 372 0 0.00 0 2125 17 0 0
t$perf-norm:sp_cancel_supplier_order 409 409 0 0.00 0 18145 1619 0 0
t$perf-norm:sp_cancel_write_off 984 984 0 0.00 0 1132 6 0 0
t$perf-norm:sp_client_order 19322 19322 0 0.00 0 13686 18 0 0
t$perf-norm:sp_customer_reserve 913 913 0 0.00 0 1590 8 0 0
t$perf-norm:sp_pay_from_customer 6710 6710 0 0.00 0 6164 8 0 0
t$perf-norm:sp_pay_to_supplier 5548 5548 0 0.00 0 11894 11 0 0
t$perf-norm:sp_reserve_write_off 9694 9694 0 0.00 0 8334 12 0 0
t$perf-norm:sp_supplier_invoice 5214 5214 0 0.00 0 9589 14 0 0
t$perf-norm:sp_supplier_order 5104 5104 0 0.00 0 5047 15 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 1308 32 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 474 9 0 0
t$perf-norm:srv_make_invnt_saldo 438 438 0 0.00 0 7353 20 0 0
t$perf-norm:srv_make_money_saldo 655 655 0 0.00 0 366 3 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 28 9 0 0
v_add_invoice_to_stock 9704 9480 224 2.30 0 5378 15 0 224
v_all_customers 35100 35100 0 0.00 0 111 1 0 0
v_all_suppliers 29004 29004 0 0.00 0 109 1 0 0
v_all_wares 38642 38642 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 802 802 0 0.00 0 1059 7 0 0
v_cancel_client_order 3368 3368 0 0.00 0 3515 5 0 0
v_cancel_customer_prepayment 1352 1352 0 0.00 0 1182 5 0 0
v_cancel_customer_reserve 1296 1296 0 0.00 0 2414 5 0 0
v_cancel_payment_to_supplier 752 752 0 0.00 0 1933 6 0 0
v_cancel_supplier_invoice 752 744 8 1.06 0 468 13 0 8
v_cancel_supplier_order 818 818 0 0.00 0 368 3 0 0
v_cancel_write_off 1968 1968 0 0.00 0 508 11 0 0
v_max_id_clo_ord 5102 5102 0 0.00 0 1134 2 0 0
v_max_id_clo_res 913 913 0 0.00 0 2494 17 0 0
v_max_id_ord_sup 5202 5202 0 0.00 0 2946 4 0 0
v_max_non_paid_invoice 1350 1350 0 0.00 0 33 2 0 0
v_max_non_paid_realizn 3679 3679 0 0.00 0 4500 6 0 0
v_min_id_clo_ord 5102 5102 0 0.00 0 6652 7 0 0
v_min_id_clo_res 913 913 0 0.00 0 3166 7 0 0
v_min_id_ord_sup 5202 5202 0 0.00 0 6964 9 0 0
v_min_non_paid_invoice 5548 5548 0 0.00 0 21681 15 0 0
v_min_non_paid_realizn 6710 6710 0 0.00 0 4712 14 0 0
v_our_firm 9604 9604 0 0.00 0 92 1 0 0
v_reserve_write_off 19386 19386 0 0.00 0 3520 5 0 0
x_lock_depdocs_on_canc_invoice 11952 11952 0 0.00 0 16610 102 0 0
x_lock_depdocs_on_canc_sup_ord 409 409 0 0.00 0 7724 335 0 0
x_make_qstorno_1000_1200 5104 5104 0 0.00 2 99936 1451 0 0
x_make_qstorno_1200_2000 5214 5214 0 0.00 3 91258 3406 0 0
x_make_qstorno_2100_3300 446182 446182 0 0.00 0 75283 144 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 18-06-2021 07:39:53 to 18-06-2021 07:39:58.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
05:08:33 8 8192000000 8803343760 8816484352 6967296 0.084 3 2 3 0 7121024 92368 61360
05:09:36 21488 8192000000 9729263392 10029240320 808094192 8.978 100 91 182 0 837949360 7887376 21967792
05:11:00 32358 8192000000 9765971392 10074845184 840847520 9.308 100 86 172 1 874873664 13953632 20072512
05:12:53 12193 8192000000 9810683184 10130120704 869749536 9.598 100 79 158 2 922023248 34174224 18099488
05:14:09 12579 8192000000 9878499872 10212220928 920424928 10.100 100 70 140 1 988153392 52578944 15149520
05:15:27 8677 8192000000 9929952224 10274619392 967537888 10.563 100 86 172 1 1039575216 53999616 18037712
05:16:38 5530 8192000000 9961965600 10321797120 1006799088 10.944 100 79 159 1 1073819744 51145632 15875024
05:17:45 3132 8192000000 10008291120 10368929792 1029935248 11.168 100 89 178 0 1122381760 73841904 18604608
05:18:50 1963 8192000000 10007594240 10373308416 1045620496 11.319 100 94 186 0 1119556848 55225696 18710656
05:19:54 14581 8192000000 10037929744 10416033792 1062935744 11.485 100 64 131 0 1146583408 70531024 13116640
05:21:10 4755 8192000000 9983428080 10375065600 1073290128 11.583 100 56 109 1 1103962528 19324880 11347520
05:22:15 74254 8192000000 9979130432 10394054656 1076258640 11.612 100 38 76 4 1104478752 20047072 8173040
05:24:31 4467 8192000000 10000897824 10401595392 1083508000 11.681 100 35 68 1 1125281200 34584496 7188704
05:25:37 6444 8192000000 10009289184 10418270208 1090582640 11.748 100 33 66 0 1121367952 23076256 7709056
05:26:44 14448 8192000000 10004353120 10407780352 1071623488 11.568 98 36 70 1 1101560560 22557024 7380048
05:28:02 24435 8192000000 10019960592 10431946752 1081726352 11.664 99 35 70 0 1120722464 31709488 7286624
05:29:29 4908 8192000000 10007218560 10392391680 1064095552 11.496 100 34 67 0 1099040320 27093712 7851056
05:30:37 7449 8192000000 9968266176 10357035008 1019958496 11.072 98 37 75 1 1041013248 13316064 7738688
05:31:45 12290 8192000000 9971482096 10375385088 1038847408 11.254 99 29 56 0 1071272864 26306000 6119456
05:32:57 32409 8192000000 9939190160 10329407488 967505792 10.562 92 29 58 1 985673536 11418720 6749024
05:34:35 6914 8192000000 9963690880 10363871232 1031093984 11.179 99 49 97 0 1083378080 40823904 11460192
05:35:44 3962 8192000000 9971386576 10367422464 1061569648 11.472 100 37 72 4 1080862480 10532160 8760672
05:36:50 74401 8192000000 9984647696 10392543232 1075601360 11.606 100 29 56 0 1096947456 14954352 6391744
05:39:07 5713 8192000000 10045167296 10443010048 1097322816 11.812 100 49 98 0 1142741696 35622912 9795968
05:40:19 26484 8192000000 10071495648 10472357888 1117295840 12.001 100 47 94 1 1174268928 47111504 9861584
05:41:52 20575 8192000000 10088825856 10502217728 1130626864 12.127 100 39 78 1 1198489040 59377072 8485104
05:43:18 16014 8192000000 10135677776 10556063744 1141530944 12.230 100 51 101 1 1256234672 103375376 11328352
05:44:42 30783 8192000000 10201327440 10615779328 1158783632 12.392 100 43 85 1 1297519600 128989344 9746624
05:46:22 24992 8192000000 10221354304 10643001344 1174859824 12.542 100 48 94 0 1331105840 145088112 11157904
05:47:49 3034 8192000000 10264513408 10685210624 1186367536 12.650 100 57 114 0 1373551264 173960160 13223568
05:48:57 4252 8192000000 10329050784 10749788160 1178405072 12.575 99 73 143 0 1431508384 237249504 15853808
05:50:05 3639 8192000000 10333709312 10749022208 1186157840 12.648 100 74 148 1 1436827312 233420400 17249072
05:51:13 1568 8192000000 10354000400 10762969088 1187630176 12.661 100 87 174 0 1458274320 251047088 19597056
05:52:24 1959 8192000000 10375531200 10782318592 1178454784 12.576 100 93 183 3 1473852528 274651056 20746688
05:53:29 5825 8192000000 10324837088 10747592704 1166058544 12.460 100 44 88 0 1421029552 244488832 10482176
05:54:44 4286 8192000000 10330817568 10753990656 1156491440 12.370 100 39 77 0 1430917984 264526912 9899632
05:55:55 2236 8192000000 10321622304 10727243776 1149705200 12.307 100 81 163 0 1419073584 250434784 18933600
05:57:01 5630 8192000000 10309476864 10753011712 1149118592 12.301 100 45 90 0 1424930464 263983824 11828048
05:58:17 10396 8192000000 10318687296 10756612096 1115409104 11.984 98 41 82 1 1414656304 288819680 10427520
05:59:29 11327 8192000000 10310879120 10740240384 1139879040 12.214 100 38 77 0 1408652496 259467424 9306032
06:00:48 21447 8192000000 10323777728 10794893312 1129977520 12.121 100 25 50 1 1425793584 288755840 7060224
06:02:17 15406 8192000000 10275451104 10712924160 1130122992 12.123 100 28 56 1 1368984528 232067776 6793760
06:03:38 16956 8192000000 10122037952 10602999808 1096001792 11.800 98 24 48 3 1237506960 135053920 6451248
06:05:18 5601 8192000000 10119918672 10539544576 1105242832 11.887 100 42 84 0 1215000304 100975664 8781808
06:06:29 71886 8192000000 10120107344 10654228480 1110367856 11.936 99 11 22 1 1263621904 150458128 2795920
06:08:58 39880 8192000000 10042584544 10467454976 1114603712 11.976 99 28 53 1 1146218480 24557216 7057552
06:10:42 83744 8192000000 10081407392 10553163776 1089904560 11.742 98 15 30 1 1168197440 75119216 3173664
06:13:06 56612 8192000000 10044795552 10477793280 1094681744 11.787 98 9 18 0 1118508864 22322240 1504880
06:15:29 41739 8192000000 10104264976 10542338048 1126472208 12.088 100 30 60 0 1192577136 59563424 6541504
06:17:14 57264 8192000000 10138310256 10558717952 1106425008 11.899 98 25 50 2 1173419760 61891680 5103072
06:19:14 103984 8192000000 10085629360 10553303040 1096203840 11.802 97 9 18 1 1151937616 54024832 1708944
06:22:01 13193 8192000000 10068297504 10488754176 1134512064 12.164 100 44 88 2 1157051168 13030800 9508304
06:23:49 153668 8192000000 10102661904 10559934464 1111539840 11.947 97 30 59 2 1187068464 68825648 6702976
06:27:25 29847 8192000000 10170837840 10614280192 1155815008 12.364 100 37 76 0 1258945760 94941504 8189248
06:28:56 1716 8192000000 10218887920 10650718208 1167437344 12.473 100 67 129 5 1314411360 132388240 14585776
06:30:09 70787 8192000000 10208204624 10716884992 1167290224 12.471 100 16 33 1 1294870400 123696784 3883392
06:32:21 27752 8192000000 10162048016 10642771968 1158996688 12.394 100 17 32 0 1281265456 118600352 3668416
06:33:50 78998 8192000000 10158917216 10692911104 1160641888 12.409 100 11 22 0 1278615744 115492304 2481552
06:36:09 30824 8192000000 10082939968 10531209216 1144813744 12.261 99 26 49 2 1174038592 23733456 5491392
06:37:43 38557 8192000000 10122397248 10565173248 1162245584 12.424 100 29 58 0 1217942496 49539648 6157264
06:39:25 43833 8192000000 10101958112 10540650496 1124368592 12.068 97 11 22 0 1194388640 67797280 2222768
06:41:28 53049 8192000000 10080779392 10504134656 1118470096 12.013 97 22 44 0 1154183280 31155824 4557360
06:43:26 35655 8192000000 10131623904 10571948032 1086674448 11.711 96 32 64 1 1167565280 74443248 6447584
06:45:26 29930 8192000000 10066561008 10500108288 1090613760 11.748 97 24 48 0 1136467456 40805632 5048064
06:46:57 150470 8192000000 10084707120 10525065216 1099879520 11.836 98 19 38 0 1195346608 91069408 4397680
06:50:34 82528 8192000000 10041840928 10510311424 1084829216 11.693 97 10 20 0 1117619936 30073344 2717376
06:53:05 111969 8192000000 10100178800 10563489792 1097175392 11.811 98 14 28 0 1147564880 47750176 2639312
06:56:36 41065 8192000000 10144533424 10594238464 1121495360 12.041 99 26 52 1 1196564384 69381856 5687168
06:58:20 45892 8192000000 10129081984 10563866624 1100115056 11.839 97 24 48 0 1212626848 107321120 5190672
07:00:13 73487 8192000000 10091221296 10580774912 1110489136 11.937 99 14 28 0 1210919504 97226336 3204032
07:02:32 50617 8192000000 10050583984 10509352960 1105759680 11.892 99 18 36 0 1180078512 70234080 4084752
07:04:27 40840 8192000000 10075122080 10523254784 1091467632 11.757 99 40 79 0 1158749680 59803552 7478496
07:06:08 122302 8192000000 10082787440 10567610368 1110874080 11.941 100 39 78 3 1216895664 97059104 8962480
07:09:29 58690 8192000000 10086902624 10590318592 1117937376 12.008 100 6 12 0 1181517440 62518880 1061184
07:12:01 79047 8192000000 10084864848 10523672576 1078427248 11.632 95 20 40 0 1128300032 45914608 3958176
07:14:28 51742 8192000000 10117868208 10560811008 1127212112 12.095 99 25 50 2 1189521584 57197008 5112464
07:16:34 89511 8192000000 10094142832 10559086592 1133252784 12.152 99 15 30 0 1218234624 81835040 3146800
07:19:20 45937 8192000000 10104789568 10542874624 1134817856 12.167 99 30 60 0 1174640896 33497024 6326016
07:21:11 158822 8192000000 10101023664 10590343168 1121911952 12.045 97 11 22 1 1181211728 57495936 1803840
07:24:50 164237 8192000000 10088665440 10549207040 1143098480 12.245 99 8 16 0 1176693456 31901456 1693520
07:28:39 74215 8192000000 10134817984 10612867072 1152856768 12.336 100 11 22 0 1219664672 64644336 2163568
07:31:21 53595 8192000000 10118111680 10658308096 1152385552 12.332 100 4 8 0 1230961904 77657808 918544
07:33:23 55002 8192000000 10056645648 10500702208 1093071808 11.772 95 23 45 2 1125187584 27927632 4188144
07:35:25 26157 8192000000 10106728352 10540556288 1117587728 12.004 98 32 64 0 1170405408 46221520 6596160
07:37:11 43019 8192000000 10135326016 10564845568 1096167856 11.801 96 39 78 1 1201115136 95921936 9025344

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 18-06-2021 07:39:59 to 18-06-2021 07:40:14.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 112 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 18-06-2021 07:40:15 to 18-06-2021 07:40:17.

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          223034
MON$OLDEST_ACTIVE               227827
MON$OLDEST_SNAPSHOT             227827
MON$NEXT_TRANSACTION            227827
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                       2113984
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             787
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2518 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2518 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2518 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2518 Firebird 4.0/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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Fri Jun 18 07:38:47 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Fri Jun 18 07:38:47 2021
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Fri Jun 18 07:38:47 2021
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:17: 
--- end of diff output ---

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

18.06.21 07:40:18. 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-06-18 07:40:21 No severe PSQL-related problems occured
18.06.21 07:40:22. End of report.