0s autopkgtest [17:40:47]: starting date and time: 2025-10-29 17:40:47+0000 0s autopkgtest [17:40:47]: git checkout: 4b346b80 nova: make wait_reboot return success even when a no-op 0s autopkgtest [17:40:47]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.78s57mfs/out --timeout-copy=6000 -a i386 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:netpbm-free --apt-upgrade python-docx-template --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=netpbm-free/2:11.12.00-1 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor builder-cpu2-ram4-disk20 --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos03-2.secgroup --name adt-resolute-i386-python-docx-template-20251029-174047-juju-7f2275-prod-proposed-migration-environment-2-c3f7d810-69f2-4d59-a973-057373318970 --image adt/ubuntu-resolute-amd64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --net-id=net_prod-proposed-migration-amd64 -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 4s Creating nova instance adt-resolute-i386-python-docx-template-20251029-174047-juju-7f2275-prod-proposed-migration-environment-2-c3f7d810-69f2-4d59-a973-057373318970 from image adt/ubuntu-resolute-amd64-server-20251029.img (UUID 5761d511-8185-4ff5-8689-0f6c7cb11ec6)... 87s autopkgtest [17:42:14]: testbed dpkg architecture: amd64 87s autopkgtest [17:42:14]: testbed apt version: 3.1.11 88s autopkgtest [17:42:15]: @@@@@@@@@@@@@@@@@@@@ test bed setup 88s autopkgtest [17:42:15]: testbed release detected to be: None 89s autopkgtest [17:42:16]: updating testbed package index (apt update) 89s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease [87.8 kB] 89s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 89s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 89s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 89s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/universe Sources [3643 kB] 90s Get:6 http://ftpmaster.internal/ubuntu resolute-proposed/main Sources [205 kB] 90s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse Sources [70.5 kB] 90s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/restricted Sources [5856 B] 90s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/main i386 Packages [185 kB] 90s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 Packages [287 kB] 91s Get:11 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 c-n-f Metadata [5760 B] 91s Get:12 http://ftpmaster.internal/ubuntu resolute-proposed/restricted i386 Packages [4212 B] 91s Get:13 http://ftpmaster.internal/ubuntu resolute-proposed/restricted amd64 Packages [28.6 kB] 91s Get:14 http://ftpmaster.internal/ubuntu resolute-proposed/restricted amd64 c-n-f Metadata [336 B] 91s Get:15 http://ftpmaster.internal/ubuntu resolute-proposed/universe i386 Packages [1131 kB] 91s Get:16 http://ftpmaster.internal/ubuntu resolute-proposed/universe amd64 Packages [2559 kB] 91s Get:17 http://ftpmaster.internal/ubuntu resolute-proposed/universe amd64 c-n-f Metadata [71.9 kB] 91s Get:18 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse amd64 Packages [47.4 kB] 91s Get:19 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse i386 Packages [19.9 kB] 91s Get:20 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse amd64 c-n-f Metadata [2072 B] 93s Fetched 8354 kB in 3s (3242 kB/s) 93s Reading package lists... 94s Hit:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease 94s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 94s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 94s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 95s Reading package lists... 95s Reading package lists... 96s Building dependency tree... 96s Reading state information... 96s Calculating upgrade... 96s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 96s autopkgtest [17:42:23]: upgrading testbed (apt dist-upgrade and autopurge) 96s Reading package lists... 97s Building dependency tree... 97s Reading state information... 97s Calculating upgrade... 97s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 97s Reading package lists... 97s Building dependency tree... 97s Reading state information... 98s Solving dependencies... 98s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 100s autopkgtest [17:42:27]: testbed running kernel: Linux 6.17.0-5-generic #5-Ubuntu SMP PREEMPT_DYNAMIC Mon Sep 22 10:00:33 UTC 2025 100s autopkgtest [17:42:27]: @@@@@@@@@@@@@@@@@@@@ apt-source python-docx-template 103s Get:1 http://ftpmaster.internal/ubuntu resolute/universe python-docx-template 0.20.1+dfsg-1 (dsc) [2360 B] 103s Get:2 http://ftpmaster.internal/ubuntu resolute/universe python-docx-template 0.20.1+dfsg-1 (tar) [1586 kB] 103s Get:3 http://ftpmaster.internal/ubuntu resolute/universe python-docx-template 0.20.1+dfsg-1 (diff) [2904 B] 103s gpgv: Signature made Tue Sep 2 12:36:18 2025 UTC 103s gpgv: using RSA key 772292F6F7AC85FAE041D41EE5F43F9C2734F287 103s gpgv: issuer "merkys@debian.org" 103s gpgv: Can't check signature: No public key 103s dpkg-source: warning: cannot verify inline signature for ./python-docx-template_0.20.1+dfsg-1.dsc: no acceptable signature found 103s autopkgtest [17:42:30]: testing package python-docx-template version 0.20.1+dfsg-1 103s autopkgtest [17:42:30]: build not needed 104s autopkgtest [17:42:31]: test command1: preparing testbed 105s Reading package lists... 105s Building dependency tree... 105s Reading state information... 105s Solving dependencies... 105s The following NEW packages will be installed: 105s libdeflate0 libjbig0 libjpeg-turbo8 libjpeg8 liblerc4 libnetpbm11t64 105s libqrencode4 libsharpyuv0 libtiff6 libwebp7 libxslt1.1 netpbm python3-docx 105s python3-docxcompose python3-docxtpl python3-lxml qrencode 105s 0 upgraded, 17 newly installed, 0 to remove and 0 not upgraded. 105s Need to get 6109 kB of archives. 105s After this operation, 24.8 MB of additional disk space will be used. 105s Get:1 http://ftpmaster.internal/ubuntu resolute/main amd64 libdeflate0 amd64 1.23-2 [49.9 kB] 106s Get:2 http://ftpmaster.internal/ubuntu resolute/main amd64 libjpeg-turbo8 amd64 2.1.5-4ubuntu2 [152 kB] 106s Get:3 http://ftpmaster.internal/ubuntu resolute/main amd64 libjpeg8 amd64 8c-2ubuntu11 [2148 B] 106s Get:4 http://ftpmaster.internal/ubuntu resolute/main amd64 liblerc4 amd64 4.0.0+ds-5ubuntu1 [271 kB] 106s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/universe amd64 libnetpbm11t64 amd64 2:11.12.00-1 [116 kB] 106s Get:6 http://ftpmaster.internal/ubuntu resolute/universe amd64 libqrencode4 amd64 4.1.1-2 [41.2 kB] 106s Get:7 http://ftpmaster.internal/ubuntu resolute/main amd64 libsharpyuv0 amd64 1.5.0-0.1 [25.9 kB] 106s Get:8 http://ftpmaster.internal/ubuntu resolute/main amd64 libjbig0 amd64 2.1-6.1ubuntu2 [29.7 kB] 106s Get:9 http://ftpmaster.internal/ubuntu resolute/main amd64 libwebp7 amd64 1.5.0-0.1 [378 kB] 106s Get:10 http://ftpmaster.internal/ubuntu resolute/main amd64 libtiff6 amd64 4.7.0-3ubuntu3 [209 kB] 106s Get:11 http://ftpmaster.internal/ubuntu resolute/main amd64 libxslt1.1 amd64 1.1.43-0.3 [172 kB] 106s Get:12 http://ftpmaster.internal/ubuntu resolute-proposed/universe amd64 netpbm amd64 2:11.12.00-1 [2097 kB] 106s Get:13 http://ftpmaster.internal/ubuntu resolute/main amd64 python3-lxml amd64 6.0.1-1build1 [2336 kB] 107s Get:14 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-docx all 1.2.0+dfsg-1 [163 kB] 107s Get:15 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-docxcompose all 1.4.0-4 [20.7 kB] 107s Get:16 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-docxtpl all 0.20.1+dfsg-1 [19.2 kB] 107s Get:17 http://ftpmaster.internal/ubuntu resolute/universe amd64 qrencode amd64 4.1.1-2 [26.7 kB] 107s Fetched 6109 kB in 2s (3719 kB/s) 107s Selecting previously unselected package libdeflate0:amd64. 107s (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 78531 files and directories currently installed.) 107s Preparing to unpack .../00-libdeflate0_1.23-2_amd64.deb ... 107s Unpacking libdeflate0:amd64 (1.23-2) ... 107s Selecting previously unselected package libjpeg-turbo8:amd64. 107s Preparing to unpack .../01-libjpeg-turbo8_2.1.5-4ubuntu2_amd64.deb ... 107s Unpacking libjpeg-turbo8:amd64 (2.1.5-4ubuntu2) ... 107s Selecting previously unselected package libjpeg8:amd64. 107s Preparing to unpack .../02-libjpeg8_8c-2ubuntu11_amd64.deb ... 107s Unpacking libjpeg8:amd64 (8c-2ubuntu11) ... 107s Selecting previously unselected package liblerc4:amd64. 107s Preparing to unpack .../03-liblerc4_4.0.0+ds-5ubuntu1_amd64.deb ... 107s Unpacking liblerc4:amd64 (4.0.0+ds-5ubuntu1) ... 107s Selecting previously unselected package libnetpbm11t64:amd64. 107s Preparing to unpack .../04-libnetpbm11t64_2%3a11.12.00-1_amd64.deb ... 107s Unpacking libnetpbm11t64:amd64 (2:11.12.00-1) ... 107s Selecting previously unselected package libqrencode4:amd64. 107s Preparing to unpack .../05-libqrencode4_4.1.1-2_amd64.deb ... 107s Unpacking libqrencode4:amd64 (4.1.1-2) ... 107s Selecting previously unselected package libsharpyuv0:amd64. 107s Preparing to unpack .../06-libsharpyuv0_1.5.0-0.1_amd64.deb ... 107s Unpacking libsharpyuv0:amd64 (1.5.0-0.1) ... 107s Selecting previously unselected package libjbig0:amd64. 107s Preparing to unpack .../07-libjbig0_2.1-6.1ubuntu2_amd64.deb ... 107s Unpacking libjbig0:amd64 (2.1-6.1ubuntu2) ... 107s Selecting previously unselected package libwebp7:amd64. 107s Preparing to unpack .../08-libwebp7_1.5.0-0.1_amd64.deb ... 107s Unpacking libwebp7:amd64 (1.5.0-0.1) ... 108s Selecting previously unselected package libtiff6:amd64. 108s Preparing to unpack .../09-libtiff6_4.7.0-3ubuntu3_amd64.deb ... 108s Unpacking libtiff6:amd64 (4.7.0-3ubuntu3) ... 108s Selecting previously unselected package libxslt1.1:amd64. 108s Preparing to unpack .../10-libxslt1.1_1.1.43-0.3_amd64.deb ... 108s Unpacking libxslt1.1:amd64 (1.1.43-0.3) ... 108s Selecting previously unselected package netpbm. 108s Preparing to unpack .../11-netpbm_2%3a11.12.00-1_amd64.deb ... 108s Unpacking netpbm (2:11.12.00-1) ... 108s Selecting previously unselected package python3-lxml:amd64. 108s Preparing to unpack .../12-python3-lxml_6.0.1-1build1_amd64.deb ... 108s Unpacking python3-lxml:amd64 (6.0.1-1build1) ... 108s Selecting previously unselected package python3-docx. 108s Preparing to unpack .../13-python3-docx_1.2.0+dfsg-1_all.deb ... 108s Unpacking python3-docx (1.2.0+dfsg-1) ... 108s Selecting previously unselected package python3-docxcompose. 108s Preparing to unpack .../14-python3-docxcompose_1.4.0-4_all.deb ... 108s Unpacking python3-docxcompose (1.4.0-4) ... 108s Selecting previously unselected package python3-docxtpl. 108s Preparing to unpack .../15-python3-docxtpl_0.20.1+dfsg-1_all.deb ... 108s Unpacking python3-docxtpl (0.20.1+dfsg-1) ... 108s Selecting previously unselected package qrencode. 108s Preparing to unpack .../16-qrencode_4.1.1-2_amd64.deb ... 108s Unpacking qrencode (4.1.1-2) ... 108s Setting up libsharpyuv0:amd64 (1.5.0-0.1) ... 108s Setting up liblerc4:amd64 (4.0.0+ds-5ubuntu1) ... 108s Setting up libqrencode4:amd64 (4.1.1-2) ... 108s Setting up qrencode (4.1.1-2) ... 108s Setting up libdeflate0:amd64 (1.23-2) ... 108s Setting up libjbig0:amd64 (2.1-6.1ubuntu2) ... 108s Setting up libnetpbm11t64:amd64 (2:11.12.00-1) ... 108s Setting up libjpeg-turbo8:amd64 (2.1.5-4ubuntu2) ... 108s Setting up libwebp7:amd64 (1.5.0-0.1) ... 108s Setting up libxslt1.1:amd64 (1.1.43-0.3) ... 108s Setting up libjpeg8:amd64 (8c-2ubuntu11) ... 108s Setting up python3-lxml:amd64 (6.0.1-1build1) ... 108s Setting up libtiff6:amd64 (4.7.0-3ubuntu3) ... 108s Setting up python3-docx (1.2.0+dfsg-1) ... 108s Setting up python3-docxcompose (1.4.0-4) ... 108s Setting up python3-docxtpl (0.20.1+dfsg-1) ... 109s Setting up netpbm (2:11.12.00-1) ... 109s Processing triggers for man-db (2.13.1-1) ... 109s Processing triggers for libc-bin (2.42-0ubuntu3) ... 110s autopkgtest [17:42:37]: test environment configured for cross building 111s autopkgtest [17:42:38]: test command1: make -f debian/rules override_dh_auto_test 111s autopkgtest [17:42:38]: test command1: [----------------------- 111s qrencode --output debian/test.png TEST 111s pngtopnm debian/test.png | pnmtojpeg > debian/test.jpg 111s cp debian/test.png tests/templates/dummy_pic_for_header.png 111s cp debian/test.png tests/templates/python_logo.png 111s cp debian/test.png tests/templates/django.png 111s cp debian/test.png tests/templates/python.png 111s cp debian/test.png tests/templates/zope.png 111s cp debian/test.png tests/templates/bottle.png 111s cp debian/test.png tests/templates/tornado.png 111s cp debian/test.png tests/templates/pyramid.png 111s cp debian/test.jpg tests/templates/python_jpeg.jpg 111s cd tests && PYTHONPATH=$(find ../.pybuild -name build 2>/dev/null | xargs echo | tr ' ' :) python3 runtests.py 114s Testing get_undeclared_template_variables method... 114s ================================================== 114s === Test 1: Before render === 114s Variables found: {'name', 'age', 'is_student', 'has_degree', 'email', 'page_number', 'projects', 'generation_date', 'author', 'company_name', 'degree_field', 'skills'} 114s PASS: Found all expected variables before render 114s 114s === Test 2: After incomplete render === 114s Missing variables: {'has_degree', 'page_number', 'generation_date', 'projects', 'degree_field'} 114s PASS: Found missing variables after incomplete render 114s 114s === Test 3: After complete render === 114s Undeclared variables: set() 114s PASS: No undeclared variables after complete render 114s 114s === Test 4: With custom Jinja environment === 114s Variables found with custom env: {'name', 'age', 'is_student', 'has_degree', 'email', 'page_number', 'projects', 'generation_date', 'author', 'company_name', 'degree_field', 'skills'} 114s PASS: Custom Jinja environment works correctly 114s 114s ================================================== 114s SUMMARY: 114s Test 1 (Before render): PASS 114s Test 2 (After incomplete render): PASS 114s Test 3 (After complete render): PASS 114s Test 4 (Custom Jinja env): PASS 114s ALL TESTS PASSED! 114s 114s Note: This test demonstrates that get_undeclared_template_variables 114s now correctly analyzes the original template, not the rendered document. 117s Executing "python3 -m docxtpl templates/module_execute_tpl.docx templates/module_execute.json output/module_execute.docx -o -q" ... 117s --> File output/module_execute.docx has been generated. 120s ================================================================================ 120s Generating template error for testing (so it is safe to ignore) : 120s ................................................................................ 120s Encountered unknown tag 'endif'. 120s Context: 120s I am the second paragraph. 120s I am the third paragraph. 120s I am the fourth paragraph. 120s I am the fifth paragraph. {% if test_variable %}I am part of it, too.{% endif %} {% endif %} 120s I am the sixth paragraph. 120s I am the seventh paragraph. 120s I am the eighth paragraph. 120s ................................................................................ 120s End of TemplateError Test 120s ================================================================================ 121s cellbg.py ... 121s comments.py ... 121s custom_jinja_filters.py ... 121s doc_properties.py ... 121s dynamic_table.py ... 121s embedded.py ... 121s escape.py ... 121s escape_auto.py ... 121s footnotes.py ... 121s get_undeclared_variables.py ... 121s header_footer.py ... 121s header_footer_entities.py ... 121s header_footer_image.py ... 121s header_footer_image_file_obj.py ... 121s header_footer_inline_image.py ... 121s header_footer_utf8.py ... 121s horizontal_merge.py ... 121s inline_image.py ... 121s less_cells_after_loop.py ... 121s merge_docx.py ... 121s merge_paragraph.py ... 121s module_execute.py ... 121s multi_rendering.py ... 121s nested_for.py ... 121s order.py ... 121s preserve_spaces.py ... 121s replace_picture.py ... 121s richtext.py ... 121s richtext_and_if.py ... 121s richtext_eastAsia.py ... 121s richtextparagraph.py ... 121s subdoc.py ... 121s template_error.py ... 121s vertical_merge.py ... 121s vertical_merge_nested.py ... 121s word2016.py ... 121s Done. 121s autopkgtest [17:42:48]: test command1: -----------------------] 122s autopkgtest [17:42:49]: test command1: - - - - - - - - - - results - - - - - - - - - - 122s command1 PASS 122s autopkgtest [17:42:49]: @@@@@@@@@@@@@@@@@@@@ summary 122s command1 PASS