Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.04.2021 07:38
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 df9f488d08424df294c459d6c283e9c3
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-03-04 16:07
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.26
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 16869776 kB
MemAvailable 31397764 kB
Buffers 0 kB
Cached 14751548 kB
SwapCached 96 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-04-07 07:38:01
DTS_END                         2021-04-07 07:38:01.7960
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/43606
TRN_ID                          230677
ATT_ID                          605
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: 8584.76 23023 1030171
customer order: creation 167.96 438 20155
customer order: refuse 14.29 407 1715
order to supplier: creation 45.59 1529 5471
order to supplier: removal 3.14 124187 377
invoice (draft): creation 45.14 4152 5417
invoice (draft): removal 45.52 1093 5462
invoice accept: apply 41.06 18393 4927
invoice accept: cancel 41.32 1144 4958
customer reserve: creation 3780.28 179 453633
customer reserve: removal 4124.80 74 494976
realization accept: apply 81.57 195 9788
realization accept: cancel 69.67 138 8360
payment from customer: creation 57.49 137 6899
payment from customer: removal 5.49 95 659
payment to supplier: creation 47.71 138 5725
payment to supplier: removal 3.07 77 368
service: total inventory turnovers 3.77 3992 452
service: total monetary turnovers 5.99 676 719
service: aggregate perf. data 0.88 17838 106
service: refresh index statistics 0.03 285577 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 07-04-2021 07:38:41 to 07-04-2021 07:38:43.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8894
TEST_TIME 2 6345
TEST_TIME 3 5005
TEST_TIME 4 4962
TEST_TIME 5 5011
TEST_TIME 6 5639
TEST_TIME 7 5575
TEST_TIME 8 5907
TEST_TIME 9 5822
TEST_TIME 10 5980
TEST_TIME 11 6264
TEST_TIME 12 6303
TEST_TIME 13 6437
TEST_TIME 14 6447
TEST_TIME 15 6777
TEST_TIME 16 6839
TEST_TIME 17 7516
TEST_TIME 18 7936
TEST_TIME 19 7884
TEST_TIME 20 8089
TEST_TIME 21 8416
TEST_TIME 22 8733
TEST_TIME 23 8557
TEST_TIME 24 8668
TEST_TIME 25 8920
TEST_TIME 26 9043
TEST_TIME 27 9154
TEST_TIME 28 9808
TEST_TIME 29 10191
TEST_TIME 30 10862
TEST_TIME 31 10594
TEST_TIME 32 10508
TEST_TIME 33 10470
TEST_TIME 34 10326
TEST_TIME 35 10329
TEST_TIME 36 10165
TEST_TIME 37 10012
TEST_TIME 38 9865
TEST_TIME 39 9751
TEST_TIME 40 9737
TEST_TIME 41 9589
TEST_TIME 42 9405
TEST_TIME 43 9305
TEST_TIME 44 9170
TEST_TIME 45 9330
TEST_TIME 46 9237
TEST_TIME 47 9137
TEST_TIME 48 8991
TEST_TIME 49 9072
TEST_TIME 50 8962
TEST_TIME 51 9033
TEST_TIME 52 8993
TEST_TIME 53 8957
TEST_TIME 54 8962
TEST_TIME 55 9044
TEST_TIME 56 9087
TEST_TIME 57 9024
TEST_TIME 58 9016
TEST_TIME 59 9086
TEST_TIME 60 9066
TEST_TIME 61 8995
TEST_TIME 62 9066
TEST_TIME 63 9134
TEST_TIME 64 9015
TEST_TIME 65 8930
TEST_TIME 66 8921
TEST_TIME 67 8829
TEST_TIME 68 8748
TEST_TIME 69 8739
TEST_TIME 70 8682
TEST_TIME 71 8743
TEST_TIME 72 8715
TEST_TIME 73 8669
TEST_TIME 74 8687
TEST_TIME 75 8743
TEST_TIME 76 8713
TEST_TIME 77 8763
TEST_TIME 78 8726
TEST_TIME 79 8732
TEST_TIME 80 8834
TEST_TIME 81 8812
TEST_TIME 82 8930
TEST_TIME 83 8877
TEST_TIME 84 8798
TEST_TIME 85 8734
TEST_TIME 86 8679
TEST_TIME 87 8632
TEST_TIME 88 8608
TEST_TIME 89 8552
TEST_TIME 90 8611
TEST_TIME 91 8593
TEST_TIME 92 8594
TEST_TIME 93 8596
TEST_TIME 94 8590
TEST_TIME 95 8592
TEST_TIME 96 8668
TEST_TIME 97 8628
TEST_TIME 98 8656
TEST_TIME 99 8656
TEST_TIME 100 8698
TEST_TIME 101 8686
TEST_TIME 102 8749
TEST_TIME 103 8747
TEST_TIME 104 8746
TEST_TIME 105 8696
TEST_TIME 106 8625
TEST_TIME 107 8669
TEST_TIME 108 8637
TEST_TIME 109 8638
TEST_TIME 110 8604
TEST_TIME 111 8639
TEST_TIME 112 8621
TEST_TIME 113 8654
TEST_TIME 114 8681
TEST_TIME 115 8666
TEST_TIME 116 8647
TEST_TIME 117 8633
TEST_TIME 118 8687
TEST_TIME 119 8657
TEST_TIME 120 8589

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 07-04-2021 07:38:44 to 07-04-2021 07:38:48.

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 1447848 1447848 0 0.00 0 25137 7 0 0
doc_list_biud 1447848 1447848 0 0.00 0 86280 33 0 0
sp_add_invoice_to_stock 5052 4927 125 2.47 35 195150 18393 0 125
sp_cancel_adding_invoice 4958 4958 0 0.00 5 72419 1144 0 0
sp_cancel_client_order 1715 1715 0 0.00 5 33216 408 0 0
sp_cancel_customer_reserve 494976 494976 0 0.00 1 86281 75 0 0
sp_cancel_pay_from_customer 659 659 0 0.00 3 7872 95 0 0
sp_cancel_pay_to_supplier 368 368 0 0.00 3 1489 78 0 0
sp_cancel_supplier_invoice 5468 5462 6 0.10 1 76545 1094 0 6
sp_cancel_supplier_order 377 377 0 0.00 6 1131605 124188 0 0
sp_cancel_write_off 8360 8360 0 0.00 1 9553 139 0 0
sp_client_order 20155 20155 0 0.00 4 24494 439 0 0
sp_customer_reserve 453633 453633 0 0.00 0 37081 180 0 0
sp_fill_shopping_cart 31039 31039 0 0.00 1 18752 57 0 0
sp_fill_shopping_cart_clo_res 453630 453630 0 0.00 0 26062 19 0 0
sp_get_clo_for_invoice 4924 4924 0 0.00 0 32128 480 0 0
sp_kill_cost_storno 14345 14345 0 0.00 0 10032 72 0 0
sp_kill_qstorno_ret_qs2qd 1004151 1004151 0 0.00 0 83009 40 0 0
sp_kill_qty_storno 530560 530560 0 0.00 0 86280 89 0 0
sp_lock_selected_doc 20203 20203 0 0.00 0 7567 3 0 0
sp_make_cost_storno 27336 27336 0 0.00 0 25114 59 0 0
sp_multiply_rows_for_pdistr 27336 27336 0 0.00 0 24933 36 0 0
sp_multiply_rows_for_qdistr 20155 20155 0 0.00 0 20083 234 0 0
sp_pay_from_customer 6899 6899 0 0.00 4 24961 138 0 0
sp_pay_to_supplier 5725 5725 0 0.00 4 17939 139 0 0
sp_payment_common 12624 12624 0 0.00 1 9573 22 0 0
sp_qd_handle_on_cancel_clo 1715 1715 0 0.00 0 33210 365 0 0
sp_qd_handle_on_invoice_upd_sts 9882 9882 0 0.00 0 42539 471 0 0
sp_qd_handle_on_reserve_upd_sts 9788 9788 0 0.00 0 20662 64 0 0
sp_reserve_write_off 9788 9788 0 0.00 7 20989 196 0 0
sp_supplier_invoice 5417 5417 0 0.00 7 126488 4152 0 0
sp_supplier_order 5471 5471 0 0.00 5 37880 1530 0 0
srv_aggregate_perf_data 106 106 0 0.00 2457 379707 17838 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1221 162866 45642 0 0
srv_find_qd_qs_mism 982379 982379 0 0.00 0 24729 6 0 0
srv_make_invnt_saldo 452 452 0 0.00 52 109722 3993 0 0
srv_make_money_saldo 719 719 0 0.00 1 18029 676 0 0
srv_recalc_idx_stat 4 4 0 0.00 176667 450418 285578 0 0
t$perf-abend:sp_add_invoice_to_stock 125 125 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4928 4928 0 0.00 0 9815 174 0 0
t$perf-norm:sp_cancel_adding_invoice 384 384 0 0.00 0 4812 116 0 0
t$perf-norm:sp_cancel_client_order 1715 1715 0 0.00 0 6069 17 0 0
t$perf-norm:sp_cancel_customer_reserve 670 670 0 0.00 0 676 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 659 659 0 0.00 0 1390 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 368 368 0 0.00 0 109 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 355 355 0 0.00 0 3510 28 0 0
t$perf-norm:sp_cancel_supplier_order 377 377 0 0.00 0 19721 1589 0 0
t$perf-norm:sp_cancel_write_off 963 963 0 0.00 0 7663 13 0 0
t$perf-norm:sp_client_order 20155 20155 0 0.00 0 6717 21 0 0
t$perf-norm:sp_customer_reserve 987 987 0 0.00 0 3182 8 0 0
t$perf-norm:sp_pay_from_customer 6899 6899 0 0.00 0 7362 13 0 0
t$perf-norm:sp_pay_to_supplier 5725 5725 0 0.00 0 5737 10 0 0
t$perf-norm:sp_reserve_write_off 9788 9788 0 0.00 0 6879 11 0 0
t$perf-norm:sp_supplier_invoice 5417 5417 0 0.00 0 5391 19 0 0
t$perf-norm:sp_supplier_order 5471 5471 0 0.00 0 8132 20 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 186 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 133 5 0 0
t$perf-norm:srv_make_invnt_saldo 452 452 0 0.00 0 1788 11 0 0
t$perf-norm:srv_make_money_saldo 719 719 0 0.00 0 1557 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 7 3 0 0
v_add_invoice_to_stock 10098 9848 250 2.47 0 10453 17 0 250
v_all_customers 36612 36612 0 0.00 0 29 1 0 0
v_all_suppliers 29906 29906 0 0.00 0 21 1 0 0
v_all_wares 40310 40310 0 0.00 0 102 1 0 0
v_cancel_adding_invoice 768 768 0 0.00 0 425 7 0 0
v_cancel_client_order 3430 3430 0 0.00 0 4129 6 0 0
v_cancel_customer_prepayment 1318 1318 0 0.00 0 1882 7 0 0
v_cancel_customer_reserve 1340 1340 0 0.00 0 12078 28 0 0
v_cancel_payment_to_supplier 736 736 0 0.00 0 718 6 0 0
v_cancel_supplier_invoice 722 710 12 1.66 0 4705 26 0 12
v_cancel_supplier_order 754 754 0 0.00 0 163 4 0 0
v_cancel_write_off 1926 1926 0 0.00 0 1709 14 0 0
v_max_id_clo_ord 5467 5467 0 0.00 0 4147 2 0 0
v_max_id_clo_res 987 987 0 0.00 0 3520 17 0 0
v_max_id_ord_sup 5415 5415 0 0.00 0 8059 7 0 0
v_max_non_paid_invoice 1654 1654 0 0.00 0 1522 3 0 0
v_max_non_paid_realizn 3648 3648 0 0.00 0 5676 7 0 0
v_min_id_clo_ord 5467 5467 0 0.00 0 1219 6 0 0
v_min_id_clo_res 987 987 0 0.00 0 7676 16 0 0
v_min_id_ord_sup 5415 5415 0 0.00 0 9382 13 0 0
v_min_non_paid_invoice 5725 5725 0 0.00 0 2734 10 0 0
v_min_non_paid_realizn 6899 6899 0 0.00 0 9556 16 0 0
v_our_firm 10200 10200 0 0.00 0 61 1 0 0
v_reserve_write_off 19576 19576 0 0.00 0 8873 6 0 0
x_lock_depdocs_on_canc_invoice 10065 10065 0 0.00 0 8243 133 0 0
x_lock_depdocs_on_canc_sup_ord 377 377 0 0.00 0 5153 359 0 0
x_make_qstorno_1000_1200 5471 5471 0 0.00 2 37750 1442 0 0
x_make_qstorno_1200_2000 5417 5417 0 0.00 3 117850 3917 0 0
x_make_qstorno_2100_3300 453633 453633 0 0.00 0 37079 158 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-04-2021 07:38:49 to 07-04-2021 07:38:53.

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:05 10 8192000000 8802512992 8815173632 7653712 0.093 3 1 2 0 7774720 80864 40144
05:09:06 24377 8192000000 9720399904 10016223232 801200304 8.908 100 83 166 0 829340304 7475264 20664736
05:10:37 28875 8192000000 9771726944 10078789632 842616336 9.326 100 86 172 0 875022240 12256016 20149888
05:12:10 20437 8192000000 9814025488 10133639168 876655456 9.666 100 82 164 0 916188592 20899664 18633472
05:13:33 9219 8192000000 9874032480 10204598272 922186384 10.118 100 73 146 0 980900832 42880944 15833504
05:14:45 7977 8192000000 9897791072 10247159808 959259472 10.482 100 66 132 1 1019147984 46020896 13867616
05:15:55 5794 8192000000 9968879392 10324656128 990916816 10.790 100 76 151 0 1079619616 73030000 15672800
05:17:03 2788 8192000000 10005106512 10366779392 1019908720 11.071 100 85 166 2 1116688880 79865392 16914768
05:18:08 1632 8192000000 10013073280 10380713984 1046545088 11.328 100 89 178 2 1124284480 59377728 18361664
05:19:12 1714 8192000000 10048535968 10421190656 1070438048 11.556 100 89 179 1 1149457856 61001552 18018256
05:20:15 6138 8192000000 10007271952 10387841024 1071379440 11.565 100 52 104 1 1106661680 23720000 11562240
05:21:21 2889 8192000000 10016106224 10406309888 1082236416 11.669 100 48 94 0 1131603888 38727456 10640016
05:22:24 10044 8192000000 10000718592 10396684288 1091325616 11.755 100 38 76 2 1118531104 20007584 7197904
05:23:37 8537 8192000000 10007486224 10403373056 1094503008 11.785 100 36 72 0 1125670320 22884400 8282912
05:24:47 39317 8192000000 10008290240 10421678080 1100680944 11.844 100 42 83 1 1119465648 9977632 8807072
05:26:29 5823 8192000000 10015019072 10420056064 1102350048 11.860 100 30 60 1 1127048304 17927376 6770880
05:27:40 6545 8192000000 10031012880 10433073152 1106110560 11.896 100 52 101 1 1141757504 23930880 11716064
05:28:51 6605 8192000000 9988409328 10395422720 1074960688 11.599 98 30 60 2 1095124480 13009136 7154656
05:29:59 49418 8192000000 9955814880 10350411776 994635632 10.826 93 33 66 1 1024168416 22285360 7247424
05:31:55 8618 8192000000 9910089616 10285559808 964818480 10.536 95 24 44 3 996745472 26525568 5401424
05:33:06 10194 8192000000 9937488928 10326138880 995415792 10.834 97 40 80 1 1015598416 11167920 9014704
05:34:17 7601 8192000000 9949410288 10341523456 1039599520 11.261 100 31 59 1 1056101120 10005776 6495824
05:35:30 28127 8192000000 9961841552 10365526016 1054758464 11.406 100 25 48 0 1095618432 36200448 4659520
05:37:01 7966 8192000000 9974335808 10372497408 1062216272 11.478 100 37 72 3 1087851312 17109712 8525328
05:38:13 6032 8192000000 10003264320 10391654400 1085896992 11.704 100 51 102 2 1104382928 7307936 11178000
05:39:23 11194 8192000000 10035443696 10436149248 1097749952 11.816 100 49 97 0 1148814240 40924640 10139648
05:40:38 44441 8192000000 10046981712 10453843968 1115791936 11.987 100 32 64 2 1162851216 40616880 6442400
05:42:32 13128 8192000000 10104646144 10516676608 1128682640 12.109 100 36 72 1 1205624384 69311024 7630720
05:43:52 11393 8192000000 10142320128 10553356288 1142610960 12.240 100 55 109 2 1256225152 101914320 11699872
05:45:08 3022 8192000000 10171631984 10572484608 1149463072 12.304 100 72 141 3 1269800400 105691360 14645968
05:46:21 12807 8192000000 10190072896 10608005120 1158453376 12.389 100 48 96 1 1301522192 133006176 10062640
05:47:37 3631 8192000000 10227785248 10639220736 1161491664 12.417 100 71 140 0 1331840048 155111120 15237264
05:48:44 4813 8192000000 10240416928 10652422144 1164804240 12.448 100 64 124 0 1355516144 177119888 13592016
05:49:54 2422 8192000000 10259810016 10665222144 1164719536 12.447 100 82 162 0 1363533072 181046256 17767280
05:51:01 4622 8192000000 10288205952 10709164032 1168285200 12.481 100 64 127 3 1396939648 213995296 14659152
05:52:10 4782 8192000000 10296741344 10713182208 1156313008 12.369 100 55 110 0 1398739424 229282960 13143456
05:53:21 7968 8192000000 10355347712 10762530816 1164530528 12.446 100 75 148 0 1457725792 275810480 17384784
05:54:37 1830 8192000000 10397866080 10809548800 1172064080 12.516 100 78 156 2 1502158864 312055664 18039120
05:55:42 4855 8192000000 10311309328 10740760576 1133923872 12.158 98 72 142 0 1411245536 261142352 16179312
05:56:52 1219 8192000000 10308975152 10711937024 1157877616 12.383 100 83 164 0 1410572352 234059840 18634896
05:58:00 6638 8192000000 10303837744 10724556800 1136445856 12.182 98 64 127 0 1395264816 243693456 15125504
05:59:13 7785 8192000000 10298051280 10704510976 1134013264 12.159 99 56 107 3 1397300240 250006576 13280400
06:00:26 15650 8192000000 10334213808 10762895360 1149655152 12.306 100 38 76 1 1433836960 274234848 9946960
06:01:49 6747 8192000000 10404282544 10815557632 1152256144 12.331 99 61 122 1 1493344288 326723552 14364592
06:03:02 10986 8192000000 10392487904 10810851328 1142848688 12.242 100 37 74 0 1491011328 338240096 9922544
06:04:16 9479 8192000000 10390311536 10813399040 1114425024 11.974 99 35 70 0 1478975248 355274656 9275568
06:05:32 9567 8192000000 10392646048 10818355200 1113400848 11.965 100 31 62 0 1481953984 360132112 8421024
06:06:48 27440 8192000000 10282022240 10769588224 1116980128 11.998 100 24 48 1 1436165392 312501632 6683632
06:08:16 56429 8192000000 10065422688 10497949696 1099465248 11.833 98 24 47 1 1202172160 97878640 4828272
06:10:15 51352 8192000000 10116007536 10544914432 1126871760 12.092 100 35 70 1 1200443520 65455216 8116544
06:12:12 77071 8192000000 10081793360 10544164864 1119542032 12.023 100 17 35 0 1185355936 61873072 3940832
06:15:42 33691 8192000000 10027037216 10465619968 1100056304 11.838 99 17 34 0 1118639264 14602352 3980608
06:17:25 53336 8192000000 10092246560 10531262464 1124691344 12.071 100 34 66 3 1184070800 52395248 6984208
06:19:40 51597 8192000000 10104852256 10532577280 1138289520 12.199 100 24 49 0 1197327440 53770736 5267184
06:21:33 80265 8192000000 10180172112 10624856064 1150065152 12.310 100 18 34 0 1266940112 113237792 3637168
06:24:30 80077 8192000000 10097280864 10560319488 1142922496 12.243 100 23 46 0 1218492992 70489824 5080672
06:27:20 82497 8192000000 10096819936 10568372224 1124620720 12.071 98 13 26 1 1185360752 57856992 2883040
06:29:43 59552 8192000000 10069915248 10531250176 1113698896 11.967 98 10 21 0 1147559088 31801360 2058832
06:31:53 50596 8192000000 10120050832 10577293312 1141864496 12.233 100 32 64 1 1228989744 79784608 7340640
06:33:44 70708 8192000000 10078609328 10539667456 1134615824 12.165 99 11 22 2 1183548496 46516544 2416128
06:36:02 81295 8192000000 10118731392 10560581632 1117047408 11.999 97 23 46 1 1187096400 64783728 5265264
06:39:50 87152 8192000000 10075372320 10559348736 1112705696 11.958 97 17 34 1 1220777920 104575264 3496960
06:42:18 105996 8192000000 10076024640 10526101504 1125621120 12.080 99 24 48 1 1178284992 47558368 5105504
06:45:04 47488 8192000000 10070126064 10548084736 1124050544 12.065 99 7 14 0 1168158144 42698400 1409200
06:47:00 51127 8192000000 10057192240 10533408768 1092548656 11.767 96 16 32 1 1153660448 57250288 3861504
06:49:00 86904 8192000000 10060081488 10526261248 1112343760 11.955 98 13 26 0 1147967616 32720208 2903648
06:51:27 64643 8192000000 10115240256 10561388544 1082006880 11.667 95 26 52 1 1134064224 46771984 5285360
06:53:36 60092 8192000000 10063567584 10501992448 1071038944 11.562 95 20 40 1 1123310512 48010960 4260608
06:55:37 69071 8192000000 10094014368 10528489472 1070378976 11.556 95 11 21 0 1124897120 52384800 2133344
06:57:47 49472 8192000000 10117419136 10564661248 1089102512 11.734 98 21 42 0 1182647664 89269056 4276096
06:59:43 39413 8192000000 10059697920 10543849472 1106237952 11.897 99 22 44 1 1176761696 65427040 5096704
07:01:24 123632 8192000000 10007511248 10467586048 1072529200 11.576 97 13 26 0 1101237040 25830832 2877008
07:04:42 62850 8192000000 10046325136 10492932096 1107685792 11.911 100 14 28 0 1157047808 46021408 3340608
07:07:38 65737 8192000000 10049389328 10531307520 1083491296 11.681 97 20 40 0 1145314560 58009776 3813488
07:09:49 76595 8192000000 10043008544 10475302912 1059743920 11.454 94 17 34 0 1089060688 26124416 3192352
07:12:07 42888 8192000000 10090856000 10530160640 1136721392 12.185 100 29 59 3 1198069200 55003168 6344640
07:14:02 78492 8192000000 10098745168 10567213056 1127941152 12.102 99 20 39 2 1213898128 81716992 4239984
07:16:21 46719 8192000000 10095362944 10579402752 1115330976 11.983 98 17 34 0 1164403008 45124880 3947152
07:18:10 78835 8192000000 10071253568 10522255360 1095396160 11.794 97 28 56 1 1140316320 39128048 5792112
07:20:30 72704 8192000000 10096218912 10579464192 1111840816 11.950 98 14 28 0 1193021104 77811216 3369072
07:22:45 162866 8192000000 10067227184 10572521472 1126618304 12.089 99 19 38 0 1231177552 100487728 4071520
07:26:50 68837 8192000000 10083683392 10566529024 1113204032 11.963 98 3 6 0 1170307936 56636112 467792
07:29:19 75969 8192000000 10103937968 10602471424 1141778832 12.232 100 17 34 3 1253813504 108174448 3860224
07:31:36 83317 8192000000 10078095968 10550677504 1110113184 11.933 97 9 18 0 1171306064 59319696 1873184
07:34:00 51928 8192000000 10066666880 10503819264 1077099936 11.620 95 23 46 0 1111674624 29941472 4633216
07:35:52 53017 8192000000 10057729664 10536095744 1050580976 11.366 94 23 46 1 1131111232 75846416 4683840
07:38:00 8715 8192000000 9167566064 9442562048 295905872 3.486 28 5 5 2 334614096 37342640 1365584

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 07-04-2021 07:38:55 to 07-04-2021 07:39:10.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 125 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 07-04-2021 07:39:11 to 07-04-2021 07:39:13.

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          231050
MON$OLDEST_ACTIVE               231051
MON$OLDEST_SNAPSHOT             231051
MON$NEXT_TRANSACTION            231051
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                       2087520
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             793
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2411 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2411 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2411 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.2411 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 ---
--- end of diff output ---

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

07.04.21 07:39:14. 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-04-07 07:39:16 No severe PSQL-related problems occured
07.04.21 07:39:18. End of report.