Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.08.2021 01: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 16040480 kB
MemAvailable 30993332 kB
Buffers 0 kB
Cached 15660128 kB
SwapCached 3640 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 3.0.8
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-08-21 01:37:46
DTS_END                         2021-08-21 01:37:46.0120
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_CLIENT_ORDER(32:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/59746
TRN_ID                          250271
ATT_ID                          699
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: 9780.11 20031 1173613
customer order: creation 180.08 339 21610
customer order: refuse 15.53 350 1864
order to supplier: creation 48.14 1225 5777
order to supplier: removal 3.61 104853 433
invoice (draft): creation 49.11 3462 5893
invoice (draft): removal 55.98 811 6718
invoice accept: apply 45.14 18170 5417
invoice accept: cancel 50.53 808 6064
customer reserve: creation 4063.05 185 487566
customer reserve: removal 4958.04 60 594965
realization accept: apply 88.79 187 10655
realization accept: cancel 84.34 117 10121
payment from customer: creation 62.90 114 7548
payment from customer: removal 5.72 85 686
payment to supplier: creation 52.23 136 6268
payment to supplier: removal 3.84 82 461
service: total inventory turnovers 4.81 1138 577
service: total monetary turnovers 7.48 29 898
service: aggregate perf. data 0.73 24919 88
service: refresh index statistics 0.03 243556 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-08-2021 01:39:46 to 21-08-2021 01:39:48.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3810
TEST_TIME 2 4889
TEST_TIME 3 4888
TEST_TIME 4 5392
TEST_TIME 5 6335
TEST_TIME 6 6183
TEST_TIME 7 6232
TEST_TIME 8 6371
TEST_TIME 9 6607
TEST_TIME 10 6910
TEST_TIME 11 6847
TEST_TIME 12 6525
TEST_TIME 13 6302
TEST_TIME 14 6356
TEST_TIME 15 6525
TEST_TIME 16 6646
TEST_TIME 17 6683
TEST_TIME 18 6949
TEST_TIME 19 7163
TEST_TIME 20 7199
TEST_TIME 21 7416
TEST_TIME 22 7483
TEST_TIME 23 7840
TEST_TIME 24 7736
TEST_TIME 25 8181
TEST_TIME 26 8496
TEST_TIME 27 8770
TEST_TIME 28 8887
TEST_TIME 29 9103
TEST_TIME 30 9085
TEST_TIME 31 9630
TEST_TIME 32 10257
TEST_TIME 33 10534
TEST_TIME 34 10591
TEST_TIME 35 10512
TEST_TIME 36 10524
TEST_TIME 37 10385
TEST_TIME 38 10257
TEST_TIME 39 10089
TEST_TIME 40 10034
TEST_TIME 41 10053
TEST_TIME 42 10019
TEST_TIME 43 10023
TEST_TIME 44 9907
TEST_TIME 45 9825
TEST_TIME 46 9811
TEST_TIME 47 9722
TEST_TIME 48 9881
TEST_TIME 49 9937
TEST_TIME 50 9942
TEST_TIME 51 9860
TEST_TIME 52 9750
TEST_TIME 53 9644
TEST_TIME 54 9661
TEST_TIME 55 9693
TEST_TIME 56 9611
TEST_TIME 57 9585
TEST_TIME 58 9535
TEST_TIME 59 9556
TEST_TIME 60 9495
TEST_TIME 61 9466
TEST_TIME 62 9644
TEST_TIME 63 9674
TEST_TIME 64 9654
TEST_TIME 65 9691
TEST_TIME 66 9706
TEST_TIME 67 9705
TEST_TIME 68 9667
TEST_TIME 69 9638
TEST_TIME 70 9772
TEST_TIME 71 9840
TEST_TIME 72 9895
TEST_TIME 73 9843
TEST_TIME 74 9807
TEST_TIME 75 9752
TEST_TIME 76 9777
TEST_TIME 77 9691
TEST_TIME 78 9724
TEST_TIME 79 9672
TEST_TIME 80 9615
TEST_TIME 81 9536
TEST_TIME 82 9489
TEST_TIME 83 9509
TEST_TIME 84 9617
TEST_TIME 85 9644
TEST_TIME 86 9606
TEST_TIME 87 9634
TEST_TIME 88 9626
TEST_TIME 89 9662
TEST_TIME 90 9654
TEST_TIME 91 9724
TEST_TIME 92 9730
TEST_TIME 93 9795
TEST_TIME 94 9808
TEST_TIME 95 9841
TEST_TIME 96 9859
TEST_TIME 97 9826
TEST_TIME 98 9770
TEST_TIME 99 9775
TEST_TIME 100 9738
TEST_TIME 101 9732
TEST_TIME 102 9705
TEST_TIME 103 9679
TEST_TIME 104 9626
TEST_TIME 105 9596
TEST_TIME 106 9580
TEST_TIME 107 9616
TEST_TIME 108 9681
TEST_TIME 109 9667
TEST_TIME 110 9632
TEST_TIME 111 9670
TEST_TIME 112 9664
TEST_TIME 113 9715
TEST_TIME 114 9720
TEST_TIME 115 9731
TEST_TIME 116 9775
TEST_TIME 117 9790
TEST_TIME 118 9775
TEST_TIME 119 9800
TEST_TIME 120 9782

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 21-08-2021 01:39:50 to 21-08-2021 01:39:53.

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 1625196 1625196 0 0.00 0 32412 6 0 0
doc_list_biud 1625195 1625195 0 0.00 0 39116 28 0 0
sp_add_invoice_to_stock 5510 5417 93 1.68 55 199825 18170 0 93
sp_cancel_adding_invoice 6064 6064 0 0.00 4 71571 808 0 0
sp_cancel_client_order 1864 1864 0 0.00 5 14192 350 0 0
sp_cancel_customer_reserve 594965 594965 0 0.00 1 22666 60 0 0
sp_cancel_pay_from_customer 686 686 0 0.00 3 6100 86 0 0
sp_cancel_pay_to_supplier 461 461 0 0.00 2 9276 82 0 0
sp_cancel_supplier_invoice 6724 6718 6 0.08 1 39121 812 0 6
sp_cancel_supplier_order 433 433 0 0.00 5 1157026 104854 0 0
sp_cancel_write_off 10121 10121 0 0.00 1 6854 118 0 0
sp_client_order 21610 21610 0 0.00 4 17142 340 0 0
sp_customer_reserve 487566 487566 0 0.00 0 32266 185 0 0
sp_fill_shopping_cart 33271 33271 0 0.00 1 17669 38 0 0
sp_fill_shopping_cart_clo_res 487565 487565 0 0.00 0 16780 14 0 0
sp_get_clo_for_invoice 5416 5416 0 0.00 0 19606 258 0 0
sp_kill_cost_storno 17332 17332 0 0.00 0 8600 56 0 0
sp_kill_qstorno_ret_qs2qd 1207202 1207202 0 0.00 0 39115 32 0 0
sp_kill_qty_storno 636235 636235 0 0.00 0 39116 70 0 0
sp_lock_selected_doc 22160 22160 0 0.00 0 7321 7 0 0
sp_make_cost_storno 29887 29887 0 0.00 0 32395 54 0 0
sp_multiply_rows_for_pdistr 29886 29886 0 0.00 0 7597 28 0 0
sp_multiply_rows_for_qdistr 21610 21610 0 0.00 0 13725 144 0 0
sp_pay_from_customer 7548 7548 0 0.00 4 8372 115 0 0
sp_pay_to_supplier 6268 6268 0 0.00 5 10653 136 0 0
sp_payment_common 13816 13816 0 0.00 1 8418 33 0 0
sp_qd_handle_on_cancel_clo 1864 1864 0 0.00 0 14185 317 0 0
sp_qd_handle_on_invoice_upd_sts 11479 11479 0 0.00 0 23833 312 0 0
sp_qd_handle_on_reserve_upd_sts 10655 10655 0 0.00 0 17353 57 0 0
sp_reserve_write_off 10655 10655 0 0.00 6 17395 188 0 0
sp_supplier_invoice 5893 5893 0 0.00 7 87846 3462 0 0
sp_supplier_order 5777 5777 0 0.00 5 25151 1226 0 0
srv_aggregate_perf_data 88 88 0 0.00 2252 357885 24920 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1536 177434 43814 0 0
srv_find_qd_qs_mism 1123237 1123237 0 0.00 0 32242 5 0 0
srv_make_invnt_saldo 577 577 0 0.00 60 69957 1138 0 0
srv_make_money_saldo 898 898 0 0.00 1 2270 30 0 0
srv_recalc_idx_stat 4 4 0 0.00 185683 317600 243556 0 0
t$perf-abend:sp_add_invoice_to_stock 93 93 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 5417 5417 0 0.00 0 15632 197 0 0
t$perf-norm:sp_cancel_adding_invoice 419 419 0 0.00 1 7959 130 0 0
t$perf-norm:sp_cancel_client_order 1864 1864 0 0.00 0 8283 28 0 0
t$perf-norm:sp_cancel_customer_reserve 732 732 0 0.00 0 2286 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 686 686 0 0.00 0 529 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 461 461 0 0.00 0 1717 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 411 411 0 0.00 0 509 15 0 0
t$perf-norm:sp_cancel_supplier_order 433 433 0 0.00 0 26278 1554 0 0
t$perf-norm:sp_cancel_write_off 1084 1084 0 0.00 0 5735 10 0 0
t$perf-norm:sp_client_order 21610 21610 0 0.00 0 10998 20 0 0
t$perf-norm:sp_customer_reserve 1063 1063 0 0.00 0 2435 8 0 0
t$perf-norm:sp_pay_from_customer 7548 7548 0 0.00 0 4922 9 0 0
t$perf-norm:sp_pay_to_supplier 6268 6268 0 0.00 0 7949 12 0 0
t$perf-norm:sp_reserve_write_off 10655 10655 0 0.00 0 12824 15 0 0
t$perf-norm:sp_supplier_invoice 5893 5893 0 0.00 0 10320 21 0 0
t$perf-norm:sp_supplier_order 5777 5777 0 0.00 0 3208 14 0 0
t$perf-norm:srv_aggregate_perf_data 88 88 0 0.00 0 95 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 471 18 0 0
t$perf-norm:srv_make_invnt_saldo 577 577 0 0.00 0 1249 21 0 0
t$perf-norm:srv_make_money_saldo 898 898 0 0.00 0 2704 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 9 5 0 0
v_add_invoice_to_stock 11016 10830 186 1.68 0 4126 18 0 186
v_all_customers 39322 39322 0 0.00 0 17 1 0 0
v_all_suppliers 32206 32206 0 0.00 0 33 1 0 0
v_all_wares 43220 43220 0 0.00 0 291 1 0 0
v_cancel_adding_invoice 838 838 0 0.00 0 2887 12 0 0
v_cancel_client_order 3728 3728 0 0.00 0 3691 6 0 0
v_cancel_customer_prepayment 1372 1372 0 0.00 0 2397 5 0 0
v_cancel_customer_reserve 1464 1464 0 0.00 0 2621 8 0 0
v_cancel_payment_to_supplier 922 922 0 0.00 0 1778 7 0 0
v_cancel_supplier_invoice 834 822 12 1.43 0 2672 19 0 12
v_cancel_supplier_order 866 866 0 0.00 0 727 5 0 0
v_cancel_write_off 2168 2168 0 0.00 0 5831 25 0 0
v_max_id_clo_ord 5777 5777 0 0.00 0 4341 2 0 0
v_max_id_clo_res 1063 1063 0 0.00 0 2198 14 0 0
v_max_id_ord_sup 5884 5884 0 0.00 0 4363 4 0 0
v_max_non_paid_invoice 1826 1826 0 0.00 0 2583 5 0 0
v_max_non_paid_realizn 4075 4075 0 0.00 0 4105 5 0 0
v_min_id_clo_ord 5777 5777 0 0.00 0 3220 5 0 0
v_min_id_clo_res 1063 1063 0 0.00 0 9115 18 0 0
v_min_id_ord_sup 5884 5884 0 0.00 0 6250 12 0 0
v_min_non_paid_invoice 6268 6268 0 0.00 0 8414 24 0 0
v_min_non_paid_realizn 7548 7548 0 0.00 0 6650 21 0 0
v_our_firm 10844 10844 0 0.00 0 16 1 0 0
v_reserve_write_off 21310 21310 0 0.00 0 5474 8 0 0
x_lock_depdocs_on_canc_invoice 12371 12371 0 0.00 0 16116 134 0 0
x_lock_depdocs_on_canc_sup_ord 433 433 0 0.00 0 16361 441 0 0
x_make_qstorno_1000_1200 5777 5777 0 0.00 1 25132 1143 0 0
x_make_qstorno_1200_2000 5893 5893 0 0.00 1 83023 3327 0 0
x_make_qstorno_2100_3300 487566 487566 0 0.00 0 32266 168 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-08-2021 01:39:54 to 21-08-2021 01: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
23:07:49 1 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
23:08:51 24885 8192000000 10363583200 10683011072 1412683840 14.708 100 83 166 2 1446131696 6003184 27444672
23:10:20 33258 8192000000 10446763072 10777972736 1500218576 15.478 100 92 184 1 1538524800 8870704 29435520
23:11:59 11135 8192000000 10501885216 10847076352 1557332096 15.973 100 86 173 0 1594749280 10257072 27160112
23:13:19 9449 8192000000 10586255808 10944454656 1645387520 16.725 100 86 172 1 1682694096 11429120 25877456
23:14:32 11333 8192000000 10640427568 11009486848 1706891312 17.243 100 81 161 2 1742749040 12088912 23768816
23:15:45 7037 8192000000 10700307536 11076882432 1760313120 17.687 100 91 182 0 1800569632 15053328 25203184
23:16:54 3334 8192000000 10736213312 11115659264 1804344048 18.050 100 92 181 2 1843337568 14080240 24913280
23:18:00 1637 8192000000 10780377728 11166900224 1837381952 18.319 100 87 174 1 1877201168 14827088 24992128
23:19:03 1836 8192000000 10792388160 11190751232 1859138416 18.496 100 77 154 0 1891154080 10969504 21046160
23:20:07 14603 8192000000 10785964208 11195326464 1862662624 18.525 100 35 70 1 1878267344 5960160 9644560
23:21:24 5616 8192000000 10782916432 11208454144 1874965360 18.624 100 24 47 2 1887957360 6324384 6667616
23:22:33 9522 8192000000 10790923040 11223269376 1884912416 18.705 100 12 24 1 1893327760 5564752 2850592
23:23:43 19153 8192000000 10802884240 11234390016 1897746704 18.808 100 27 53 0 1910640128 5439008 7454416
23:25:04 16287 8192000000 10864241984 11265839104 1914138464 18.940 100 71 146 1 1941780080 6847152 20794464
23:26:21 6001 8192000000 10824552544 11255947264 1906945088 18.882 100 27 52 3 1920659328 6077904 7636336
23:27:34 14271 8192000000 10772896096 11194769408 1810636672 18.101 96 21 41 1 1822420000 5911664 5871664
23:28:48 13585 8192000000 10775045344 11208671232 1837602688 18.321 98 12 24 0 1845990784 5048512 3339584
23:30:02 10083 8192000000 10702008592 11112423424 1705800992 17.234 92 24 48 3 1721926464 8897728 7227744
23:31:17 8325 8192000000 10716646752 11138887680 1793291456 17.959 99 26 51 2 1806591872 5971072 7329344
23:32:27 70308 8192000000 10720909888 11145285632 1798276080 18.000 99 17 34 0 1812363568 9303936 4783552
23:34:38 4162 8192000000 10770757312 11196452864 1855287584 18.465 100 25 50 1 1868183808 5622912 7273312
23:35:43 8474 8192000000 10795300992 11221667840 1874268560 18.619 100 25 47 1 1891160992 10601184 6291248
23:36:57 24403 8192000000 10812960384 11238375424 1881927728 18.681 100 19 37 3 1891556848 5037616 4591504
23:38:37 5551 8192000000 10850588704 11263770624 1915558736 18.951 100 47 92 4 1935699696 6413712 13727248
23:39:48 14566 8192000000 10856264928 11285123072 1928139760 19.052 100 38 76 0 1947522096 8862032 10520304
23:41:07 18450 8192000000 10876681616 11300134912 1939249696 19.141 100 42 82 1 1962812464 11300464 12262304
23:42:29 8996 8192000000 10886237552 11311534080 1953366368 19.253 100 64 128 1 1983204624 10895968 18942288
23:43:43 12106 8192000000 10898563216 11324989440 1956496272 19.278 100 55 107 4 1982495840 11529872 14469696
23:45:01 6176 8192000000 10902960176 11340902400 1974368656 19.420 100 53 105 3 2005674112 15439888 15865568
23:46:12 8955 8192000000 10942737344 11356360704 1984432512 19.500 100 80 157 3 2024851248 18140480 22278256
23:47:26 2988 8192000000 10911443152 11337175040 1951534896 19.239 99 66 132 0 1994382496 23298080 19549520
23:48:38 43682 8192000000 10901810400 11350876160 1953002688 19.250 99 36 72 0 1988214384 24512400 10699296
23:50:26 7696 8192000000 10904786064 11345866752 1943533408 19.175 99 30 60 1 1981813008 28606448 9673152
23:51:44 16006 8192000000 10907894848 11346051072 1945958656 19.194 99 40 80 0 1990936752 31805856 13172240
23:53:12 8856 8192000000 10956253312 11380822016 1981577168 19.477 100 58 113 1 2031926480 33118784 17230528
23:54:28 2425 8192000000 10978633744 11384422400 1990474288 19.548 100 83 164 0 2053939440 38716208 24748944
23:55:34 1536 8192000000 10969840176 11378929664 1977453104 19.445 100 87 172 3 2050122320 46140944 26528272
23:56:40 2784 8192000000 10949564896 11353587712 1952502464 19.246 99 77 152 0 2020496432 44345024 23648944
23:57:47 7330 8192000000 10944399168 11354316800 1935890272 19.114 98 80 158 2 2008075024 47523680 24661072
23:59:00 5343 8192000000 10965605344 11386916864 1975821472 19.432 100 61 121 2 2045685152 50547696 19315984
00:00:12 7175 8192000000 10957172320 11387453440 1969021888 19.378 100 56 112 1 2043918784 56728800 18168096
00:01:26 2087 8192000000 10980089824 11408478208 1991089152 19.552 100 47 94 0 2058337248 51678496 15569600
00:02:32 17122 8192000000 10959021232 11404312576 1981462032 19.476 100 39 78 0 2046757488 51843328 13452128
00:03:58 12043 8192000000 10974316480 11422810112 1993820864 19.574 100 31 62 1 2054251136 50128912 10301360
00:05:14 18296 8192000000 10945534544 11397238784 1973015440 19.409 100 26 52 1 2030319312 48032928 9270944
00:06:42 15342 8192000000 10961208368 11417591808 1977311632 19.443 100 27 54 2 2038404704 51722240 9370832
00:07:59 47730 8192000000 10945680112 11393822720 1967796064 19.368 99 19 38 2 2018316368 44134496 6385808
00:09:48 46496 8192000000 10904586272 11365703680 1916969920 18.963 97 11 22 1 1939202352 19326048 2906384
00:11:36 51801 8192000000 10916850736 11370631168 1928964608 19.059 97 16 33 1 1942017968 8127872 4925488
00:13:29 77256 8192000000 10904901568 11361742848 1930373936 19.070 97 27 52 1 1944888240 7287584 7226720
00:15:54 79808 8192000000 10935272560 11381202944 1974098352 19.418 99 49 99 1 1997156784 9204912 13853520
00:18:20 59594 8192000000 10893656000 11346870272 1872671776 18.606 94 38 76 1 1895257680 11926304 10659600
00:20:30 11885 8192000000 10895605488 11349061632 1959295008 19.300 99 34 70 1 1974906784 6262112 9349664
00:22:04 30373 8192000000 10913858864 11367280640 1966379056 19.357 99 43 87 1 1990633888 10741760 13513072
00:23:39 37877 8192000000 10937138352 11389468672 1990038208 19.544 100 30 58 1 2012834640 13310640 9485792
00:25:29 52923 8192000000 10911641648 11384606720 1960527936 19.310 99 17 34 0 1990051712 24210720 5313056
00:27:55 25622 8192000000 10880196160 11350253568 1946584768 19.199 99 6 12 0 1955273696 6886112 1802816
00:30:12 52734 8192000000 10866144160 11336429568 1935439024 19.110 98 15 30 0 1948589424 8956560 4193840
00:32:10 60230 8192000000 10870166304 11352449024 1945760832 19.193 99 9 18 1 1956374640 7924976 2688832
00:34:17 56348 8192000000 10863394032 11338051584 1918890288 18.978 98 29 58 0 1939298672 12080016 8328368
00:36:33 50019 8192000000 10865391664 11329953792 1903807520 18.857 97 15 30 0 1915121344 7052096 4261728
00:38:24 51255 8192000000 10896689312 11366363136 1933805328 19.097 98 17 34 1 1951668816 12876752 4986736
00:40:21 58427 8192000000 10854263392 11308503040 1794815488 17.971 92 13 26 0 1807207936 8872784 3519664
00:42:21 41675 8192000000 10849082544 11314450432 1863209456 18.529 96 17 34 1 1883791072 15887360 4694256
00:44:03 57762 8192000000 10852133888 11316895744 1846756496 18.396 95 17 34 1 1864562912 12961424 4844992
00:46:01 107737 8192000000 10872837472 11360124928 1870845840 18.591 96 18 36 0 1895172912 19356960 4970112
00:48:50 66939 8192000000 10884836352 11355467776 1917169920 18.964 98 26 52 1 1936751232 12474528 7106784
00:50:57 87728 8192000000 10900559728 11365322752 1966316976 19.356 100 43 86 3 1989597888 11129168 12151744
00:53:38 41108 8192000000 10854349744 11330506752 1892463680 18.766 97 14 28 1 1906704496 10397520 3843296
00:55:23 61920 8192000000 10869816704 11356372992 1952315408 19.245 100 17 34 0 1965915808 8761712 4838688
00:57:30 94369 8192000000 10899151696 11369062400 1960832208 19.313 99 19 38 1 1980240960 13541792 5866960
01:00:11 66124 8192000000 10906585632 11400335360 1981687216 19.478 100 12 24 2 2003478336 17951856 3839264
01:02:25 52397 8192000000 10893899744 11369127936 1936983200 19.123 98 11 22 1 1952472096 12511200 2977696
01:04:18 17893 8192000000 10888611248 11353120768 1923393680 19.014 97 58 117 2 1952285120 11899136 16992304
01:05:37 33304 8192000000 10883753152 11355742208 1914007312 18.939 97 36 72 3 1937560592 13378544 10174736
01:07:34 115283 8192000000 10894783536 11380932608 1915346768 18.950 97 11 22 0 1928800448 10751088 2702592
01:10:29 177434 8192000000 10893634432 11370926080 1868022768 18.568 95 12 23 0 1883629648 12222928 3383952
01:14:27 132191 8192000000 10885248288 11365720064 1917773408 18.969 97 36 72 1 1940316432 12401120 10141904
01:17:42 30522 8192000000 10907706624 11362467840 1967024848 19.362 100 51 100 3 1992081696 11190736 13866112
01:19:13 66931 8192000000 10892461808 11370545152 1956142336 19.275 99 13 26 0 1971603824 11667584 3793904
01:22:11 89030 8192000000 10870141008 11346710528 1933187824 19.092 98 12 24 1 1954655424 17593296 3874304
01:24:48 59868 8192000000 10850863040 11331137536 1879029696 18.657 96 13 26 1 1900769280 17816864 3922720
01:26:55 22348 8192000000 10858474400 11324043264 1903669456 18.856 98 28 56 1 1918959264 7574192 7715616
01:28:25 56475 8192000000 10883697088 11361452032 1942020672 19.163 100 13 26 0 1967323312 21700528 3602112
01:30:33 99230 8192000000 10867208992 11357507584 1881703424 18.679 96 15 28 2 1902265184 15977408 4584352
01:33:14 103521 8192000000 10883038416 11325067264 1812281280 18.115 93 18 36 0 1834143328 16332624 5529424
01:36:00 36931 8192000000 10878951456 11342684160 1920655824 18.992 99 30 60 0 1947194688 18009088 8529776
01:37:38 33259 8192000000 9599754272 9928622080 743958272 8.325 39 4 7 0 749904256 4900624 1045360

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-08-2021 01:40:00 to 21-08-2021 01:40:15.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 93 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 21-08-2021 01:40:16 to 21-08-2021 01:40:18.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          250512
MON$OLDEST_ACTIVE               250513
MON$OLDEST_SNAPSHOT             250513
MON$NEXT_TRANSACTION            250513
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-03 16:41:55.3470
MON$PAGES                       2056224
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33489 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33489 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33489 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33489 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:217: 
:218: mscs-test-161.budzdorov.ru    Sat Aug 21 01:38:21 2021
:219:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:220: 
:221: 
:222: mscs-test-161.budzdorov.ru    Sat Aug 21 01:38:21 2021
:223:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:224: 
--- end of diff output ---

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

21.08.21 01:40:18. There are 3 logs in /var/tmp/logs-oltp30 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-08-21 01:40:21 No severe PSQL-related problems occured
21.08.21 01:40:22. End of report.