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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2020-12-09 10:31:47
DTS_END                         2020-12-09 10:31:47.2000
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/54298
TRN_ID                          229773
ATT_ID                          660
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: 9160.04 23482 1099205
customer order: creation 161.23 456 19347
customer order: refuse 13.98 336 1678
order to supplier: creation 45.14 1337 5417
order to supplier: removal 3.44 123608 413
invoice (draft): creation 44.03 3547 5284
invoice (draft): removal 52.63 953 6315
invoice accept: apply 39.89 17803 4787
invoice accept: cancel 47.75 977 5730
customer reserve: creation 3747.12 170 449654
customer reserve: removal 4725.63 71 567075
realization accept: apply 78.20 189 9384
realization accept: cancel 77.03 123 9244
payment from customer: creation 55.93 101 6712
payment from customer: removal 5.67 83 680
payment to supplier: creation 48.06 127 5767
payment to supplier: removal 3.23 103 388
service: total inventory turnovers 3.63 3571 436
service: total monetary turnovers 6.54 522 785
service: aggregate perf. data 0.88 16251 105
service: refresh index statistics 0.03 299303 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-12-2020 10:32:49 to 09-12-2020 10:32:51.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5157
TEST_TIME 2 4560
TEST_TIME 3 5387
TEST_TIME 4 5503
TEST_TIME 5 5452
TEST_TIME 6 5611
TEST_TIME 7 5711
TEST_TIME 8 6415
TEST_TIME 9 6506
TEST_TIME 10 7004
TEST_TIME 11 7384
TEST_TIME 12 7360
TEST_TIME 13 7605
TEST_TIME 14 7718
TEST_TIME 15 8010
TEST_TIME 16 8275
TEST_TIME 17 8272
TEST_TIME 18 8576
TEST_TIME 19 8588
TEST_TIME 20 8777
TEST_TIME 21 8840
TEST_TIME 22 8872
TEST_TIME 23 9420
TEST_TIME 24 9624
TEST_TIME 25 9959
TEST_TIME 26 10473
TEST_TIME 27 11636
TEST_TIME 28 11564
TEST_TIME 29 11358
TEST_TIME 30 11582
TEST_TIME 31 11371
TEST_TIME 32 11130
TEST_TIME 33 10804
TEST_TIME 34 10787
TEST_TIME 35 10669
TEST_TIME 36 10508
TEST_TIME 37 10476
TEST_TIME 38 10512
TEST_TIME 39 10329
TEST_TIME 40 10250
TEST_TIME 41 10266
TEST_TIME 42 10141
TEST_TIME 43 10084
TEST_TIME 44 10017
TEST_TIME 45 10025
TEST_TIME 46 9992
TEST_TIME 47 9970
TEST_TIME 48 9953
TEST_TIME 49 9930
TEST_TIME 50 10006
TEST_TIME 51 10139
TEST_TIME 52 10189
TEST_TIME 53 10217
TEST_TIME 54 10195
TEST_TIME 55 10215
TEST_TIME 56 10179
TEST_TIME 57 10036
TEST_TIME 58 9985
TEST_TIME 59 9924
TEST_TIME 60 9826
TEST_TIME 61 9743
TEST_TIME 62 9783
TEST_TIME 63 9797
TEST_TIME 64 9777
TEST_TIME 65 9721
TEST_TIME 66 9711
TEST_TIME 67 9767
TEST_TIME 68 9800
TEST_TIME 69 9790
TEST_TIME 70 9833
TEST_TIME 71 9886
TEST_TIME 72 9950
TEST_TIME 73 9905
TEST_TIME 74 9793
TEST_TIME 75 9742
TEST_TIME 76 9740
TEST_TIME 77 9663
TEST_TIME 78 9651
TEST_TIME 79 9597
TEST_TIME 80 9590
TEST_TIME 81 9590
TEST_TIME 82 9575
TEST_TIME 83 9544
TEST_TIME 84 9560
TEST_TIME 85 9620
TEST_TIME 86 9629
TEST_TIME 87 9631
TEST_TIME 88 9618
TEST_TIME 89 9619
TEST_TIME 90 9605
TEST_TIME 91 9510
TEST_TIME 92 9458
TEST_TIME 93 9402
TEST_TIME 94 9338
TEST_TIME 95 9323
TEST_TIME 96 9312
TEST_TIME 97 9274
TEST_TIME 98 9248
TEST_TIME 99 9248
TEST_TIME 100 9225
TEST_TIME 101 9210
TEST_TIME 102 9259
TEST_TIME 103 9356
TEST_TIME 104 9351
TEST_TIME 105 9407
TEST_TIME 106 9437
TEST_TIME 107 9458
TEST_TIME 108 9435
TEST_TIME 109 9360
TEST_TIME 110 9367
TEST_TIME 111 9317
TEST_TIME 112 9280
TEST_TIME 113 9230
TEST_TIME 114 9201
TEST_TIME 115 9195
TEST_TIME 116 9181
TEST_TIME 117 9178
TEST_TIME 118 9209
TEST_TIME 119 9205
TEST_TIME 120 9165

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 09-12-2020 10:32:52 to 09-12-2020 10:32:56.

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 1515896 1515896 0 0.00 0 18297 6 0 0
doc_list_biud 1515896 1515896 0 0.00 0 88110 33 0 0
sp_add_invoice_to_stock 4881 4787 94 1.92 37 184605 17803 0 94
sp_cancel_adding_invoice 5730 5730 0 0.00 5 70411 978 0 0
sp_cancel_client_order 1678 1678 0 0.00 5 8064 337 0 0
sp_cancel_customer_reserve 567075 567075 0 0.00 1 88112 72 0 0
sp_cancel_pay_from_customer 680 680 0 0.00 3 2850 83 0 0
sp_cancel_pay_to_supplier 388 388 0 0.00 3 5364 103 0 0
sp_cancel_supplier_invoice 6328 6315 13 0.20 2 35981 954 0 13
sp_cancel_supplier_order 413 413 0 0.00 8 1261875 123608 0 0
sp_cancel_write_off 9244 9244 0 0.00 1 9572 123 0 0
sp_client_order 19347 19347 0 0.00 4 47434 456 0 0
sp_customer_reserve 449654 449654 0 0.00 0 85859 170 0 0
sp_fill_shopping_cart 30035 30035 0 0.00 1 16035 45 0 0
sp_fill_shopping_cart_clo_res 449650 449650 0 0.00 0 31755 21 0 0
sp_get_clo_for_invoice 4776 4776 0 0.00 0 42215 394 0 0
sp_kill_cost_storno 16042 16042 0 0.00 0 5483 61 0 0
sp_kill_qstorno_ret_qs2qd 1150122 1150122 0 0.00 0 88052 38 0 0
sp_kill_qty_storno 604613 604613 0 0.00 0 88110 83 0 0
sp_lock_selected_doc 19772 19772 0 0.00 0 6634 7 0 0
sp_make_cost_storno 26637 26637 0 0.00 0 9526 52 0 0
sp_multiply_rows_for_pdistr 26637 26637 0 0.00 0 8750 33 0 0
sp_multiply_rows_for_qdistr 19347 19347 0 0.00 0 24694 293 0 0
sp_pay_from_customer 6712 6712 0 0.00 4 9868 101 0 0
sp_pay_to_supplier 5767 5767 0 0.00 5 14493 127 0 0
sp_payment_common 12479 12479 0 0.00 1 9862 20 0 0
sp_qd_handle_on_cancel_clo 1678 1678 0 0.00 0 8057 307 0 0
sp_qd_handle_on_invoice_upd_sts 10504 10504 0 0.00 0 45598 396 0 0
sp_qd_handle_on_reserve_upd_sts 9384 9384 0 0.00 0 13611 60 0 0
sp_reserve_write_off 9384 9384 0 0.00 6 13949 189 0 0
sp_supplier_invoice 5284 5284 0 0.00 8 147480 3548 0 0
sp_supplier_order 5417 5417 0 0.00 5 84044 1338 0 0
srv_aggregate_perf_data 105 105 0 0.00 2595 303770 16251 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1375 174112 48706 0 0
srv_find_qd_qs_mism 1053489 1053489 0 0.00 0 18287 5 0 0
srv_make_invnt_saldo 436 436 0 0.00 26 103621 3571 0 0
srv_make_money_saldo 785 785 0 0.00 1 13075 522 0 0
srv_recalc_idx_stat 4 4 0 0.00 191162 503814 299304 0 0
t$perf-abend:sp_add_invoice_to_stock 94 94 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4787 4787 0 0.00 0 13432 181 0 0
t$perf-norm:sp_cancel_adding_invoice 416 416 0 0.00 0 5444 86 0 0
t$perf-norm:sp_cancel_client_order 1678 1678 0 0.00 0 3197 16 0 0
t$perf-norm:sp_cancel_customer_reserve 678 678 0 0.00 0 822 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 680 680 0 0.00 0 576 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 388 388 0 0.00 0 226 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 381 381 0 0.00 0 1277 22 0 0
t$perf-norm:sp_cancel_supplier_order 413 413 0 0.00 0 21745 1639 0 0
t$perf-norm:sp_cancel_write_off 980 980 0 0.00 0 2448 5 0 0
t$perf-norm:sp_client_order 19347 19347 0 0.00 0 12257 17 0 0
t$perf-norm:sp_customer_reserve 925 925 0 0.00 0 1562 5 0 0
t$perf-norm:sp_pay_from_customer 6712 6712 0 0.00 0 2932 7 0 0
t$perf-norm:sp_pay_to_supplier 5767 5767 0 0.00 0 6964 8 0 0
t$perf-norm:sp_reserve_write_off 9384 9384 0 0.00 0 5118 9 0 0
t$perf-norm:sp_supplier_invoice 5284 5284 0 0.00 0 5292 16 0 0
t$perf-norm:sp_supplier_order 5417 5417 0 0.00 0 4038 16 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 256 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 47 2 0 0
t$perf-norm:srv_make_invnt_saldo 436 436 0 0.00 0 376 8 0 0
t$perf-norm:srv_make_money_saldo 785 785 0 0.00 0 2997 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 17 6 0 0
v_add_invoice_to_stock 9736 9548 188 1.93 0 5055 16 0 188
v_all_customers 35720 35720 0 0.00 0 25 1 0 0
v_all_suppliers 30060 30060 0 0.00 0 30 1 0 0
v_all_wares 38694 38694 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 832 832 0 0.00 0 1511 9 0 0
v_cancel_client_order 3356 3356 0 0.00 0 2878 6 0 0
v_cancel_customer_prepayment 1360 1360 0 0.00 0 2437 10 0 0
v_cancel_customer_reserve 1356 1356 0 0.00 0 895 4 0 0
v_cancel_payment_to_supplier 776 776 0 0.00 0 274 4 0 0
v_cancel_supplier_invoice 788 762 26 3.29 0 1327 16 0 26
v_cancel_supplier_order 826 826 0 0.00 0 1119 6 0 0
v_cancel_write_off 1960 1960 0 0.00 0 768 12 0 0
v_max_id_clo_ord 5417 5417 0 0.00 0 406 1 0 0
v_max_id_clo_res 925 925 0 0.00 0 2351 17 0 0
v_max_id_ord_sup 5271 5271 0 0.00 0 2234 3 0 0
v_max_non_paid_invoice 1425 1425 0 0.00 0 2104 4 0 0
v_max_non_paid_realizn 3426 3426 0 0.00 0 1997 3 0 0
v_min_id_clo_ord 5417 5417 0 0.00 0 1556 5 0 0
v_min_id_clo_res 925 925 0 0.00 0 3122 14 0 0
v_min_id_ord_sup 5271 5271 0 0.00 0 8574 13 0 0
v_min_non_paid_invoice 5767 5767 0 0.00 0 8387 16 0 0
v_min_non_paid_realizn 6712 6712 0 0.00 0 4010 11 0 0
v_our_firm 9546 9546 0 0.00 0 23 1 0 0
v_reserve_write_off 18768 18768 0 0.00 0 3455 6 0 0
x_lock_depdocs_on_canc_invoice 11664 11664 0 0.00 0 27421 116 0 0
x_lock_depdocs_on_canc_sup_ord 413 413 0 0.00 0 7989 350 0 0
x_make_qstorno_1000_1200 5417 5417 0 0.00 2 84018 1259 0 0
x_make_qstorno_1200_2000 5284 5284 0 0.00 4 147395 3371 0 0
x_make_qstorno_2100_3300 449654 449654 0 0.00 0 85857 146 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-12-2020 10:32:57 to 09-12-2020 10:33:01.

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
08:01:50 11 8192000000 8796641776 8807108608 3168704 0.038 1 1 2 0 3266400 61840 35856
08:02:52 33991 8192000000 9740496560 10043015168 812705616 9.025 100 84 168 0 841352112 7975488 20671008
08:04:28 29782 8192000000 9783640512 10094391296 853308560 9.433 100 86 172 1 887033664 13698304 20026800
08:06:01 13994 8192000000 9835229440 10156900352 888591248 9.785 100 84 168 1 937576144 30039264 18945632
08:07:21 12923 8192000000 9874357200 10208878592 920997344 10.106 100 81 162 0 987255424 48662976 17595104
08:08:38 10219 8192000000 9907752080 10254069760 960393792 10.493 100 76 153 2 1024545104 48289472 15861840
08:09:51 4980 8192000000 9971784208 10324791296 989938096 10.781 100 79 154 4 1084007024 78219312 15849616
08:10:58 3398 8192000000 9994772704 10356490240 1023014880 11.101 100 87 173 0 1111858960 72066112 16777968
08:12:03 1845 8192000000 10001571152 10368331776 1045782752 11.320 100 85 171 0 1114633584 51193568 17657264
08:13:07 2757 8192000000 10044863280 10429325312 1067033840 11.524 100 82 162 1 1160816976 77426896 16356240
08:14:11 4779 8192000000 9993953344 10391760896 1067808752 11.531 100 44 87 3 1116080960 39545824 8726384
08:15:18 5455 8192000000 9981216976 10386100224 1071384336 11.565 100 19 38 1 1103182448 27815568 3982544
08:16:27 64992 8192000000 10003925712 10393075712 1081439520 11.661 100 43 87 4 1104999328 13977376 9582432
08:18:33 7313 8192000000 10011251456 10409955328 1087846000 11.722 100 43 83 2 1127820688 30383648 9591040
08:19:40 6177 8192000000 10020741296 10419744768 1090903648 11.751 100 32 61 1 1115889536 18931264 6054624
08:20:47 9145 8192000000 9993408560 10405040128 1072822992 11.579 98 32 64 0 1102976624 23492768 6660864
08:21:58 44140 8192000000 10003771552 10418012160 1067055456 11.524 99 29 58 1 1093179968 19995792 6128720
08:23:48 6543 8192000000 9961718592 10347204608 1049154016 11.353 100 32 63 1 1082064208 25473088 7437104
08:24:55 9600 8192000000 9952302112 10332094464 1012678208 11.001 99 33 66 2 1044292704 24568368 7046128
08:26:06 4520 8192000000 9928733232 10306174976 1001126384 10.889 98 48 97 0 1023428240 11532752 10769104
08:27:14 23608 8192000000 9940537088 10339115008 1008588976 10.962 97 21 42 1 1039113344 25716112 4808256
08:28:39 4939 8192000000 9964637104 10350039040 1044959920 11.312 100 38 75 1 1081348816 27229696 9159200
08:29:47 9922 8192000000 9998977248 10406563840 1067359568 11.527 100 47 95 2 1105599568 27180176 11059824
08:31:02 57228 8192000000 10001442400 10397638656 1077308448 11.622 100 35 72 2 1112101456 26859120 7933888
08:33:08 33179 8192000000 10053366576 10459185152 1098909104 11.827 100 39 78 3 1151071696 43987936 8174656
08:34:49 12273 8192000000 10081105184 10480652288 1115940688 11.989 100 47 93 1 1188630912 62920416 9769808
08:36:05 9068 8192000000 10160788576 10563100672 1136623904 12.184 100 64 128 0 1259887760 108951472 14312384
08:37:17 21960 8192000000 10187828880 10608001024 1143556064 12.249 100 43 86 1 1298562800 144437376 10569360
08:38:42 11858 8192000000 10224812576 10643210240 1149648864 12.306 100 45 90 0 1318919248 158464384 10806000
08:40:03 11156 8192000000 10212847664 10629214208 1152512592 12.333 100 53 106 0 1310790256 146332336 11945328
08:41:21 1987 8192000000 10220739520 10625044480 1154959552 12.356 99 76 152 3 1323389248 151829456 16600240
08:42:33 3348 8192000000 10254452944 10666995712 1176680688 12.559 100 81 162 1 1353145840 158850144 17615008
08:43:40 1374 8192000000 10272794832 10682236928 1175100144 12.544 100 82 161 0 1378035408 184536944 18398320
08:44:47 2267 8192000000 10325469888 10730340352 1181124512 12.601 100 83 165 0 1424197776 224049744 19023520
08:45:53 2606 8192000000 10345963488 10741456896 1159576672 12.399 100 88 177 3 1438621440 259928704 19116064
08:46:59 4405 8192000000 10357618368 10763182080 1168299440 12.481 100 90 177 1 1457994000 269396560 20298000
08:48:09 2071 8192000000 10372991696 10771406848 1165889136 12.458 100 85 169 0 1466987232 281404560 19693536
08:49:18 5074 8192000000 10386832992 10809372672 1161630048 12.419 100 51 101 1 1510323248 336182048 12511152
08:50:29 4927 8192000000 10441249312 10862170112 1164622992 12.447 100 57 114 0 1535359472 356510944 14225536
08:51:49 7005 8192000000 10479545440 10913816576 1178767648 12.579 100 45 90 0 1567644912 377339536 11537728
08:53:02 11156 8192000000 10486097360 10927271936 1161600480 12.418 99 41 82 1 1576230288 403776336 10853472
08:54:18 8995 8192000000 10528851600 10987130880 1174280608 12.537 100 51 102 0 1641483088 454165760 13036720
08:55:36 8922 8192000000 10445220048 10912296960 1164071744 12.441 100 39 77 1 1541508416 367580800 9855872
08:56:51 22166 8192000000 10421495808 10983849984 1142373792 12.238 100 17 34 1 1531242352 383690272 5178288
08:58:24 59851 8192000000 10061748480 10548289536 1095643696 11.796 96 7 14 1 1133731168 36742624 1344848
09:00:24 90699 8192000000 10068523456 10535161856 1114618224 11.976 98 16 32 3 1225214992 106698240 3898528
09:02:56 174112 8192000000 10053644880 10522939392 1109178112 11.925 99 45 90 0 1166076704 46913408 9985184
09:06:51 65659 8192000000 10112560528 10552422400 1115910864 11.988 99 38 75 2 1190630704 66971024 7748816
09:09:06 80680 8192000000 10080201536 10559209472 1119755936 12.025 99 8 16 0 1178689744 57023312 1910496
09:11:27 79796 8192000000 10076813024 10545356800 1127228048 12.095 99 13 25 0 1193171392 63104736 2838608
09:14:09 69001 8192000000 10053790960 10560684032 1103517472 11.871 97 4 8 0 1144764656 40622528 624656
09:16:18 59455 8192000000 10099693520 10551947264 1146305648 12.275 100 26 52 3 1190564480 38519184 5739648
09:18:46 42070 8192000000 10170590736 10618892288 1145043584 12.263 98 25 50 1 1235502112 84750896 5707632
09:20:29 47328 8192000000 10196717472 10642309120 1128855424 12.111 97 23 46 0 1242547904 108810592 4881888
09:22:28 69000 8192000000 10130102080 10712588288 1152263936 12.331 100 13 26 1 1233110192 77486080 3360176
09:24:38 61013 8192000000 10081160608 10521174016 1129572192 12.117 98 20 39 1 1185952528 52163104 4217232
09:26:40 49543 8192000000 10081854080 10561245184 1137097360 12.188 99 24 49 1 1180938096 39088944 4751792
09:28:29 105866 8192000000 10097406400 10584043520 1145059904 12.263 100 17 34 0 1225836208 77061632 3714672
09:31:17 44220 8192000000 10079878528 10549583872 1140980624 12.225 100 9 18 0 1171010512 28060816 1969072
09:33:13 84477 8192000000 10080106928 10587291648 1098674064 11.825 97 13 25 0 1172835472 71404384 2757024
09:35:39 45627 8192000000 10093634352 10537705472 1118821744 12.016 98 24 46 1 1167552112 43970416 4759952
09:37:25 65113 8192000000 10113863504 10597920768 1121511632 12.041 98 19 38 1 1188480784 62631120 4338032
09:39:42 58723 8192000000 10090669568 10522247168 1102904112 11.865 97 30 60 1 1161301584 52155920 6241552
09:41:42 36974 8192000000 10111041984 10574704640 1085469152 11.700 96 29 57 3 1169790096 78450192 5870752
09:43:20 51972 8192000000 10073388528 10505924608 1104326720 11.879 98 16 32 2 1143662112 36557232 2778160
09:45:17 119360 8192000000 10038492816 10538586112 1102390464 11.860 99 18 36 0 1168910416 62891968 3627984
09:48:44 75406 8192000000 10049604448 10523160576 1080684128 11.654 96 16 31 1 1141878656 57983616 3210912
09:51:05 38956 8192000000 10100437008 10561282048 1117754240 12.006 99 24 48 1 1173336688 50396560 5185888
09:52:51 34070 8192000000 10080251504 10516217856 1081954544 11.666 96 41 82 1 1140879728 50740432 8184752
09:54:25 48732 8192000000 10106201360 10582953984 1127768560 12.100 100 29 57 1 1199989120 66396128 5824432
09:56:15 37051 8192000000 10070980512 10526412800 1071873712 11.570 95 25 50 1 1142200448 65282112 5044624
09:57:52 111275 8192000000 10068303856 10504851456 1078166912 11.630 96 17 34 2 1125106160 43479792 3459456
10:00:44 55218 8192000000 10051760640 10544009216 1118405888 12.012 100 16 31 2 1182924256 61193648 3324720
10:03:57 44649 8192000000 10048972416 10509873152 1131634720 12.137 100 10 20 0 1153592464 20253680 1704064
10:06:07 89407 8192000000 10041872704 10496708608 1121612016 12.042 100 14 28 0 1184181344 59678624 2890704
10:08:44 63149 8192000000 10106184224 10534813696 1099142432 11.830 97 27 52 0 1145241888 40297952 5801504
10:10:50 54931 8192000000 10145878384 10597453824 1119061776 12.018 98 26 52 0 1212658688 87830176 5766736
10:12:46 92944 8192000000 10139405968 10585714688 1109682352 11.929 98 22 44 0 1250999792 136323152 4994288
10:15:23 89203 8192000000 10107491776 10572058624 1121872384 12.045 98 15 30 3 1233532928 108525936 3134608
10:17:54 68765 8192000000 10089248176 10516533248 1129470960 12.116 99 28 57 1 1185773280 50367776 5934544
10:20:03 90284 8192000000 10074727744 10546085888 1117654800 12.005 98 26 52 1 1201262528 78202528 5405200
10:22:54 48596 8192000000 10058838224 10528612352 1121845776 12.044 99 4 8 0 1165743184 43114240 783168
10:24:43 72794 8192000000 10078544400 10530418688 1120520688 12.032 98 12 25 0 1162836944 39604096 2712160
10:27:15 34951 8192000000 10111091920 10556411904 1125625776 12.080 98 24 48 2 1186735120 56195824 4913520
10:28:50 92076 8192000000 10074664512 10524434432 1103462384 11.870 97 14 28 1 1151888960 45608608 2817968
10:31:32 22416 8192000000 8989090080 9223770112 143722176 1.724 13 3 4 1 154845584 10616736 506672

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 09-12-2020 10:33:03 to 09-12-2020 10:33:18.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 94 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 09-12-2020 10:33:18 to 09-12-2020 10:33:20.

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          230071
MON$OLDEST_ACTIVE               230072
MON$OLDEST_SNAPSHOT             230072
MON$NEXT_TRANSACTION            230072
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                       2093248
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                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             789
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2293 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2293 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2293 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2293 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:83: 
:84: mscs-test-161.budzdorov.ru    Wed Dec  9 08:39:09 2020
:85:     INET/inet_error: read errno = 104
:86: 
--- end of diff output ---

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

09.12.20 10:33:21. 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
2020-12-09 10:33:24 No severe PSQL-related problems occured
09.12.20 10:33:25. End of report.