Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.09.2021 04:37
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 15692952 kB
MemAvailable 30754688 kB
Buffers 20 kB
Cached 15919008 kB
SwapCached 668 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-09-21 04:37:07
DTS_END                         2021-09-21 04:37:07.3550
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/46778
TRN_ID                          238481
ATT_ID                          630
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: 9370.47 23767 1124456
customer order: creation 173.46 419 20815
customer order: refuse 14.81 339 1777
order to supplier: creation 44.62 1516 5354
order to supplier: removal 3.60 130045 432
invoice (draft): creation 45.84 3623 5501
invoice (draft): removal 54.20 1197 6504
invoice accept: apply 41.68 18609 5002
invoice accept: cancel 48.94 956 5873
customer reserve: creation 3867.98 182 464157
customer reserve: removal 4781.13 76 573736
realization accept: apply 84.01 187 10081
realization accept: cancel 78.72 136 9446
payment from customer: creation 60.77 124 7292
payment from customer: removal 5.61 98 673
payment to supplier: creation 49.93 135 5991
payment to supplier: removal 3.56 114 427
service: total inventory turnovers 4.25 3232 510
service: total monetary turnovers 6.55 557 786
service: aggregate perf. data 0.79 17786 95
service: refresh index statistics 0.03 295999 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 21-09-2021 04:38:07 to 21-09-2021 04:38:10.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4289
TEST_TIME 2 5745
TEST_TIME 3 5191
TEST_TIME 4 4979
TEST_TIME 5 4645
TEST_TIME 6 4402
TEST_TIME 7 4502
TEST_TIME 8 4520
TEST_TIME 9 4905
TEST_TIME 10 5102
TEST_TIME 11 5714
TEST_TIME 12 5986
TEST_TIME 13 6474
TEST_TIME 14 6694
TEST_TIME 15 7409
TEST_TIME 16 7790
TEST_TIME 17 8210
TEST_TIME 18 8173
TEST_TIME 19 8180
TEST_TIME 20 8294
TEST_TIME 21 8262
TEST_TIME 22 8181
TEST_TIME 23 8736
TEST_TIME 24 9140
TEST_TIME 25 8954
TEST_TIME 26 9499
TEST_TIME 27 10392
TEST_TIME 28 12080
TEST_TIME 29 12073
TEST_TIME 30 11824
TEST_TIME 31 11697
TEST_TIME 32 11607
TEST_TIME 33 11454
TEST_TIME 34 11276
TEST_TIME 35 11167
TEST_TIME 36 10922
TEST_TIME 37 10868
TEST_TIME 38 10836
TEST_TIME 39 10875
TEST_TIME 40 10806
TEST_TIME 41 10785
TEST_TIME 42 10689
TEST_TIME 43 10562
TEST_TIME 44 10480
TEST_TIME 45 10286
TEST_TIME 46 10261
TEST_TIME 47 10255
TEST_TIME 48 10223
TEST_TIME 49 10255
TEST_TIME 50 10231
TEST_TIME 51 10174
TEST_TIME 52 10128
TEST_TIME 53 10096
TEST_TIME 54 10304
TEST_TIME 55 10266
TEST_TIME 56 10350
TEST_TIME 57 10327
TEST_TIME 58 10316
TEST_TIME 59 10290
TEST_TIME 60 10290
TEST_TIME 61 10188
TEST_TIME 62 10110
TEST_TIME 63 10052
TEST_TIME 64 9950
TEST_TIME 65 9865
TEST_TIME 66 9915
TEST_TIME 67 9903
TEST_TIME 68 9871
TEST_TIME 69 9870
TEST_TIME 70 9917
TEST_TIME 71 9861
TEST_TIME 72 9859
TEST_TIME 73 9824
TEST_TIME 74 9944
TEST_TIME 75 9919
TEST_TIME 76 9880
TEST_TIME 77 9903
TEST_TIME 78 9887
TEST_TIME 79 9859
TEST_TIME 80 9827
TEST_TIME 81 9784
TEST_TIME 82 9774
TEST_TIME 83 9782
TEST_TIME 84 9742
TEST_TIME 85 9675
TEST_TIME 86 9627
TEST_TIME 87 9579
TEST_TIME 88 9534
TEST_TIME 89 9473
TEST_TIME 90 9505
TEST_TIME 91 9516
TEST_TIME 92 9540
TEST_TIME 93 9516
TEST_TIME 94 9531
TEST_TIME 95 9565
TEST_TIME 96 9534
TEST_TIME 97 9638
TEST_TIME 98 9657
TEST_TIME 99 9692
TEST_TIME 100 9670
TEST_TIME 101 9674
TEST_TIME 102 9680
TEST_TIME 103 9732
TEST_TIME 104 9644
TEST_TIME 105 9574
TEST_TIME 106 9543
TEST_TIME 107 9523
TEST_TIME 108 9490
TEST_TIME 109 9450
TEST_TIME 110 9435
TEST_TIME 111 9405
TEST_TIME 112 9397
TEST_TIME 113 9375
TEST_TIME 114 9368
TEST_TIME 115 9381
TEST_TIME 116 9430
TEST_TIME 117 9434
TEST_TIME 118 9429
TEST_TIME 119 9445
TEST_TIME 120 9373

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 21-09-2021 04:38:11 to 21-09-2021 04:38:14.

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 1552946 1552946 0 0.00 0 40093 7 0 0
doc_list_biud 1552945 1552945 0 0.00 0 49645 36 0 0
sp_add_invoice_to_stock 5134 5002 132 2.57 40 168434 18610 0 132
sp_cancel_adding_invoice 5873 5873 0 0.00 5 94368 957 0 0
sp_cancel_client_order 1777 1777 0 0.00 5 13149 340 0 0
sp_cancel_customer_reserve 573736 573736 0 0.00 1 49653 76 0 0
sp_cancel_pay_from_customer 673 673 0 0.00 3 9086 98 0 0
sp_cancel_pay_to_supplier 427 427 0 0.00 3 8021 114 0 0
sp_cancel_supplier_invoice 6514 6504 10 0.15 2 21208 1198 0 10
sp_cancel_supplier_order 432 432 0 0.00 8 1387268 130045 0 0
sp_cancel_write_off 9446 9446 0 0.00 1 11176 137 0 0
sp_client_order 20815 20815 0 0.00 4 26285 419 0 0
sp_customer_reserve 464157 464157 0 0.00 0 100832 183 0 0
sp_fill_shopping_cart 31659 31659 0 0.00 1 13587 46 0 0
sp_fill_shopping_cart_clo_res 464156 464156 0 0.00 0 19854 17 0 0
sp_get_clo_for_invoice 5000 5000 0 0.00 0 24951 354 0 0
sp_kill_cost_storno 16419 16419 0 0.00 0 40079 68 0 0
sp_kill_qstorno_ret_qs2qd 1163854 1163854 0 0.00 0 49636 42 0 0
sp_kill_qty_storno 612848 612848 0 0.00 0 49645 90 0 0
sp_lock_selected_doc 20913 20913 0 0.00 0 9119 5 0 0
sp_make_cost_storno 28364 28364 0 0.00 0 15984 55 0 0
sp_multiply_rows_for_pdistr 28364 28364 0 0.00 0 13898 35 0 0
sp_multiply_rows_for_qdistr 20815 20815 0 0.00 0 22154 225 0 0
sp_pay_from_customer 7292 7292 0 0.00 4 11698 125 0 0
sp_pay_to_supplier 5991 5991 0 0.00 5 13328 135 0 0
sp_payment_common 13283 13283 0 0.00 1 5457 23 0 0
sp_qd_handle_on_cancel_clo 1777 1777 0 0.00 0 13143 301 0 0
sp_qd_handle_on_invoice_upd_sts 10872 10872 0 0.00 0 25128 409 0 0
sp_qd_handle_on_reserve_upd_sts 10081 10081 0 0.00 0 19943 66 0 0
sp_reserve_write_off 10081 10081 0 0.00 7 19956 187 0 0
sp_supplier_invoice 5501 5501 0 0.00 8 88422 3624 0 0
sp_supplier_order 5354 5354 0 0.00 6 81555 1517 0 0
srv_aggregate_perf_data 95 95 0 0.00 2545 136959 17787 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1094 142728 41839 0 0
srv_find_qd_qs_mism 1075711 1075711 0 0.00 0 10549 5 0 0
srv_make_invnt_saldo 510 510 0 0.00 26 100441 3232 0 0
srv_make_money_saldo 786 786 0 0.00 1 35801 558 0 0
srv_recalc_idx_stat 4 4 0 0.00 152027 514565 295999 0 0
t$perf-abend:sp_add_invoice_to_stock 132 132 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5003 5003 0 0.00 0 22698 207 0 0
t$perf-norm:sp_cancel_adding_invoice 402 402 0 0.00 0 4248 119 0 0
t$perf-norm:sp_cancel_client_order 1777 1777 0 0.00 0 2090 11 0 0
t$perf-norm:sp_cancel_customer_reserve 720 720 0 0.00 0 26128 50 0 0
t$perf-norm:sp_cancel_pay_from_customer 673 673 0 0.00 0 2668 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 427 427 0 0.00 0 3536 21 0 0
t$perf-norm:sp_cancel_supplier_invoice 396 396 0 0.00 0 9255 64 0 0
t$perf-norm:sp_cancel_supplier_order 432 432 0 0.00 0 26874 1639 0 0
t$perf-norm:sp_cancel_write_off 1006 1006 0 0.00 0 7930 16 0 0
t$perf-norm:sp_client_order 20815 20815 0 0.00 0 4628 19 0 0
t$perf-norm:sp_customer_reserve 1013 1013 0 0.00 0 3116 11 0 0
t$perf-norm:sp_pay_from_customer 7292 7292 0 0.00 0 11094 9 0 0
t$perf-norm:sp_pay_to_supplier 5991 5991 0 0.00 0 7571 10 0 0
t$perf-norm:sp_reserve_write_off 10081 10081 0 0.00 0 12942 15 0 0
t$perf-norm:sp_supplier_invoice 5501 5501 0 0.00 0 7627 21 0 0
t$perf-norm:sp_supplier_order 5354 5354 0 0.00 0 6507 16 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 198 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 145 3 0 0
t$perf-norm:srv_make_invnt_saldo 510 510 0 0.00 0 1590 16 0 0
t$perf-norm:srv_make_money_saldo 786 786 0 0.00 0 1090 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10262 9998 264 2.57 0 5893 18 0 264
v_all_customers 38154 38154 0 0.00 0 21 1 0 0
v_all_suppliers 30224 30224 0 0.00 0 20 1 0 0
v_all_wares 41630 41630 0 0.00 0 27 1 0 0
v_cancel_adding_invoice 804 804 0 0.00 0 4078 16 0 0
v_cancel_client_order 3554 3554 0 0.00 0 1756 6 0 0
v_cancel_customer_prepayment 1346 1346 0 0.00 0 3320 10 0 0
v_cancel_customer_reserve 1440 1440 0 0.00 0 837 4 0 0
v_cancel_payment_to_supplier 854 854 0 0.00 0 7981 13 0 0
v_cancel_supplier_invoice 812 792 20 2.46 0 1441 18 0 20
v_cancel_supplier_order 864 864 0 0.00 0 6630 13 0 0
v_cancel_write_off 2012 2012 0 0.00 0 1514 15 0 0
v_max_id_clo_ord 5352 5352 0 0.00 0 218 1 0 0
v_max_id_clo_res 1013 1013 0 0.00 0 1629 15 0 0
v_max_id_ord_sup 5490 5490 0 0.00 0 4829 5 0 0
v_max_non_paid_invoice 1721 1721 0 0.00 0 1531 3 0 0
v_max_non_paid_realizn 3794 3794 0 0.00 0 3905 4 0 0
v_min_id_clo_ord 5352 5352 0 0.00 0 4720 7 0 0
v_min_id_clo_res 1013 1013 0 0.00 0 2840 10 0 0
v_min_id_ord_sup 5490 5490 0 0.00 0 4779 8 0 0
v_min_non_paid_invoice 5991 5991 0 0.00 0 5338 16 0 0
v_min_non_paid_realizn 7292 7292 0 0.00 0 5352 16 0 0
v_our_firm 10472 10472 0 0.00 0 14 1 0 0
v_reserve_write_off 20162 20162 0 0.00 0 4063 6 0 0
x_lock_depdocs_on_canc_invoice 11981 11981 0 0.00 0 16950 117 0 0
x_lock_depdocs_on_canc_sup_ord 432 432 0 0.00 0 9089 317 0 0
x_make_qstorno_1000_1200 5354 5354 0 0.00 2 81545 1436 0 0
x_make_qstorno_1200_2000 5501 5501 0 0.00 3 88404 3446 0 0
x_make_qstorno_2100_3300 464157 464157 0 0.00 0 100830 163 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 21-09-2021 04:38:15 to 21-09-2021 04:38:20.

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
02:07:11 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:11 27761 8192000000 9720244192 10017533952 795253360 8.848 100 86 172 0 823982736 7768224 20961152
02:09:45 22231 8192000000 9762794464 10069442560 836548320 9.265 100 84 168 0 868905152 12348976 20007856
02:11:12 15819 8192000000 9820575904 10137059328 872504144 9.625 100 85 170 0 915995344 24178672 19312528
02:12:30 9105 8192000000 9883015632 10217304064 915584256 10.052 100 81 161 1 990386560 57263168 17539136
02:13:44 6932 8192000000 9925243568 10270916608 957305632 10.463 100 77 154 0 1037393792 63866080 16222080
02:14:54 4176 8192000000 9958566368 10311438336 995230032 10.832 100 85 169 1 1072109392 60059872 16819488
02:16:01 1974 8192000000 9997040048 10356883456 1028652112 11.155 100 89 178 0 1113720256 66955568 18112576
02:17:04 1914 8192000000 10029141920 10397659136 1050423584 11.365 100 91 182 0 1143085088 74420000 18241504
02:18:07 6663 8192000000 10002054896 10396110848 1059458992 11.451 100 51 100 1 1130043488 60837200 9747296
02:19:14 6198 8192000000 9989532288 10382249984 1072742672 11.578 100 53 107 0 1111529184 26756208 12030304
02:20:22 63138 8192000000 9984825552 10379751424 1077556032 11.624 100 32 64 2 1104460128 19516208 7387888
02:22:34 8653 8192000000 9999932848 10401632256 1086929712 11.713 100 30 60 0 1114307792 20593456 6784624
02:23:43 5894 8192000000 10008269600 10404544512 1092935136 11.771 100 31 61 1 1130044624 30591968 6517520
02:24:49 6909 8192000000 10006220720 10415034368 1094990624 11.790 100 33 67 2 1119191392 17153968 7046800
02:26:03 39924 8192000000 10006669808 10410643456 1089806272 11.741 100 57 114 0 1114916160 13234608 11875280
02:27:46 6635 8192000000 9989654416 10378735616 1051811472 11.378 97 35 70 1 1101771424 42831920 7128032
02:28:52 3520 8192000000 9899904048 10262253568 977764160 10.662 96 45 90 0 1002382912 14706160 9912592
02:29:57 9596 8192000000 9945517168 10331443200 981739632 10.701 95 33 67 0 1014378016 25449840 7188544
02:31:08 30606 8192000000 9913408912 10300231680 941301424 10.306 91 22 44 1 965192592 19882320 4008848
02:32:39 6453 8192000000 9952900240 10350395392 1041336704 11.278 100 34 68 2 1067537216 19432832 6767680
02:33:46 5428 8192000000 10007474912 10393493504 1055810256 11.416 100 55 110 2 1108898736 40752800 12335680
02:34:53 74879 8192000000 9971580368 10385027072 1070826016 11.560 100 22 42 0 1096248672 20825200 4597456
02:37:09 21509 8192000000 10008869248 10404347904 1090932992 11.752 100 34 67 0 1110415120 11197792 8284336
02:38:36 25060 8192000000 10065336368 10465476608 1117591280 12.004 100 41 82 2 1165340624 38869536 8879808
02:40:05 19800 8192000000 10114466624 10521628672 1134322032 12.162 100 55 108 0 1219171584 73933664 10915888
02:41:51 35089 8192000000 10145682368 10556567552 1152840192 12.336 100 43 86 0 1266536208 104336448 9359568
02:43:38 26075 8192000000 10223985424 10643259392 1166920160 12.468 100 37 74 0 1322422752 147388048 8114544
02:45:09 3684 8192000000 10284994544 10694504448 1178387952 12.575 100 63 126 1 1388959344 197417888 13153504
02:46:19 10616 8192000000 10282035120 10702258176 1182837264 12.617 100 40 80 0 1397059120 204560512 9661344
02:47:34 3716 8192000000 10354238848 10764845056 1196040992 12.740 100 70 139 0 1460336592 248445968 15849632
02:48:40 1956 8192000000 10400112752 10804072448 1187883120 12.664 100 96 188 1 1503727664 294965136 20879408
02:49:46 1091 8192000000 10435070496 10844422144 1190192784 12.685 100 89 174 2 1536779648 326278000 20308864
02:50:49 3047 8192000000 10419312512 10837782528 1178619936 12.577 100 87 171 0 1519887136 321874592 19392608
02:51:55 3826 8192000000 10425778608 10848772096 1168300304 12.481 99 80 160 1 1516682592 329151200 19231088
02:53:02 11136 8192000000 10389585552 10828455936 1152920976 12.337 98 58 116 2 1504407056 338175136 13310944
02:54:29 3551 8192000000 10434583584 10847137792 1176658016 12.559 100 69 138 0 1530971280 338515904 15797360
02:55:40 3827 8192000000 10435758480 10844577792 1176693584 12.559 100 81 162 2 1528978256 333345776 18938896
02:56:46 6124 8192000000 10479077792 10892132352 1167163584 12.470 99 62 124 0 1571840944 389112768 15564592
02:57:58 6640 8192000000 10534519248 10949177344 1172185264 12.517 99 67 134 0 1622555536 433737680 16632592
02:59:11 4893 8192000000 10607151280 11031887872 1186051232 12.647 100 63 126 1 1704900128 502030960 16817936
03:00:18 24854 8192000000 10611142832 11111981056 1170425504 12.501 99 49 99 0 1753214656 569599248 13189904
03:01:57 9728 8192000000 10600370544 11042877440 1162143312 12.423 100 39 78 1 1695878416 522903824 10831280
03:03:19 18138 8192000000 10568235872 11126423552 1135730976 12.175 100 35 70 0 1707569456 560983200 10855280
03:04:45 34088 8192000000 10197638000 10779910144 1127435808 12.097 100 20 40 1 1517683072 384584784 5662480
03:06:40 70877 8192000000 10080485328 10511192064 1121993936 12.046 98 12 25 1 1169154016 44692928 2467152
03:08:52 92922 8192000000 10099397072 10574929920 1141707328 12.232 99 40 80 1 1199937552 49906240 8323984
03:11:32 65032 8192000000 10114386288 10549280768 1158076304 12.385 100 48 96 1 1198542480 29850688 10615488
03:13:39 22332 8192000000 10110514784 10553307136 1148993136 12.300 99 41 80 2 1201858160 44674656 8190368
03:15:03 39574 8192000000 10137498032 10611159040 1148809776 12.298 99 17 34 2 1215762272 63241312 3711184
03:16:47 24162 8192000000 10110225792 10553110528 1152698032 12.335 100 48 92 1 1199903648 38038384 9167232
03:18:16 98798 8192000000 10054167968 10525884416 1100521024 11.843 95 8 16 0 1152428448 50309440 1597984
03:20:55 30220 8192000000 10073133968 10545750016 1120970944 12.036 98 19 40 0 1167554272 42491040 4092288
03:22:27 78952 8192000000 10150663520 10649665536 1151344064 12.322 100 23 46 2 1265139328 108425088 5370176
03:26:32 39297 8192000000 10057595088 10514989056 1118834560 12.016 97 8 16 0 1134341232 14250176 1256496
03:28:20 11375 8192000000 10082995296 10524364800 1114519744 11.975 98 36 71 0 1160131920 38210384 7401792
03:29:33 70050 8192000000 10152432544 10607550464 1096837952 11.808 96 19 39 0 1211103280 109800832 4464496
03:31:43 76150 8192000000 10100450736 10546180096 1112533216 11.956 98 26 52 2 1191066336 73031504 5501616
03:34:08 45592 8192000000 10068774736 10527109120 1102115360 11.858 98 16 32 0 1170502800 65322976 3064464
03:35:58 48231 8192000000 10096711504 10549137408 1068929040 11.542 96 27 54 0 1136214880 61757456 5528384
03:37:58 98816 8192000000 10032508480 10484469760 1088995536 11.733 98 25 50 1 1136201104 42138496 5067072
03:40:42 41886 8192000000 10050511424 10509893632 1115992320 11.989 99 11 22 0 1160829248 42361776 2475152
03:42:25 49769 8192000000 10091680848 10553270272 1090476256 11.747 97 11 22 0 1144091232 51346256 2268720
03:44:17 44031 8192000000 10080748128 10539720704 1100638288 11.844 97 20 40 0 1162090304 57034800 4417216
03:46:01 25085 8192000000 10111358032 10559950848 1117048960 11.999 99 39 78 0 1192665264 67220384 8395920
03:47:27 48042 8192000000 10061505872 10514313216 1112353456 11.955 98 22 44 1 1175520864 58250560 4916848
03:49:22 37935 8192000000 10132797968 10561732608 1111479600 11.946 98 33 66 0 1200018848 81859280 6679968
03:51:02 67784 8192000000 10051022976 10562830336 1118138720 12.009 100 21 41 1 1216054032 93964944 3950368
03:53:11 22463 8192000000 10085897744 10523160576 1121135728 12.038 100 41 81 1 1181935776 52720576 8079472
03:54:34 64948 8192000000 10064608544 10513448960 1096443824 11.804 98 16 32 0 1144432208 44572688 3415696
03:57:27 142726 8192000000 10066290864 10564100096 1118081888 12.009 99 17 34 0 1182980000 61360016 3538096
04:00:59 81255 8192000000 10050746912 10545213440 1134523968 12.164 100 7 14 1 1167154976 31001488 1629520
04:03:20 71083 8192000000 10090386576 10545197056 1133358992 12.153 99 23 46 0 1185261440 47308912 4593536
04:05:37 46827 8192000000 10098366608 10561249280 1135803568 12.176 99 17 33 0 1186816944 47758208 3255168
04:07:29 71058 8192000000 10085226288 10552909824 1130362560 12.125 98 10 20 0 1179011808 46695856 1953392
04:09:47 57757 8192000000 10104679504 10580312064 1147865616 12.289 99 11 22 1 1194932640 44913568 2153456
04:11:54 30933 8192000000 10134219680 10578202624 1130235776 12.124 98 47 94 0 1201061360 61099392 9726192
04:13:29 36998 8192000000 10133093408 10578616320 1114471680 11.975 97 43 86 1 1192043216 68603088 8968448
04:15:13 53368 8192000000 10076961520 10559868928 1105706480 11.892 97 14 28 0 1166635024 58159504 2769040
04:17:08 64150 8192000000 10083891776 10549329920 1070764576 11.559 94 16 32 1 1124351616 50257312 3329728
04:19:19 26854 8192000000 10114036128 10565615616 1104673696 11.882 98 39 78 0 1180077824 67050848 8353280
04:21:07 103092 8192000000 10041580912 10483679232 1102367728 11.860 98 21 42 1 1130383984 23544704 4471552
04:23:50 38787 8192000000 10054811552 10522210304 1113979600 11.970 99 17 33 0 1164373104 46912144 3481360
04:25:51 46622 8192000000 10060546000 10537373696 1102815136 11.864 98 8 16 0 1159910656 55589248 1506272
04:27:38 82749 8192000000 10064347920 10555834368 1112534080 11.956 99 15 30 3 1154193872 38447088 3212704
04:30:01 36182 8192000000 10079473776 10523193344 1073918560 11.589 95 28 56 0 1127736880 48250832 5567488
04:31:38 30132 8192000000 10106242528 10560212992 1109095664 11.924 99 38 74 0 1204756592 86681552 8979376
04:33:09 55450 8192000000 10087416528 10578087936 1106460800 11.899 99 28 56 1 1223175312 110606688 6107824
04:35:09 92507 8192000000 10098556832 10568531968 1100112080 11.839 98 24 48 1 1153497104 48514608 4870416

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 21-09-2021 04:38:22 to 21-09-2021 04:38:37.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 132 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-09-2021 04:38:37 to 21-09-2021 04:38:39.

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          236292
MON$OLDEST_ACTIVE               238801
MON$OLDEST_SNAPSHOT             238801
MON$NEXT_TRANSACTION            238801
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                       2069408
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             811
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2606 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2606 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2606 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2606 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17: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 ---
:33: 
:34: mscs-test-161.budzdorov.ru    Tue Sep 21 04:37:08 2021
:35:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:36: 
:37: 
:38: mscs-test-161.budzdorov.ru    Tue Sep 21 04:37:25 2021
:39:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:40: 
--- end of diff output ---

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

21.09.21 04:38:40. 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-09-21 04:38:43 No severe PSQL-related problems occured
21.09.21 04:38:44. End of report.