Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.10.2020 16:31
Common Performance Final Results
  1. Hardware and OS info
  2. DB and 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, full
  3. Ratio "Versions / Records" for tables
  4. Database Validation Results
  5. New in firebird.log while test was run
  6. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID e0e7b46250ba4f9795ceb55e0face73f
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2020-09-25 13:42
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.00
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 17288100 kB
MemAvailable 31574064 kB
Buffers 0 kB
Cached 14411508 kB
SwapCached 3816 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.7
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 2020-10-22 16:30:43
DTS_END                         2020-10-22 16:30:43.6030
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(56:17)
IP                              ::1/33524
TRN_ID                          239181
ATT_ID                          626
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: 9214.08 25835 1105689
customer order: creation 170.55 383 20466
customer order: refuse 14.45 362 1734
order to supplier: creation 45.82 1392 5498
order to supplier: removal 3.34 111850 401
invoice (draft): creation 45.97 3714 5516
invoice (draft): removal 52.79 890 6335
invoice accept: apply 40.89 18355 4907
invoice accept: cancel 47.77 877 5732
customer reserve: creation 3823.65 178 458838
customer reserve: removal 4666.60 61 559992
realization accept: apply 82.81 221 9937
realization accept: cancel 89.09 117 10691
payment from customer: creation 60.10 140 7212
payment from customer: removal 6.06 81 727
payment to supplier: creation 48.66 143 5839
payment to supplier: removal 3.44 71 413
service: total inventory turnovers 4.41 1252 529
service: total monetary turnovers 6.88 39 826
service: aggregate perf. data 0.77 22721 92
service: refresh index statistics 0.03 353853 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-10-2020 16:32:03 to 22-10-2020 16:32:05.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 712
TEST_TIME 2 1198
TEST_TIME 3 2118
TEST_TIME 4 2091
TEST_TIME 5 2398
TEST_TIME 6 3518
TEST_TIME 7 3820
TEST_TIME 8 3894
TEST_TIME 9 4321
TEST_TIME 10 4599
TEST_TIME 11 4734
TEST_TIME 12 5112
TEST_TIME 13 5498
TEST_TIME 14 6044
TEST_TIME 15 5933
TEST_TIME 16 5973
TEST_TIME 17 6436
TEST_TIME 18 6667
TEST_TIME 19 7114
TEST_TIME 20 7233
TEST_TIME 21 7557
TEST_TIME 22 7963
TEST_TIME 23 7863
TEST_TIME 24 8049
TEST_TIME 25 8400
TEST_TIME 26 8704
TEST_TIME 27 8988
TEST_TIME 28 9616
TEST_TIME 29 9575
TEST_TIME 30 9442
TEST_TIME 31 9638
TEST_TIME 32 10136
TEST_TIME 33 10228
TEST_TIME 34 10090
TEST_TIME 35 9958
TEST_TIME 36 9991
TEST_TIME 37 9940
TEST_TIME 38 9902
TEST_TIME 39 9905
TEST_TIME 40 9763
TEST_TIME 41 9829
TEST_TIME 42 10049
TEST_TIME 43 9917
TEST_TIME 44 9964
TEST_TIME 45 9776
TEST_TIME 46 9633
TEST_TIME 47 9648
TEST_TIME 48 9596
TEST_TIME 49 9533
TEST_TIME 50 9460
TEST_TIME 51 9428
TEST_TIME 52 9338
TEST_TIME 53 9360
TEST_TIME 54 9501
TEST_TIME 55 9399
TEST_TIME 56 9388
TEST_TIME 57 9323
TEST_TIME 58 9218
TEST_TIME 59 9135
TEST_TIME 60 9241
TEST_TIME 61 9163
TEST_TIME 62 9154
TEST_TIME 63 9127
TEST_TIME 64 9141
TEST_TIME 65 9236
TEST_TIME 66 9235
TEST_TIME 67 9285
TEST_TIME 68 9358
TEST_TIME 69 9362
TEST_TIME 70 9314
TEST_TIME 71 9322
TEST_TIME 72 9448
TEST_TIME 73 9469
TEST_TIME 74 9514
TEST_TIME 75 9581
TEST_TIME 76 9565
TEST_TIME 77 9654
TEST_TIME 78 9538
TEST_TIME 79 9492
TEST_TIME 80 9433
TEST_TIME 81 9406
TEST_TIME 82 9365
TEST_TIME 83 9308
TEST_TIME 84 9327
TEST_TIME 85 9295
TEST_TIME 86 9336
TEST_TIME 87 9352
TEST_TIME 88 9326
TEST_TIME 89 9348
TEST_TIME 90 9357
TEST_TIME 91 9376
TEST_TIME 92 9376
TEST_TIME 93 9476
TEST_TIME 94 9465
TEST_TIME 95 9515
TEST_TIME 96 9550
TEST_TIME 97 9620
TEST_TIME 98 9652
TEST_TIME 99 9627
TEST_TIME 100 9571
TEST_TIME 101 9500
TEST_TIME 102 9488
TEST_TIME 103 9525
TEST_TIME 104 9496
TEST_TIME 105 9503
TEST_TIME 106 9459
TEST_TIME 107 9420
TEST_TIME 108 9368
TEST_TIME 109 9349
TEST_TIME 110 9418
TEST_TIME 111 9365
TEST_TIME 112 9380
TEST_TIME 113 9333
TEST_TIME 114 9286
TEST_TIME 115 9227
TEST_TIME 116 9287
TEST_TIME 117 9268
TEST_TIME 118 9264
TEST_TIME 119 9251
TEST_TIME 120 9215

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 22-10-2020 16:32:06 to 22-10-2020 16:32:10.

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 1529697 1529697 0 0.00 0 32902 7 0 0
doc_list_biud 1529689 1529689 0 0.00 0 20958 29 0 0
sp_add_invoice_to_stock 5009 4907 102 2.03 23 172861 18356 0 102
sp_cancel_adding_invoice 5732 5732 0 0.00 3 118239 878 0 0
sp_cancel_client_order 1734 1734 0 0.00 4 10652 362 0 0
sp_cancel_customer_reserve 559992 559992 0 0.00 1 20963 62 0 0
sp_cancel_pay_from_customer 727 727 0 0.00 3 6732 82 0 0
sp_cancel_pay_to_supplier 413 413 0 0.00 3 2451 71 0 0
sp_cancel_supplier_invoice 6346 6335 11 0.17 3 17902 890 0 11
sp_cancel_supplier_order 401 401 0 0.00 9 1060781 111850 0 0
sp_cancel_write_off 10691 10691 0 0.00 1 16897 118 0 0
sp_client_order 20466 20466 0 0.00 3 75127 383 0 0
sp_customer_reserve 458838 458838 0 0.00 0 96669 178 0 0
sp_fill_shopping_cart 31478 31478 0 0.00 1 42643 44 0 0
sp_fill_shopping_cart_clo_res 458836 458836 0 0.00 0 18765 13 0 0
sp_get_clo_for_invoice 4905 4905 0 0.00 0 20525 303 0 0
sp_kill_cost_storno 17563 17563 0 0.00 0 13947 61 0 0
sp_kill_qstorno_ret_qs2qd 1137411 1137411 0 0.00 0 20954 34 0 0
sp_kill_qty_storno 599721 599721 0 0.00 0 20956 74 0 0
sp_lock_selected_doc 20605 20605 0 0.00 0 7752 4 0 0
sp_make_cost_storno 27893 27893 0 0.00 0 32570 89 0 0
sp_multiply_rows_for_pdistr 27889 27889 0 0.00 0 6060 29 0 0
sp_multiply_rows_for_qdistr 20466 20466 0 0.00 0 75115 164 0 0
sp_pay_from_customer 7212 7212 0 0.00 4 30248 141 0 0
sp_pay_to_supplier 5839 5839 0 0.00 4 27368 144 0 0
sp_payment_common 13049 13049 0 0.00 1 10815 26 0 0
sp_qd_handle_on_cancel_clo 1734 1734 0 0.00 0 10645 336 0 0
sp_qd_handle_on_invoice_upd_sts 10634 10634 0 0.00 0 20132 387 0 0
sp_qd_handle_on_reserve_upd_sts 9934 9934 0 0.00 0 17519 76 0 0
sp_reserve_write_off 9937 9937 0 0.00 6 30651 221 0 0
sp_supplier_invoice 5516 5516 0 0.00 7 94092 3715 0 0
sp_supplier_order 5498 5498 0 0.00 6 50660 1392 0 0
srv_aggregate_perf_data 92 92 0 0.00 2320 211514 22722 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 2705 140760 43538 0 0
srv_find_qd_qs_mism 1057612 1057612 0 0.00 0 23720 5 0 0
srv_make_invnt_saldo 529 529 0 0.00 24 55191 1253 0 0
srv_make_money_saldo 826 826 0 0.00 0 10451 40 0 0
srv_recalc_idx_stat 4 4 0 0.00 265037 456940 353853 0 0
t$perf-abend:sp_add_invoice_to_stock 102 102 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4907 4907 0 0.00 0 7866 169 0 0
t$perf-norm:sp_cancel_adding_invoice 423 423 0 0.00 0 3315 79 0 0
t$perf-norm:sp_cancel_client_order 1734 1734 0 0.00 0 3794 17 0 0
t$perf-norm:sp_cancel_customer_reserve 701 701 0 0.00 0 10923 36 0 0
t$perf-norm:sp_cancel_pay_from_customer 727 727 0 0.00 0 1339 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 413 413 0 0.00 0 487 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 399 399 0 0.00 0 6603 35 0 0
t$perf-norm:sp_cancel_supplier_order 401 401 0 0.00 0 27689 1673 0 0
t$perf-norm:sp_cancel_write_off 971 971 0 0.00 0 4394 9 0 0
t$perf-norm:sp_client_order 20466 20466 0 0.00 0 4292 16 0 0
t$perf-norm:sp_customer_reserve 1035 1035 0 0.00 0 1455 8 0 0
t$perf-norm:sp_pay_from_customer 7212 7212 0 0.00 0 4755 11 0 0
t$perf-norm:sp_pay_to_supplier 5839 5839 0 0.00 0 5615 11 0 0
t$perf-norm:sp_reserve_write_off 9937 9937 0 0.00 0 6563 14 0 0
t$perf-norm:sp_supplier_invoice 5516 5516 0 0.00 0 6315 19 0 0
t$perf-norm:sp_supplier_order 5498 5498 0 0.00 0 3091 19 0 0
t$perf-norm:srv_aggregate_perf_data 92 92 0 0.00 0 4960 58 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 89 6 0 0
t$perf-norm:srv_make_invnt_saldo 529 529 0 0.00 0 1349 21 0 0
t$perf-norm:srv_make_money_saldo 826 826 0 0.00 0 2309 21 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10008 9804 204 2.03 0 4969 18 0 204
v_all_customers 37516 37516 0 0.00 0 17 1 0 0
v_all_suppliers 30662 30662 0 0.00 0 238 1 0 0
v_all_wares 40932 40932 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 846 846 0 0.00 0 268 6 0 0
v_cancel_client_order 3468 3468 0 0.00 0 4813 6 0 0
v_cancel_customer_prepayment 1454 1454 0 0.00 0 1082 5 0 0
v_cancel_customer_reserve 1402 1402 0 0.00 0 1460 5 0 0
v_cancel_payment_to_supplier 826 826 0 0.00 0 2443 7 0 0
v_cancel_supplier_invoice 820 798 22 2.68 0 2399 19 0 22
v_cancel_supplier_order 802 802 0 0.00 0 1490 7 0 0
v_cancel_write_off 1942 1942 0 0.00 0 5107 16 0 0
v_max_id_clo_ord 5498 5498 0 0.00 0 179 1 0 0
v_max_id_clo_res 1035 1035 0 0.00 0 2488 17 0 0
v_max_id_ord_sup 5514 5514 0 0.00 0 2746 4 0 0
v_max_non_paid_invoice 1519 1519 0 0.00 0 289 2 0 0
v_max_non_paid_realizn 3759 3759 0 0.00 0 3718 4 0 0
v_min_id_clo_ord 5498 5498 0 0.00 0 23646 28 0 0
v_min_id_clo_res 1035 1035 0 0.00 0 1113 5 0 0
v_min_id_ord_sup 5514 5514 0 0.00 0 6058 18 0 0
v_min_non_paid_invoice 5838 5838 0 0.00 0 6791 13 0 0
v_min_non_paid_realizn 7211 7211 0 0.00 0 6698 16 0 0
v_our_firm 10320 10320 0 0.00 0 27 1 0 0
v_reserve_write_off 19868 19868 0 0.00 0 3876 5 0 0
x_lock_depdocs_on_canc_invoice 11668 11668 0 0.00 0 19971 136 0 0
x_lock_depdocs_on_canc_sup_ord 401 401 0 0.00 0 15528 479 0 0
x_make_qstorno_1000_1200 5498 5498 0 0.00 1 31976 1286 0 0
x_make_qstorno_1200_2000 5516 5516 0 0.00 3 94072 3569 0 0
x_make_qstorno_2100_3300 458838 458838 0 0.00 0 96668 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-10-2020 16:32:11 to 22-10-2020 16:32:15.

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
14:00:47 2 8192000000 8798422528 8808923136 5084624 0.062 1 1 2 0 5202672 55488 62560
14:01:48 34346 8192000000 10370896144 10691526656 1409548592 14.680 100 88 177 0 1444890320 6566368 28775360
14:03:27 9047 8192000000 10420072832 10748596224 1476802240 15.273 100 91 182 0 1515155680 8470432 29883008
14:04:41 20000 8192000000 10471570672 10814119936 1523404512 15.680 100 78 156 0 1557213104 9281952 24526640
14:06:12 13611 8192000000 10543544480 10901233664 1604129648 16.375 100 78 156 1 1638154832 10627600 23397584
14:07:28 8213 8192000000 10621598416 10987724800 1678809024 17.007 100 85 170 1 1717281152 13537920 24934208
14:08:42 5674 8192000000 10669237472 11048345600 1737145440 17.495 100 69 138 1 1769768016 12783648 19838928
14:09:52 3819 8192000000 10744971424 11130970112 1805639440 18.060 100 77 154 0 1838108976 11600784 20868752
14:10:57 2502 8192000000 10790709056 11180519424 1849578000 18.419 100 95 191 2 1890431648 14153920 26699728
14:12:02 1367 8192000000 10812267760 11200778240 1865977008 18.552 100 91 180 0 1905440784 13931200 25532576
14:13:04 4254 8192000000 10807779232 11216715776 1880282864 18.667 100 67 134 0 1909795104 11038464 18473776
14:14:13 4609 8192000000 10804224832 11228614656 1885501568 18.710 100 27 55 2 1903453376 10617040 7334768
14:15:22 71067 8192000000 10850316848 11252641792 1903414288 18.854 100 56 110 2 1925962624 5777920 16770416
14:17:34 4825 8192000000 10835793296 11258703872 1911519584 18.919 100 44 88 2 1930815648 6873248 12422816
14:18:40 28233 8192000000 10813756848 11253207040 1908796240 18.897 100 29 58 0 1922186992 5192704 8198048
14:20:14 7234 8192000000 10820172592 11252629504 1892073328 18.762 99 32 65 4 1907287712 6452400 8761984
14:21:23 14702 8192000000 10778894976 11208314880 1811389216 18.107 96 10 20 1 1819050384 5341792 2319376
14:22:42 41905 8192000000 10704106496 11130560512 1707561008 17.248 93 25 50 1 1720499840 5975520 6963312
14:24:24 6372 8192000000 10690357232 11100069888 1715190000 17.312 95 27 55 1 1728884976 5734368 7960608
14:25:32 8641 8192000000 10713738928 11118497792 1770097152 17.768 97 28 56 1 1784947792 6181760 8668880
14:26:44 62068 8192000000 10790497952 11190009856 1855117072 18.464 100 58 115 2 1878344416 5575472 17651872
14:28:46 5380 8192000000 10775557568 11200831488 1858960320 18.495 100 32 63 1 1875557904 7586928 9010656
14:29:52 7987 8192000000 10787753920 11217559552 1874591232 18.621 100 29 58 1 1888344800 4743264 9010304
14:31:06 126167 8192000000 10863643760 11289661440 1919337088 18.982 100 38 75 1 1937006016 6354784 11314144
14:34:36 19400 8192000000 10887895392 11312553984 1948702144 19.216 100 53 106 1 1978780080 14955472 15122464
14:36:03 10251 8192000000 10910345312 11337822208 1957910352 19.289 100 41 82 2 1991407344 21578624 11918368
14:37:16 20899 8192000000 10910973584 11346255872 1967489824 19.366 100 54 107 1 2009466736 25764272 16212640
14:38:57 9979 8192000000 10940988000 11367010304 1983207072 19.490 100 51 102 0 2024248384 26012592 15028720
14:40:09 8031 8192000000 10960009776 11382231040 1985930928 19.512 99 76 152 2 2039416560 30513456 22972176
14:41:27 7619 8192000000 10981376736 11407822848 2016791024 19.755 100 59 118 0 2068556288 33144320 18620944
14:42:38 6528 8192000000 10971924560 11400683520 2000691360 19.628 100 66 131 1 2061702624 40568592 20442672
14:43:50 9779 8192000000 10960575904 11385327616 1981555840 19.477 100 57 114 1 2041063040 41471376 18035824
14:45:06 5699 8192000000 10969357424 11389939712 1976424480 19.436 99 70 141 0 2038036192 40384976 21226736
14:46:18 4424 8192000000 10979400640 11403677696 1997644576 19.604 100 68 136 0 2065946064 46694176 21607312
14:47:32 4842 8192000000 10962727872 11391598592 1983170800 19.490 100 73 146 1 2057222560 50548816 23502944
14:48:42 9713 8192000000 10966758496 11400065024 1993071568 19.568 100 64 127 1 2059019600 45793760 20154272
14:50:03 32109 8192000000 10915589840 11358453760 1930190080 19.068 98 33 66 2 1981025056 39722656 11112320
14:51:41 2704 8192000000 10956044240 11369975808 1960236928 19.308 100 82 165 0 2036146624 50481808 25427888
14:52:51 3984 8192000000 10930247168 11356213248 1955587312 19.271 100 61 118 2 2010316560 36698336 18030912
14:53:59 2750 8192000000 10930789936 11342856192 1941095008 19.155 100 70 135 2 2006002672 44047648 20860016
14:55:19 13136 8192000000 10897264432 11340521472 1929677776 19.064 100 33 66 1 1984549808 43613280 11258752
14:56:40 9235 8192000000 10914207376 11358732288 1945417824 19.190 100 37 74 1 2001987472 43324624 13245024
14:57:53 15980 8192000000 10919754304 11359952896 1948045680 19.211 100 33 66 1 2008561328 49391056 11124592
14:59:10 22562 8192000000 10916008800 11363504128 1957711200 19.288 100 20 40 0 1996405920 32064128 6630592
15:00:36 25625 8192000000 10912551600 11363074048 1958148064 19.291 100 29 58 1 1997169072 29868688 9152320
15:02:05 14430 8192000000 10916356464 11360301056 1956668112 19.280 100 29 58 1 1996118368 29745488 9704768
15:03:20 61771 8192000000 10885512048 11332939776 1917626608 18.968 98 13 26 0 1932611984 11201408 3783968
15:05:25 61593 8192000000 10866963504 11326283776 1893725840 18.776 97 12 24 0 1910089408 13138400 3225168
15:07:27 76033 8192000000 10886199184 11346694144 1937719264 19.129 99 13 24 1 1954254720 13328336 3207120
15:09:50 30594 8192000000 10894249280 11339608064 1941764256 19.161 99 51 100 2 1965605248 9059392 14781600
15:11:22 47338 8192000000 10908244032 11377266688 1974201152 19.419 100 17 34 2 2000931280 21441360 5288768
15:13:10 50259 8192000000 10895268720 11354628096 1933090000 19.092 98 20 41 0 1950780416 11835840 5854576
15:15:07 89247 8192000000 10924015008 11369205760 1957388080 19.285 99 26 50 0 1974348896 9633664 7327152
15:17:38 76213 8192000000 10925030320 11384164352 1984198960 19.498 100 24 48 0 2002579232 11706672 6673600
15:19:59 29712 8192000000 10933127824 11372826624 1975459888 19.429 100 50 97 3 1998655952 8627776 14568288
15:21:30 39055 8192000000 10946138112 11388985344 1990990432 19.552 100 25 50 1 2011668736 13235488 7442816
15:23:10 38970 8192000000 10921181120 11383971840 1977325552 19.444 100 48 93 1 2008965280 18477472 13162256
15:25:06 72060 8192000000 10855173744 11336159232 1909386080 18.902 98 7 14 0 1922877600 11516944 1974576
15:27:18 52776 8192000000 10876355648 11352608768 1944540800 19.183 99 15 30 0 1959186864 10539120 4106944
15:29:50 63724 8192000000 10900636800 11386261504 1978478592 19.453 100 22 44 0 2003596384 18962432 6155360
15:31:55 54572 8192000000 10906171408 11391856640 1990805296 19.550 100 11 22 0 2000805792 7315136 2685360
15:33:51 32429 8192000000 10911925984 11387330560 1959580416 19.303 98 16 32 2 1973329264 9570944 4177904
15:35:23 69700 8192000000 10890309840 11369680896 1898160640 18.811 95 21 42 2 1921549456 17188880 6199936
15:37:37 39293 8192000000 10909412608 11368919040 1960563504 19.311 100 27 54 0 1983941760 15664656 7713600
15:39:18 43667 8192000000 10889605504 11338272768 1852309824 18.441 94 29 58 0 1867533248 7090288 8133136
15:41:07 71498 8192000000 10885363152 11372904448 1916341120 18.958 98 17 34 1 1948279776 26251248 5687408
15:43:38 51301 8192000000 10872924896 11334926336 1892135648 18.763 97 14 28 2 1906797616 10366032 4295936
15:45:51 31533 8192000000 10885277440 11346067456 1943173984 19.172 100 37 74 0 1964322160 10962096 10186080
15:47:30 109613 8192000000 10862616400 11320274944 1894222064 18.780 97 12 24 0 1909146768 11073504 3851200
15:50:28 10847 8192000000 10880958720 11332423680 1931247344 19.077 99 40 80 0 1950245248 7407040 11590864
15:51:39 71033 8192000000 10877647792 11361943552 1957046176 19.283 100 21 42 2 1982508848 19016640 6446032
15:54:16 63755 8192000000 10869870112 11342647296 1915870752 18.954 98 17 34 0 1936291856 15473712 4947392
15:56:36 46507 8192000000 10864622832 11343523840 1916247856 18.957 98 10 20 0 1933685216 14289488 3147872
15:58:23 15380 8192000000 10876609024 11345506304 1956019968 19.274 100 52 102 3 1978216176 8066288 14129920
15:59:42 60941 8192000000 10873965664 11348353024 1922973424 19.011 98 16 33 3 1934618432 7303136 4341872
16:01:43 93711 8192000000 10902495712 11380731904 1932504864 19.087 98 9 18 1 1954422560 19458416 2459280
16:04:18 21793 8192000000 10887141008 11336437760 1900280176 18.829 97 45 90 1 1927492096 14749888 12462032
16:05:42 64418 8192000000 10898651856 11372908544 1928357952 19.054 98 9 18 0 1944989488 14605040 2026496
16:08:01 123126 8192000000 10890784448 11346771968 1840400512 18.344 93 15 30 1 1855567056 11420480 3746064
16:11:11 140760 8192000000 10907289728 11371921408 1949843104 19.225 99 37 73 0 1975900064 15585072 10471888
16:15:05 64935 8192000000 10867138288 11346178048 1921660912 19.000 98 10 20 0 1931975792 7675456 2639424
16:17:10 61509 8192000000 10900813104 11381243904 1962301600 19.324 99 23 47 2 1983420816 14863136 6256080
16:19:12 77776 8192000000 10872299408 11364376576 1875077504 18.625 95 16 31 1 1893863760 14119952 4666304
16:21:38 18466 8192000000 10886910192 11360149504 1964201200 19.339 100 27 54 0 1981821184 9311968 8308016
16:23:04 70288 8192000000 10904845424 11379515392 1959575056 19.303 99 13 25 2 1969886176 6774496 3536624
16:25:27 69534 8192000000 10896626736 11387768832 1940345792 19.150 98 13 26 1 1959751056 16128752 3276512
16:27:38 52493 8192000000 10898978256 11371728896 1905823952 18.873 96 23 46 1 1918991584 6763040 6404592
16:29:44 23737 8192000000 10886488416 11347189760 1946624880 19.200 100 35 70 0 1969501328 12811328 10065120

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 22-10-2020 16:32:17 to 22-10-2020 16:32:31.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 102 v_add_invoice_to_stock 335544517
user_exc 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 22-10-2020 16:32:32 to 22-10-2020 16:32:34.

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          235767
MON$OLDEST_ACTIVE               239300
MON$OLDEST_SNAPSHOT             239300
MON$NEXT_TRANSACTION            239300
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                       2055584
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.7.33379 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.7.33379 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.7.33379 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.7.33379 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Thu Oct 22 16:30:47 2020
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Thu Oct 22 16:30:56 2020
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:13: 
--- end of diff output ---

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

22.10.20 16:32:35. 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
2020-10-22 16:32:38 No severe PSQL-related problems occured
22.10.20 16:32:39. End of report.