Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.04.2021 04:28
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID df9f488d08424df294c459d6c283e9c3
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-03-04 16:07
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.26
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16667248 kB
MemAvailable 31419176 kB
Buffers 20 kB
Cached 14930716 kB
SwapCached 0 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-04-03 04:26:58
DTS_END                         2021-04-03 04:26:58.1290
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/35686
TRN_ID                          219779
ATT_ID                          620
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: 8424.50 28120 1010940
customer order: creation 150.66 460 18079
customer order: refuse 13.10 410 1572
order to supplier: creation 40.60 1521 4872
order to supplier: removal 3.09 151546 371
invoice (draft): creation 41.10 4680 4932
invoice (draft): removal 48.95 1275 5874
invoice accept: apply 36.95 18234 4434
invoice accept: cancel 44.50 1040 5340
customer reserve: creation 3433.94 174 412073
customer reserve: removal 4339.32 83 520718
realization accept: apply 74.94 200 8993
realization accept: cancel 81.65 134 9798
payment from customer: creation 53.18 112 6382
payment from customer: removal 5.24 87 629
payment to supplier: creation 44.48 131 5338
payment to supplier: removal 3.13 115 376
service: total inventory turnovers 3.20 4650 384
service: total monetary turnovers 5.51 658 661
service: aggregate perf. data 0.92 14991 110
service: refresh index statistics 0.03 361900 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-04-2021 04:28:24 to 03-04-2021 04:28:26.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3192
TEST_TIME 2 2063
TEST_TIME 3 2684
TEST_TIME 4 3286
TEST_TIME 5 3388
TEST_TIME 6 4009
TEST_TIME 7 4214
TEST_TIME 8 4706
TEST_TIME 9 4645
TEST_TIME 10 4985
TEST_TIME 11 4995
TEST_TIME 12 5156
TEST_TIME 13 5312
TEST_TIME 14 5653
TEST_TIME 15 5676
TEST_TIME 16 5887
TEST_TIME 17 5941
TEST_TIME 18 6136
TEST_TIME 19 6505
TEST_TIME 20 7029
TEST_TIME 21 7287
TEST_TIME 22 7465
TEST_TIME 23 7446
TEST_TIME 24 7456
TEST_TIME 25 7682
TEST_TIME 26 7755
TEST_TIME 27 7595
TEST_TIME 28 7819
TEST_TIME 29 8199
TEST_TIME 30 8799
TEST_TIME 31 9739
TEST_TIME 32 10552
TEST_TIME 33 10306
TEST_TIME 34 10308
TEST_TIME 35 10135
TEST_TIME 36 9904
TEST_TIME 37 10002
TEST_TIME 38 9892
TEST_TIME 39 9738
TEST_TIME 40 9538
TEST_TIME 41 9485
TEST_TIME 42 9426
TEST_TIME 43 9325
TEST_TIME 44 9187
TEST_TIME 45 9104
TEST_TIME 46 9137
TEST_TIME 47 9087
TEST_TIME 48 8948
TEST_TIME 49 8931
TEST_TIME 50 8832
TEST_TIME 51 8801
TEST_TIME 52 8748
TEST_TIME 53 8842
TEST_TIME 54 8896
TEST_TIME 55 8936
TEST_TIME 56 8854
TEST_TIME 57 8811
TEST_TIME 58 8796
TEST_TIME 59 8861
TEST_TIME 60 8814
TEST_TIME 61 8763
TEST_TIME 62 9007
TEST_TIME 63 9003
TEST_TIME 64 9006
TEST_TIME 65 8983
TEST_TIME 66 9034
TEST_TIME 67 9034
TEST_TIME 68 9119
TEST_TIME 69 9034
TEST_TIME 70 8913
TEST_TIME 71 8846
TEST_TIME 72 8799
TEST_TIME 73 8729
TEST_TIME 74 8666
TEST_TIME 75 8716
TEST_TIME 76 8627
TEST_TIME 77 8590
TEST_TIME 78 8573
TEST_TIME 79 8554
TEST_TIME 80 8495
TEST_TIME 81 8493
TEST_TIME 82 8507
TEST_TIME 83 8528
TEST_TIME 84 8593
TEST_TIME 85 8569
TEST_TIME 86 8604
TEST_TIME 87 8607
TEST_TIME 88 8700
TEST_TIME 89 8677
TEST_TIME 90 8678
TEST_TIME 91 8717
TEST_TIME 92 8830
TEST_TIME 93 8775
TEST_TIME 94 8685
TEST_TIME 95 8642
TEST_TIME 96 8606
TEST_TIME 97 8589
TEST_TIME 98 8552
TEST_TIME 99 8528
TEST_TIME 100 8509
TEST_TIME 101 8564
TEST_TIME 102 8533
TEST_TIME 103 8557
TEST_TIME 104 8538
TEST_TIME 105 8554
TEST_TIME 106 8560
TEST_TIME 107 8706
TEST_TIME 108 8677
TEST_TIME 109 8733
TEST_TIME 110 8717
TEST_TIME 111 8661
TEST_TIME 112 8592
TEST_TIME 113 8585
TEST_TIME 114 8549
TEST_TIME 115 8518
TEST_TIME 116 8488
TEST_TIME 117 8458
TEST_TIME 118 8466
TEST_TIME 119 8437
TEST_TIME 120 8427

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 03-04-2021 04:28:28 to 03-04-2021 04:28:31.

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 1392413 1392413 0 0.00 0 17604 6 0 0
doc_list_biud 1392412 1392412 0 0.00 0 42457 40 0 0
sp_add_invoice_to_stock 4546 4434 112 2.46 33 182198 18235 0 112
sp_cancel_adding_invoice 5340 5340 0 0.00 5 74645 1040 0 0
sp_cancel_client_order 1572 1572 0 0.00 5 14630 411 0 0
sp_cancel_customer_reserve 520718 520718 0 0.00 1 42460 84 0 0
sp_cancel_pay_from_customer 629 629 0 0.00 3 7986 88 0 0
sp_cancel_pay_to_supplier 376 376 0 0.00 3 4744 115 0 0
sp_cancel_supplier_invoice 5881 5874 7 0.11 2 23865 1276 0 7
sp_cancel_supplier_order 371 371 0 0.00 12 1471492 151547 0 0
sp_cancel_write_off 9798 9798 0 0.00 1 20428 135 0 0
sp_client_order 18079 18079 0 0.00 4 33608 461 0 0
sp_customer_reserve 412073 412073 0 0.00 0 72842 175 0 0
sp_fill_shopping_cart 27876 27876 0 0.00 1 36479 60 0 0
sp_fill_shopping_cart_clo_res 412072 412072 0 0.00 0 34463 18 0 0
sp_get_clo_for_invoice 4433 4433 0 0.00 0 49790 516 0 0
sp_kill_cost_storno 16143 16143 0 0.00 0 8786 66 0 0
sp_kill_qstorno_ret_qs2qd 1057479 1057479 0 0.00 0 42446 46 0 0
sp_kill_qty_storno 557098 557098 0 0.00 0 42455 99 0 0
sp_lock_selected_doc 18556 18556 0 0.00 0 8715 5 0 0
sp_make_cost_storno 25146 25146 0 0.00 0 17596 61 0 0
sp_multiply_rows_for_pdistr 25145 25145 0 0.00 0 6691 29 0 0
sp_multiply_rows_for_qdistr 18079 18079 0 0.00 0 18140 260 0 0
sp_pay_from_customer 6382 6382 0 0.00 4 11641 113 0 0
sp_pay_to_supplier 5338 5338 0 0.00 4 9466 132 0 0
sp_payment_common 11720 11720 0 0.00 1 10469 20 0 0
sp_qd_handle_on_cancel_clo 1572 1572 0 0.00 0 14623 382 0 0
sp_qd_handle_on_invoice_upd_sts 9773 9773 0 0.00 0 19961 495 0 0
sp_qd_handle_on_reserve_upd_sts 8992 8992 0 0.00 0 12653 62 0 0
sp_reserve_write_off 8993 8993 0 0.00 7 17501 200 0 0
sp_supplier_invoice 4932 4932 0 0.00 9 118545 4680 0 0
sp_supplier_order 4872 4872 0 0.00 5 36828 1522 0 0
srv_aggregate_perf_data 110 110 0 0.00 2957 181366 14992 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1705 152269 45813 0 0
srv_find_qd_qs_mism 968396 968396 0 0.00 0 8762 5 0 0
srv_make_invnt_saldo 384 384 0 0.00 69 107594 4650 0 0
srv_make_money_saldo 661 661 0 0.00 1 13474 658 0 0
srv_recalc_idx_stat 4 4 0 0.00 218603 571570 361900 0 0
t$perf-abend:sp_add_invoice_to_stock 112 112 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4436 4436 0 0.00 0 18629 193 0 0
t$perf-norm:sp_cancel_adding_invoice 366 366 0 0.00 0 12891 158 0 0
t$perf-norm:sp_cancel_client_order 1572 1572 0 0.00 0 3077 18 0 0
t$perf-norm:sp_cancel_customer_reserve 575 575 0 0.00 0 541 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 629 629 0 0.00 0 460 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 376 376 0 0.00 0 9846 36 0 0
t$perf-norm:sp_cancel_supplier_invoice 383 383 0 0.00 0 807 17 0 0
t$perf-norm:sp_cancel_supplier_order 371 371 0 0.00 0 23502 1881 0 0
t$perf-norm:sp_cancel_write_off 861 861 0 0.00 0 2542 9 0 0
t$perf-norm:sp_client_order 18079 18079 0 0.00 0 8847 19 0 0
t$perf-norm:sp_customer_reserve 897 897 0 0.00 0 847 4 0 0
t$perf-norm:sp_pay_from_customer 6382 6382 0 0.00 0 8907 11 0 0
t$perf-norm:sp_pay_to_supplier 5338 5338 0 0.00 0 11734 11 0 0
t$perf-norm:sp_reserve_write_off 8993 8993 0 0.00 0 3352 9 0 0
t$perf-norm:sp_supplier_invoice 4932 4932 0 0.00 0 7682 22 0 0
t$perf-norm:sp_supplier_order 4872 4872 0 0.00 0 10828 23 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 543 18 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 1298 40 0 0
t$perf-norm:srv_make_invnt_saldo 384 384 0 0.00 0 992 9 0 0
t$perf-norm:srv_make_money_saldo 661 661 0 0.00 0 1002 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 3 3 0 0
v_add_invoice_to_stock 9086 8862 224 2.46 0 4803 18 0 224
v_all_customers 32848 32848 0 0.00 0 60 1 0 0
v_all_suppliers 27416 27416 0 0.00 0 14 1 0 0
v_all_wares 36158 36158 0 0.00 0 14 1 0 0
v_cancel_adding_invoice 732 732 0 0.00 0 4709 12 0 0
v_cancel_client_order 3144 3144 0 0.00 0 3427 7 0 0
v_cancel_customer_prepayment 1258 1258 0 0.00 0 1095 6 0 0
v_cancel_customer_reserve 1150 1150 0 0.00 0 439 4 0 0
v_cancel_payment_to_supplier 752 752 0 0.00 0 4705 18 0 0
v_cancel_supplier_invoice 780 766 14 1.79 0 2883 20 0 14
v_cancel_supplier_order 742 742 0 0.00 0 802 7 0 0
v_cancel_write_off 1722 1722 0 0.00 0 7667 20 0 0
v_max_id_clo_ord 4872 4872 0 0.00 0 13198 4 0 0
v_max_id_clo_res 897 897 0 0.00 0 10195 48 0 0
v_max_id_ord_sup 4925 4925 0 0.00 0 7183 12 0 0
v_max_non_paid_invoice 1427 1427 0 0.00 0 932 3 0 0
v_max_non_paid_realizn 3551 3551 0 0.00 0 1367 3 0 0
v_min_id_clo_ord 4872 4872 0 0.00 0 2966 9 0 0
v_min_id_clo_res 897 897 0 0.00 0 4092 16 0 0
v_min_id_ord_sup 4925 4925 0 0.00 0 8864 16 0 0
v_min_non_paid_invoice 5338 5338 0 0.00 0 8272 11 0 0
v_min_non_paid_realizn 6382 6382 0 0.00 0 10464 12 0 0
v_our_firm 8972 8972 0 0.00 0 14 1 0 0
v_reserve_write_off 17984 17984 0 0.00 0 4777 9 0 0
x_lock_depdocs_on_canc_invoice 10831 10831 0 0.00 0 18251 141 0 0
x_lock_depdocs_on_canc_sup_ord 371 371 0 0.00 0 20843 440 0 0
x_make_qstorno_1000_1200 4872 4872 0 0.00 2 36814 1429 0 0
x_make_qstorno_1200_2000 4932 4932 0 0.00 5 118474 4439 0 0
x_make_qstorno_2100_3300 412073 412073 0 0.00 0 72841 154 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-04-2021 04:28:32 to 03-04-2021 04:28:36.

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
01:57:01 2 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
01:58:02 26796 8192000000 9724619104 10022928384 798112480 8.877 100 91 183 1 828037872 7622928 22302464
01:59:41 20313 8192000000 9770976464 10077814784 845252896 9.352 100 90 180 0 879737056 13211216 21272944
02:01:07 14541 8192000000 9814404336 10131742720 879163424 9.691 100 83 166 0 919417904 21423712 18830768
02:02:27 15458 8192000000 9871850608 10202832896 920697056 10.103 100 80 160 0 980092128 41887264 17507808
02:03:45 7892 8192000000 9918759536 10264797184 958592480 10.475 100 82 164 1 1038138480 62220320 17325680
02:05:01 6003 8192000000 9955176864 10309586944 996759104 10.847 100 82 164 0 1061766048 47934672 17072272
02:06:09 2730 8192000000 10015148128 10376265728 1025041728 11.121 100 94 186 0 1126194560 82880912 18271920
02:07:14 2263 8192000000 10027939680 10391252992 1046473568 11.327 100 91 182 1 1140211168 74535072 19202528
02:08:18 1267 8192000000 10031659840 10403409920 1067695440 11.530 100 92 180 2 1147421648 61260064 18466144
02:09:20 9150 8192000000 9978047344 10373603328 1076241472 11.612 100 37 76 3 1113778736 29143536 8393728
02:10:30 11062 8192000000 10010811280 10404786176 1086982896 11.714 100 43 86 1 1130694704 34788928 8922880
02:11:48 33123 8192000000 9995062560 10408439808 1098138096 11.820 100 25 50 0 1116195648 12222464 5835088
02:13:26 11627 8192000000 10015195296 10423967744 1100033424 11.838 100 28 55 3 1122624272 16086096 6504752
02:14:38 8665 8192000000 10021905696 10438189056 1106272992 11.897 100 27 54 0 1140132592 28626080 5233520
02:15:51 8427 8192000000 10029903104 10437173248 1112188592 11.953 100 27 52 1 1135881536 17573264 6119680
02:17:02 67552 8192000000 10000104848 10416005120 1087216384 11.716 99 27 54 1 1118935936 25720336 5999216
02:19:10 5579 8192000000 9979797120 10374979584 1063425488 11.489 99 37 74 1 1083866016 12324816 8115712
02:20:17 5253 8192000000 9960894432 10351632384 1040075088 11.265 99 41 82 1 1072757808 23945424 8737296
02:21:23 11424 8192000000 9935727504 10314612736 989436656 10.776 96 25 50 0 1018543232 23220128 5886448
02:22:40 78082 8192000000 9951150704 10356166656 996078592 10.840 96 29 55 2 1023192800 20904800 6209408
02:24:58 8370 8192000000 9971888736 10376290304 1051043040 11.371 100 27 53 2 1084839664 28296224 5500400
02:26:09 10639 8192000000 9979343776 10381664256 1062168192 11.477 100 34 68 0 1082453840 12479344 7806304
02:27:23 28619 8192000000 10004267840 10399084544 1079992640 11.647 100 45 87 0 1105109584 15417392 9699552
02:28:53 33967 8192000000 10038694384 10444083200 1095914896 11.799 100 41 82 2 1135918880 30745808 9258176
02:30:34 10671 8192000000 10075845664 10483130368 1112770176 11.959 100 47 94 0 1181984656 59398464 9816016
02:31:50 14289 8192000000 10111863136 10511462400 1131771488 12.138 100 54 106 0 1217374848 74059632 11543728
02:33:08 18275 8192000000 10161541984 10577080320 1138169776 12.198 100 60 120 1 1271142848 119846976 13126096
02:34:32 10214 8192000000 10178875840 10596847616 1149485808 12.305 100 54 107 3 1294647536 133964752 11196976
02:35:46 13958 8192000000 10216733648 10634268672 1152724912 12.335 100 52 103 1 1331139264 166760048 11654304
02:37:11 3970 8192000000 10245534864 10653257728 1163836640 12.439 100 72 144 0 1344409008 164099856 16472512
02:38:19 6241 8192000000 10270494672 10681765888 1173702256 12.531 100 66 132 0 1369125296 180402048 15020992
02:39:32 8010 8192000000 10296697200 10704396288 1175442928 12.548 100 80 157 1 1391550672 198420928 17686816
02:40:46 8779 8192000000 10311435104 10723389440 1171213312 12.508 99 63 126 0 1411922112 226762464 13946336
02:42:02 6715 8192000000 10319194848 10736259072 1184455600 12.632 100 64 127 0 1429412752 230630208 14326944
02:43:16 4375 8192000000 10363677456 10769747968 1173403360 12.529 100 82 162 2 1467035104 275152320 18479424
02:44:25 1700 8192000000 10367024528 10771623936 1159884176 12.402 99 85 168 0 1463394496 283106880 20403440
02:45:31 1715 8192000000 10406113344 10828210176 1159255136 12.396 100 96 192 1 1498847680 318615952 20976592
02:46:40 3984 8192000000 10385054496 10791817216 1161786848 12.420 100 82 162 3 1490269200 309341504 19140848
02:47:51 5103 8192000000 10381776224 10794016768 1173073040 12.526 100 67 135 1 1482185904 293345552 15767312
02:49:01 2793 8192000000 10386600128 10803273728 1172690864 12.522 100 60 118 2 1491110928 304048688 14371376
02:50:13 4847 8192000000 10468672272 10887929856 1173590400 12.530 99 55 108 2 1572503872 385228704 13684768
02:51:37 7549 8192000000 10511728912 10948390912 1174633824 12.540 100 54 108 0 1610105360 421200944 14270592
02:52:51 8142 8192000000 10564062064 11015618560 1174139152 12.535 100 47 94 0 1679991120 493483728 12368240
02:54:16 9631 8192000000 10593541072 11081199616 1153613552 12.343 100 47 94 0 1713327376 546132656 13581168
02:55:37 13965 8192000000 10584849728 11090849792 1138429584 12.201 100 30 60 1 1687262624 539264912 9568128
02:56:59 18862 8192000000 10413841296 10879606784 1098065136 11.819 98 24 48 1 1511643152 407090912 6487104
02:58:23 34763 8192000000 10053295888 10493767680 1133145904 12.151 100 20 39 0 1170582208 32859696 4576608
03:00:13 20810 8192000000 10116465472 10566049792 1146383312 12.276 100 23 46 1 1218982528 67906512 4692704
03:02:44 64641 8192000000 10070107808 10546073600 1113292816 11.964 98 32 63 1 1200794368 80048000 7453552
03:05:03 100800 8192000000 10062327632 10502684672 1132798592 12.148 100 35 70 2 1161972272 21827824 7345856
03:07:53 103051 8192000000 10074628240 10522099712 1121941360 12.045 98 13 26 2 1168476144 43343584 3191200
03:10:37 16657 8192000000 10093989792 10533986304 1140659408 12.222 100 24 47 2 1198856208 52942304 5254496
03:11:58 35408 8192000000 10143948544 10584727552 1146680032 12.278 100 34 68 1 1232376896 78187440 7509424
03:13:53 57902 8192000000 10174594480 10624307200 1139412320 12.210 99 18 36 0 1265459888 122041760 4005808
03:15:53 93721 8192000000 10132282624 10621386752 1150988784 12.319 100 16 32 1 1264773072 109822864 3961424
03:18:37 102685 8192000000 10134102480 10638049280 1146289200 12.275 99 16 34 1 1277688496 127513440 3885856
03:21:28 73734 8192000000 10081232320 10520145920 1140682320 12.222 99 18 36 0 1168766768 23810000 4274448
03:23:58 66701 8192000000 10112422080 10583232512 1164465520 12.445 100 23 45 1 1229993616 60364736 5163360
03:26:12 40458 8192000000 10089989040 10528882688 1119570096 12.023 98 25 50 0 1152695408 27801600 5323712
03:27:56 72285 8192000000 10087683424 10618597376 1112965392 11.960 98 14 28 0 1217417264 101361248 3090624
03:30:45 100415 8192000000 10061450144 10539069440 1077078752 11.620 95 10 20 0 1141180016 62272656 1828608
03:33:26 48515 8192000000 10061166224 10539143168 1107309936 11.907 98 11 22 0 1148912944 39427952 2175056
03:35:18 152267 8192000000 10055111600 10497871872 1102120160 11.858 97 17 34 1 1124132832 18758656 3254016
03:38:52 55323 8192000000 10055164928 10501713920 1101454128 11.851 97 13 26 0 1147928576 43917200 2557248
03:41:10 57794 8192000000 10073225456 10570870784 1130946304 12.130 100 12 24 0 1182871024 49418288 2506432
03:43:58 42298 8192000000 10045449056 10491232256 1108847888 11.922 98 20 41 1 1141450848 27974784 4628176
03:45:41 56410 8192000000 10086362464 10538659840 1125921600 12.083 99 12 24 0 1170548400 42094336 2532464
03:47:38 55375 8192000000 10083805488 10543996928 1113536720 11.966 98 16 32 1 1197600096 80366336 3697040
03:49:35 81437 8192000000 10064537536 10517442560 1083841168 11.684 96 13 26 0 1147767616 61247680 2678768
03:51:57 55909 8192000000 10065098144 10520719360 1086402176 11.708 97 27 54 2 1143261664 50999920 5859568
03:53:57 85854 8192000000 10059054208 10546855936 1116887472 11.998 99 16 32 1 1188236800 67563616 3785712
03:56:23 56627 8192000000 10089508448 10560622592 1115064224 11.980 100 19 38 1 1190578560 71679216 3835120
03:58:37 69450 8192000000 10051757984 10573803520 1074063360 11.591 95 9 17 1 1166632736 90932272 1637104
04:00:47 91253 8192000000 10042853024 10509451264 1109389792 11.927 99 21 42 0 1143713216 29851824 4471600
04:03:50 94514 8192000000 10089465696 10530160640 1120000432 12.027 99 21 42 2 1163385936 39416688 3968816
04:06:36 67631 8192000000 10096839680 10563493888 1140228128 12.218 100 20 40 0 1194572304 50336288 4007888
04:08:43 29939 8192000000 10122137200 10555404288 1091926032 11.761 95 25 49 1 1171166192 74079968 5160192
04:10:20 73289 8192000000 10179331168 10621218816 1132627984 12.146 99 28 55 4 1255826864 116721344 6477536
04:12:44 85838 8192000000 10114224112 10636480512 1138184112 12.198 100 12 24 0 1232716848 92037472 2495264
04:15:10 83248 8192000000 10103055168 10553487360 1147356496 12.285 100 14 28 2 1197247808 47173696 2717616
04:17:34 88717 8192000000 10088781792 10534830080 1134974960 12.168 99 21 42 0 1180529120 41212640 4341520
04:20:17 48525 8192000000 10081804736 10546147328 1147751264 12.288 100 12 24 0 1191035312 40934496 2349552
04:22:06 69884 8192000000 10104673840 10591875072 1140083104 12.216 99 7 14 0 1191842928 49996688 1763136
04:24:19 65082 8192000000 10096677104 10599809024 1115269104 11.982 97 8 16 0 1149442064 32196928 1976032
04:26:25 40513 8192000000 9265640368 9574141952 368717264 4.307 33 9 18 0 420845664 50319600 1808800

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 03-04-2021 04:28:38 to 03-04-2021 04:28:53.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 112 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 03-04-2021 04:28:54 to 03-04-2021 04:28:56.

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          220085
MON$OLDEST_ACTIVE               220086
MON$OLDEST_SNAPSHOT             220086
MON$NEXT_TRANSACTION            220086
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                       2109056
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             769
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2399 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2399 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2399 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.2399 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 ---
:45: 
:46: mscs-test-161.budzdorov.ru    Sat Apr  3 03:11:19 2021
:47:     INET/inet_error: read errno = 104
:48: 
--- end of diff output ---

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

03.04.21 04:28:57. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2021-04-03 04:28:59 No severe PSQL-related problems occured
03.04.21 04:29:00. End of report.