builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-047 starttime: 1506119347.96 results: success (0) revision: 61e58a7d800b8a84d3da7c3db977830e206428a3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-22 15:29:07.960250) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-22 15:29:07.960703) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-22 15:29:07.960974) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-22 15:29:07.994056) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-22 15:29:07.994444) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=03627c79f6bb3d1578c4a402000001ba-1506119346.703642-147467537 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-22 15:29:08-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 111M=0s 2017-09-22 15:29:08 (111 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.236334 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-22 15:29:08.247923) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-22 15:29:08.248242) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=03627c79f6bb3d1578c4a402000001ba-1506119346.703642-147467537 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015956 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-22 15:29:08.297016) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-22 15:29:08.297338) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 61e58a7d800b8a84d3da7c3db977830e206428a3 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 61e58a7d800b8a84d3da7c3db977830e206428a3 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=03627c79f6bb3d1578c4a402000001ba-1506119346.703642-147467537 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-22 15:29:08,301 truncating revision to first 12 chars 2017-09-22 15:29:08,301 Setting DEBUG logging. 2017-09-22 15:29:08,301 attempt 1/10 2017-09-22 15:29:08,301 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/61e58a7d800b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-22 15:29:08,709 unpacking tar archive at: mozilla-central-61e58a7d800b/testing/mozharness/ program finished with exit code 0 elapsedTime=0.707126 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-22 15:29:09.023533) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-22 15:29:09.023841) ========= script_repo_revision: 61e58a7d800b8a84d3da7c3db977830e206428a3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-22 15:29:09.024213) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-22 15:29:09.024492) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-22 15:29:09.037548) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2017-09-22 15:29:09.037822) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=03627c79f6bb3d1578c4a402000001ba-1506119346.703642-147467537 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:29:09 INFO - MultiFileLogger online at 20170922 15:29:09 in /builds/slave/test 15:29:09 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 15:29:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:29:09 INFO - {'append_to_log': False, 15:29:09 INFO - 'base_work_dir': '/builds/slave/test', 15:29:09 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 15:29:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:29:09 INFO - 'branch': 'Firefox-Non-PGO', 15:29:09 INFO - 'buildbot_json_path': 'buildprops.json', 15:29:09 INFO - 'code_coverage': False, 15:29:09 INFO - 'config_files': ('talos/linux_config.py',), 15:29:09 INFO - 'default_actions': ('clobber', 15:29:09 INFO - 'read-buildbot-config', 15:29:09 INFO - 'download-and-extract', 15:29:09 INFO - 'populate-webroot', 15:29:09 INFO - 'create-virtualenv', 15:29:09 INFO - 'install', 15:29:09 INFO - 'setup-mitmproxy', 15:29:09 INFO - 'run-tests'), 15:29:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:29:09 INFO - 'disable_ccov_upload': False, 15:29:09 INFO - 'disable_stylo': False, 15:29:09 INFO - 'download_minidump_stackwalk': True, 15:29:09 INFO - 'download_symbols': 'ondemand', 15:29:09 INFO - 'enable_stylo': False, 15:29:09 INFO - 'enable_webrender': False, 15:29:09 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 15:29:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:29:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:29:09 INFO - 'gecko_profile': False, 15:29:09 INFO - 'gecko_profile_interval': 0, 15:29:09 INFO - 'installer_path': 'installer.exe', 15:29:09 INFO - 'log_level': 'info', 15:29:09 INFO - 'log_name': 'talos', 15:29:09 INFO - 'log_to_console': True, 15:29:09 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:29:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:29:09 INFO - 'opt_config_files': (), 15:29:09 INFO - 'pip_index': False, 15:29:09 INFO - 'suite': 'dromaeojs-e10s', 15:29:09 INFO - 'system_bits': '32', 15:29:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:29:09 INFO - 'title': 'talos-linux64-ix-047', 15:29:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:29:09 INFO - 'use_talos_json': True, 15:29:09 INFO - 'verify': 'False', 15:29:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:29:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:29:09 INFO - 'work_dir': 'build'} 15:29:09 INFO - [mozharness: 2017-09-22 22:29:09.263254Z] Running clobber step. 15:29:09 INFO - Running pre-action listener: _resource_record_pre_action 15:29:09 INFO - Running main action method: clobber 15:29:09 INFO - rmtree: /builds/slave/test/build 15:29:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:29:10 INFO - Running post-action listener: _resource_record_post_action 15:29:10 INFO - [mozharness: 2017-09-22 22:29:10.207325Z] Finished clobber step (success) 15:29:10 INFO - [mozharness: 2017-09-22 22:29:10.207422Z] Running read-buildbot-config step. 15:29:10 INFO - Running pre-action listener: _resource_record_pre_action 15:29:10 INFO - Running main action method: read_buildbot_config 15:29:10 INFO - Using buildbot properties: 15:29:10 INFO - { 15:29:10 INFO - "project": "", 15:29:10 INFO - "product": "firefox", 15:29:10 INFO - "who": "kwierso@gmail.com", 15:29:10 INFO - "installer_path": "public/build/target.tar.bz2", 15:29:10 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 15:29:10 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 15:29:10 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 15:29:10 INFO - "stage_platform": "linux64", 15:29:10 INFO - "basedir": "/builds/slave/test", 15:29:10 INFO - "buildnumber": 1170, 15:29:10 INFO - "platform": "ubuntu64_hw", 15:29:10 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 15:29:10 INFO - "slavebuilddir": "test", 15:29:10 INFO - "taskId": "FJgl7ODrQn2bo-uuiJm2Rw", 15:29:10 INFO - "branch": "mozilla-central", 15:29:10 INFO - "script_repo_revision": "production", 15:29:10 INFO - "revision": "61e58a7d800b8a84d3da7c3db977830e206428a3", 15:29:10 INFO - "slavename": "talos-linux64-ix-047", 15:29:10 INFO - "repo_path": "mozilla-central" 15:29:10 INFO - } 15:29:10 INFO - Finding installer, test and symbols from parent task. 15:29:10 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FJgl7ODrQn2bo-uuiJm2Rw'}, attempt #1 15:29:10 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FJgl7ODrQn2bo-uuiJm2Rw'}, attempt #1 15:29:11 INFO - Task dependencies: e1Vm0nnPTMG3de0u1_1tzQ 15:29:11 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/e1Vm0nnPTMG3de0u1_1tzQ'}, attempt #1 15:29:11 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/e1Vm0nnPTMG3de0u1_1tzQ/artifacts/public/build/target.tar.bz2 15:29:11 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/e1Vm0nnPTMG3de0u1_1tzQ/artifacts/public/build/target.test_packages.json 15:29:11 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/e1Vm0nnPTMG3de0u1_1tzQ/artifacts/public/build/target.crashreporter-symbols.zip 15:29:11 INFO - Running post-action listener: _resource_record_post_action 15:29:11 INFO - [mozharness: 2017-09-22 22:29:11.592881Z] Finished read-buildbot-config step (success) 15:29:11 INFO - [mozharness: 2017-09-22 22:29:11.593098Z] Running download-and-extract step. 15:29:11 INFO - Running pre-action listener: _resource_record_pre_action 15:29:11 INFO - Running main action method: download_and_extract 15:29:11 INFO - mkdir: /builds/slave/test/build/tests 15:29:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:11 INFO - trying https://queue.taskcluster.net/v1/task/e1Vm0nnPTMG3de0u1_1tzQ/artifacts/public/build/target.test_packages.json 15:29:11 INFO - Downloading https://queue.taskcluster.net/v1/task/e1Vm0nnPTMG3de0u1_1tzQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 15:29:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/e1Vm0nnPTMG3de0u1_1tzQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 15:29:12 INFO - Downloaded 989 bytes. 15:29:12 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 15:29:12 INFO - Using the following test package requirements: 15:29:12 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 15:29:12 INFO - u'common': [u'target.common.tests.zip'], 15:29:12 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 15:29:12 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 15:29:12 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 15:29:12 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 15:29:12 INFO - u'mozbase': [u'target.common.tests.zip'], 15:29:12 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 15:29:12 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 15:29:12 INFO - u'web-platform': [u'target.common.tests.zip', 15:29:12 INFO - u'target.web-platform.tests.tar.gz'], 15:29:12 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 15:29:12 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 15:29:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/e1Vm0nnPTMG3de0u1_1tzQ/artifacts/public/build/target.common.tests.zip 15:29:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/e1Vm0nnPTMG3de0u1_1tzQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:29:12 INFO - Fetch https://queue.taskcluster.net/v1/task/e1Vm0nnPTMG3de0u1_1tzQ/artifacts/public/build/target.common.tests.zip into memory 15:29:13 INFO - Content-Length response header: 54892611 15:29:13 INFO - Bytes received: 54892611 15:29:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/e1Vm0nnPTMG3de0u1_1tzQ/artifacts/public/build/target.talos.tests.zip 15:29:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/e1Vm0nnPTMG3de0u1_1tzQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 15:29:24 INFO - Fetch https://queue.taskcluster.net/v1/task/e1Vm0nnPTMG3de0u1_1tzQ/artifacts/public/build/target.talos.tests.zip into memory 15:29:24 INFO - Content-Length response header: 14513823 15:29:24 INFO - Bytes received: 14513823 15:29:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:25 INFO - trying https://queue.taskcluster.net/v1/task/e1Vm0nnPTMG3de0u1_1tzQ/artifacts/public/build/target.tar.bz2 15:29:25 INFO - Downloading https://queue.taskcluster.net/v1/task/e1Vm0nnPTMG3de0u1_1tzQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 15:29:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/e1Vm0nnPTMG3de0u1_1tzQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 15:29:26 INFO - Downloaded 61644940 bytes. 15:29:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/e1Vm0nnPTMG3de0u1_1tzQ/artifacts/public/build/target.tar.bz2 15:29:26 INFO - mkdir: /builds/slave/test/properties 15:29:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:29:26 INFO - Writing to file /builds/slave/test/properties/build_url 15:29:26 INFO - Contents: 15:29:26 INFO - build_url:https://queue.taskcluster.net/v1/task/e1Vm0nnPTMG3de0u1_1tzQ/artifacts/public/build/target.tar.bz2 15:29:26 INFO - Running post-action listener: _resource_record_post_action 15:29:26 INFO - Running post-action listener: find_tests_for_verification 15:29:26 INFO - Running post-action listener: set_extra_try_arguments 15:29:26 INFO - [mozharness: 2017-09-22 22:29:26.287431Z] Finished download-and-extract step (success) 15:29:26 INFO - [mozharness: 2017-09-22 22:29:26.287530Z] Running populate-webroot step. 15:29:26 INFO - Running pre-action listener: _resource_record_pre_action 15:29:26 INFO - Running main action method: populate_webroot 15:29:26 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:29:26 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:29:26 INFO - 'tests': ['tresize', 15:29:26 INFO - 'tcanvasmark']}, 15:29:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:29:26 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:29:26 INFO - 'tests': ['dromaeo_css', 15:29:26 INFO - 'kraken']}, 15:29:26 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:29:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:29:26 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:29:26 INFO - 'talos_options': ['--disable-stylo'], 15:29:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:29:26 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:29:26 INFO - 'tests': ['damp', 'tps']}, 15:29:26 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:29:26 INFO - 'talos_options': ['--disable-stylo'], 15:29:26 INFO - 'tests': ['damp', 'tps']}, 15:29:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:29:26 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:29:26 INFO - 'tests': ['dromaeo_dom']}, 15:29:26 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 15:29:26 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:29:26 INFO - 'tests': ['basic_compositor_video', 15:29:26 INFO - 'glvideo']}, 15:29:26 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 15:29:26 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 15:29:26 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:29:26 INFO - 'talos_options': ['--disable-stylo'], 15:29:26 INFO - 'tests': ['ts_paint_webext', 15:29:26 INFO - 'tp5o_webext']}, 15:29:26 INFO - 'other-e10s': {'tests': ['a11yr', 15:29:26 INFO - 'ts_paint', 15:29:26 INFO - 'tpaint', 15:29:26 INFO - 'sessionrestore', 15:29:26 INFO - 'sessionrestore_many_windows', 15:29:26 INFO - 'sessionrestore_no_auto_restore', 15:29:26 INFO - 'tabpaint']}, 15:29:26 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:29:26 INFO - 'tests': ['a11yr', 15:29:26 INFO - 'ts_paint', 15:29:26 INFO - 'tpaint', 15:29:26 INFO - 'sessionrestore', 15:29:26 INFO - 'sessionrestore_many_windows', 15:29:26 INFO - 'sessionrestore_no_auto_restore', 15:29:26 INFO - 'tabpaint']}, 15:29:26 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 15:29:26 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 15:29:26 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:29:26 INFO - 'tests': ['perf_reftest_singletons']}, 15:29:26 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:29:26 INFO - 'tests': ['perf_reftest']}, 15:29:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:29:26 INFO - 'tsvgr_opacity', 15:29:26 INFO - 'tart', 15:29:26 INFO - 'tscrollx', 15:29:26 INFO - 'tsvg_static']}, 15:29:26 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:29:26 INFO - 'tests': ['tsvgx', 15:29:26 INFO - 'tsvgr_opacity', 15:29:26 INFO - 'tart', 15:29:26 INFO - 'tscrollx', 15:29:26 INFO - 'tsvg_static']}, 15:29:26 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:29:26 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:29:26 INFO - 'talos_options': ['--disable-stylo'], 15:29:26 INFO - 'tests': ['tp5o']}, 15:29:26 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:29:26 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 15:29:26 INFO - 'talos_options': ['--mitmproxy', 15:29:26 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:29:26 INFO - '--firstNonBlankPaint'], 15:29:26 INFO - 'tests': ['tp6_google', 15:29:26 INFO - 'tp6_youtube', 15:29:26 INFO - 'tp6_amazon', 15:29:26 INFO - 'tp6_facebook']}, 15:29:26 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:29:26 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 15:29:26 INFO - 'talos_options': ['--disable-stylo', 15:29:26 INFO - '--mitmproxy', 15:29:26 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:29:26 INFO - '--firstNonBlankPaint'], 15:29:26 INFO - 'tests': ['tp6_google', 15:29:26 INFO - 'tp6_youtube', 15:29:26 INFO - 'tp6_amazon', 15:29:26 INFO - 'tp6_facebook']}, 15:29:26 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:29:26 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 15:29:26 INFO - 'talos_options': ['--stylo-threads=1', 15:29:26 INFO - '--mitmproxy', 15:29:26 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:29:26 INFO - '--firstNonBlankPaint'], 15:29:26 INFO - 'tests': ['tp6_google', 15:29:26 INFO - 'tp6_youtube', 15:29:26 INFO - 'tp6_amazon', 15:29:26 INFO - 'tp6_facebook']}, 15:29:26 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:29:26 INFO - 'talos_options': ['--xperf_path', 15:29:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:29:26 INFO - 'tests': ['tp5n']}, 15:29:26 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:29:26 INFO - 'talos_options': ['--disable-stylo', 15:29:26 INFO - '--xperf_path', 15:29:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:29:26 INFO - 'tests': ['tp5n']}}} 15:29:26 INFO - Running post-action listener: _resource_record_post_action 15:29:26 INFO - [mozharness: 2017-09-22 22:29:26.302959Z] Finished populate-webroot step (success) 15:29:26 INFO - [mozharness: 2017-09-22 22:29:26.303048Z] Running create-virtualenv step. 15:29:26 INFO - Running pre-action listener: _resource_record_pre_action 15:29:26 INFO - Running main action method: create_virtualenv 15:29:26 INFO - Creating virtualenv /builds/slave/test/build/venv 15:29:26 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:29:26 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 15:29:26 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 15:29:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:29:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:29:27 INFO - Installing distribute.............................................................................................................................................................................................done. 15:29:27 INFO - Installing pip...............done. 15:29:27 INFO - Return code: 0 15:29:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:29:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:29:27 INFO - Reading from file tmpfile_stdout 15:29:27 INFO - Output received: 15:29:27 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 15:29:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:29:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:29:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:29:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28fa1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ac2b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c679c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c64d60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c65a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c65ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1506119346.703642-147467537', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:29:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:29:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:29:27 INFO - Using env: {'DISPLAY': ':0', 15:29:27 INFO - 'HOME': '/home/cltbld', 15:29:27 INFO - 'LANG': 'en_US.UTF-8', 15:29:27 INFO - 'LANGUAGE': 'en_US:en', 15:29:27 INFO - 'LOGNAME': 'cltbld', 15:29:27 INFO - 'MAIL': '/var/mail/cltbld', 15:29:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:27 INFO - 'MOZ_NO_REMOTE': '1', 15:29:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:29:27 INFO - 'NO_EM_RESTART': '1', 15:29:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:29:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:27 INFO - 'PWD': '/builds/slave/test', 15:29:27 INFO - 'SHELL': '/bin/bash', 15:29:27 INFO - 'SHLVL': '1', 15:29:27 INFO - 'TERM': 'linux', 15:29:27 INFO - 'TMOUT': '86400', 15:29:27 INFO - 'USER': 'cltbld', 15:29:27 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1506119346.703642-147467537', 15:29:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:27 INFO - '_': '/tools/buildbot/bin/python'} 15:29:28 INFO - Ignoring indexes: http://pypi.python.org/simple/ 15:29:28 INFO - Downloading/unpacking pip>=1.5 15:29:28 INFO - Running setup.py egg_info for package pip 15:29:28 INFO - warning: no files found matching 'pip/cacert.pem' 15:29:28 INFO - warning: no files found matching '*.html' under directory 'docs' 15:29:28 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:29:28 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:29:28 INFO - Installing collected packages: pip 15:29:28 INFO - Found existing installation: pip 1.1 15:29:28 INFO - Uninstalling pip: 15:29:28 INFO - Successfully uninstalled pip 15:29:28 INFO - Running setup.py install for pip 15:29:28 INFO - warning: no files found matching 'pip/cacert.pem' 15:29:28 INFO - warning: no files found matching '*.html' under directory 'docs' 15:29:28 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:29:28 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:29:28 INFO - Installing pip script to /builds/slave/test/build/venv/bin 15:29:28 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 15:29:28 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 15:29:28 INFO - Successfully installed pip 15:29:28 INFO - Cleaning up... 15:29:28 INFO - Return code: 0 15:29:28 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:29:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:29:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:29:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28fa1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ac2b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c679c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c64d60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c65a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c65ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1506119346.703642-147467537', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:29:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:29:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:29:28 INFO - Using env: {'DISPLAY': ':0', 15:29:28 INFO - 'HOME': '/home/cltbld', 15:29:28 INFO - 'LANG': 'en_US.UTF-8', 15:29:28 INFO - 'LANGUAGE': 'en_US:en', 15:29:28 INFO - 'LOGNAME': 'cltbld', 15:29:28 INFO - 'MAIL': '/var/mail/cltbld', 15:29:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:28 INFO - 'MOZ_NO_REMOTE': '1', 15:29:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:29:28 INFO - 'NO_EM_RESTART': '1', 15:29:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:29:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:28 INFO - 'PWD': '/builds/slave/test', 15:29:28 INFO - 'SHELL': '/bin/bash', 15:29:28 INFO - 'SHLVL': '1', 15:29:28 INFO - 'TERM': 'linux', 15:29:28 INFO - 'TMOUT': '86400', 15:29:28 INFO - 'USER': 'cltbld', 15:29:28 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1506119346.703642-147467537', 15:29:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:28 INFO - '_': '/tools/buildbot/bin/python'} 15:29:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:29:29 INFO - Downloading/unpacking psutil>=3.1.1 15:29:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:29:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:29:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:29:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:29:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:29:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:29:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:29:30 INFO - Installing collected packages: psutil 15:29:30 INFO - Running setup.py install for psutil 15:29:30 INFO - building 'psutil._psutil_linux' extension 15:29:30 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 15:29:31 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 15:29:31 INFO - building 'psutil._psutil_posix' extension 15:29:31 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 15:29:31 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 15:29:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:29:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:29:31 INFO - Successfully installed psutil 15:29:31 INFO - Cleaning up... 15:29:31 INFO - Return code: 0 15:29:31 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:29:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:29:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:29:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28fa1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ac2b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c679c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c64d60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c65a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c65ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1506119346.703642-147467537', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:29:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:29:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:29:31 INFO - Using env: {'DISPLAY': ':0', 15:29:31 INFO - 'HOME': '/home/cltbld', 15:29:31 INFO - 'LANG': 'en_US.UTF-8', 15:29:31 INFO - 'LANGUAGE': 'en_US:en', 15:29:31 INFO - 'LOGNAME': 'cltbld', 15:29:31 INFO - 'MAIL': '/var/mail/cltbld', 15:29:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:31 INFO - 'MOZ_NO_REMOTE': '1', 15:29:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:29:31 INFO - 'NO_EM_RESTART': '1', 15:29:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:29:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:31 INFO - 'PWD': '/builds/slave/test', 15:29:31 INFO - 'SHELL': '/bin/bash', 15:29:31 INFO - 'SHLVL': '1', 15:29:31 INFO - 'TERM': 'linux', 15:29:31 INFO - 'TMOUT': '86400', 15:29:31 INFO - 'USER': 'cltbld', 15:29:31 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1506119346.703642-147467537', 15:29:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:31 INFO - '_': '/tools/buildbot/bin/python'} 15:29:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:29:31 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:29:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:29:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:29:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:29:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:29:33 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:29:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:29:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:29:33 INFO - Installing collected packages: mozsystemmonitor 15:29:33 INFO - Running setup.py install for mozsystemmonitor 15:29:33 INFO - Successfully installed mozsystemmonitor 15:29:33 INFO - Cleaning up... 15:29:33 INFO - Return code: 0 15:29:33 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:29:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:29:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:29:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28fa1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ac2b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c679c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c64d60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c65a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c65ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1506119346.703642-147467537', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:29:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:29:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:29:33 INFO - Using env: {'DISPLAY': ':0', 15:29:33 INFO - 'HOME': '/home/cltbld', 15:29:33 INFO - 'LANG': 'en_US.UTF-8', 15:29:33 INFO - 'LANGUAGE': 'en_US:en', 15:29:33 INFO - 'LOGNAME': 'cltbld', 15:29:33 INFO - 'MAIL': '/var/mail/cltbld', 15:29:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:33 INFO - 'MOZ_NO_REMOTE': '1', 15:29:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:29:33 INFO - 'NO_EM_RESTART': '1', 15:29:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:29:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:33 INFO - 'PWD': '/builds/slave/test', 15:29:33 INFO - 'SHELL': '/bin/bash', 15:29:33 INFO - 'SHLVL': '1', 15:29:33 INFO - 'TERM': 'linux', 15:29:33 INFO - 'TMOUT': '86400', 15:29:33 INFO - 'USER': 'cltbld', 15:29:33 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1506119346.703642-147467537', 15:29:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:33 INFO - '_': '/tools/buildbot/bin/python'} 15:29:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:29:33 INFO - Downloading/unpacking jsonschema==2.5.1 15:29:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:29:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:29:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:29:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:29:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:29:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:29:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:29:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:29:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:29:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:29:34 INFO - Downloading functools32-3.2.3-2.tar.gz 15:29:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:29:35 INFO - warning: no files found matching '*.txt' 15:29:35 INFO - no previously-included directories found matching 'build' 15:29:35 INFO - no previously-included directories found matching 'dist' 15:29:35 INFO - no previously-included directories found matching '.git*' 15:29:35 INFO - Installing collected packages: jsonschema, functools32 15:29:35 INFO - Running setup.py install for functools32 15:29:35 INFO - warning: no files found matching '*.txt' 15:29:35 INFO - no previously-included directories found matching 'build' 15:29:35 INFO - no previously-included directories found matching 'dist' 15:29:35 INFO - no previously-included directories found matching '.git*' 15:29:35 INFO - Successfully installed jsonschema functools32 15:29:35 INFO - Cleaning up... 15:29:35 INFO - Return code: 0 15:29:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:29:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:29:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:29:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28fa1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ac2b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c679c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c64d60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c65a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c65ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1506119346.703642-147467537', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:29:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 15:29:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 15:29:35 INFO - Using env: {'DISPLAY': ':0', 15:29:35 INFO - 'HOME': '/home/cltbld', 15:29:35 INFO - 'LANG': 'en_US.UTF-8', 15:29:35 INFO - 'LANGUAGE': 'en_US:en', 15:29:35 INFO - 'LOGNAME': 'cltbld', 15:29:35 INFO - 'MAIL': '/var/mail/cltbld', 15:29:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:35 INFO - 'MOZ_NO_REMOTE': '1', 15:29:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:29:35 INFO - 'NO_EM_RESTART': '1', 15:29:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:29:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:35 INFO - 'PWD': '/builds/slave/test', 15:29:35 INFO - 'SHELL': '/bin/bash', 15:29:35 INFO - 'SHLVL': '1', 15:29:35 INFO - 'TERM': 'linux', 15:29:35 INFO - 'TMOUT': '86400', 15:29:35 INFO - 'USER': 'cltbld', 15:29:35 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1506119346.703642-147467537', 15:29:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:35 INFO - '_': '/tools/buildbot/bin/python'} 15:29:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:29:36 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:29:36 INFO - Cleaning up... 15:29:36 INFO - Return code: 0 15:29:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:29:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:29:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:29:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28fa1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ac2b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c679c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c64d60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c65a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c65ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1506119346.703642-147467537', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:29:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:29:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:29:36 INFO - Using env: {'DISPLAY': ':0', 15:29:36 INFO - 'HOME': '/home/cltbld', 15:29:36 INFO - 'LANG': 'en_US.UTF-8', 15:29:36 INFO - 'LANGUAGE': 'en_US:en', 15:29:36 INFO - 'LOGNAME': 'cltbld', 15:29:36 INFO - 'MAIL': '/var/mail/cltbld', 15:29:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:36 INFO - 'MOZ_NO_REMOTE': '1', 15:29:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:29:36 INFO - 'NO_EM_RESTART': '1', 15:29:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:29:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:36 INFO - 'PWD': '/builds/slave/test', 15:29:36 INFO - 'SHELL': '/bin/bash', 15:29:36 INFO - 'SHLVL': '1', 15:29:36 INFO - 'TERM': 'linux', 15:29:36 INFO - 'TMOUT': '86400', 15:29:36 INFO - 'USER': 'cltbld', 15:29:36 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1506119346.703642-147467537', 15:29:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:36 INFO - '_': '/tools/buildbot/bin/python'} 15:29:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:29:36 INFO - Downloading/unpacking blobuploader==1.2.4 15:29:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:29:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:29:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:29:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:29:37 INFO - Downloading blobuploader-1.2.4.tar.gz 15:29:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:29:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:29:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:29:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:29:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:29:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:29:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:29:37 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:29:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:29:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:29:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:29:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:29:37 INFO - Downloading docopt-0.6.1.tar.gz 15:29:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:29:38 INFO - Installing collected packages: blobuploader, requests, docopt 15:29:38 INFO - Running setup.py install for blobuploader 15:29:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:29:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:29:38 INFO - Running setup.py install for requests 15:29:38 INFO - Running setup.py install for docopt 15:29:38 INFO - Successfully installed blobuploader requests docopt 15:29:38 INFO - Cleaning up... 15:29:38 INFO - Return code: 0 15:29:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:29:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:29:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:29:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28fa1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ac2b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c679c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c64d60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c65a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c65ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1506119346.703642-147467537', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:29:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:29:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:29:38 INFO - Using env: {'DISPLAY': ':0', 15:29:38 INFO - 'HOME': '/home/cltbld', 15:29:38 INFO - 'LANG': 'en_US.UTF-8', 15:29:38 INFO - 'LANGUAGE': 'en_US:en', 15:29:38 INFO - 'LOGNAME': 'cltbld', 15:29:38 INFO - 'MAIL': '/var/mail/cltbld', 15:29:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:38 INFO - 'MOZ_NO_REMOTE': '1', 15:29:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:29:38 INFO - 'NO_EM_RESTART': '1', 15:29:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:29:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:38 INFO - 'PWD': '/builds/slave/test', 15:29:38 INFO - 'SHELL': '/bin/bash', 15:29:38 INFO - 'SHLVL': '1', 15:29:38 INFO - 'TERM': 'linux', 15:29:38 INFO - 'TMOUT': '86400', 15:29:38 INFO - 'USER': 'cltbld', 15:29:38 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1506119346.703642-147467537', 15:29:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:38 INFO - '_': '/tools/buildbot/bin/python'} 15:29:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:29:38 INFO - Running setup.py (path:/tmp/pip-CDKqaj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:29:38 INFO - Running setup.py (path:/tmp/pip-fawNG7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:29:38 INFO - Running setup.py (path:/tmp/pip-dgijhl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:29:39 INFO - Running setup.py (path:/tmp/pip-u0NYfZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:29:39 INFO - Running setup.py (path:/tmp/pip-Yi1nxZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:29:39 INFO - Running setup.py (path:/tmp/pip-szKetR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:29:39 INFO - Running setup.py (path:/tmp/pip-cTQxqO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:29:39 INFO - Running setup.py (path:/tmp/pip-3AQxQu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:29:39 INFO - Running setup.py (path:/tmp/pip-HoeMOy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:29:39 INFO - Running setup.py (path:/tmp/pip-56wP_C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:29:39 INFO - Running setup.py (path:/tmp/pip-dfPBfR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:29:39 INFO - Running setup.py (path:/tmp/pip-3NJtiV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:29:39 INFO - Running setup.py (path:/tmp/pip-gWflU1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:29:39 INFO - Running setup.py (path:/tmp/pip-_2dklc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:29:39 INFO - Running setup.py (path:/tmp/pip-CJQ2sT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:29:39 INFO - Running setup.py (path:/tmp/pip-nktOPY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:29:39 INFO - Running setup.py (path:/tmp/pip-XOM5zP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:29:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:29:40 INFO - Running setup.py install for manifestparser 15:29:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:29:40 INFO - Running setup.py install for mozcrash 15:29:40 INFO - Running setup.py install for mozdebug 15:29:40 INFO - Running setup.py install for mozdevice 15:29:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:29:40 INFO - Running setup.py install for mozfile 15:29:40 INFO - Running setup.py install for mozhttpd 15:29:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:29:40 INFO - Running setup.py install for mozinfo 15:29:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:29:40 INFO - Running setup.py install for mozInstall 15:29:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:29:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:29:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:29:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:29:40 INFO - Running setup.py install for mozleak 15:29:40 INFO - Running setup.py install for mozlog 15:29:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:29:41 INFO - Running setup.py install for moznetwork 15:29:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:29:41 INFO - Running setup.py install for mozprocess 15:29:41 INFO - Running setup.py install for mozprofile 15:29:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:29:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:29:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:29:41 INFO - Running setup.py install for mozrunner 15:29:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:29:41 INFO - Running setup.py install for mozscreenshot 15:29:41 INFO - Running setup.py install for moztest 15:29:41 INFO - Running setup.py install for mozversion 15:29:41 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:29:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:29:41 INFO - Cleaning up... 15:29:41 INFO - Return code: 0 15:29:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:29:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:29:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:29:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28fa1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ac2b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c679c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c64d60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c65a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c65ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1506119346.703642-147467537', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:29:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:29:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:29:41 INFO - Using env: {'DISPLAY': ':0', 15:29:41 INFO - 'HOME': '/home/cltbld', 15:29:41 INFO - 'LANG': 'en_US.UTF-8', 15:29:41 INFO - 'LANGUAGE': 'en_US:en', 15:29:41 INFO - 'LOGNAME': 'cltbld', 15:29:41 INFO - 'MAIL': '/var/mail/cltbld', 15:29:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:41 INFO - 'MOZ_NO_REMOTE': '1', 15:29:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:29:41 INFO - 'NO_EM_RESTART': '1', 15:29:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:29:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:41 INFO - 'PWD': '/builds/slave/test', 15:29:41 INFO - 'SHELL': '/bin/bash', 15:29:41 INFO - 'SHLVL': '1', 15:29:41 INFO - 'TERM': 'linux', 15:29:41 INFO - 'TMOUT': '86400', 15:29:41 INFO - 'USER': 'cltbld', 15:29:41 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1506119346.703642-147467537', 15:29:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:41 INFO - '_': '/tools/buildbot/bin/python'} 15:29:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:29:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:29:41 INFO - Running setup.py (path:/tmp/pip-_yynWf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:29:42 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 15:29:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:29:42 INFO - Running setup.py (path:/tmp/pip-yP_DoK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:29:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:29:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:29:42 INFO - Running setup.py (path:/tmp/pip-_VnXBU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:29:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:29:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:29:42 INFO - Running setup.py (path:/tmp/pip-kiodi9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:29:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:29:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:29:42 INFO - Running setup.py (path:/tmp/pip-PJEB0T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:29:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 15:29:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:29:42 INFO - Running setup.py (path:/tmp/pip-c_N3FL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:29:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 15:29:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:29:42 INFO - Running setup.py (path:/tmp/pip-_C4fCB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:29:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 15:29:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:29:42 INFO - Running setup.py (path:/tmp/pip-RNbHR4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:29:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:29:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:29:42 INFO - Running setup.py (path:/tmp/pip-C5xvXH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:29:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 15:29:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:29:42 INFO - Running setup.py (path:/tmp/pip-9jc7R8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:29:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:29:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:29:42 INFO - Running setup.py (path:/tmp/pip-73fPzK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:29:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 15:29:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:29:42 INFO - Running setup.py (path:/tmp/pip-RDgpA2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:29:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 15:29:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:29:42 INFO - Running setup.py (path:/tmp/pip-lJnpyw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:29:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 15:29:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:29:42 INFO - Running setup.py (path:/tmp/pip-1r6BUI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:29:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 15:29:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:29:42 INFO - Running setup.py (path:/tmp/pip-S7yhf_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:29:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 15:29:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:29:42 INFO - Running setup.py (path:/tmp/pip-4yKdOq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:29:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 15:29:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:29:42 INFO - Running setup.py (path:/tmp/pip-x4juEF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:29:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 15:29:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:29:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:29:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:29:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:29:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:29:43 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:29:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:29:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:29:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:29:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:29:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:29:44 INFO - Downloading blessings-1.6.tar.gz 15:29:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:29:44 INFO - Installing collected packages: blessings 15:29:44 INFO - Running setup.py install for blessings 15:29:44 INFO - Successfully installed blessings 15:29:44 INFO - Cleaning up... 15:29:44 INFO - Return code: 0 15:29:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:29:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:29:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:29:44 INFO - Reading from file tmpfile_stdout 15:29:44 INFO - Current package versions: 15:29:44 INFO - argparse == 1.2.1 15:29:44 INFO - blessings == 1.6 15:29:44 INFO - blobuploader == 1.2.4 15:29:44 INFO - docopt == 0.6.1 15:29:44 INFO - functools32 == 3.2.3-2 15:29:44 INFO - jsonschema == 2.5.1 15:29:44 INFO - manifestparser == 1.1 15:29:44 INFO - mozInstall == 1.13 15:29:44 INFO - mozcrash == 1.0 15:29:44 INFO - mozdebug == 0.1 15:29:44 INFO - mozdevice == 0.51 15:29:44 INFO - mozfile == 1.2 15:29:44 INFO - mozhttpd == 0.7 15:29:44 INFO - mozinfo == 0.10 15:29:44 INFO - mozleak == 0.1 15:29:44 INFO - mozlog == 3.5 15:29:44 INFO - moznetwork == 0.27 15:29:44 INFO - mozprocess == 0.25 15:29:44 INFO - mozprofile == 0.28 15:29:44 INFO - mozrunner == 6.13 15:29:44 INFO - mozscreenshot == 0.1 15:29:44 INFO - mozsystemmonitor == 0.3 15:29:44 INFO - moztest == 0.8 15:29:44 INFO - mozversion == 1.4 15:29:44 INFO - psutil == 3.1.1 15:29:44 INFO - requests == 1.2.3 15:29:44 INFO - wsgiref == 0.1.2 15:29:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:29:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:29:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:29:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28fa1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ac2b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c679c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c64d60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c65a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c65ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1506119346.703642-147467537', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:29:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 15:29:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:29:44 INFO - Using env: {'DISPLAY': ':0', 15:29:44 INFO - 'HOME': '/home/cltbld', 15:29:44 INFO - 'LANG': 'en_US.UTF-8', 15:29:44 INFO - 'LANGUAGE': 'en_US:en', 15:29:44 INFO - 'LOGNAME': 'cltbld', 15:29:44 INFO - 'MAIL': '/var/mail/cltbld', 15:29:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:44 INFO - 'MOZ_NO_REMOTE': '1', 15:29:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:29:44 INFO - 'NO_EM_RESTART': '1', 15:29:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:29:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:44 INFO - 'PWD': '/builds/slave/test', 15:29:44 INFO - 'SHELL': '/bin/bash', 15:29:44 INFO - 'SHLVL': '1', 15:29:44 INFO - 'TERM': 'linux', 15:29:44 INFO - 'TMOUT': '86400', 15:29:44 INFO - 'USER': 'cltbld', 15:29:44 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1506119346.703642-147467537', 15:29:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:44 INFO - '_': '/tools/buildbot/bin/python'} 15:29:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:29:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:29:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 15:29:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 15:29:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:29:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 15:29:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 15:29:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 15:29:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 15:29:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 15:29:44 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 15:29:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:29:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:29:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:29:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:29:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 15:29:46 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:29:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:29:46 INFO - Installing collected packages: simplejson 15:29:46 INFO - Running setup.py install for simplejson 15:29:46 INFO - building 'simplejson._speedups' extension 15:29:46 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 15:29:46 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 15:29:47 INFO - Successfully installed simplejson 15:29:47 INFO - Cleaning up... 15:29:47 INFO - Return code: 0 15:29:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:29:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:29:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:29:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28fa1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ac2b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c679c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c64d60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c65a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c65ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1506119346.703642-147467537', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:29:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:29:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:29:47 INFO - Using env: {'DISPLAY': ':0', 15:29:47 INFO - 'HOME': '/home/cltbld', 15:29:47 INFO - 'LANG': 'en_US.UTF-8', 15:29:47 INFO - 'LANGUAGE': 'en_US:en', 15:29:47 INFO - 'LOGNAME': 'cltbld', 15:29:47 INFO - 'MAIL': '/var/mail/cltbld', 15:29:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:47 INFO - 'MOZ_NO_REMOTE': '1', 15:29:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:29:47 INFO - 'NO_EM_RESTART': '1', 15:29:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:29:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:47 INFO - 'PWD': '/builds/slave/test', 15:29:47 INFO - 'SHELL': '/bin/bash', 15:29:47 INFO - 'SHLVL': '1', 15:29:47 INFO - 'TERM': 'linux', 15:29:47 INFO - 'TMOUT': '86400', 15:29:47 INFO - 'USER': 'cltbld', 15:29:47 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1506119346.703642-147467537', 15:29:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:47 INFO - '_': '/tools/buildbot/bin/python'} 15:29:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:29:47 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:29:47 INFO - Cleaning up... 15:29:47 INFO - Return code: 0 15:29:47 INFO - Running post-action listener: _resource_record_post_action 15:29:47 INFO - Running post-action listener: _start_resource_monitoring 15:29:47 INFO - Starting resource monitoring. 15:29:47 INFO - [mozharness: 2017-09-22 22:29:47.418956Z] Finished create-virtualenv step (success) 15:29:47 INFO - [mozharness: 2017-09-22 22:29:47.419975Z] Running install step. 15:29:47 INFO - Running pre-action listener: _resource_record_pre_action 15:29:47 INFO - Running main action method: install 15:29:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:29:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:29:47 INFO - Reading from file tmpfile_stdout 15:29:47 INFO - Detecting whether we're running mozinstall >=1.0... 15:29:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:29:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:29:47 INFO - Reading from file tmpfile_stdout 15:29:47 INFO - Output received: 15:29:47 INFO - Usage: mozinstall [options] installer 15:29:47 INFO - Options: 15:29:47 INFO - -h, --help show this help message and exit 15:29:47 INFO - -d DEST, --destination=DEST 15:29:47 INFO - Directory to install application into. [default: 15:29:47 INFO - "/builds/slave/test"] 15:29:47 INFO - --app=APP Application being installed. [default: firefox] 15:29:47 INFO - mkdir: /builds/slave/test/build/application 15:29:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 15:29:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 15:30:05 INFO - Reading from file tmpfile_stdout 15:30:05 INFO - Output received: 15:30:05 INFO - /builds/slave/test/build/application/firefox/firefox 15:30:05 INFO - Running post-action listener: _resource_record_post_action 15:30:05 INFO - [mozharness: 2017-09-22 22:30:05.569308Z] Finished install step (success) 15:30:05 INFO - [mozharness: 2017-09-22 22:30:05.569561Z] Running setup-mitmproxy step. 15:30:05 INFO - Running pre-action listener: _resource_record_pre_action 15:30:05 INFO - Running main action method: setup_mitmproxy 15:30:05 INFO - Skipping: mitmproxy is not required 15:30:05 INFO - Running post-action listener: _resource_record_post_action 15:30:05 INFO - [mozharness: 2017-09-22 22:30:05.570685Z] Finished setup-mitmproxy step (success) 15:30:05 INFO - [mozharness: 2017-09-22 22:30:05.570905Z] Running run-tests step. 15:30:05 INFO - Running pre-action listener: _resource_record_pre_action 15:30:05 INFO - Running pre-action listener: _set_gcov_prefix 15:30:05 INFO - Running main action method: run_tests 15:30:05 WARNING - Try message not found. 15:30:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:30:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:30:05 INFO - Python 2.7.3 15:30:05 INFO - Return code: 0 15:30:05 INFO - grabbing minidump binary from tooltool 15:30:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:30:05 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c64d60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c65a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c65ef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:30:05 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 15:30:05 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 15:30:05 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 15:30:05 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:30:05 INFO - Return code: 0 15:30:05 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:30:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:30:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:30:05 INFO - ENV: RUST_BACKTRACE is now full 15:30:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:30:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:30:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/e1Vm0nnPTMG3de0u1_1tzQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-047', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 15:30:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/e1Vm0nnPTMG3de0u1_1tzQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-047 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 15:30:05 INFO - Using env: {'DISPLAY': ':0', 15:30:05 INFO - 'HOME': '/home/cltbld', 15:30:05 INFO - 'LANG': 'en_US.UTF-8', 15:30:05 INFO - 'LANGUAGE': 'en_US:en', 15:30:05 INFO - 'LOGNAME': 'cltbld', 15:30:05 INFO - 'MAIL': '/var/mail/cltbld', 15:30:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:30:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:30:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:30:05 INFO - 'MOZ_NO_REMOTE': '1', 15:30:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:30:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:30:05 INFO - 'NO_EM_RESTART': '1', 15:30:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:30:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:30:05 INFO - 'PWD': '/builds/slave/test', 15:30:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:30:05 INFO - 'RUST_BACKTRACE': 'full', 15:30:05 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:30:05 INFO - 'SHELL': '/bin/bash', 15:30:05 INFO - 'SHLVL': '1', 15:30:05 INFO - 'TERM': 'linux', 15:30:05 INFO - 'TMOUT': '86400', 15:30:05 INFO - 'USER': 'cltbld', 15:30:05 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1506119346.703642-147467537', 15:30:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:30:05 INFO - '_': '/tools/buildbot/bin/python'} 15:30:05 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/e1Vm0nnPTMG3de0u1_1tzQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-047', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 15:30:06 INFO - mozversion application_buildid: 20170922212946 15:30:06 INFO - mozversion application_changeset: 61e58a7d800b8a84d3da7c3db977830e206428a3 15:30:06 INFO - mozversion application_display_name: Nightly 15:30:06 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:30:06 INFO - mozversion application_name: Firefox 15:30:06 INFO - mozversion application_remotingname: firefox 15:30:06 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:30:06 INFO - mozversion application_vendor: Mozilla 15:30:06 INFO - mozversion application_version: 58.0a1 15:30:06 INFO - mozversion platform_buildid: 20170922212946 15:30:06 INFO - mozversion platform_changeset: 61e58a7d800b8a84d3da7c3db977830e206428a3 15:30:06 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:30:06 INFO - mozversion platform_version: 58.0a1 15:30:06 INFO - using testdate: 1506119406 15:30:06 INFO - actual date: 1506119406 15:30:06 INFO - starting webserver on 'localhost:48919' 15:30:06 INFO - SUITE-START | Running 2 tests 15:30:06 INFO - TEST-START | dromaeo_css 15:30:06 INFO - Initialising browser for dromaeo_css test... 15:30:06 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:48919/getInfo.html -profile /tmp/tmpZkHijA/profile 15:30:06 INFO - TEST-INFO | started process 3584 (/builds/slave/test/build/application/firefox/firefox http://localhost:48919/getInfo.html) 15:30:14 INFO - TEST-INFO | 3584: exit 0 15:30:14 INFO - Browser initialized. 15:30:14 INFO - Running cycle 1/1 for dromaeo_css test... 15:30:14 INFO - Using env: {'DISPLAY': ':0', 15:30:14 INFO - 'HOME': '/home/cltbld', 15:30:14 INFO - 'JSGC_DISABLE_POISONING': '1', 15:30:14 INFO - 'LANG': 'en_US.UTF-8', 15:30:14 INFO - 'LANGUAGE': 'en_US:en', 15:30:14 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 15:30:14 INFO - 'LOGNAME': 'cltbld', 15:30:14 INFO - 'MAIL': '/var/mail/cltbld', 15:30:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:30:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:30:14 INFO - 'MOZ_CRASHREPORTER': '1', 15:30:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:30:14 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 15:30:14 INFO - 'MOZ_NO_REMOTE': '1', 15:30:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:30:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:30:14 INFO - 'NO_EM_RESTART': '1', 15:30:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:30:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:30:14 INFO - 'PWD': '/builds/slave/test', 15:30:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:30:14 INFO - 'RUST_BACKTRACE': 'full', 15:30:14 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:30:14 INFO - 'SHELL': '/bin/bash', 15:30:14 INFO - 'SHLVL': '1', 15:30:14 INFO - 'TERM': 'linux', 15:30:14 INFO - 'TMOUT': '86400', 15:30:14 INFO - 'USER': 'cltbld', 15:30:14 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1506119346.703642-147467537', 15:30:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:30:14 INFO - '_': '/tools/buildbot/bin/python'} 15:30:14 INFO - TEST-INFO | started process 3825 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZkHijA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 15:30:14 INFO - PID 3825 | 15:30:14 INFO - PID 3825 | (/builds/slave/test/build/application/firefox/firefox:3874): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 15:30:14 INFO - PID 3825 | 15:30:25 INFO - PID 3825 | RSS: Main: 147865600 15:30:25 INFO - PID 3825 | 15:33:21 INFO - PID 3825 | Cycle 1(1): loaded http://localhost:48919/tests/dromaeo/cssquery-dojo.html (next: http://localhost:48919/tests/dromaeo/cssquery-ext.html) 15:33:22 INFO - PID 3825 | RSS: Main: 152592384 15:33:22 INFO - PID 3825 | 15:36:24 INFO - PID 3825 | Cycle 1(1): loaded http://localhost:48919/tests/dromaeo/cssquery-ext.html (next: http://localhost:48919/tests/dromaeo/cssquery-jquery.html) 15:36:25 INFO - PID 3825 | RSS: Main: 151674880 15:36:25 INFO - PID 3825 | 15:39:26 INFO - PID 3825 | Cycle 1(1): loaded http://localhost:48919/tests/dromaeo/cssquery-jquery.html (next: http://localhost:48919/tests/dromaeo/cssquery-mootools.html) 15:39:27 INFO - PID 3825 | RSS: Main: 154198016 15:39:27 INFO - PID 3825 | 15:42:29 INFO - PID 3825 | Cycle 1(1): loaded http://localhost:48919/tests/dromaeo/cssquery-mootools.html (next: http://localhost:48919/tests/dromaeo/cssquery-prototype.html) 15:42:29 INFO - PID 3825 | RSS: Main: 152928256 15:42:29 INFO - PID 3825 | 15:45:33 INFO - PID 3825 | Cycle 1(1): loaded http://localhost:48919/tests/dromaeo/cssquery-prototype.html (next: http://localhost:48919/tests/dromaeo/cssquery-yui.html) 15:45:33 INFO - PID 3825 | RSS: Main: 153710592 15:45:33 INFO - PID 3825 | 15:48:35 INFO - PID 3825 | Cycle 1(1): loaded http://localhost:48919/tests/dromaeo/cssquery-yui.html (next: http://localhost:48919/tests/dromaeo/cssquery-dojo.html) 15:48:35 INFO - PID 3825 | RSS: Main: 154062848 15:48:35 INFO - PID 3825 | 15:48:35 INFO - PID 3825 | __start_tp_report 15:48:35 INFO - PID 3825 | _x_x_mozilla_page_load 15:48:35 INFO - PID 3825 | _x_x_mozilla_page_load_details 15:48:35 INFO - PID 3825 | |i|pagename|runs| 15:48:35 INFO - PID 3825 | |0;dojo.html;3106.0371284901667;3118.8128712277266;3127.9218019549517;3154.5110507871286;3157.8263195524237;6136.785212517563;6299.1496148020005;6302.0861975013595;6327.462165715914;6445.097686323913;6239.719212635431;6275.435210831024;6278.686065696708;6281.811545653626;6321.1150438938585;5802.564807639423;5904.911426328608;5905.261842269716;5912.408759124096;5945.37573554777;35.68210542412979;5788.929048126089;5795.9130613040625;5881.294244690643;5939.079442686369;5400.540954018913;5724.39893811148;5728.312602487707;5864.941350586503;5883.14694369314;5786.334571524287;5797.913031304492;5865.794697185578;5872.794452194195;5877.823665290048;5749.252597162342;5766.538676905838;5828.737706803204;5849.941500585025;5866.120081987693;6202.72087756052;6208.068789681539;6261.812145635638;6322.071768110724;6394.552381333308;6616.636085015322;6723.025161351626;6746;6836.248012718597;6838.076859623987;6799.218089919673;6889.069975553286;6891;6906.551074180162;6920.377166055079;6144.662043587601;6248.781292654735;6274.56078074535;6294.559380843343;6358.046293056079;7068.858622827566;7069.540479868792;7083.0437890885105;7084.752033678797;7087.539309944837;6647.900281495781;6651.66741662915;6668.599884006975;6747.6626168692355;6759.121314229119;6616.7022483987375;6731.057651928734;6731.865362692719;6747.257801641816;6851.828704282433;6589.670516474157;6622.106015687917;6656.201255849329;6669.499787515956;6697.6986035628515;6611.239707433707;6638.933610663928;6639.900401493981;6646;6689.96305572637;5254.211868219798;5328.520433160957;5352.785888564414;5361.249425080492;5361.383440904268;4596.97701511497;4635.814567417333;4660;4663.067386522641;4665.930011049837;5627.380988091307;5724.055530837449;5756.856078597984;5836.766529338864;5842.036064049385;6734.9326506734305;6828.351306625863;6828.863422731617;6837.931620683729;6855.520113592101;5756.481916627524;5819.5053420459735;5827.125931110368;5837.94162058374;5845.181674565564;5811.738471768696;5817.563682723728;5893.057110862242;5893.174955506232;5894.381089985575;6362.84092897672;6476.902846457307;6536.182977127859;6615.735370585219;6621.735130594819;6802.591844489347;6851.657417129124;6852.109225800614;6854.074699915647;6870.965645171742;6515;6575.4410875074245;6659.2008958925235;6669.233038200669;6675.7997260082275;6710.966445167743;6720.32796720324;6769.559978601375;6791.083203767428;6811.89782153278;6569.638669873203;6678.599284042973;6762.391384775394;6783.3555812197765;6861.1080559526945;6353.841153971188;6443.935560644333;6502.869942601216;6529.67351632407;6610.669466526559;6536.705848236936;6552.967235163794;6584.506162037962;6642.634655094016;6657;6414.134091897721;6421.646809425526;6511.34886511345;6515.153030106056;6517.73929042833;6409.359064093554;6466;6595.901061484177;6608.273089960197;6687.398134167948;6315.400036996478;6317.810465686037;6332.968335158294;6379.553431259767;6388.776392826228 15:48:35 INFO - PID 3825 | |1;ext.html;851.1020872979012;880.067128843426;904.629102068152;906.0124464333875;910.5492781073369;38611;40490.1901961961;40503;40639;40652.39021414681;37343.81328093356;37575.436368454495;37655.24689506208;37671.86984390473;37688.43467347992;7213.531120477179;7274.8181295467775;7280.963595182016;7294.234105418935;7327.230640782721;7150.570965742072;7202.6038567879;7221.819454513653;7222.09723784527;7226.891596626028;8024.759257222293;8228.711995080173;8230.341572674202;8231.711890083849;8241.876371854427;8101.392395570327;8166.224208700164;8175.95912020439;8177.713780017701;8178.713745018896;7708;7904.09102953162;7915.445918785687;7962.920370796276;8066.7579972600915;37700.057498562484;37897;38121.61878381236;38271;38303;3524.4360902255517;3583.2475180212186;3589.982050089759;3593.4789455528803;3610.5667319921513;30285.697143028727;30434;30434.239144021354;30527.08418747441;30660.540091898638;5147.0992576298995;5151.04705629456;5181.196914478224;5208.453112423179;5238.1618940969265;180384;182538.08730956394;183167;183401;183788;47591.5240847594;47740;47794.28308575374;47838.28242576364;47893.76053119782;49913.75043124761;49914.50085499171;49939.75030124899;49973.25040124401;50018.49981500211;15417.768733468783;15500.224988750517;15585.844141558666;15597.610059748597;15705.68588628221;12658.936705316599;12724;12728.109032367645;12735.108542402128;12741.55404560836;13994.580162595139;14312.785308220386;14483.855161448462;14504.057236279608;14566.563003109924;5525.171224316385;5542.944570554324;5644.604877658525;5689.544836413077;5862.882742345129;1003.5985605757755;1015.3451024089421;1019.6584144311738;1027.701966429742;1027.928045036855;23128.01210817703;23205.187818426613;23223.187188448323;23243.186488472824;23249;1107.0811226681997;1119.6808909460765;1122.6014764758393;1129.4578602270792;1131.3268605179906;12292.262464252173;12424.56514022005;12427.130100892851;12512.74974500523;12613.936930315473;225144;225843;226428;227302;227533;52032.73983630058;52368.47631523712;55698.443015570134;55752.442475574724;55856.441435585126;1400.3138462153674;1411.7105993271355;1420.1904914199015;1444.8988570800154;1454.1711224602086;26904.192874213804;27120.593191102544;27884.721152788214;27935.58096628593;28008.719912801425;37452.43821342628;37766;37866.242675146896;37935.43096853605;37935.81032094822;199440;201408;201905;202336;203014;31437;32493.675063249066;32675.509867352484;32707.509387358732;32787.672123279415;41283.1743365137;41532.58467415408;41548.37677434902;41712;42176;31277.941880888153;31293;31324.373512529164;31687.683123169394;31914.36171276608;29016;30399.84800076074;30565.54151687771;31143.844280778452;31227.375452490367;35284.82357588298;35493.64506354903;35493.82253088718;35567.82216088903;35628;31029;31047.84476077605;31064;31294.68705313009;31703.52444713285;23118.778766186762;23793.643095353236;23893.761062389847;24008.759912400652;24192.274231773074 15:48:35 INFO - PID 3825 | |2;jquery.html;584.3893131677397;590.5983930926968;594.2186025376627;599.4035934245427;603.378520124272;22269.44326391841;22861;22955.770442295572;22983.655245171292;23041.078356865728;23689.88155059222;24781.62827557588;25000;25180.622290665655;25238.49523009539;6571.7371305147735;6696.397324240817;6731.56244844086;6736.05695202672;6742.157230346207;6422.614643121405;6563.1796025496815;6608.405243528082;6638.73445062197;6693.330666933316;6644.833879153011;6657.400833924946;6679.499037572177;6689.933100669004;6690.59856408617;6661.566998145129;6701.966490167542;6706.329367063304;6715.227748808877;6715.6977935993;5959.582829201958;6133.539984501135;6181.752729890799;6188.164597779332;6195.535334849904;27566.862165689243;27957.301067473272;27987.580186297222;28049;28261.43477130446;1984.900754962246;1989.9900500497404;2011.8994050297426;2018.8586798924082;2019.8283145932614;19018.904905475523;19404.80595194044;19746.605067898705;19751.901240493706;19771.80228197714;2351.2711059571416;2393.712754469457;2405.7610330679713;2419.935228499472;2421.987890060556;129636;135564.32217838947;135930.3203483986;142889;148762.99999999892;14468.106058740901;14799.334029968677;14879.10725356482;14968.262608808596;15343.00270482415;23006.7699323008;23028.769712302998;23072;23073.423164421023;23074.192403265806;9951.452670103214;10041.347312424816;10058.547365368577;10074.294799364121;10263.2302577307;9207.40151890125;9237.030111838294;9253.583588738524;9269.490178040138;9382.249420046517;8966.730998070068;9056.456612603133;9166.770830729152;9327;9375.578098985561;2675.919722408331;2740.780737540992;2741.9725802742114;2753.132763179592;2838.673552541443;930.7068273493963;945.7541039329824;953.1897886796168;956.5073986896754;966.6809952715587;18595.721064184047;18955.336563220222;19015;19250.422487325122;19391.612167756564;1001.8046480936293;1004.5630150884389;1015.6952914125881;1021.6066814276558;1025.0723095598496;10396.428196449117;10409.375437473776;10416.749990001248;10560.2079844012;10593.89406105929;496306;497059;514230;514380;521317;108893;111358;111786.44106779413;111834.44082779533;113258.43370783093;1133.1501373969522;1180.2977228549;1230.3195769912522;1253.8119282107757;1254.4668515880896;20352.491187720425;20701.89649051745;20872.47818804542;20878.895605521877;20945.37163885086;57140.714296428254;57557;57739.711301444906;58198;58546.12180817376;69644;73073.6346318265;73969.63015184889;74632.13419664334;75221.6238918802;14262.358193881508;14477;14537.85462145365;14668.926655366655;14694.118352898433;21973.340799776033;21974.45063873416;22092.779072209072;22365.217217397312;22461.32616021522;17764.911175444042;17787;17881.910590446965;17933.461996140137;18152.27390904349;15808.051516909023;16051.438199662956;16197.919010404872;16712.83287167113;16730.581735456224;18446;18522.81477185211;18590.81409185891;18736.25054997785;18783.53041173927;14101.858981410054;14109.153450792985;14300.141991480545;14413.85586144167;14438.494652687526;18182.45452636423;18223.362182323555;18293.26826926908;18319.81680183181;18324.358647447276 15:48:35 INFO - PID 3825 | |3;mootools.html;1310.7640624687558;1413.2439145057397;1415.4550498058252;1420.9715805683893;1432.1908121911126;16062.999999999985;16552.420665276688;16565.33738650454;16567.50297491074;16576.66846663066;16012.83987160128;16171.43399981001;16180.27188776505;16198;16238.350465981346;2866.827990320577;2869;2874.3245337345707;2892.2191008427712;2895.6235689360356;2734.5214587447217;2826.1380279014893;2827.5758636204528;2828.292926768308;2828.6039954406297;2748.6839013513404;2787.7630401415904;2789.0935445980035;2795.259256297083;2795.9580606290924;2626.986865065672;2649.8145129840727;2655.8008149388775;2658.322127857384;2668.746469085434;2558.5522533556555;2564.2050964201158;2606.374470127175;2608.1132414978997;2623.0294790927287;14825.258737063212;15179.241037948059;15188.924055379652;15213.61965950849;15224.847751522453;8073.919260807375;8437.915620843836;8452.32381409492;8454.492730436194;8463.534505602189;5208.270842082112;5506.449355064502;5743.368229494752;5779.739911703983;5794.043982742843;1730.5500569851804;1883.2467013194694;1924.8363889069446;1990.1840245499275;2003.5692326149801;25156.748432515622;25314.620280695803;26993.730062699513;27913.162605121877;27931.58102628462;7417.332440080419;7445.404367650575;7462.141853686787;7468.925310746823;7517.849643007218;20675.276365327143;20751.481212969495;20773;20898.164073436892;20918.89540552318;8459.154084591615;8464.661413543512;8473.872891906627;8493.532855692996;8504.14958504157;7248.7677094894;7395.2235015323695;7408.185099639036;7454.440916931143;7474.140473845578;5586.972065139729;5816.447437493432;5841.415858414124;5855.355910849803;5885.676287804126;1762.8060913299528;1804.440623406722;1810.583565779878;1811.8822276552246;1838.0993313276665;853.0999795216106;869.3653632848057;878.9824203516022;888.071964796789;890.5146695051212;7989.241022102891;8167.63245653947;8256.669733210725;8257;8390.077091519928;715.9140903091558;722.9277072292833;737.6938570493185;738.2617382617383;745.8955746195537;4289.458893514623;4307;4340.500842403101;4403.9559604404185;4446.244138496463;21514.56970860574;22010.779892200873;22533.661995070088;22574.774252257266;22581;21905.890470547864;22037.889810551165;22078.558428831653;22140.446488837908;22291;10049.095581397563;10380.896191038144;11119.72200694989;11258.605948791754;11393.658190254306;8649.783755405913;8656.826863462822;8764.386492945687;8777.605007774793;8797.076306987674;18300.359487417878;18640.906795465937;18809;19381;19565.217391304188;19075.904620477362;19309.613807724047;19475.220991160193;19540.31608893682;20115.99420029022;11689.590864319707;11866.466009029442;11948.76102477963;11965.820512692491;11991.22057066288;14143.080699754895;14202.147871127765;14217.218053007184;14230.928845355707;14282.07166534172;11704.180707350744;11825.881741182822;11929.522819087138;12068.250103735085;12183.878161218274;10897.56409743633;10968.396738179477;11024.614138505114;11249.943750281196;11508.079353651856;9805.803883922425;10014.19886409071;10020.69937901864;10027.598896044077;10033.849492257476;6912.619805910723;6942.479314051366;6965.686544105426;7030.753923612649;7067.222605513287;6913.792586222421;6946.895796562954;7031.542949708252;7080.398166155935;7093.32613401726 15:48:35 INFO - PID 3825 | |4;prototype.html;502.9195328747401;515.9251908473271;527.4857014410949;533.5971341637063;535.8874636326372;6222.5021998240145;6315.968335199107;6326.513269381698;6365.363463653638;6399.680015999193;6327.4305312521865;6336.936630633692;6367.554271201017;6370.458511026568;6384.457321127698;5227.712475813829;5256.290400795901;5259.421463638997;5271.393789714193;5274.393444753864;4830.468648448667;5121.155009423441;5188.6367954243215;5194.480551944795;5250.842474725764;4920.926186107211;4936.456989731127;4963.478834722357;4970.403551573816;4976.651634385595;4972.8756781080765;4979.8008079676765;4992.475790042066;5000.674956127858;5018.121828679985;4459.464864216315;4465.0400163964905;4488.528704486047;4513.1425029244665;4515.796789144496;6051.455369016766;6069.6054756441135;6080.361562035967;6105.565192179834;6173.197484327054;5664.54683625309;5726.169705392736;5775.88448231033;5778.942210577882;5794.188813566104;6467.805965821033;6516.914587332056;6575.276719560845;6595.571287866273;6634.734610615569;4941.382327209099;4945.826896058621;4949.109160351135;4975.850724478271;4986.551210391046;6602.207735071774;6664.733410663616;6747.966260168668;6768.56004359715;6824.249332573413;5031.496850314939;5035.748212589429;5040.697558146523;5067.138586440314;5067.391912970467;5120;5137.743112844343;5141.305923700253;5151.665141765849;5160.845174644692;5757.222774925415;5763.596548241667;5765.221695071195;5768.653880767083;5781.364049954502;6579.440747536417;6615.900761488581;6628.138342015507;6633;6694.297098804555;1745.720684690469;1766.8674849386348;1770.3538208553784;1772.6277481728982;1779.510634575502;1187.3588262338412;1234.3951463782862;1243.1670780577035;1247.002398081531;1258.6161220827553;5690.658560486384;5698.230738850285;5707.1153971134545;5711.628744131618;5732.598718089777;6694.297098804555;6726.091977582963;6773.8983915241315;6776.390124888783;6777.898331525031;5702.144678298288;5713.5714821388565;5724.247131632683;5730.34101078373;5781.53747700183;3544.503769472276;3549.538559987185;3552.857885684544;3569.446735755962;3589.120665436985;6437.806865794034;6458.515611329169;6491;6507.772227972093;6528.51036172289;6495.837604059936;6706.899396509057;6764.627945463047;6881.483888708367;6920.688569014305;6410.8076757697345;6456.12842266288;6456.3543645636;6484.708188131545;6494.545381823322;6420.839479013062;6555.475561955128;6558.245801732862;6629.204495460575;6634;6508.633187030588;6563.340028579762;6606.207255129223;6626.304238055128;6642.43539299165;6346.873062538631;6381.744730210739;6417.454516365974;6462.159919210472;6482.902756458751;5006.824761133343;5034.144195486703;5052.5705315048635;5092.745362731923;5094.566961808139;6491.792790451269;6682.9331706682315;6741.932580674326;6751.45988320943;6756.459483241427;6275;6418.775342862978;6501.414872661483;6522.738630684657;6530.412262896362;6423.522589804465;6463.224413070461;6495.538503836788;6505.772297969548;6545.181852268466;6103.42017508336;6213.502919766498;6274.278457977391;6332.778352757631;6387.680615969276;6398.904016439664;6399.616023038632;6481.6111033336265;6552.246491653521;6612.272650008399;6233.75064997395;6284.717187726655;6363.309280617138;6377.44902039188;6407.807765767035 15:48:35 INFO - PID 3825 | |5;yui.html;888.3781353052865;915.4873270968259;929.4144688846026;933.0436302789635;940.2149205413477;19108.235670573176;19852.305169319076;20006.099725512333;20013.099410526524;20048.198072077135;17725.911370443162;18074.096295185253;18289.994050327226;18938.526536836587;19123;963.426761077158;974.7270764186004;979.6767066867917;980.206033113177;980.4607465893765;961.7499450142943;985.4186030242156;994.6767300627289;995.4873240281261;996.4071377530358;1037.0407373179817;1040.032769524342;1042;1043.2592859070069;1043.4365442660992;1038.9064984151426;1040.6357774778842;1040.9011143941314;1041.1098510773293;1041.4792603698152;729.8248420379124;743.2901578992049;744.9366803821682;752.003595236314;763.3664058831157;20532.692009619866;20835.895820520953;20842.166313347447;20846.270380536607;20859.165633374647;21190.89404552983;21243.68134477984;21380.358589242347;21560.245391411227;21563.24528641506;16243.350265989346;16437;16552.42066527678;16572.917135414245;16722.498325050266;3761.793101379423;3781.5273090863643;3784.2620688965662;3785.9432108518395;3787.4886890269736;131014;132827;134894.32552837173;135835;136527;16983.83016169847;17144.742828857576;17210.31158753661;17618.03100829441;17643.911780441016;30242.395152096833;32242;32306.192345191088;32334.191645208586;32435.35129297448;8536.573171341408;8577.656893724306;8587.871181932276;8632.309415246766;8662.436941598777;4405.537418571068;4432.778361081998;4455.131249406335;4456.888577785581;4479.193745125844;5470.097433923438;5682.801101961411;5684.7157642118555;5686.857828554236;5703.515201207943;1001.3941565353002;1028.7582418131733;1035.4253389368973;1037.7716902281486;1040.5109598488698;682.6416131530985;709.2092317066433;712.3659942651045;715.7101373943561;720.718919621348;11895.702607434883;12198.451069701885;12349.826766457172;12415.93792031034;12559.623211303675;641.7336805225872;645;659.7361055577711;673.6934694713893;680.1464162476061;5493;5574.160527026072;5629.971850140723;5649.943500565024;5692.345380281321;124989;126017;129004;129446;130695;108183.45908270408;110346;110533;114049;116033;100847;102055;102906.39267135531;104588.47705761575;105847;10302.003528288924;10382.377057376581;10444.738881528023;10505.78988420212;10511.26421150512;45611.54388456206;46267.76866115648;46652.7667361661;46673;47116.4085975261;60702.39297606968;61088.38911611005;61278.693606531684;61328.38671613405;62526;14880.330385132478;15167.848321516643;15172.696546068795;15239.923800380928;15340.76988845146;17133.571660708083;17219.13904304805;17328.39350622722;17392.739108913123;17402.12989350502;15579;15820.446284380452;15889.205539723198;16074.758878616596;16136.193190340202;15304;15499.690006200039;15537.45618903378;15648.843511564739;15665.921670391575;14321.856781432052;14434.061785983877;14512.274386280433;14533.764630006568;14707.852921470649;11643.417829108681;11722.94138529336;11782.941085294518;11985.22096063753;12104.273743575413;12569.371531423209;12666.936665316614;12684.175528590613;12727.308980698572;12759.872401275868 15:48:35 INFO - PID 3825 | __end_tp_report 15:48:35 INFO - PID 3825 | __start_cc_report 15:48:35 INFO - PID 3825 | _x_x_mozilla_cycle_collect,275 15:48:35 INFO - PID 3825 | __end_cc_report 15:48:35 INFO - PID 3825 | __startTimestamp1506120515559__endTimestamp 15:48:35 INFO - PID 3825 | 15:48:35 INFO - PID 3825 | ------- Summary: start ------- 15:48:35 INFO - PID 3825 | Number of tests: 6 15:48:35 INFO - PID 3825 | 15:48:35 INFO - PID 3825 | [#0] dojo.html Cycles:165 Average:6176.21 Median:6414.13 stddev:891.92 (13.9%) stddev-sans-first:861.52 15:48:35 INFO - PID 3825 | Values: 3106.0 3118.8 3127.9 3154.5 3157.8 6136.8 6299.1 6302.1 6327.5 6445.1 6239.7 6275.4 6278.7 6281.8 6321.1 5802.6 5904.9 5905.3 5912.4 5945.4 35.7 5788.9 5795.9 5881.3 5939.1 5400.5 5724.4 5728.3 5864.9 5883.1 5786.3 5797.9 5865.8 5872.8 5877.8 5749.3 5766.5 5828.7 5849.9 5866.1 6202.7 6208.1 6261.8 6322.1 6394.6 6616.6 6723.0 6746.0 6836.2 6838.1 6799.2 6889.1 6891.0 6906.6 6920.4 6144.7 6248.8 6274.6 6294.6 6358.0 7068.9 7069.5 7083.0 7084.8 7087.5 6647.9 6651.7 6668.6 6747.7 6759.1 6616.7 6731.1 6731.9 6747.3 6851.8 6589.7 6622.1 6656.2 6669.5 6697.7 6611.2 6638.9 6639.9 6646.0 6690.0 5254.2 5328.5 5352.8 5361.2 5361.4 4597.0 4635.8 4660.0 4663.1 4665.9 5627.4 5724.1 5756.9 5836.8 5842.0 6734.9 6828.4 6828.9 6837.9 6855.5 5756.5 5819.5 5827.1 5837.9 5845.2 5811.7 5817.6 5893.1 5893.2 5894.4 6362.8 6476.9 6536.2 6615.7 6621.7 6802.6 6851.7 6852.1 6854.1 6871.0 6515.0 6575.4 6659.2 6669.2 6675.8 6711.0 6720.3 6769.6 6791.1 6811.9 6569.6 6678.6 6762.4 6783.4 6861.1 6353.8 6443.9 6502.9 6529.7 6610.7 6536.7 6553.0 6584.5 6642.6 6657.0 6414.1 6421.6 6511.3 6515.2 6517.7 6409.4 6466.0 6595.9 6608.3 6687.4 6315.4 6317.8 6333.0 6379.6 6388.8 15:48:35 INFO - PID 3825 | 15:48:35 INFO - PID 3825 | [#1] ext.html Cycles:180 Average:37137.42 Median:19404.63 stddev:52895.13 (272.6%) stddev-sans-first:52973.34 15:48:35 INFO - PID 3825 | Values: 851.1 880.1 904.6 906.0 910.5 38611.0 40490.2 40503.0 40639.0 40652.4 37343.8 37575.4 37655.2 37671.9 37688.4 7213.5 7274.8 7281.0 7294.2 7327.2 7150.6 7202.6 7221.8 7222.1 7226.9 8024.8 8228.7 8230.3 8231.7 8241.9 8101.4 8166.2 8176.0 8177.7 8178.7 7708.0 7904.1 7915.4 7962.9 8066.8 37700.1 37897.0 38121.6 38271.0 38303.0 3524.4 3583.2 3590.0 3593.5 3610.6 30285.7 30434.0 30434.2 30527.1 30660.5 5147.1 5151.0 5181.2 5208.5 5238.2 180384.0 182538.1 183167.0 183401.0 183788.0 47591.5 47740.0 47794.3 47838.3 47893.8 49913.8 49914.5 49939.8 49973.3 50018.5 15417.8 15500.2 15585.8 15597.6 15705.7 12658.9 12724.0 12728.1 12735.1 12741.6 13994.6 14312.8 14483.9 14504.1 14566.6 5525.2 5542.9 5644.6 5689.5 5862.9 1003.6 1015.3 1019.7 1027.7 1027.9 23128.0 23205.2 23223.2 23243.2 23249.0 1107.1 1119.7 1122.6 1129.5 1131.3 12292.3 12424.6 12427.1 12512.7 12613.9 225144.0 225843.0 226428.0 227302.0 227533.0 52032.7 52368.5 55698.4 55752.4 55856.4 1400.3 1411.7 1420.2 1444.9 1454.2 26904.2 27120.6 27884.7 27935.6 28008.7 37452.4 37766.0 37866.2 37935.4 37935.8 199440.0 201408.0 201905.0 202336.0 203014.0 31437.0 32493.7 32675.5 32707.5 32787.7 41283.2 41532.6 41548.4 41712.0 42176.0 31277.9 31293.0 31324.4 31687.7 31914.4 29016.0 30399.8 30565.5 31143.8 31227.4 35284.8 35493.6 35493.8 35567.8 35628.0 31029.0 31047.8 31064.0 31294.7 31703.5 23118.8 23793.6 23893.8 24008.8 24192.3 15:48:35 INFO - PID 3825 | 15:48:35 INFO - PID 3825 | [#2] jquery.html Cycles:180 Average:35205.44 Median:22908.39 stddev:85422.98 (372.9%) stddev-sans-first:85623.06 15:48:35 INFO - PID 3825 | Values: 584.4 590.6 594.2 599.4 603.4 22269.4 22861.0 22955.8 22983.7 23041.1 23689.9 24781.6 25000.0 25180.6 25238.5 6571.7 6696.4 6731.6 6736.1 6742.2 6422.6 6563.2 6608.4 6638.7 6693.3 6644.8 6657.4 6679.5 6689.9 6690.6 6661.6 6702.0 6706.3 6715.2 6715.7 5959.6 6133.5 6181.8 6188.2 6195.5 27566.9 27957.3 27987.6 28049.0 28261.4 1984.9 1990.0 2011.9 2018.9 2019.8 19018.9 19404.8 19746.6 19751.9 19771.8 2351.3 2393.7 2405.8 2419.9 2422.0 129636.0 135564.3 135930.3 142889.0 148763.0 14468.1 14799.3 14879.1 14968.3 15343.0 23006.8 23028.8 23072.0 23073.4 23074.2 9951.5 10041.3 10058.5 10074.3 10263.2 9207.4 9237.0 9253.6 9269.5 9382.2 8966.7 9056.5 9166.8 9327.0 9375.6 2675.9 2740.8 2742.0 2753.1 2838.7 930.7 945.8 953.2 956.5 966.7 18595.7 18955.3 19015.0 19250.4 19391.6 1001.8 1004.6 1015.7 1021.6 1025.1 10396.4 10409.4 10416.7 10560.2 10593.9 496306.0 497059.0 514230.0 514380.0 521317.0 108893.0 111358.0 111786.4 111834.4 113258.4 1133.2 1180.3 1230.3 1253.8 1254.5 20352.5 20701.9 20872.5 20878.9 20945.4 57140.7 57557.0 57739.7 58198.0 58546.1 69644.0 73073.6 73969.6 74632.1 75221.6 14262.4 14477.0 14537.9 14668.9 14694.1 21973.3 21974.5 22092.8 22365.2 22461.3 17764.9 17787.0 17881.9 17933.5 18152.3 15808.1 16051.4 16197.9 16712.8 16730.6 18446.0 18522.8 18590.8 18736.3 18783.5 14101.9 14109.2 14300.1 14413.9 14438.5 18182.5 18223.4 18293.3 18319.8 18324.4 15:48:35 INFO - PID 3825 | 15:48:35 INFO - PID 3825 | [#3] mootools.html Cycles:180 Average:9634.49 Median:2625.01 stddev:6972.63 (265.6%) stddev-sans-first:6964.14 15:48:35 INFO - PID 3825 | Values: 1310.8 1413.2 1415.5 1421.0 1432.2 16063.0 16552.4 16565.3 16567.5 16576.7 16012.8 16171.4 16180.3 16198.0 16238.4 2866.8 2869.0 2874.3 2892.2 2895.6 2734.5 2826.1 2827.6 2828.3 2828.6 2748.7 2787.8 2789.1 2795.3 2796.0 2627.0 2649.8 2655.8 2658.3 2668.7 2558.6 2564.2 2606.4 2608.1 2623.0 14825.3 15179.2 15188.9 15213.6 15224.8 8073.9 8437.9 8452.3 8454.5 8463.5 5208.3 5506.4 5743.4 5779.7 5794.0 1730.6 1883.2 1924.8 1990.2 2003.6 25156.7 25314.6 26993.7 27913.2 27931.6 7417.3 7445.4 7462.1 7468.9 7517.8 20675.3 20751.5 20773.0 20898.2 20918.9 8459.2 8464.7 8473.9 8493.5 8504.1 7248.8 7395.2 7408.2 7454.4 7474.1 5587.0 5816.4 5841.4 5855.4 5885.7 1762.8 1804.4 1810.6 1811.9 1838.1 853.1 869.4 879.0 888.1 890.5 7989.2 8167.6 8256.7 8257.0 8390.1 715.9 722.9 737.7 738.3 745.9 4289.5 4307.0 4340.5 4404.0 4446.2 21514.6 22010.8 22533.7 22574.8 22581.0 21905.9 22037.9 22078.6 22140.4 22291.0 10049.1 10380.9 11119.7 11258.6 11393.7 8649.8 8656.8 8764.4 8777.6 8797.1 18300.4 18640.9 18809.0 19381.0 19565.2 19075.9 19309.6 19475.2 19540.3 20116.0 11689.6 11866.5 11948.8 11965.8 11991.2 14143.1 14202.1 14217.2 14230.9 14282.1 11704.2 11825.9 11929.5 12068.3 12183.9 10897.6 10968.4 11024.6 11249.9 11508.1 9805.8 10014.2 10020.7 10027.6 10033.8 6912.6 6942.5 6965.7 7030.8 7067.2 6913.8 6946.9 7031.5 7080.4 7093.3 15:48:35 INFO - PID 3825 | 15:48:35 INFO - PID 3825 | [#4] prototype.html Cycles:180 Average:5512.96 Median:6193.35 stddev:1522.53 (24.6%) stddev-sans-first:1479.64 15:48:35 INFO - PID 3825 | Values: 502.9 515.9 527.5 533.6 535.9 6222.5 6316.0 6326.5 6365.4 6399.7 6327.4 6336.9 6367.6 6370.5 6384.5 5227.7 5256.3 5259.4 5271.4 5274.4 4830.5 5121.2 5188.6 5194.5 5250.8 4920.9 4936.5 4963.5 4970.4 4976.7 4972.9 4979.8 4992.5 5000.7 5018.1 4459.5 4465.0 4488.5 4513.1 4515.8 6051.5 6069.6 6080.4 6105.6 6173.2 5664.5 5726.2 5775.9 5778.9 5794.2 6467.8 6516.9 6575.3 6595.6 6634.7 4941.4 4945.8 4949.1 4975.9 4986.6 6602.2 6664.7 6748.0 6768.6 6824.2 5031.5 5035.7 5040.7 5067.1 5067.4 5120.0 5137.7 5141.3 5151.7 5160.8 5757.2 5763.6 5765.2 5768.7 5781.4 6579.4 6615.9 6628.1 6633.0 6694.3 1745.7 1766.9 1770.4 1772.6 1779.5 1187.4 1234.4 1243.2 1247.0 1258.6 5690.7 5698.2 5707.1 5711.6 5732.6 6694.3 6726.1 6773.9 6776.4 6777.9 5702.1 5713.6 5724.2 5730.3 5781.5 3544.5 3549.5 3552.9 3569.4 3589.1 6437.8 6458.5 6491.0 6507.8 6528.5 6495.8 6706.9 6764.6 6881.5 6920.7 6410.8 6456.1 6456.4 6484.7 6494.5 6420.8 6555.5 6558.2 6629.2 6634.0 6508.6 6563.3 6606.2 6626.3 6642.4 6346.9 6381.7 6417.5 6462.2 6482.9 5006.8 5034.1 5052.6 5092.7 5094.6 6491.8 6682.9 6741.9 6751.5 6756.5 6275.0 6418.8 6501.4 6522.7 6530.4 6423.5 6463.2 6495.5 6505.8 6545.2 6103.4 6213.5 6274.3 6332.8 6387.7 6398.9 6399.6 6481.6 6552.2 6612.3 6233.8 6284.7 6363.3 6377.4 6407.8 15:48:35 INFO - PID 3825 | 15:48:35 INFO - PID 3825 | [#5] yui.html Cycles:180 Average:24809.45 Median:17900.00 stddev:36058.76 (201.4%) stddev-sans-first:36115.18 15:48:35 INFO - PID 3825 | Values: 888.4 915.5 929.4 933.0 940.2 19108.2 19852.3 20006.1 20013.1 20048.2 17725.9 18074.1 18290.0 18938.5 19123.0 963.4 974.7 979.7 980.2 980.5 961.7 985.4 994.7 995.5 996.4 1037.0 1040.0 1042.0 1043.3 1043.4 1038.9 1040.6 1040.9 1041.1 1041.5 729.8 743.3 744.9 752.0 763.4 20532.7 20835.9 20842.2 20846.3 20859.2 21190.9 21243.7 21380.4 21560.2 21563.2 16243.4 16437.0 16552.4 16572.9 16722.5 3761.8 3781.5 3784.3 3785.9 3787.5 131014.0 132827.0 134894.3 135835.0 136527.0 16983.8 17144.7 17210.3 17618.0 17643.9 30242.4 32242.0 32306.2 32334.2 32435.4 8536.6 8577.7 8587.9 8632.3 8662.4 4405.5 4432.8 4455.1 4456.9 4479.2 5470.1 5682.8 5684.7 5686.9 5703.5 1001.4 1028.8 1035.4 1037.8 1040.5 682.6 709.2 712.4 715.7 720.7 11895.7 12198.5 12349.8 12415.9 12559.6 641.7 645.0 659.7 673.7 680.1 5493.0 5574.2 5630.0 5649.9 5692.3 124989.0 126017.0 129004.0 129446.0 130695.0 108183.5 110346.0 110533.0 114049.0 116033.0 100847.0 102055.0 102906.4 104588.5 105847.0 10302.0 10382.4 10444.7 10505.8 10511.3 45611.5 46267.8 46652.8 46673.0 47116.4 60702.4 61088.4 61278.7 61328.4 62526.0 14880.3 15167.8 15172.7 15239.9 15340.8 17133.6 17219.1 17328.4 17392.7 17402.1 15579.0 15820.4 15889.2 16074.8 16136.2 15304.0 15499.7 15537.5 15648.8 15665.9 14321.9 14434.1 14512.3 14533.8 14707.9 11643.4 11722.9 11782.9 11985.2 12104.3 12569.4 12666.9 12684.2 12727.3 12759.9 15:48:35 INFO - PID 3825 | -------- Summary: end -------- 15:48:35 INFO - PID 3825 | 15:48:36 INFO - TEST-INFO | 3825: exit 0 15:48:36 INFO - TEST-OK | dromaeo_css | took 1109917ms 15:48:36 INFO - TEST-START | kraken 15:48:36 INFO - Initialising browser for kraken test... 15:48:36 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:48919/getInfo.html -profile /tmp/tmpqrgUQE/profile 15:48:36 INFO - TEST-INFO | started process 4197 (/builds/slave/test/build/application/firefox/firefox http://localhost:48919/getInfo.html) 15:48:41 INFO - TEST-INFO | 4197: exit 0 15:48:41 INFO - Browser initialized. 15:48:41 INFO - Running cycle 1/1 for kraken test... 15:48:41 INFO - Using env: {'DISPLAY': ':0', 15:48:41 INFO - 'HOME': '/home/cltbld', 15:48:41 INFO - 'JSGC_DISABLE_POISONING': '1', 15:48:41 INFO - 'LANG': 'en_US.UTF-8', 15:48:41 INFO - 'LANGUAGE': 'en_US:en', 15:48:41 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 15:48:41 INFO - 'LOGNAME': 'cltbld', 15:48:41 INFO - 'MAIL': '/var/mail/cltbld', 15:48:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:48:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:48:41 INFO - 'MOZ_CRASHREPORTER': '1', 15:48:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:41 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 15:48:41 INFO - 'MOZ_NO_REMOTE': '1', 15:48:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:48:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:48:41 INFO - 'NO_EM_RESTART': '1', 15:48:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:48:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:48:41 INFO - 'PWD': '/builds/slave/test', 15:48:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:48:41 INFO - 'RUST_BACKTRACE': 'full', 15:48:41 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:48:41 INFO - 'SHELL': '/bin/bash', 15:48:41 INFO - 'SHLVL': '1', 15:48:41 INFO - 'TERM': 'linux', 15:48:41 INFO - 'TMOUT': '86400', 15:48:41 INFO - 'USER': 'cltbld', 15:48:41 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1506119346.703642-147467537', 15:48:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:41 INFO - '_': '/tools/buildbot/bin/python'} 15:48:41 INFO - TEST-INFO | started process 4429 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpqrgUQE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 15:48:42 INFO - PID 4429 | 15:48:42 INFO - PID 4429 | (/builds/slave/test/build/application/firefox/firefox:4478): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 15:48:42 INFO - PID 4429 | 15:48:52 INFO - PID 4429 | RSS: Main: 153034752 15:48:52 INFO - PID 4429 | 15:48:55 INFO - PID 4429 | Cycle 1(1): loaded http://localhost:48919/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:48919/tests/kraken/driver.html?testName=audio-beat-detection) 15:48:56 INFO - PID 4429 | RSS: Main: 159088640 15:48:56 INFO - PID 4429 | 15:48:58 INFO - PID 4429 | Cycle 1(1): loaded http://localhost:48919/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:48919/tests/kraken/driver.html?testName=audio-dft) 15:48:59 INFO - PID 4429 | RSS: Main: 159404032 15:48:59 INFO - PID 4429 | 15:49:02 INFO - PID 4429 | Cycle 1(1): loaded http://localhost:48919/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:48919/tests/kraken/driver.html?testName=audio-fft) 15:49:02 INFO - PID 4429 | RSS: Main: 156250112 15:49:02 INFO - PID 4429 | 15:49:05 INFO - PID 4429 | Cycle 1(1): loaded http://localhost:48919/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:48919/tests/kraken/driver.html?testName=audio-oscillator) 15:49:05 INFO - PID 4429 | RSS: Main: 157020160 15:49:05 INFO - PID 4429 | 15:49:07 INFO - PID 4429 | Cycle 1(1): loaded http://localhost:48919/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:48919/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:49:08 INFO - PID 4429 | RSS: Main: 157097984 15:49:08 INFO - PID 4429 | 15:49:11 INFO - PID 4429 | Cycle 1(1): loaded http://localhost:48919/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:48919/tests/kraken/driver.html?testName=imaging-darkroom) 15:49:12 INFO - PID 4429 | RSS: Main: 159363072 15:49:12 INFO - PID 4429 | 15:49:15 INFO - PID 4429 | Cycle 1(1): loaded http://localhost:48919/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:48919/tests/kraken/driver.html?testName=imaging-desaturate) 15:49:16 INFO - PID 4429 | RSS: Main: 159744000 15:49:16 INFO - PID 4429 | 15:49:19 INFO - PID 4429 | Cycle 1(1): loaded http://localhost:48919/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:48919/tests/kraken/driver.html?testName=json-parse-financial) 15:49:20 INFO - PID 4429 | RSS: Main: 159506432 15:49:20 INFO - PID 4429 | 15:49:21 INFO - PID 4429 | Cycle 1(1): loaded http://localhost:48919/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:48919/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:49:22 INFO - PID 4429 | RSS: Main: 159420416 15:49:22 INFO - PID 4429 | 15:49:23 INFO - PID 4429 | Cycle 1(1): loaded http://localhost:48919/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:48919/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:49:24 INFO - PID 4429 | RSS: Main: 159649792 15:49:24 INFO - PID 4429 | 15:49:26 INFO - PID 4429 | Cycle 1(1): loaded http://localhost:48919/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:48919/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:49:27 INFO - PID 4429 | RSS: Main: 156524544 15:49:27 INFO - PID 4429 | 15:49:29 INFO - PID 4429 | Cycle 1(1): loaded http://localhost:48919/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:48919/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:49:30 INFO - PID 4429 | RSS: Main: 156872704 15:49:30 INFO - PID 4429 | 15:49:33 INFO - PID 4429 | Cycle 1(1): loaded http://localhost:48919/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:48919/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:49:34 INFO - PID 4429 | RSS: Main: 157110272 15:49:34 INFO - PID 4429 | 15:49:35 INFO - PID 4429 | Cycle 1(1): loaded http://localhost:48919/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:48919/tests/kraken/driver.html?testName=ai-astar) 15:49:36 INFO - PID 4429 | RSS: Main: 158433280 15:49:36 INFO - PID 4429 | 15:49:36 INFO - PID 4429 | __start_tp_report 15:49:36 INFO - PID 4429 | _x_x_mozilla_page_load 15:49:36 INFO - PID 4429 | _x_x_mozilla_page_load_details 15:49:36 INFO - PID 4429 | |i|pagename|runs| 15:49:36 INFO - PID 4429 | |0;ai-astar;108;107;107;107;107;109;108;107;107;108 15:49:36 INFO - PID 4429 | |1;audio-beat-detection;117;134;116;116;116;116;116;116;146;122 15:49:36 INFO - PID 4429 | |2;audio-dft;163;162;162;162;164;164;165;164;165;165 15:49:36 INFO - PID 4429 | |3;audio-fft;76;75;74;73;75;73;75;107;74;75 15:49:36 INFO - PID 4429 | |4;audio-oscillator;79;79;80;79;79;82;79;79;79;79 15:49:36 INFO - PID 4429 | |5;imaging-gaussian-blur;114;110;114;114;111;115;114;113;114;115 15:49:36 INFO - PID 4429 | |6;imaging-darkroom;155;147;149;148;149;148;150;149;148;149 15:49:36 INFO - PID 4429 | |7;imaging-desaturate;95;95;94;94;94;93;94;94;95;94 15:49:36 INFO - PID 4429 | |8;json-parse-financial;52;52;54;52;54;53;50;50;51;54 15:49:36 INFO - PID 4429 | |9;json-stringify-tinderbox;38;38;38;38;39;38;39;38;39;38 15:49:36 INFO - PID 4429 | |10;stanford-crypto-aes;74;76;73;74;76;76;78;74;74;78 15:49:36 INFO - PID 4429 | |11;stanford-crypto-ccm;114;120;117;112;120;112;111;117;111;121 15:49:36 INFO - PID 4429 | |12;stanford-crypto-pbkdf2;174;167;177;167;171;174;173;182;170;175 15:49:36 INFO - PID 4429 | |13;stanford-crypto-sha256-iterative;67;65;65;65;71;68;66;67;66;65 15:49:36 INFO - PID 4429 | __end_tp_report 15:49:36 INFO - PID 4429 | __start_cc_report 15:49:36 INFO - PID 4429 | _x_x_mozilla_cycle_collect,747 15:49:36 INFO - PID 4429 | __end_cc_report 15:49:36 INFO - PID 4429 | __startTimestamp1506120576131__endTimestamp 15:49:36 INFO - PID 4429 | 15:49:36 INFO - PID 4429 | ------- Summary: start ------- 15:49:36 INFO - PID 4429 | Number of tests: 14 15:49:36 INFO - PID 4429 | 15:49:36 INFO - PID 4429 | [#0] ai-astar Cycles:10 Average:107.50 Median:107.50 stddev:0.71 (0.7%) stddev-sans-first:0.73 15:49:36 INFO - PID 4429 | Values: 108.0 107.0 107.0 107.0 107.0 109.0 108.0 107.0 107.0 108.0 15:49:36 INFO - PID 4429 | 15:49:36 INFO - PID 4429 | [#1] audio-beat-detection Cycles:10 Average:121.50 Median:116.50 stddev:10.32 (8.9%) stddev-sans-first:10.82 15:49:36 INFO - PID 4429 | Values: 117.0 134.0 116.0 116.0 116.0 116.0 116.0 116.0 146.0 122.0 15:49:36 INFO - PID 4429 | 15:49:36 INFO - PID 4429 | [#2] audio-dft Cycles:10 Average:163.60 Median:164.00 stddev:1.26 (0.8%) stddev-sans-first:1.32 15:49:36 INFO - PID 4429 | Values: 163.0 162.0 162.0 162.0 164.0 164.0 165.0 164.0 165.0 165.0 15:49:36 INFO - PID 4429 | 15:49:36 INFO - PID 4429 | [#3] audio-fft Cycles:10 Average:77.70 Median:75.00 stddev:10.34 (13.8%) stddev-sans-first:10.95 15:49:36 INFO - PID 4429 | Values: 76.0 75.0 74.0 73.0 75.0 73.0 75.0 107.0 74.0 75.0 15:49:36 INFO - PID 4429 | 15:49:36 INFO - PID 4429 | [#4] audio-oscillator Cycles:10 Average:79.40 Median:79.00 stddev:0.97 (1.2%) stddev-sans-first:1.01 15:49:36 INFO - PID 4429 | Values: 79.0 79.0 80.0 79.0 79.0 82.0 79.0 79.0 79.0 79.0 15:49:36 INFO - PID 4429 | 15:49:36 INFO - PID 4429 | [#5] imaging-gaussian-blur Cycles:10 Average:113.40 Median:114.00 stddev:1.65 (1.4%) stddev-sans-first:1.73 15:49:36 INFO - PID 4429 | Values: 114.0 110.0 114.0 114.0 111.0 115.0 114.0 113.0 114.0 115.0 15:49:36 INFO - PID 4429 | 15:49:36 INFO - PID 4429 | [#6] imaging-darkroom Cycles:10 Average:149.20 Median:149.00 stddev:2.20 (1.5%) stddev-sans-first:0.88 15:49:36 INFO - PID 4429 | Values: 155.0 147.0 149.0 148.0 149.0 148.0 150.0 149.0 148.0 149.0 15:49:36 INFO - PID 4429 | 15:49:36 INFO - PID 4429 | [#7] imaging-desaturate Cycles:10 Average:94.20 Median:94.00 stddev:0.63 (0.7%) stddev-sans-first:0.60 15:49:36 INFO - PID 4429 | Values: 95.0 95.0 94.0 94.0 94.0 93.0 94.0 94.0 95.0 94.0 15:49:36 INFO - PID 4429 | 15:49:36 INFO - PID 4429 | [#8] json-parse-financial Cycles:10 Average:52.20 Median:52.50 stddev:1.55 (3.0%) stddev-sans-first:1.64 15:49:36 INFO - PID 4429 | Values: 52.0 52.0 54.0 52.0 54.0 53.0 50.0 50.0 51.0 54.0 15:49:36 INFO - PID 4429 | 15:49:36 INFO - PID 4429 | [#9] json-stringify-tinderbox Cycles:10 Average:38.30 Median:38.00 stddev:0.48 (1.3%) stddev-sans-first:0.50 15:49:36 INFO - PID 4429 | Values: 38.0 38.0 38.0 38.0 39.0 38.0 39.0 38.0 39.0 38.0 15:49:36 INFO - PID 4429 | 15:49:36 INFO - PID 4429 | [#10] stanford-crypto-aes Cycles:10 Average:75.30 Median:76.00 stddev:1.77 (2.3%) stddev-sans-first:1.81 15:49:36 INFO - PID 4429 | Values: 74.0 76.0 73.0 74.0 76.0 76.0 78.0 74.0 74.0 78.0 15:49:36 INFO - PID 4429 | 15:49:36 INFO - PID 4429 | [#11] stanford-crypto-ccm Cycles:10 Average:115.50 Median:117.00 stddev:3.98 (3.4%) stddev-sans-first:4.18 15:49:36 INFO - PID 4429 | Values: 114.0 120.0 117.0 112.0 120.0 112.0 111.0 117.0 111.0 121.0 15:49:36 INFO - PID 4429 | 15:49:36 INFO - PID 4429 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.00 Median:174.00 stddev:4.57 (2.6%) stddev-sans-first:4.83 15:49:36 INFO - PID 4429 | Values: 174.0 167.0 177.0 167.0 171.0 174.0 173.0 182.0 170.0 175.0 15:49:36 INFO - PID 4429 | 15:49:36 INFO - PID 4429 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.50 Median:66.50 stddev:1.90 (2.9%) stddev-sans-first:2.01 15:49:36 INFO - PID 4429 | Values: 67.0 65.0 65.0 65.0 71.0 68.0 66.0 67.0 66.0 65.0 15:49:36 INFO - PID 4429 | -------- Summary: end -------- 15:49:36 INFO - PID 4429 | 15:49:36 INFO - PID 4429 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 15:49:36 INFO - TEST-INFO | 4429: exit 0 15:49:36 INFO - TEST-OK | kraken | took 60617ms 15:49:36 INFO - SUITE-END | took 1170s 15:49:37 INFO - Completed test suite (00:19:31) 15:49:37 INFO - javascript benchmark 15:49:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8540.115676734264, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3106.0371284901667, 3118.8128712277266, 3127.9218019549517, 3154.5110507871286, 3157.8263195524237, 6136.785212517563, 6299.1496148020005, 6302.0861975013595, 6327.462165715914, 6445.097686323913, 6239.719212635431, 6275.435210831024, 6278.686065696708, 6281.811545653626, 6321.1150438938585, 5802.564807639423, 5904.911426328608, 5905.261842269716, 5912.408759124096, 5945.37573554777, 35.68210542412979, 5788.929048126089, 5795.9130613040625, 5881.294244690643, 5939.079442686369, 5400.540954018913, 5724.39893811148, 5728.312602487707, 5864.941350586503, 5883.14694369314, 5786.334571524287, 5797.913031304492, 5865.794697185578, 5872.794452194195, 5877.823665290048, 5749.252597162342, 5766.538676905838, 5828.737706803204, 5849.941500585025, 5866.120081987693, 6202.72087756052, 6208.068789681539, 6261.812145635638, 6322.071768110724, 6394.552381333308, 6616.636085015322, 6723.025161351626, 6746.0, 6836.248012718597, 6838.076859623987, 6799.218089919673, 6889.069975553286, 6891.0, 6906.551074180162, 6920.377166055079, 6144.662043587601, 6248.781292654735, 6274.56078074535, 6294.559380843343, 6358.046293056079, 7068.858622827566, 7069.540479868792, 7083.0437890885105, 7084.752033678797, 7087.539309944837, 6647.900281495781, 6651.66741662915, 6668.599884006975, 6747.6626168692355, 6759.121314229119, 6616.7022483987375, 6731.057651928734, 6731.865362692719, 6747.257801641816, 6851.828704282433, 6589.670516474157, 6622.106015687917, 6656.201255849329, 6669.499787515956, 6697.6986035628515, 6611.239707433707, 6638.933610663928, 6639.900401493981, 6646.0, 6689.96305572637, 5254.211868219798, 5328.520433160957, 5352.785888564414, 5361.249425080492, 5361.383440904268, 4596.97701511497, 4635.814567417333, 4660.0, 4663.067386522641, 4665.930011049837, 5627.380988091307, 5724.055530837449, 5756.856078597984, 5836.766529338864, 5842.036064049385, 6734.9326506734305, 6828.351306625863, 6828.863422731617, 6837.931620683729, 6855.520113592101, 5756.481916627524, 5819.5053420459735, 5827.125931110368, 5837.94162058374, 5845.181674565564, 5811.738471768696, 5817.563682723728, 5893.057110862242, 5893.174955506232, 5894.381089985575, 6362.84092897672, 6476.902846457307, 6536.182977127859, 6615.735370585219, 6621.735130594819, 6802.591844489347, 6851.657417129124, 6852.109225800614, 6854.074699915647, 6870.965645171742, 6515.0, 6575.4410875074245, 6659.2008958925235, 6669.233038200669, 6675.7997260082275, 6710.966445167743, 6720.32796720324, 6769.559978601375, 6791.083203767428, 6811.89782153278, 6569.638669873203, 6678.599284042973, 6762.391384775394, 6783.3555812197765, 6861.1080559526945, 6353.841153971188, 6443.935560644333, 6502.869942601216, 6529.67351632407, 6610.669466526559, 6536.705848236936, 6552.967235163794, 6584.506162037962, 6642.634655094016, 6657.0, 6414.134091897721, 6421.646809425526, 6511.34886511345, 6515.153030106056, 6517.73929042833, 6409.359064093554, 6466.0, 6595.901061484177, 6608.273089960197, 6687.398134167948, 6315.400036996478, 6317.810465686037, 6332.968335158294, 6379.553431259767, 6388.776392826228], "value": 6112.307336458675, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [851.1020872979012, 880.067128843426, 904.629102068152, 906.0124464333875, 910.5492781073369, 38611.0, 40490.1901961961, 40503.0, 40639.0, 40652.39021414681, 37343.81328093356, 37575.436368454495, 37655.24689506208, 37671.86984390473, 37688.43467347992, 7213.531120477179, 7274.8181295467775, 7280.963595182016, 7294.234105418935, 7327.230640782721, 7150.570965742072, 7202.6038567879, 7221.819454513653, 7222.09723784527, 7226.891596626028, 8024.759257222293, 8228.711995080173, 8230.341572674202, 8231.711890083849, 8241.876371854427, 8101.392395570327, 8166.224208700164, 8175.95912020439, 8177.713780017701, 8178.713745018896, 7708.0, 7904.09102953162, 7915.445918785687, 7962.920370796276, 8066.7579972600915, 37700.057498562484, 37897.0, 38121.61878381236, 38271.0, 38303.0, 3524.4360902255517, 3583.2475180212186, 3589.982050089759, 3593.4789455528803, 3610.5667319921513, 30285.697143028727, 30434.0, 30434.239144021354, 30527.08418747441, 30660.540091898638, 5147.0992576298995, 5151.04705629456, 5181.196914478224, 5208.453112423179, 5238.1618940969265, 180384.0, 182538.08730956394, 183167.0, 183401.0, 183788.0, 47591.5240847594, 47740.0, 47794.28308575374, 47838.28242576364, 47893.76053119782, 49913.75043124761, 49914.50085499171, 49939.75030124899, 49973.25040124401, 50018.49981500211, 15417.768733468783, 15500.224988750517, 15585.844141558666, 15597.610059748597, 15705.68588628221, 12658.936705316599, 12724.0, 12728.109032367645, 12735.108542402128, 12741.55404560836, 13994.580162595139, 14312.785308220386, 14483.855161448462, 14504.057236279608, 14566.563003109924, 5525.171224316385, 5542.944570554324, 5644.604877658525, 5689.544836413077, 5862.882742345129, 1003.5985605757755, 1015.3451024089421, 1019.6584144311738, 1027.701966429742, 1027.928045036855, 23128.01210817703, 23205.187818426613, 23223.187188448323, 23243.186488472824, 23249.0, 1107.0811226681997, 1119.6808909460765, 1122.6014764758393, 1129.4578602270792, 1131.3268605179906, 12292.262464252173, 12424.56514022005, 12427.130100892851, 12512.74974500523, 12613.936930315473, 225144.0, 225843.0, 226428.0, 227302.0, 227533.0, 52032.73983630058, 52368.47631523712, 55698.443015570134, 55752.442475574724, 55856.441435585126, 1400.3138462153674, 1411.7105993271355, 1420.1904914199015, 1444.8988570800154, 1454.1711224602086, 26904.192874213804, 27120.593191102544, 27884.721152788214, 27935.58096628593, 28008.719912801425, 37452.43821342628, 37766.0, 37866.242675146896, 37935.43096853605, 37935.81032094822, 199440.0, 201408.0, 201905.0, 202336.0, 203014.0, 31437.0, 32493.675063249066, 32675.509867352484, 32707.509387358732, 32787.672123279415, 41283.1743365137, 41532.58467415408, 41548.37677434902, 41712.0, 42176.0, 31277.941880888153, 31293.0, 31324.373512529164, 31687.683123169394, 31914.36171276608, 29016.0, 30399.84800076074, 30565.54151687771, 31143.844280778452, 31227.375452490367, 35284.82357588298, 35493.64506354903, 35493.82253088718, 35567.82216088903, 35628.0, 31029.0, 31047.84476077605, 31064.0, 31294.68705313009, 31703.52444713285, 23118.778766186762, 23793.643095353236, 23893.761062389847, 24008.759912400652, 24192.274231773074], "value": 17082.123103075646, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [584.3893131677397, 590.5983930926968, 594.2186025376627, 599.4035934245427, 603.378520124272, 22269.44326391841, 22861.0, 22955.770442295572, 22983.655245171292, 23041.078356865728, 23689.88155059222, 24781.62827557588, 25000.0, 25180.622290665655, 25238.49523009539, 6571.7371305147735, 6696.397324240817, 6731.56244844086, 6736.05695202672, 6742.157230346207, 6422.614643121405, 6563.1796025496815, 6608.405243528082, 6638.73445062197, 6693.330666933316, 6644.833879153011, 6657.400833924946, 6679.499037572177, 6689.933100669004, 6690.59856408617, 6661.566998145129, 6701.966490167542, 6706.329367063304, 6715.227748808877, 6715.6977935993, 5959.582829201958, 6133.539984501135, 6181.752729890799, 6188.164597779332, 6195.535334849904, 27566.862165689243, 27957.301067473272, 27987.580186297222, 28049.0, 28261.43477130446, 1984.900754962246, 1989.9900500497404, 2011.8994050297426, 2018.8586798924082, 2019.8283145932614, 19018.904905475523, 19404.80595194044, 19746.605067898705, 19751.901240493706, 19771.80228197714, 2351.2711059571416, 2393.712754469457, 2405.7610330679713, 2419.935228499472, 2421.987890060556, 129636.0, 135564.32217838947, 135930.3203483986, 142889.0, 148762.99999999892, 14468.106058740901, 14799.334029968677, 14879.10725356482, 14968.262608808596, 15343.00270482415, 23006.7699323008, 23028.769712302998, 23072.0, 23073.423164421023, 23074.192403265806, 9951.452670103214, 10041.347312424816, 10058.547365368577, 10074.294799364121, 10263.2302577307, 9207.40151890125, 9237.030111838294, 9253.583588738524, 9269.490178040138, 9382.249420046517, 8966.730998070068, 9056.456612603133, 9166.770830729152, 9327.0, 9375.578098985561, 2675.919722408331, 2740.780737540992, 2741.9725802742114, 2753.132763179592, 2838.673552541443, 930.7068273493963, 945.7541039329824, 953.1897886796168, 956.5073986896754, 966.6809952715587, 18595.721064184047, 18955.336563220222, 19015.0, 19250.422487325122, 19391.612167756564, 1001.8046480936293, 1004.5630150884389, 1015.6952914125881, 1021.6066814276558, 1025.0723095598496, 10396.428196449117, 10409.375437473776, 10416.749990001248, 10560.2079844012, 10593.89406105929, 496306.0, 497059.0, 514230.0, 514380.0, 521317.0, 108893.0, 111358.0, 111786.44106779413, 111834.44082779533, 113258.43370783093, 1133.1501373969522, 1180.2977228549, 1230.3195769912522, 1253.8119282107757, 1254.4668515880896, 20352.491187720425, 20701.89649051745, 20872.47818804542, 20878.895605521877, 20945.37163885086, 57140.714296428254, 57557.0, 57739.711301444906, 58198.0, 58546.12180817376, 69644.0, 73073.6346318265, 73969.63015184889, 74632.13419664334, 75221.6238918802, 14262.358193881508, 14477.0, 14537.85462145365, 14668.926655366655, 14694.118352898433, 21973.340799776033, 21974.45063873416, 22092.779072209072, 22365.217217397312, 22461.32616021522, 17764.911175444042, 17787.0, 17881.910590446965, 17933.461996140137, 18152.27390904349, 15808.051516909023, 16051.438199662956, 16197.919010404872, 16712.83287167113, 16730.581735456224, 18446.0, 18522.81477185211, 18590.81409185891, 18736.25054997785, 18783.53041173927, 14101.858981410054, 14109.153450792985, 14300.141991480545, 14413.85586144167, 14438.494652687526, 18182.45452636423, 18223.362182323555, 18293.26826926908, 18319.81680183181, 18324.358647447276], "value": 11964.421146140867, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1310.7640624687558, 1413.2439145057397, 1415.4550498058252, 1420.9715805683893, 1432.1908121911126, 16062.999999999985, 16552.420665276688, 16565.33738650454, 16567.50297491074, 16576.66846663066, 16012.83987160128, 16171.43399981001, 16180.27188776505, 16198.0, 16238.350465981346, 2866.827990320577, 2869.0, 2874.3245337345707, 2892.2191008427712, 2895.6235689360356, 2734.5214587447217, 2826.1380279014893, 2827.5758636204528, 2828.292926768308, 2828.6039954406297, 2748.6839013513404, 2787.7630401415904, 2789.0935445980035, 2795.259256297083, 2795.9580606290924, 2626.986865065672, 2649.8145129840727, 2655.8008149388775, 2658.322127857384, 2668.746469085434, 2558.5522533556555, 2564.2050964201158, 2606.374470127175, 2608.1132414978997, 2623.0294790927287, 14825.258737063212, 15179.241037948059, 15188.924055379652, 15213.61965950849, 15224.847751522453, 8073.919260807375, 8437.915620843836, 8452.32381409492, 8454.492730436194, 8463.534505602189, 5208.270842082112, 5506.449355064502, 5743.368229494752, 5779.739911703983, 5794.043982742843, 1730.5500569851804, 1883.2467013194694, 1924.8363889069446, 1990.1840245499275, 2003.5692326149801, 25156.748432515622, 25314.620280695803, 26993.730062699513, 27913.162605121877, 27931.58102628462, 7417.332440080419, 7445.404367650575, 7462.141853686787, 7468.925310746823, 7517.849643007218, 20675.276365327143, 20751.481212969495, 20773.0, 20898.164073436892, 20918.89540552318, 8459.154084591615, 8464.661413543512, 8473.872891906627, 8493.532855692996, 8504.14958504157, 7248.7677094894, 7395.2235015323695, 7408.185099639036, 7454.440916931143, 7474.140473845578, 5586.972065139729, 5816.447437493432, 5841.415858414124, 5855.355910849803, 5885.676287804126, 1762.8060913299528, 1804.440623406722, 1810.583565779878, 1811.8822276552246, 1838.0993313276665, 853.0999795216106, 869.3653632848057, 878.9824203516022, 888.071964796789, 890.5146695051212, 7989.241022102891, 8167.63245653947, 8256.669733210725, 8257.0, 8390.077091519928, 715.9140903091558, 722.9277072292833, 737.6938570493185, 738.2617382617383, 745.8955746195537, 4289.458893514623, 4307.0, 4340.500842403101, 4403.9559604404185, 4446.244138496463, 21514.56970860574, 22010.779892200873, 22533.661995070088, 22574.774252257266, 22581.0, 21905.890470547864, 22037.889810551165, 22078.558428831653, 22140.446488837908, 22291.0, 10049.095581397563, 10380.896191038144, 11119.72200694989, 11258.605948791754, 11393.658190254306, 8649.783755405913, 8656.826863462822, 8764.386492945687, 8777.605007774793, 8797.076306987674, 18300.359487417878, 18640.906795465937, 18809.0, 19381.0, 19565.217391304188, 19075.904620477362, 19309.613807724047, 19475.220991160193, 19540.31608893682, 20115.99420029022, 11689.590864319707, 11866.466009029442, 11948.76102477963, 11965.820512692491, 11991.22057066288, 14143.080699754895, 14202.147871127765, 14217.218053007184, 14230.928845355707, 14282.07166534172, 11704.180707350744, 11825.881741182822, 11929.522819087138, 12068.250103735085, 12183.878161218274, 10897.56409743633, 10968.396738179477, 11024.614138505114, 11249.943750281196, 11508.079353651856, 9805.803883922425, 10014.19886409071, 10020.69937901864, 10027.598896044077, 10033.849492257476, 6912.619805910723, 6942.479314051366, 6965.686544105426, 7030.753923612649, 7067.222605513287, 6913.792586222421, 6946.895796562954, 7031.542949708252, 7080.398166155935, 7093.32613401726], "value": 6797.271496363274, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [502.9195328747401, 515.9251908473271, 527.4857014410949, 533.5971341637063, 535.8874636326372, 6222.5021998240145, 6315.968335199107, 6326.513269381698, 6365.363463653638, 6399.680015999193, 6327.4305312521865, 6336.936630633692, 6367.554271201017, 6370.458511026568, 6384.457321127698, 5227.712475813829, 5256.290400795901, 5259.421463638997, 5271.393789714193, 5274.393444753864, 4830.468648448667, 5121.155009423441, 5188.6367954243215, 5194.480551944795, 5250.842474725764, 4920.926186107211, 4936.456989731127, 4963.478834722357, 4970.403551573816, 4976.651634385595, 4972.8756781080765, 4979.8008079676765, 4992.475790042066, 5000.674956127858, 5018.121828679985, 4459.464864216315, 4465.0400163964905, 4488.528704486047, 4513.1425029244665, 4515.796789144496, 6051.455369016766, 6069.6054756441135, 6080.361562035967, 6105.565192179834, 6173.197484327054, 5664.54683625309, 5726.169705392736, 5775.88448231033, 5778.942210577882, 5794.188813566104, 6467.805965821033, 6516.914587332056, 6575.276719560845, 6595.571287866273, 6634.734610615569, 4941.382327209099, 4945.826896058621, 4949.109160351135, 4975.850724478271, 4986.551210391046, 6602.207735071774, 6664.733410663616, 6747.966260168668, 6768.56004359715, 6824.249332573413, 5031.496850314939, 5035.748212589429, 5040.697558146523, 5067.138586440314, 5067.391912970467, 5120.0, 5137.743112844343, 5141.305923700253, 5151.665141765849, 5160.845174644692, 5757.222774925415, 5763.596548241667, 5765.221695071195, 5768.653880767083, 5781.364049954502, 6579.440747536417, 6615.900761488581, 6628.138342015507, 6633.0, 6694.297098804555, 1745.720684690469, 1766.8674849386348, 1770.3538208553784, 1772.6277481728982, 1779.510634575502, 1187.3588262338412, 1234.3951463782862, 1243.1670780577035, 1247.002398081531, 1258.6161220827553, 5690.658560486384, 5698.230738850285, 5707.1153971134545, 5711.628744131618, 5732.598718089777, 6694.297098804555, 6726.091977582963, 6773.8983915241315, 6776.390124888783, 6777.898331525031, 5702.144678298288, 5713.5714821388565, 5724.247131632683, 5730.34101078373, 5781.53747700183, 3544.503769472276, 3549.538559987185, 3552.857885684544, 3569.446735755962, 3589.120665436985, 6437.806865794034, 6458.515611329169, 6491.0, 6507.772227972093, 6528.51036172289, 6495.837604059936, 6706.899396509057, 6764.627945463047, 6881.483888708367, 6920.688569014305, 6410.8076757697345, 6456.12842266288, 6456.3543645636, 6484.708188131545, 6494.545381823322, 6420.839479013062, 6555.475561955128, 6558.245801732862, 6629.204495460575, 6634.0, 6508.633187030588, 6563.340028579762, 6606.207255129223, 6626.304238055128, 6642.43539299165, 6346.873062538631, 6381.744730210739, 6417.454516365974, 6462.159919210472, 6482.902756458751, 5006.824761133343, 5034.144195486703, 5052.5705315048635, 5092.745362731923, 5094.566961808139, 6491.792790451269, 6682.9331706682315, 6741.932580674326, 6751.45988320943, 6756.459483241427, 6275.0, 6418.775342862978, 6501.414872661483, 6522.738630684657, 6530.412262896362, 6423.522589804465, 6463.224413070461, 6495.538503836788, 6505.772297969548, 6545.181852268466, 6103.42017508336, 6213.502919766498, 6274.278457977391, 6332.778352757631, 6387.680615969276, 6398.904016439664, 6399.616023038632, 6481.6111033336265, 6552.246491653521, 6612.272650008399, 6233.75064997395, 6284.717187726655, 6363.309280617138, 6377.44902039188, 6407.807765767035], "value": 5066.706236587304, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [888.3781353052865, 915.4873270968259, 929.4144688846026, 933.0436302789635, 940.2149205413477, 19108.235670573176, 19852.305169319076, 20006.099725512333, 20013.099410526524, 20048.198072077135, 17725.911370443162, 18074.096295185253, 18289.994050327226, 18938.526536836587, 19123.0, 963.426761077158, 974.7270764186004, 979.6767066867917, 980.206033113177, 980.4607465893765, 961.7499450142943, 985.4186030242156, 994.6767300627289, 995.4873240281261, 996.4071377530358, 1037.0407373179817, 1040.032769524342, 1042.0, 1043.2592859070069, 1043.4365442660992, 1038.9064984151426, 1040.6357774778842, 1040.9011143941314, 1041.1098510773293, 1041.4792603698152, 729.8248420379124, 743.2901578992049, 744.9366803821682, 752.003595236314, 763.3664058831157, 20532.692009619866, 20835.895820520953, 20842.166313347447, 20846.270380536607, 20859.165633374647, 21190.89404552983, 21243.68134477984, 21380.358589242347, 21560.245391411227, 21563.24528641506, 16243.350265989346, 16437.0, 16552.42066527678, 16572.917135414245, 16722.498325050266, 3761.793101379423, 3781.5273090863643, 3784.2620688965662, 3785.9432108518395, 3787.4886890269736, 131014.0, 132827.0, 134894.32552837173, 135835.0, 136527.0, 16983.83016169847, 17144.742828857576, 17210.31158753661, 17618.03100829441, 17643.911780441016, 30242.395152096833, 32242.0, 32306.192345191088, 32334.191645208586, 32435.35129297448, 8536.573171341408, 8577.656893724306, 8587.871181932276, 8632.309415246766, 8662.436941598777, 4405.537418571068, 4432.778361081998, 4455.131249406335, 4456.888577785581, 4479.193745125844, 5470.097433923438, 5682.801101961411, 5684.7157642118555, 5686.857828554236, 5703.515201207943, 1001.3941565353002, 1028.7582418131733, 1035.4253389368973, 1037.7716902281486, 1040.5109598488698, 682.6416131530985, 709.2092317066433, 712.3659942651045, 715.7101373943561, 720.718919621348, 11895.702607434883, 12198.451069701885, 12349.826766457172, 12415.93792031034, 12559.623211303675, 641.7336805225872, 645.0, 659.7361055577711, 673.6934694713893, 680.1464162476061, 5493.0, 5574.160527026072, 5629.971850140723, 5649.943500565024, 5692.345380281321, 124989.0, 126017.0, 129004.0, 129446.0, 130695.0, 108183.45908270408, 110346.0, 110533.0, 114049.0, 116033.0, 100847.0, 102055.0, 102906.39267135531, 104588.47705761575, 105847.0, 10302.003528288924, 10382.377057376581, 10444.738881528023, 10505.78988420212, 10511.26421150512, 45611.54388456206, 46267.76866115648, 46652.7667361661, 46673.0, 47116.4085975261, 60702.39297606968, 61088.38911611005, 61278.693606531684, 61328.38671613405, 62526.0, 14880.330385132478, 15167.848321516643, 15172.696546068795, 15239.923800380928, 15340.76988845146, 17133.571660708083, 17219.13904304805, 17328.39350622722, 17392.739108913123, 17402.12989350502, 15579.0, 15820.446284380452, 15889.205539723198, 16074.758878616596, 16136.193190340202, 15304.0, 15499.690006200039, 15537.45618903378, 15648.843511564739, 15665.921670391575, 14321.856781432052, 14434.061785983877, 14512.274386280433, 14533.764630006568, 14707.852921470649, 11643.417829108681, 11722.94138529336, 11782.941085294518, 11985.22096063753, 12104.273743575413, 12569.371531423209, 12666.936665316614, 12684.175528590613, 12727.308980698572, 12759.872401275868], "value": 9016.916233707301, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1427.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 107.0, 107.0, 107.0, 107.0, 109.0, 108.0, 107.0, 107.0, 108.0], "value": 107.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 134.0, 116.0, 116.0, 116.0, 116.0, 116.0, 116.0, 146.0, 122.0], "value": 121.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 162.0, 162.0, 164.0, 164.0, 165.0, 164.0, 165.0, 165.0], "value": 163.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 74.0, 73.0, 75.0, 73.0, 75.0, 107.0, 74.0, 75.0], "value": 77.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 80.0, 79.0, 79.0, 82.0, 79.0, 79.0, 79.0, 79.0], "value": 79.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 110.0, 114.0, 114.0, 111.0, 115.0, 114.0, 113.0, 114.0, 115.0], "value": 113.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 147.0, 149.0, 148.0, 149.0, 148.0, 150.0, 149.0, 148.0, 149.0], "value": 149.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 94.0, 94.0, 93.0, 94.0, 94.0, 95.0, 94.0], "value": 94.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 52.0, 54.0, 52.0, 54.0, 53.0, 50.0, 50.0, 51.0, 54.0], "value": 52.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 38.0, 38.0, 38.0, 39.0, 38.0, 39.0, 38.0, 39.0, 38.0], "value": 38.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 73.0, 74.0, 76.0, 76.0, 78.0, 74.0, 74.0, 78.0], "value": 75.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 120.0, 117.0, 112.0, 120.0, 112.0, 111.0, 117.0, 111.0, 121.0], "value": 115.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 167.0, 177.0, 167.0, 171.0, 174.0, 173.0, 182.0, 170.0, 175.0], "value": 173.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 65.0, 65.0, 71.0, 68.0, 66.0, 67.0, 66.0, 65.0], "value": 66.5, "unit": "score"}]}]} 15:49:37 INFO - Return code: 0 15:49:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:49:37 INFO - # TBPL SUCCESS # 15:49:37 INFO - Running post-action listener: _package_coverage_data 15:49:37 INFO - Running post-action listener: _resource_record_post_action 15:49:37 INFO - [mozharness: 2017-09-22 22:49:37.411954Z] Finished run-tests step (success) 15:49:37 INFO - Running post-run listener: _resource_record_post_run 15:49:37 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 9146368; Write bytes: 328871936; Read time: 2640; Write time: 367172 15:49:37 INFO - TinderboxPrint: CPU usage
13.1% 15:49:37 INFO - TinderboxPrint: I/O read bytes / time
9,146,368 / 2,640 15:49:37 INFO - TinderboxPrint: I/O write bytes / time
328,871,936 / 367,172 15:49:37 INFO - TinderboxPrint: CPU idle
8,233.4 (86.8%) 15:49:37 INFO - TinderboxPrint: CPU user
1,192.1 (12.6%) 15:49:37 INFO - TinderboxPrint: Swap in / out
0 / 0 15:49:37 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 37834752; Read time: 0; Write time: 12804 15:49:37 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 15:49:37 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 6787072; Write bytes: 226549760; Read time: 2000; Write time: 269688 15:49:38 INFO - Running post-run listener: _upload_blobber_files 15:49:38 INFO - Blob upload gear active. 15:49:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:49:38 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 15:49:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:49:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:49:38 INFO - (blobuploader) - INFO - Open directory for files ... 15:49:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:49:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:49:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:49:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:49:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:49:44 INFO - (blobuploader) - INFO - Done attempting. 15:49:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:49:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:49:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:49:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:49:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:49:45 INFO - (blobuploader) - INFO - Done attempting. 15:49:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:49:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:49:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:49:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:49:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:49:46 INFO - (blobuploader) - INFO - Done attempting. 15:49:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 15:49:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:49:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:49:47 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 15:49:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:49:47 INFO - (blobuploader) - INFO - Done attempting. 15:49:47 INFO - (blobuploader) - INFO - Iteration through files over. 15:49:47 INFO - Return code: 0 15:49:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:49:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:49:47 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4dae0828de83e245337cabfa6f196e96e20eb1c06d882fd755abe7683a47779fa6df735ee7443d2420348dc3cadcfb24d15a101c5692ebc5dd9a874420d5c283", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9ad485fdf6e0bf69c6b395dc03e9bdff8b6f2f8eeaa79c94d8e060403e5e8ff1fac2a6946554dfce4f35d99bfcbfaaac76b4717af6898c30a97ce9a2d1311863", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2e1cba4ce3e6364447ac984be5b80b229f94d6aeac6e48ab7433d633fe378247955494fb000b47b327950e44006fb6534f6e6fb74ddeea79ce746355ef8788e2", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/68f3fa1331d9062f407fb07ded5772c0162d6669a9a4a04caa3285b28f18be43e2c855a53e183882d569a09817d5145d38bea66fff1b85bbf656fd5e9dbeafcc"} 15:49:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:49:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:49:47 INFO - Contents: 15:49:47 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4dae0828de83e245337cabfa6f196e96e20eb1c06d882fd755abe7683a47779fa6df735ee7443d2420348dc3cadcfb24d15a101c5692ebc5dd9a874420d5c283", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9ad485fdf6e0bf69c6b395dc03e9bdff8b6f2f8eeaa79c94d8e060403e5e8ff1fac2a6946554dfce4f35d99bfcbfaaac76b4717af6898c30a97ce9a2d1311863", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2e1cba4ce3e6364447ac984be5b80b229f94d6aeac6e48ab7433d633fe378247955494fb000b47b327950e44006fb6534f6e6fb74ddeea79ce746355ef8788e2", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/68f3fa1331d9062f407fb07ded5772c0162d6669a9a4a04caa3285b28f18be43e2c855a53e183882d569a09817d5145d38bea66fff1b85bbf656fd5e9dbeafcc"} 15:49:47 INFO - Running post-run listener: copy_logs_to_upload_dir 15:49:47 INFO - Copying logs to upload dir... 15:49:47 INFO - mkdir: /builds/slave/test/build/upload/logs 15:49:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.783055 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2017-09-22 15:49:47.853871) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-22 15:49:47.858126) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=03627c79f6bb3d1578c4a402000001ba-1506119346.703642-147467537 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4dae0828de83e245337cabfa6f196e96e20eb1c06d882fd755abe7683a47779fa6df735ee7443d2420348dc3cadcfb24d15a101c5692ebc5dd9a874420d5c283", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9ad485fdf6e0bf69c6b395dc03e9bdff8b6f2f8eeaa79c94d8e060403e5e8ff1fac2a6946554dfce4f35d99bfcbfaaac76b4717af6898c30a97ce9a2d1311863", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2e1cba4ce3e6364447ac984be5b80b229f94d6aeac6e48ab7433d633fe378247955494fb000b47b327950e44006fb6534f6e6fb74ddeea79ce746355ef8788e2", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/68f3fa1331d9062f407fb07ded5772c0162d6669a9a4a04caa3285b28f18be43e2c855a53e183882d569a09817d5145d38bea66fff1b85bbf656fd5e9dbeafcc"} build_url:https://queue.taskcluster.net/v1/task/e1Vm0nnPTMG3de0u1_1tzQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011080 build_url: 'https://queue.taskcluster.net/v1/task/e1Vm0nnPTMG3de0u1_1tzQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4dae0828de83e245337cabfa6f196e96e20eb1c06d882fd755abe7683a47779fa6df735ee7443d2420348dc3cadcfb24d15a101c5692ebc5dd9a874420d5c283", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9ad485fdf6e0bf69c6b395dc03e9bdff8b6f2f8eeaa79c94d8e060403e5e8ff1fac2a6946554dfce4f35d99bfcbfaaac76b4717af6898c30a97ce9a2d1311863", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2e1cba4ce3e6364447ac984be5b80b229f94d6aeac6e48ab7433d633fe378247955494fb000b47b327950e44006fb6534f6e6fb74ddeea79ce746355ef8788e2", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/68f3fa1331d9062f407fb07ded5772c0162d6669a9a4a04caa3285b28f18be43e2c855a53e183882d569a09817d5145d38bea66fff1b85bbf656fd5e9dbeafcc"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-22 15:49:47.893332) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-22 15:49:47.893880) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=03627c79f6bb3d1578c4a402000001ba-1506119346.703642-147467537 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004487 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-22 15:49:47.979117) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-22 15:49:47.979688) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-22 15:49:47.980298) ========= ========= Total master_lag: 0.21 =========