builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-035 starttime: 1506814398.89 results: success (0) revision: a5f92049b409adbb465586f6217416aa9b7b3157 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-30 16:33:18.892658) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-30 16:33:18.893311) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-30 16:33:18.893578) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-30 16:33:19.162466) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-30 16:33:19.162724) ========= 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=ed14279e38b05397cf4aa391000001a4-1506810492.856627-1940248619 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-30 16:33:19-- 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% 85.7M=0s 2017-09-30 16:33:19 (85.7 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.102391 ========= master_lag: 0.08 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-30 16:33:19.347797) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-30 16:33:19.348092) ========= 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=ed14279e38b05397cf4aa391000001a4-1506810492.856627-1940248619 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016003 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-30 16:33:19.422766) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-30 16:33:19.423104) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev a5f92049b409adbb465586f6217416aa9b7b3157 --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 a5f92049b409adbb465586f6217416aa9b7b3157 --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=ed14279e38b05397cf4aa391000001a4-1506810492.856627-1940248619 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-30 16:33:19,458 truncating revision to first 12 chars 2017-09-30 16:33:19,459 Setting DEBUG logging. 2017-09-30 16:33:19,459 attempt 1/10 2017-09-30 16:33:19,459 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/a5f92049b409?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-30 16:33:19,822 unpacking tar archive at: mozilla-central-a5f92049b409/testing/mozharness/ program finished with exit code 0 elapsedTime=0.815911 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-30 16:33:20.263135) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-30 16:33:20.263444) ========= script_repo_revision: a5f92049b409adbb465586f6217416aa9b7b3157 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-30 16:33:20.263810) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-30 16:33:20.264085) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-30 16:33:20.278682) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2017-09-30 16:33:20.278978) ========= /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=ed14279e38b05397cf4aa391000001a4-1506810492.856627-1940248619 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:33:20 INFO - MultiFileLogger online at 20170930 16:33:20 in /builds/slave/test 16:33:20 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 16:33:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:33:20 INFO - {'append_to_log': False, 16:33:20 INFO - 'base_work_dir': '/builds/slave/test', 16:33:20 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 16:33:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:33:20 INFO - 'branch': 'Firefox-Non-PGO', 16:33:20 INFO - 'buildbot_json_path': 'buildprops.json', 16:33:20 INFO - 'code_coverage': False, 16:33:20 INFO - 'config_files': ('talos/linux_config.py',), 16:33:20 INFO - 'default_actions': ('clobber', 16:33:20 INFO - 'read-buildbot-config', 16:33:20 INFO - 'download-and-extract', 16:33:20 INFO - 'populate-webroot', 16:33:20 INFO - 'create-virtualenv', 16:33:20 INFO - 'install', 16:33:20 INFO - 'setup-mitmproxy', 16:33:20 INFO - 'run-tests'), 16:33:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:33:20 INFO - 'disable_ccov_upload': False, 16:33:20 INFO - 'disable_stylo': False, 16:33:20 INFO - 'download_minidump_stackwalk': True, 16:33:20 INFO - 'download_symbols': 'ondemand', 16:33:20 INFO - 'enable_stylo': False, 16:33:20 INFO - 'enable_webrender': False, 16:33:20 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 16:33:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:33:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:33:20 INFO - 'gecko_profile': False, 16:33:20 INFO - 'gecko_profile_interval': 0, 16:33:20 INFO - 'installer_path': 'installer.exe', 16:33:20 INFO - 'log_level': 'info', 16:33:20 INFO - 'log_name': 'talos', 16:33:20 INFO - 'log_to_console': True, 16:33:20 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:33:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:33:20 INFO - 'opt_config_files': (), 16:33:20 INFO - 'pip_index': False, 16:33:20 INFO - 'suite': 'dromaeojs-e10s', 16:33:20 INFO - 'system_bits': '32', 16:33:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:33:20 INFO - 'title': 'talos-linux64-ix-035', 16:33:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:33:20 INFO - 'use_talos_json': True, 16:33:20 INFO - 'verify': 'False', 16:33:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:33:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:33:20 INFO - 'work_dir': 'build'} 16:33:20 INFO - [mozharness: 2017-09-30 23:33:20.451371Z] Running clobber step. 16:33:20 INFO - Running pre-action listener: _resource_record_pre_action 16:33:20 INFO - Running main action method: clobber 16:33:20 INFO - rmtree: /builds/slave/test/build 16:33:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:33:21 INFO - Running post-action listener: _resource_record_post_action 16:33:21 INFO - [mozharness: 2017-09-30 23:33:21.961556Z] Finished clobber step (success) 16:33:21 INFO - [mozharness: 2017-09-30 23:33:21.961652Z] Running read-buildbot-config step. 16:33:21 INFO - Running pre-action listener: _resource_record_pre_action 16:33:21 INFO - Running main action method: read_buildbot_config 16:33:21 INFO - Using buildbot properties: 16:33:21 INFO - { 16:33:21 INFO - "project": "", 16:33:21 INFO - "product": "firefox", 16:33:21 INFO - "who": "archaeopteryx@coole-files.de", 16:33:21 INFO - "installer_path": "public/build/target.tar.bz2", 16:33:21 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 16:33:21 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 16:33:21 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 16:33:21 INFO - "stage_platform": "linux64", 16:33:21 INFO - "basedir": "/builds/slave/test", 16:33:21 INFO - "buildnumber": 886, 16:33:21 INFO - "platform": "ubuntu64_hw", 16:33:21 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 16:33:21 INFO - "slavebuilddir": "test", 16:33:21 INFO - "taskId": "Z8trGXnrSwqXDxEl7slX1A", 16:33:21 INFO - "branch": "mozilla-central", 16:33:21 INFO - "script_repo_revision": "production", 16:33:21 INFO - "revision": "a5f92049b409adbb465586f6217416aa9b7b3157", 16:33:21 INFO - "slavename": "talos-linux64-ix-035", 16:33:21 INFO - "repo_path": "mozilla-central" 16:33:21 INFO - } 16:33:21 INFO - Finding installer, test and symbols from parent task. 16:33:21 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Z8trGXnrSwqXDxEl7slX1A'}, attempt #1 16:33:22 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Z8trGXnrSwqXDxEl7slX1A'}, attempt #1 16:33:23 INFO - Task dependencies: T48oeLAPQg6zMtCYv_JcQg 16:33:23 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/T48oeLAPQg6zMtCYv_JcQg'}, attempt #1 16:33:24 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/T48oeLAPQg6zMtCYv_JcQg/artifacts/public/build/target.tar.bz2 16:33:24 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/T48oeLAPQg6zMtCYv_JcQg/artifacts/public/build/target.test_packages.json 16:33:24 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/T48oeLAPQg6zMtCYv_JcQg/artifacts/public/build/target.crashreporter-symbols.zip 16:33:24 INFO - Running post-action listener: _resource_record_post_action 16:33:24 INFO - [mozharness: 2017-09-30 23:33:24.084634Z] Finished read-buildbot-config step (success) 16:33:24 INFO - [mozharness: 2017-09-30 23:33:24.084858Z] Running download-and-extract step. 16:33:24 INFO - Running pre-action listener: _resource_record_pre_action 16:33:24 INFO - Running main action method: download_and_extract 16:33:24 INFO - mkdir: /builds/slave/test/build/tests 16:33:24 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')]} 16:33:24 INFO - trying https://queue.taskcluster.net/v1/task/T48oeLAPQg6zMtCYv_JcQg/artifacts/public/build/target.test_packages.json 16:33:24 INFO - Downloading https://queue.taskcluster.net/v1/task/T48oeLAPQg6zMtCYv_JcQg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 16:33:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/T48oeLAPQg6zMtCYv_JcQg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 16:33:24 INFO - Downloaded 989 bytes. 16:33:24 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 16:33:24 INFO - Using the following test package requirements: 16:33:24 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 16:33:24 INFO - u'common': [u'target.common.tests.zip'], 16:33:24 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 16:33:24 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 16:33:24 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 16:33:24 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 16:33:24 INFO - u'mozbase': [u'target.common.tests.zip'], 16:33:24 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 16:33:24 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 16:33:24 INFO - u'web-platform': [u'target.common.tests.zip', 16:33:24 INFO - u'target.web-platform.tests.tar.gz'], 16:33:24 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 16:33:24 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 16:33:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/T48oeLAPQg6zMtCYv_JcQg/artifacts/public/build/target.common.tests.zip 16:33:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/T48oeLAPQg6zMtCYv_JcQg/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:33:24 INFO - Fetch https://queue.taskcluster.net/v1/task/T48oeLAPQg6zMtCYv_JcQg/artifacts/public/build/target.common.tests.zip into memory 16:33:27 INFO - Content-Length response header: 55366758 16:33:27 INFO - Bytes received: 55366758 16:33:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/T48oeLAPQg6zMtCYv_JcQg/artifacts/public/build/target.talos.tests.zip 16:33:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/T48oeLAPQg6zMtCYv_JcQg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 16:33:37 INFO - Fetch https://queue.taskcluster.net/v1/task/T48oeLAPQg6zMtCYv_JcQg/artifacts/public/build/target.talos.tests.zip into memory 16:33:39 INFO - Content-Length response header: 14522385 16:33:39 INFO - Bytes received: 14522385 16:33:40 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')]} 16:33:40 INFO - trying https://queue.taskcluster.net/v1/task/T48oeLAPQg6zMtCYv_JcQg/artifacts/public/build/target.tar.bz2 16:33:40 INFO - Downloading https://queue.taskcluster.net/v1/task/T48oeLAPQg6zMtCYv_JcQg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 16:33:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/T48oeLAPQg6zMtCYv_JcQg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 16:33:43 INFO - Downloaded 61659398 bytes. 16:33:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/T48oeLAPQg6zMtCYv_JcQg/artifacts/public/build/target.tar.bz2 16:33:43 INFO - mkdir: /builds/slave/test/properties 16:33:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:33:43 INFO - Writing to file /builds/slave/test/properties/build_url 16:33:43 INFO - Contents: 16:33:43 INFO - build_url:https://queue.taskcluster.net/v1/task/T48oeLAPQg6zMtCYv_JcQg/artifacts/public/build/target.tar.bz2 16:33:43 INFO - Running post-action listener: _resource_record_post_action 16:33:43 INFO - Running post-action listener: find_tests_for_verification 16:33:43 INFO - Running post-action listener: set_extra_try_arguments 16:33:43 INFO - [mozharness: 2017-09-30 23:33:43.810544Z] Finished download-and-extract step (success) 16:33:43 INFO - [mozharness: 2017-09-30 23:33:43.810674Z] Running populate-webroot step. 16:33:43 INFO - Running pre-action listener: _resource_record_pre_action 16:33:43 INFO - Running main action method: populate_webroot 16:33:43 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:33:43 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:33:43 INFO - 'tests': ['tresize', 16:33:43 INFO - 'tcanvasmark']}, 16:33:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:33:43 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:33:43 INFO - 'tests': ['dromaeo_css', 16:33:43 INFO - 'kraken']}, 16:33:43 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:33:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:33:43 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:33:43 INFO - 'talos_options': ['--disable-stylo'], 16:33:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:33:43 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:33:43 INFO - 'tests': ['damp', 'tps']}, 16:33:43 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:33:43 INFO - 'talos_options': ['--disable-stylo'], 16:33:43 INFO - 'tests': ['damp', 'tps']}, 16:33:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:33:43 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:33:43 INFO - 'tests': ['dromaeo_dom']}, 16:33:43 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 16:33:43 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:33:43 INFO - 'tests': ['basic_compositor_video', 16:33:43 INFO - 'glvideo']}, 16:33:43 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 16:33:43 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 16:33:43 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:33:43 INFO - 'talos_options': ['--disable-stylo'], 16:33:43 INFO - 'tests': ['ts_paint_webext', 16:33:43 INFO - 'tp5o_webext']}, 16:33:43 INFO - 'other-e10s': {'tests': ['a11yr', 16:33:43 INFO - 'ts_paint', 16:33:43 INFO - 'tpaint', 16:33:43 INFO - 'sessionrestore', 16:33:43 INFO - 'sessionrestore_many_windows', 16:33:43 INFO - 'sessionrestore_no_auto_restore', 16:33:43 INFO - 'tabpaint', 16:33:43 INFO - 'cpstartup']}, 16:33:43 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:33:43 INFO - 'tests': ['a11yr', 16:33:43 INFO - 'ts_paint', 16:33:43 INFO - 'tpaint', 16:33:43 INFO - 'sessionrestore', 16:33:43 INFO - 'sessionrestore_many_windows', 16:33:43 INFO - 'sessionrestore_no_auto_restore', 16:33:43 INFO - 'tabpaint', 16:33:43 INFO - 'cpstartup']}, 16:33:43 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 16:33:43 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 16:33:43 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:33:43 INFO - 'tests': ['perf_reftest_singletons']}, 16:33:43 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:33:43 INFO - 'tests': ['perf_reftest']}, 16:33:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:33:43 INFO - 'tsvgr_opacity', 16:33:43 INFO - 'tart', 16:33:43 INFO - 'tscrollx', 16:33:43 INFO - 'tsvg_static']}, 16:33:43 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:33:43 INFO - 'tests': ['tsvgx', 16:33:43 INFO - 'tsvgr_opacity', 16:33:43 INFO - 'tart', 16:33:43 INFO - 'tscrollx', 16:33:43 INFO - 'tsvg_static']}, 16:33:43 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:33:43 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:33:43 INFO - 'talos_options': ['--disable-stylo'], 16:33:43 INFO - 'tests': ['tp5o']}, 16:33:43 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:33:43 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 16:33:43 INFO - 'talos_options': ['--mitmproxy', 16:33:43 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:33:43 INFO - '--firstNonBlankPaint'], 16:33:43 INFO - 'tests': ['tp6_google', 16:33:43 INFO - 'tp6_youtube', 16:33:43 INFO - 'tp6_amazon', 16:33:43 INFO - 'tp6_facebook']}, 16:33:43 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:33:43 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 16:33:43 INFO - 'talos_options': ['--disable-stylo', 16:33:43 INFO - '--mitmproxy', 16:33:43 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:33:43 INFO - '--firstNonBlankPaint'], 16:33:43 INFO - 'tests': ['tp6_google', 16:33:43 INFO - 'tp6_youtube', 16:33:43 INFO - 'tp6_amazon', 16:33:43 INFO - 'tp6_facebook']}, 16:33:43 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:33:43 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 16:33:43 INFO - 'talos_options': ['--stylo-threads=1', 16:33:43 INFO - '--mitmproxy', 16:33:43 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:33:43 INFO - '--firstNonBlankPaint'], 16:33:43 INFO - 'tests': ['tp6_google', 16:33:43 INFO - 'tp6_youtube', 16:33:43 INFO - 'tp6_amazon', 16:33:43 INFO - 'tp6_facebook']}, 16:33:43 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:33:43 INFO - 'talos_options': ['--xperf_path', 16:33:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:33:43 INFO - 'tests': ['tp5n']}, 16:33:43 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:33:43 INFO - 'talos_options': ['--disable-stylo', 16:33:43 INFO - '--xperf_path', 16:33:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:33:43 INFO - 'tests': ['tp5n']}}} 16:33:43 INFO - Running post-action listener: _resource_record_post_action 16:33:43 INFO - [mozharness: 2017-09-30 23:33:43.829051Z] Finished populate-webroot step (success) 16:33:43 INFO - [mozharness: 2017-09-30 23:33:43.829140Z] Running create-virtualenv step. 16:33:43 INFO - Running pre-action listener: _resource_record_pre_action 16:33:43 INFO - Running main action method: create_virtualenv 16:33:43 INFO - Creating virtualenv /builds/slave/test/build/venv 16:33:43 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 16:33:43 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 16:33:43 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 16:33:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:33:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:33:45 INFO - Installing distribute.............................................................................................................................................................................................done. 16:33:46 INFO - Installing pip...............done. 16:33:46 INFO - Return code: 0 16:33:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:33:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:33:46 INFO - Reading from file tmpfile_stdout 16:33:46 INFO - Output received: 16:33:46 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 16:33:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:33:46 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')]} 16:33:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:33:46 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')]} 16:33:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:33:46 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 0x1eb41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x207cb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22219c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221ed60>, '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 0x221fa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x221fef0>, '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': 'ed14279e38b05397cf4aa391000001a4-1506810492.856627-1940248619', '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 16:33:46 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 16:33:46 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 16:33:46 INFO - Using env: {'DISPLAY': ':0', 16:33:46 INFO - 'HOME': '/home/cltbld', 16:33:46 INFO - 'LANG': 'en_US.UTF-8', 16:33:46 INFO - 'LANGUAGE': 'en_US:en', 16:33:46 INFO - 'LOGNAME': 'cltbld', 16:33:46 INFO - 'MAIL': '/var/mail/cltbld', 16:33:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:33:46 INFO - 'MOZ_NO_REMOTE': '1', 16:33:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:33:46 INFO - 'NO_EM_RESTART': '1', 16:33:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:33:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:33:46 INFO - 'PWD': '/builds/slave/test', 16:33:46 INFO - 'SHELL': '/bin/bash', 16:33:46 INFO - 'SHLVL': '1', 16:33:46 INFO - 'TERM': 'linux', 16:33:46 INFO - 'TMOUT': '86400', 16:33:46 INFO - 'USER': 'cltbld', 16:33:46 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1506810492.856627-1940248619', 16:33:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:33:46 INFO - '_': '/tools/buildbot/bin/python'} 16:33:48 INFO - Ignoring indexes: http://pypi.python.org/simple/ 16:33:48 INFO - Downloading/unpacking pip>=1.5 16:33:48 INFO - Running setup.py egg_info for package pip 16:33:48 INFO - warning: no files found matching 'pip/cacert.pem' 16:33:48 INFO - warning: no files found matching '*.html' under directory 'docs' 16:33:48 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 16:33:48 INFO - no previously-included directories found matching 'docs/_build/_sources' 16:33:48 INFO - Installing collected packages: pip 16:33:48 INFO - Found existing installation: pip 1.1 16:33:48 INFO - Uninstalling pip: 16:33:48 INFO - Successfully uninstalled pip 16:33:48 INFO - Running setup.py install for pip 16:33:48 INFO - warning: no files found matching 'pip/cacert.pem' 16:33:48 INFO - warning: no files found matching '*.html' under directory 'docs' 16:33:48 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 16:33:48 INFO - no previously-included directories found matching 'docs/_build/_sources' 16:33:48 INFO - Installing pip script to /builds/slave/test/build/venv/bin 16:33:48 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 16:33:48 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 16:33:48 INFO - Successfully installed pip 16:33:48 INFO - Cleaning up... 16:33:48 INFO - Return code: 0 16:33:48 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:33:48 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')]} 16:33:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:33:48 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')]} 16:33:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:33:48 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 0x1eb41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x207cb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22219c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221ed60>, '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 0x221fa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x221fef0>, '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': 'ed14279e38b05397cf4aa391000001a4-1506810492.856627-1940248619', '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 16:33:48 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 16:33:48 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 16:33:48 INFO - Using env: {'DISPLAY': ':0', 16:33:48 INFO - 'HOME': '/home/cltbld', 16:33:48 INFO - 'LANG': 'en_US.UTF-8', 16:33:48 INFO - 'LANGUAGE': 'en_US:en', 16:33:48 INFO - 'LOGNAME': 'cltbld', 16:33:48 INFO - 'MAIL': '/var/mail/cltbld', 16:33:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:33:48 INFO - 'MOZ_NO_REMOTE': '1', 16:33:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:33:48 INFO - 'NO_EM_RESTART': '1', 16:33:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:33:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:33:48 INFO - 'PWD': '/builds/slave/test', 16:33:48 INFO - 'SHELL': '/bin/bash', 16:33:48 INFO - 'SHLVL': '1', 16:33:48 INFO - 'TERM': 'linux', 16:33:48 INFO - 'TMOUT': '86400', 16:33:48 INFO - 'USER': 'cltbld', 16:33:48 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1506810492.856627-1940248619', 16:33:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:33:48 INFO - '_': '/tools/buildbot/bin/python'} 16:33:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:33:48 INFO - Downloading/unpacking psutil>=3.1.1 16:33:48 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 16:33:48 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 16:33:48 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 16:33:48 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 16:33:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:33:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:33:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:33:49 INFO - Installing collected packages: psutil 16:33:49 INFO - Running setup.py install for psutil 16:33:49 INFO - building 'psutil._psutil_linux' extension 16:33:49 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 16:33:49 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 16:33:49 INFO - building 'psutil._psutil_posix' extension 16:33:49 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 16:33:49 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 16:33:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:33:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:33:50 INFO - Successfully installed psutil 16:33:50 INFO - Cleaning up... 16:33:50 INFO - Return code: 0 16:33:50 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:33:50 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')]} 16:33:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:33:50 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')]} 16:33:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:33:50 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 0x1eb41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x207cb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22219c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221ed60>, '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 0x221fa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x221fef0>, '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': 'ed14279e38b05397cf4aa391000001a4-1506810492.856627-1940248619', '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 16:33:50 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 16:33:50 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 16:33:50 INFO - Using env: {'DISPLAY': ':0', 16:33:50 INFO - 'HOME': '/home/cltbld', 16:33:50 INFO - 'LANG': 'en_US.UTF-8', 16:33:50 INFO - 'LANGUAGE': 'en_US:en', 16:33:50 INFO - 'LOGNAME': 'cltbld', 16:33:50 INFO - 'MAIL': '/var/mail/cltbld', 16:33:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:33:50 INFO - 'MOZ_NO_REMOTE': '1', 16:33:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:33:50 INFO - 'NO_EM_RESTART': '1', 16:33:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:33:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:33:50 INFO - 'PWD': '/builds/slave/test', 16:33:50 INFO - 'SHELL': '/bin/bash', 16:33:50 INFO - 'SHLVL': '1', 16:33:50 INFO - 'TERM': 'linux', 16:33:50 INFO - 'TMOUT': '86400', 16:33:50 INFO - 'USER': 'cltbld', 16:33:50 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1506810492.856627-1940248619', 16:33:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:33:50 INFO - '_': '/tools/buildbot/bin/python'} 16:33:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:33:50 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:33:50 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 16:33:50 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 16:33:50 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 16:33:50 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 16:33:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:33:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:33:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:33:51 INFO - Installing collected packages: mozsystemmonitor 16:33:51 INFO - Running setup.py install for mozsystemmonitor 16:33:51 INFO - Successfully installed mozsystemmonitor 16:33:51 INFO - Cleaning up... 16:33:51 INFO - Return code: 0 16:33:51 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:33:51 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')]} 16:33:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:33:51 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')]} 16:33:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:33:51 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 0x1eb41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x207cb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22219c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221ed60>, '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 0x221fa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x221fef0>, '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': 'ed14279e38b05397cf4aa391000001a4-1506810492.856627-1940248619', '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 16:33:51 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 16:33:51 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 16:33:51 INFO - Using env: {'DISPLAY': ':0', 16:33:51 INFO - 'HOME': '/home/cltbld', 16:33:51 INFO - 'LANG': 'en_US.UTF-8', 16:33:51 INFO - 'LANGUAGE': 'en_US:en', 16:33:51 INFO - 'LOGNAME': 'cltbld', 16:33:51 INFO - 'MAIL': '/var/mail/cltbld', 16:33:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:33:51 INFO - 'MOZ_NO_REMOTE': '1', 16:33:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:33:51 INFO - 'NO_EM_RESTART': '1', 16:33:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:33:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:33:51 INFO - 'PWD': '/builds/slave/test', 16:33:51 INFO - 'SHELL': '/bin/bash', 16:33:51 INFO - 'SHLVL': '1', 16:33:51 INFO - 'TERM': 'linux', 16:33:51 INFO - 'TMOUT': '86400', 16:33:51 INFO - 'USER': 'cltbld', 16:33:51 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1506810492.856627-1940248619', 16:33:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:33:51 INFO - '_': '/tools/buildbot/bin/python'} 16:33:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:33:51 INFO - Downloading/unpacking jsonschema==2.5.1 16:33:51 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 16:33:51 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 16:33:51 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 16:33:51 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 16:33:52 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:33:52 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:33:52 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 16:33:52 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 16:33:52 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 16:33:52 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 16:33:53 INFO - Downloading functools32-3.2.3-2.tar.gz 16:33:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:33:53 INFO - warning: no files found matching '*.txt' 16:33:53 INFO - no previously-included directories found matching 'build' 16:33:53 INFO - no previously-included directories found matching 'dist' 16:33:53 INFO - no previously-included directories found matching '.git*' 16:33:53 INFO - Installing collected packages: jsonschema, functools32 16:33:53 INFO - Running setup.py install for functools32 16:33:53 INFO - warning: no files found matching '*.txt' 16:33:53 INFO - no previously-included directories found matching 'build' 16:33:53 INFO - no previously-included directories found matching 'dist' 16:33:53 INFO - no previously-included directories found matching '.git*' 16:33:53 INFO - Successfully installed jsonschema functools32 16:33:53 INFO - Cleaning up... 16:33:53 INFO - Return code: 0 16:33:53 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:33:53 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')]} 16:33:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:33:53 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')]} 16:33:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:33:53 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 0x1eb41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x207cb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22219c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221ed60>, '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 0x221fa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x221fef0>, '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': 'ed14279e38b05397cf4aa391000001a4-1506810492.856627-1940248619', '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 16:33:53 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 16:33:53 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 16:33:53 INFO - Using env: {'DISPLAY': ':0', 16:33:53 INFO - 'HOME': '/home/cltbld', 16:33:53 INFO - 'LANG': 'en_US.UTF-8', 16:33:53 INFO - 'LANGUAGE': 'en_US:en', 16:33:53 INFO - 'LOGNAME': 'cltbld', 16:33:53 INFO - 'MAIL': '/var/mail/cltbld', 16:33:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:33:53 INFO - 'MOZ_NO_REMOTE': '1', 16:33:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:33:53 INFO - 'NO_EM_RESTART': '1', 16:33:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:33:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:33:53 INFO - 'PWD': '/builds/slave/test', 16:33:53 INFO - 'SHELL': '/bin/bash', 16:33:53 INFO - 'SHLVL': '1', 16:33:53 INFO - 'TERM': 'linux', 16:33:53 INFO - 'TMOUT': '86400', 16:33:53 INFO - 'USER': 'cltbld', 16:33:53 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1506810492.856627-1940248619', 16:33:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:33:53 INFO - '_': '/tools/buildbot/bin/python'} 16:33:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:33:53 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:33:53 INFO - Cleaning up... 16:33:53 INFO - Return code: 0 16:33:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:33:53 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')]} 16:33:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:33:53 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')]} 16:33:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:33:53 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 0x1eb41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x207cb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22219c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221ed60>, '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 0x221fa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x221fef0>, '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': 'ed14279e38b05397cf4aa391000001a4-1506810492.856627-1940248619', '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 16:33:53 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 16:33:53 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 16:33:53 INFO - Using env: {'DISPLAY': ':0', 16:33:53 INFO - 'HOME': '/home/cltbld', 16:33:53 INFO - 'LANG': 'en_US.UTF-8', 16:33:53 INFO - 'LANGUAGE': 'en_US:en', 16:33:53 INFO - 'LOGNAME': 'cltbld', 16:33:53 INFO - 'MAIL': '/var/mail/cltbld', 16:33:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:33:53 INFO - 'MOZ_NO_REMOTE': '1', 16:33:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:33:53 INFO - 'NO_EM_RESTART': '1', 16:33:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:33:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:33:53 INFO - 'PWD': '/builds/slave/test', 16:33:53 INFO - 'SHELL': '/bin/bash', 16:33:53 INFO - 'SHLVL': '1', 16:33:53 INFO - 'TERM': 'linux', 16:33:53 INFO - 'TMOUT': '86400', 16:33:53 INFO - 'USER': 'cltbld', 16:33:53 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1506810492.856627-1940248619', 16:33:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:33:53 INFO - '_': '/tools/buildbot/bin/python'} 16:33:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:33:53 INFO - Downloading/unpacking blobuploader==1.2.4 16:33:53 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 16:33:53 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 16:33:53 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 16:33:53 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 16:33:55 INFO - Downloading blobuploader-1.2.4.tar.gz 16:33:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:33:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:33:55 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 16:33:55 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 16:33:55 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 16:33:55 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 16:33:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:33:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:33:56 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 16:33:56 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 16:33:56 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 16:33:56 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 16:33:56 INFO - Downloading docopt-0.6.1.tar.gz 16:33:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:33:56 INFO - Installing collected packages: blobuploader, requests, docopt 16:33:56 INFO - Running setup.py install for blobuploader 16:33:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:33:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:33:56 INFO - Running setup.py install for requests 16:33:56 INFO - Running setup.py install for docopt 16:33:57 INFO - Successfully installed blobuploader requests docopt 16:33:57 INFO - Cleaning up... 16:33:57 INFO - Return code: 0 16:33:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:33:57 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')]} 16:33:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:33:57 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')]} 16:33:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:33:57 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 0x1eb41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x207cb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22219c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221ed60>, '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 0x221fa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x221fef0>, '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': 'ed14279e38b05397cf4aa391000001a4-1506810492.856627-1940248619', '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 16:33:57 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 16:33:57 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 16:33:57 INFO - Using env: {'DISPLAY': ':0', 16:33:57 INFO - 'HOME': '/home/cltbld', 16:33:57 INFO - 'LANG': 'en_US.UTF-8', 16:33:57 INFO - 'LANGUAGE': 'en_US:en', 16:33:57 INFO - 'LOGNAME': 'cltbld', 16:33:57 INFO - 'MAIL': '/var/mail/cltbld', 16:33:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:33:57 INFO - 'MOZ_NO_REMOTE': '1', 16:33:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:33:57 INFO - 'NO_EM_RESTART': '1', 16:33:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:33:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:33:57 INFO - 'PWD': '/builds/slave/test', 16:33:57 INFO - 'SHELL': '/bin/bash', 16:33:57 INFO - 'SHLVL': '1', 16:33:57 INFO - 'TERM': 'linux', 16:33:57 INFO - 'TMOUT': '86400', 16:33:57 INFO - 'USER': 'cltbld', 16:33:57 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1506810492.856627-1940248619', 16:33:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:33:57 INFO - '_': '/tools/buildbot/bin/python'} 16:33:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:33:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:33:57 INFO - Running setup.py (path:/tmp/pip-28x_Z5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:33:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:33:57 INFO - Running setup.py (path:/tmp/pip-efKVJy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:33:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:33:57 INFO - Running setup.py (path:/tmp/pip-ZuqTEI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:33:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:33:57 INFO - Running setup.py (path:/tmp/pip-yQ7Hz5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:33:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:33:57 INFO - Running setup.py (path:/tmp/pip-_Hk6j6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:33:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:33:57 INFO - Running setup.py (path:/tmp/pip-mtrPmp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:33:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:33:57 INFO - Running setup.py (path:/tmp/pip-jhw_GQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:33:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:33:57 INFO - Running setup.py (path:/tmp/pip-WVsfyY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:33:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:33:57 INFO - Running setup.py (path:/tmp/pip-8__hAv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:33:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:33:57 INFO - Running setup.py (path:/tmp/pip-NAU5WW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:33:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:33:58 INFO - Running setup.py (path:/tmp/pip-DfAWT4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:33:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:33:58 INFO - Running setup.py (path:/tmp/pip-eGV65h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:33:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:33:58 INFO - Running setup.py (path:/tmp/pip-l4vZUV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:33:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:33:58 INFO - Running setup.py (path:/tmp/pip-vw88qH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:33:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:33:58 INFO - Running setup.py (path:/tmp/pip-LqJWUy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:33:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:33:58 INFO - Running setup.py (path:/tmp/pip-oZoKUN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:33:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:33:58 INFO - Running setup.py (path:/tmp/pip-aVoUA8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:33:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:33:58 INFO - Running setup.py install for manifestparser 16:33:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:33:58 INFO - Running setup.py install for mozcrash 16:33:58 INFO - Running setup.py install for mozdebug 16:33:58 INFO - Running setup.py install for mozdevice 16:33:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:33:58 INFO - Running setup.py install for mozfile 16:33:59 INFO - Running setup.py install for mozhttpd 16:33:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:33:59 INFO - Running setup.py install for mozinfo 16:33:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:33:59 INFO - Running setup.py install for mozInstall 16:33:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:33:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:33:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:33:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:33:59 INFO - Running setup.py install for mozleak 16:33:59 INFO - Running setup.py install for mozlog 16:33:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:33:59 INFO - Running setup.py install for moznetwork 16:33:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:33:59 INFO - Running setup.py install for mozprocess 16:33:59 INFO - Running setup.py install for mozprofile 16:33:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:33:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:33:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:33:59 INFO - Running setup.py install for mozrunner 16:33:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:33:59 INFO - Running setup.py install for mozscreenshot 16:34:00 INFO - Running setup.py install for moztest 16:34:00 INFO - Running setup.py install for mozversion 16:34:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:34:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:34:00 INFO - Cleaning up... 16:34:00 INFO - Return code: 0 16:34:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:34:00 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')]} 16:34:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:34:00 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')]} 16:34:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:34:00 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 0x1eb41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x207cb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22219c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221ed60>, '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 0x221fa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x221fef0>, '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': 'ed14279e38b05397cf4aa391000001a4-1506810492.856627-1940248619', '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 16:34:00 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 16:34:00 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 16:34:00 INFO - Using env: {'DISPLAY': ':0', 16:34:00 INFO - 'HOME': '/home/cltbld', 16:34:00 INFO - 'LANG': 'en_US.UTF-8', 16:34:00 INFO - 'LANGUAGE': 'en_US:en', 16:34:00 INFO - 'LOGNAME': 'cltbld', 16:34:00 INFO - 'MAIL': '/var/mail/cltbld', 16:34:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:34:00 INFO - 'MOZ_NO_REMOTE': '1', 16:34:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:34:00 INFO - 'NO_EM_RESTART': '1', 16:34:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:34:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:34:00 INFO - 'PWD': '/builds/slave/test', 16:34:00 INFO - 'SHELL': '/bin/bash', 16:34:00 INFO - 'SHLVL': '1', 16:34:00 INFO - 'TERM': 'linux', 16:34:00 INFO - 'TMOUT': '86400', 16:34:00 INFO - 'USER': 'cltbld', 16:34:00 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1506810492.856627-1940248619', 16:34:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:34:00 INFO - '_': '/tools/buildbot/bin/python'} 16:34:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:34:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:34:00 INFO - Running setup.py (path:/tmp/pip-XETOKw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:34:00 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)) 16:34:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:34:00 INFO - Running setup.py (path:/tmp/pip-VK3Gis-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:34:00 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)) 16:34:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:34:00 INFO - Running setup.py (path:/tmp/pip-KJTCrN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:34:00 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)) 16:34:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:34:00 INFO - Running setup.py (path:/tmp/pip-ANbTNo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:34:00 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)) 16:34:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:34:00 INFO - Running setup.py (path:/tmp/pip-aBMHYR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:34:00 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)) 16:34:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:34:00 INFO - Running setup.py (path:/tmp/pip-7FeQ7m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:34:00 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)) 16:34:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:34:00 INFO - Running setup.py (path:/tmp/pip-ZcxzH_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:34:00 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)) 16:34:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:34:00 INFO - Running setup.py (path:/tmp/pip-ZKt_VH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:34:01 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)) 16:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:34:01 INFO - Running setup.py (path:/tmp/pip-gsHwQf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:34:01 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)) 16:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:34:01 INFO - Running setup.py (path:/tmp/pip-FWOtpD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:34:01 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)) 16:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:34:01 INFO - Running setup.py (path:/tmp/pip-CZbLeU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:34:01 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)) 16:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:34:01 INFO - Running setup.py (path:/tmp/pip-xlXFRO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:34:01 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)) 16:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:34:01 INFO - Running setup.py (path:/tmp/pip-sZvnLU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:34:01 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)) 16:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:34:01 INFO - Running setup.py (path:/tmp/pip-PzhsWq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:34:01 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)) 16:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:34:01 INFO - Running setup.py (path:/tmp/pip-IG9RzT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:34:01 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)) 16:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:34:01 INFO - Running setup.py (path:/tmp/pip-djQL85-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:34:01 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)) 16:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:34:01 INFO - Running setup.py (path:/tmp/pip-fVFp2k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:34:01 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)) 16:34:01 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)) 16:34:01 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)) 16:34:01 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)) 16:34:01 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)) 16:34:01 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)) 16:34:01 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)) 16:34:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:34:01 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 16:34:01 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 16:34:01 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 16:34:01 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 16:34:02 INFO - Downloading blessings-1.6.tar.gz 16:34:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:34:02 INFO - Installing collected packages: blessings 16:34:02 INFO - Running setup.py install for blessings 16:34:03 INFO - Successfully installed blessings 16:34:03 INFO - Cleaning up... 16:34:03 INFO - Return code: 0 16:34:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:34:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:34:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:34:03 INFO - Reading from file tmpfile_stdout 16:34:03 INFO - Current package versions: 16:34:03 INFO - argparse == 1.2.1 16:34:03 INFO - blessings == 1.6 16:34:03 INFO - blobuploader == 1.2.4 16:34:03 INFO - docopt == 0.6.1 16:34:03 INFO - functools32 == 3.2.3-2 16:34:03 INFO - jsonschema == 2.5.1 16:34:03 INFO - manifestparser == 1.1 16:34:03 INFO - mozInstall == 1.13 16:34:03 INFO - mozcrash == 1.0 16:34:03 INFO - mozdebug == 0.1 16:34:03 INFO - mozdevice == 0.51 16:34:03 INFO - mozfile == 1.2 16:34:03 INFO - mozhttpd == 0.7 16:34:03 INFO - mozinfo == 0.10 16:34:03 INFO - mozleak == 0.1 16:34:03 INFO - mozlog == 3.5 16:34:03 INFO - moznetwork == 0.27 16:34:03 INFO - mozprocess == 0.25 16:34:03 INFO - mozprofile == 0.28 16:34:03 INFO - mozrunner == 6.13 16:34:03 INFO - mozscreenshot == 0.1 16:34:03 INFO - mozsystemmonitor == 0.3 16:34:03 INFO - moztest == 0.8 16:34:03 INFO - mozversion == 1.4 16:34:03 INFO - psutil == 3.1.1 16:34:03 INFO - requests == 1.2.3 16:34:03 INFO - wsgiref == 0.1.2 16:34:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:34:03 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')]} 16:34:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:34:03 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')]} 16:34:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:34:03 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 0x1eb41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x207cb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22219c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221ed60>, '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 0x221fa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x221fef0>, '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': 'ed14279e38b05397cf4aa391000001a4-1506810492.856627-1940248619', '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 16:34:03 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 16:34:03 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 16:34:03 INFO - Using env: {'DISPLAY': ':0', 16:34:03 INFO - 'HOME': '/home/cltbld', 16:34:03 INFO - 'LANG': 'en_US.UTF-8', 16:34:03 INFO - 'LANGUAGE': 'en_US:en', 16:34:03 INFO - 'LOGNAME': 'cltbld', 16:34:03 INFO - 'MAIL': '/var/mail/cltbld', 16:34:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:34:03 INFO - 'MOZ_NO_REMOTE': '1', 16:34:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:34:03 INFO - 'NO_EM_RESTART': '1', 16:34:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:34:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:34:03 INFO - 'PWD': '/builds/slave/test', 16:34:03 INFO - 'SHELL': '/bin/bash', 16:34:03 INFO - 'SHLVL': '1', 16:34:03 INFO - 'TERM': 'linux', 16:34:03 INFO - 'TMOUT': '86400', 16:34:03 INFO - 'USER': 'cltbld', 16:34:03 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1506810492.856627-1940248619', 16:34:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:34:03 INFO - '_': '/tools/buildbot/bin/python'} 16:34:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:34:03 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)) 16:34:03 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)) 16:34:03 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)) 16:34:03 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)) 16:34:03 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)) 16:34:03 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)) 16:34:03 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)) 16:34:03 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)) 16:34:03 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)) 16:34:03 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 16:34:03 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 16:34:03 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 16:34:03 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 16:34:03 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 16:34:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 16:34:04 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)) 16:34:04 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)) 16:34:04 INFO - Installing collected packages: simplejson 16:34:04 INFO - Running setup.py install for simplejson 16:34:04 INFO - building 'simplejson._speedups' extension 16:34:04 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 16:34:05 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 16:34:05 INFO - Successfully installed simplejson 16:34:05 INFO - Cleaning up... 16:34:05 INFO - Return code: 0 16:34:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:34: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')]} 16:34:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:34: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')]} 16:34:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:34:05 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 0x1eb41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x207cb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22219c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221ed60>, '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 0x221fa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x221fef0>, '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': 'ed14279e38b05397cf4aa391000001a4-1506810492.856627-1940248619', '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 16:34:05 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 16:34:05 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 16:34:05 INFO - Using env: {'DISPLAY': ':0', 16:34:05 INFO - 'HOME': '/home/cltbld', 16:34:05 INFO - 'LANG': 'en_US.UTF-8', 16:34:05 INFO - 'LANGUAGE': 'en_US:en', 16:34:05 INFO - 'LOGNAME': 'cltbld', 16:34:05 INFO - 'MAIL': '/var/mail/cltbld', 16:34:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:34:05 INFO - 'MOZ_NO_REMOTE': '1', 16:34:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:34:05 INFO - 'NO_EM_RESTART': '1', 16:34:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:34:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:34:05 INFO - 'PWD': '/builds/slave/test', 16:34:05 INFO - 'SHELL': '/bin/bash', 16:34:05 INFO - 'SHLVL': '1', 16:34:05 INFO - 'TERM': 'linux', 16:34:05 INFO - 'TMOUT': '86400', 16:34:05 INFO - 'USER': 'cltbld', 16:34:05 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1506810492.856627-1940248619', 16:34:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:34:05 INFO - '_': '/tools/buildbot/bin/python'} 16:34:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:34:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:34:05 INFO - Cleaning up... 16:34:05 INFO - Return code: 0 16:34:05 INFO - Running post-action listener: _resource_record_post_action 16:34:05 INFO - Running post-action listener: _start_resource_monitoring 16:34:05 INFO - Starting resource monitoring. 16:34:05 INFO - [mozharness: 2017-09-30 23:34:05.965334Z] Finished create-virtualenv step (success) 16:34:05 INFO - [mozharness: 2017-09-30 23:34:05.965810Z] Running install step. 16:34:05 INFO - Running pre-action listener: _resource_record_pre_action 16:34:05 INFO - Running main action method: install 16:34:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:34:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:34:06 INFO - Reading from file tmpfile_stdout 16:34:06 INFO - Detecting whether we're running mozinstall >=1.0... 16:34:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:34:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:34:06 INFO - Reading from file tmpfile_stdout 16:34:06 INFO - Output received: 16:34:06 INFO - Usage: mozinstall [options] installer 16:34:06 INFO - Options: 16:34:06 INFO - -h, --help show this help message and exit 16:34:06 INFO - -d DEST, --destination=DEST 16:34:06 INFO - Directory to install application into. [default: 16:34:06 INFO - "/builds/slave/test"] 16:34:06 INFO - --app=APP Application being installed. [default: firefox] 16:34:06 INFO - mkdir: /builds/slave/test/build/application 16:34:06 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'] 16:34:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 16:34:24 INFO - Reading from file tmpfile_stdout 16:34:24 INFO - Output received: 16:34:24 INFO - /builds/slave/test/build/application/firefox/firefox 16:34:24 INFO - Running post-action listener: _resource_record_post_action 16:34:24 INFO - [mozharness: 2017-09-30 23:34:24.086480Z] Finished install step (success) 16:34:24 INFO - [mozharness: 2017-09-30 23:34:24.086733Z] Running setup-mitmproxy step. 16:34:24 INFO - Running pre-action listener: _resource_record_pre_action 16:34:24 INFO - Running main action method: setup_mitmproxy 16:34:24 INFO - Skipping: mitmproxy is not required 16:34:24 INFO - Running post-action listener: _resource_record_post_action 16:34:24 INFO - [mozharness: 2017-09-30 23:34:24.087857Z] Finished setup-mitmproxy step (success) 16:34:24 INFO - [mozharness: 2017-09-30 23:34:24.088093Z] Running run-tests step. 16:34:24 INFO - Running pre-action listener: _resource_record_pre_action 16:34:24 INFO - Running pre-action listener: _set_gcov_prefix 16:34:24 INFO - Running main action method: run_tests 16:34:24 WARNING - Try message not found. 16:34:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:34:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:34:24 INFO - Python 2.7.3 16:34:24 INFO - Return code: 0 16:34:24 INFO - grabbing minidump binary from tooltool 16:34:24 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')]} 16:34:24 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 0x221ed60>, '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 0x221fa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x221fef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:34:24 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 16:34:24 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 16:34:24 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 16:34:24 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:34:24 INFO - Return code: 0 16:34:24 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:34:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:34:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:34:24 INFO - ENV: RUST_BACKTRACE is now full 16:34:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:34:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:34:24 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/T48oeLAPQg6zMtCYv_JcQg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-035', '--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 16:34:24 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/T48oeLAPQg6zMtCYv_JcQg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-035 --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 16:34:24 INFO - Using env: {'DISPLAY': ':0', 16:34:24 INFO - 'HOME': '/home/cltbld', 16:34:24 INFO - 'LANG': 'en_US.UTF-8', 16:34:24 INFO - 'LANGUAGE': 'en_US:en', 16:34:24 INFO - 'LOGNAME': 'cltbld', 16:34:24 INFO - 'MAIL': '/var/mail/cltbld', 16:34:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:34:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:34:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:34:24 INFO - 'MOZ_NO_REMOTE': '1', 16:34:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:34:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:34:24 INFO - 'NO_EM_RESTART': '1', 16:34:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:34:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:34:24 INFO - 'PWD': '/builds/slave/test', 16:34:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:34:24 INFO - 'RUST_BACKTRACE': 'full', 16:34:24 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:34:24 INFO - 'SHELL': '/bin/bash', 16:34:24 INFO - 'SHLVL': '1', 16:34:24 INFO - 'TERM': 'linux', 16:34:24 INFO - 'TMOUT': '86400', 16:34:24 INFO - 'USER': 'cltbld', 16:34:24 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1506810492.856627-1940248619', 16:34:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:34:24 INFO - '_': '/tools/buildbot/bin/python'} 16:34:24 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/T48oeLAPQg6zMtCYv_JcQg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-035', '--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 16:34:24 INFO - mozversion application_buildid: 20170930223431 16:34:24 INFO - mozversion application_changeset: a5f92049b409adbb465586f6217416aa9b7b3157 16:34:24 INFO - mozversion application_display_name: Nightly 16:34:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:34:24 INFO - mozversion application_name: Firefox 16:34:24 INFO - mozversion application_remotingname: firefox 16:34:24 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:34:24 INFO - mozversion application_vendor: Mozilla 16:34:24 INFO - mozversion application_version: 58.0a1 16:34:24 INFO - mozversion platform_buildid: 20170930223431 16:34:24 INFO - mozversion platform_changeset: a5f92049b409adbb465586f6217416aa9b7b3157 16:34:24 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:34:24 INFO - mozversion platform_version: 58.0a1 16:34:24 INFO - using testdate: 1506814464 16:34:24 INFO - actual date: 1506814464 16:34:24 INFO - starting webserver on 'localhost:48868' 16:34:24 INFO - SUITE-START | Running 2 tests 16:34:24 INFO - TEST-START | dromaeo_css 16:34:24 INFO - Initialising browser for dromaeo_css test... 16:34:24 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:48868/getInfo.html -profile /tmp/tmpCWhZxe/profile 16:34:24 INFO - TEST-INFO | started process 19058 (/builds/slave/test/build/application/firefox/firefox http://localhost:48868/getInfo.html) 16:34:29 INFO - TEST-INFO | 19058: exit 0 16:34:29 INFO - Browser initialized. 16:34:29 INFO - Running cycle 1/1 for dromaeo_css test... 16:34:29 INFO - Using env: {'DISPLAY': ':0', 16:34:29 INFO - 'HOME': '/home/cltbld', 16:34:29 INFO - 'JSGC_DISABLE_POISONING': '1', 16:34:29 INFO - 'LANG': 'en_US.UTF-8', 16:34:29 INFO - 'LANGUAGE': 'en_US:en', 16:34:29 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:34:29 INFO - 'LOGNAME': 'cltbld', 16:34:29 INFO - 'MAIL': '/var/mail/cltbld', 16:34:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:34:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:34:29 INFO - 'MOZ_CRASHREPORTER': '1', 16:34:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:34:29 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:34:29 INFO - 'MOZ_NO_REMOTE': '1', 16:34:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:34:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:34:29 INFO - 'NO_EM_RESTART': '1', 16:34:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:34:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:34:29 INFO - 'PWD': '/builds/slave/test', 16:34:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:34:29 INFO - 'RUST_BACKTRACE': 'full', 16:34:29 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:34:29 INFO - 'SHELL': '/bin/bash', 16:34:29 INFO - 'SHLVL': '1', 16:34:29 INFO - 'TERM': 'linux', 16:34:29 INFO - 'TMOUT': '86400', 16:34:29 INFO - 'USER': 'cltbld', 16:34:29 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1506810492.856627-1940248619', 16:34:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:34:29 INFO - '_': '/tools/buildbot/bin/python'} 16:34:29 INFO - TEST-INFO | started process 19290 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCWhZxe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:34:30 INFO - PID 19290 | 16:34:30 INFO - PID 19290 | (/builds/slave/test/build/application/firefox/firefox:19341): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:34:30 INFO - PID 19290 | 16:34:40 INFO - PID 19290 | RSS: Main: 153726976 16:34:40 INFO - PID 19290 | 16:37:37 INFO - PID 19290 | Cycle 1(1): loaded http://localhost:48868/tests/dromaeo/cssquery-dojo.html (next: http://localhost:48868/tests/dromaeo/cssquery-ext.html) 16:37:39 INFO - PID 19290 | RSS: Main: 150994944 16:37:39 INFO - PID 19290 | 16:40:41 INFO - PID 19290 | Cycle 1(1): loaded http://localhost:48868/tests/dromaeo/cssquery-ext.html (next: http://localhost:48868/tests/dromaeo/cssquery-jquery.html) 16:40:41 INFO - PID 19290 | RSS: Main: 152084480 16:40:41 INFO - PID 19290 | 16:43:43 INFO - PID 19290 | Cycle 1(1): loaded http://localhost:48868/tests/dromaeo/cssquery-jquery.html (next: http://localhost:48868/tests/dromaeo/cssquery-mootools.html) 16:43:44 INFO - PID 19290 | RSS: Main: 153423872 16:43:44 INFO - PID 19290 | 16:46:46 INFO - PID 19290 | Cycle 1(1): loaded http://localhost:48868/tests/dromaeo/cssquery-mootools.html (next: http://localhost:48868/tests/dromaeo/cssquery-prototype.html) 16:46:46 INFO - PID 19290 | RSS: Main: 152657920 16:46:46 INFO - PID 19290 | 16:49:49 INFO - PID 19290 | Cycle 1(1): loaded http://localhost:48868/tests/dromaeo/cssquery-prototype.html (next: http://localhost:48868/tests/dromaeo/cssquery-yui.html) 16:49:50 INFO - PID 19290 | RSS: Main: 155275264 16:49:50 INFO - PID 19290 | 16:52:51 INFO - PID 19290 | Cycle 1(1): loaded http://localhost:48868/tests/dromaeo/cssquery-yui.html (next: http://localhost:48868/tests/dromaeo/cssquery-dojo.html) 16:52:52 INFO - PID 19290 | RSS: Main: 155242496 16:52:52 INFO - PID 19290 | 16:52:52 INFO - PID 19290 | __start_tp_report 16:52:52 INFO - PID 19290 | _x_x_mozilla_page_load 16:52:52 INFO - PID 19290 | _x_x_mozilla_page_load_details 16:52:52 INFO - PID 19290 | |i|pagename|runs| 16:52:52 INFO - PID 19290 | |0;dojo.html;3193.2016995751064;3226.048315746854;3275.9017229483125;3277.6394596594387;3299.224682199684;6106.389361063892;6318.336574659657;6376.744930202792;6389.616623002623;6398.776042838502;6219.284782250043;6253.624782513052;6280.277768056663;6286.748530058792;6287.7799277025315;5709.71451427429;5767.423257674241;5787.768489260446;5872.119182122673;5881.206037184989;5839.883202335951;5872.471477567018;5874.471297583216;5914.674692891889;5920.88158236835;5649.350324712669;5729;5742.971285143548;5817.709114544277;5882.852928676774;5763.567732420085;5812.186293918855;5844.064949608085;5844.12338149281;5848.298204215479;5756.222909907193;5766.481016708516;5779.104238842986;5842.678652674102;5845.152452894349;6122.846928826771;6126.326104128542;6182.103594978703;6191.2570491540855;6214.937850621481;6695.497837662146;6755.2231493378395;6794.898076528856;6803.319668033207;6816.079829223041;6796.830079248009;6820.6930688119155;6887.552309099892;6913.308669133096;6927.965360173217;6254.874902501924;6294.339094395114;6305.96847015762;6353.142325786006;6380.840478988016;6929.341712537301;6943.201531823804;6947.270536593686;6954.895676564855;6978.720851165896;6521.902171467432;6626.668666566652;6631.1379520662;6657.167854018247;6673.965535342029;6755.088063111417;6796;6830.692618832164;6832.111825462658;6846.418054465326;6667.299933507009;6681.799546013627;6701.664916754143;6795.728170873209;6814.114165158498;6596.175478065242;6618.569792963538;6683.699233534406;6749.0888730020815;6834.384905358542;5265.104932161542;5266.7629956652045;5282.867928301746;5285.207218917193;5298.284731561267;4605.723656580616;4628;4650.046740418206;4659.277811939155;4666.673332866734;5838.67887266196;5891.410858914074;5897.351291357947;5902.616329938626;5913.822585322448;6739.696713647898;6763.086983257295;6825.249222585511;6831.248562658104;6841.076454678555;5655.293088363955;5704.828855134353;5772.942270577324;5788.565857560616;5856.882862342814;5788.710564471759;5814.06974884025;5816.709164541756;5818;5826.70866456684;6504.3495650435525;6538.084668146464;6631.668416579152;6634.369734875179;6642.634655094016;6722.899156512754;6729.629870357177;6753.189617245864;6827.522073455009;6843.0419741236265;6455.483561315177;6540.934590654033;6543.934560654333;6545.14913061299;6549.4760419167305;6610.735570577123;6634.701438435185;6694.966525167538;6698.598084114774;6723.394894459522;6712.429443497357;6780.220274668279;6818;6819.352161544645;6819.488538359544;6489.1888513935755;6496.6751662415745;6504.772332968323;6573.605583664996;6577.473802095917;6411.455026322815;6477.643729594917;6561.3438656134;6573;6580.30906754803;6403.07155462446;6406.87186256263;6413.454856337264;6417.101605775102;6456.160699109086;6599.241087274832;6620.271770105381;6640.800775976729;6678.565893216925;6695.430888374348;6398.776042838478;6443.710033048618;6458.38645328693;6471.094046833541;6479.611223326616 16:52:52 INFO - PID 19290 | |1;ext.html;879.7404765594149;903.6159632156334;908.3323757038576;909.1272378516619;910.094456016264;39172.60827391725;42603.57396426035;42995;43119;43231.56768432315;39034;39372.80313598428;39528.80235598825;39541.604583954155;39604.20791584167;7260.382867456259;7366.152892417387;7388.667509962064;7882.408819338572;8101.108878023413;7860.135385107634;7939;7955.880661790078;7968.04383473981;7973.362131029533;7930.881036784453;7931.524108553476;7943.602819859013;7976.401769867254;8006.479578827358;7863.646135923897;7881.684732610689;7975.561344126071;7975.680972761083;7980.4413691041655;7941.5632140232265;7970.163132871081;8011.399145064085;8012.278894899428;8013.799655008613;38027.61972380296;38258.426123608166;38308.425373619415;38320.616793831985;38374.424383634265;3588.9102772430897;3607.4769158471877;3612.6748592626527;3622.619624939396;3627.292677927805;31277.843610782027;31366.37267254665;31388;31432;31494.370112597848;5234.895302093937;5264.684118952875;5279.076161671731;5283.973580132075;5292.073887069748;187194.06402968033;188597;189540;189572;190092;42380.364294535604;43140.13719725651;49336;49517.257241141415;49518;50846.23730644043;51326.74336628293;51629.741851291255;51664.48335516672;51802;15790.763138552931;15915.124668143131;15942.681146377008;15994.360225590846;16021;12811.295378754072;12834.229946203257;12882.742345153045;12964.546240881524;12967.286799225945;14266.928665356607;14362.85637143636;14419.63950901215;14422.350994205286;14449.205293708947;5672.4894759471845;5750.654960702372;5751.13732940054;5773.855653608609;5926.407359264125;1002.0931057393071;1007.4811472091878;1017.2675673515057;1030.7577719235974;1032.0144262229703;22834.657480137812;22879.19922802694;23015.7698423017;23107;23268.41828954256;1101.9889801102047;1117.5529788084832;1119.339589642115;1122.5173175534599;1123.7977164110375;11965.521379144911;12052.578159764542;12148.785121487781;12182.634520964386;12191.817122743167;224492;226422;226670;226971;229034;52099.218511722356;52132;52215;52382.476175238524;52396;1417.6455886028493;1444.8193975753031;1456.8251809782894;1457.3514785920163;1459.64238761502;22456.438589035406;22525.887370563698;22699.432514187276;22885.42786430286;23053;35890.46164307485;36064.63935360718;36075.458868116475;36107.45838812472;36126;199274;200315;201590;202035;202206;29953.251168720955;30914.227144321572;33408.1647958803;33756.32487350288;34124;42323.153536929705;42424;42700.78649606732;42809.357859631506;43122.353164701926;31569.368612628077;31681.366372672877;31845.20386990344;31878.68121318757;31975.36049278955;31025.224369390944;31078.53382199314;31350.843245783624;31493.685063149074;31527.52708709417;35332;35671.46492802662;35840.28319433545;35936.10159746027;35941.64058359383;30346.393072138875;30863.69136308608;30922.226944326572;31092.378152437275;31270.843645781624;23533.882330588236;23544.529109417374;23599;23886.402839929142;23941.16205932784 16:52:52 INFO - PID 19290 | |2;jquery.html;564.1763025982066;575.6977586766944;579.5161040531157;594.408563479338;595.4908553187026;22178.556428871434;23052.769472305274;23085.76914230857;23113.999999999978;23146.65280020801;23944.5211095778;24591.385215369624;26503.337416564595;26610;26661.600075998827;6576.342365763421;6758.222804377486;6758.526903116784;6758.66206689666;6758.932410675904;6471.223453185601;6547.214334279893;6679.332066793306;6683.231428385725;6740.494462915276;6693.2302785179845;6703.564268322543;6712.0938673279215;6712.261651218362;6717.328267173292;6688.431483323923;6698.1292431983775;6704.93295067048;6718.2945790691765;6725.461963042945;6197;6257.092721555395;6270.561060725751;6271.184745983039;6277.089821975834;28045.15864524047;28621.28446788847;28759.56860647092;29008.41983160325;29356;2047.3448496481133;2048.8360931125476;2061.5361543652707;2067.0284966065924;2075.4915045813723;19570.80429195718;20196;20248.190072397087;20251.189952401885;20253.48098892574;2456.4350199454225;2502.67465229519;2549.120553409071;2549.6303036059667;2550.1202085280547;117591;124155.3792231033;133709;139227;143747.281263593;20526.897365513374;20724.378268651966;22194.334169974925;22402;23130.421739456313;41668.79165604213;41865;41912;42006;42054.36918446226;11297.774044519228;11373.033292170092;11425.143114266462;11425.37160456183;11470.426478676032;9316;9432.717018489457;9480.573374198178;9488.573014214377;9543.9045609543;11332.376719280353;11409.486573104065;11570.305781653127;11808.76382472363;11826.34955077479;4145.606167414091;4303.6443520291;4373.365861949999;4378.277584198635;4396.824127034947;965.6571916969375;965.9758506037406;966.6616684160629;983.2478154212033;985.610683779903;19130.71303930442;19467.318643847397;19480.610387792163;19614.70577941332;19846.305379311943;1020.2195320579763;1023.1507848485887;1027.5838285494387;1029.5109822834095;1033.9327943683786;10707.357558546511;10795.892041079645;10805.459727013618;10816.35101893873;10832;472453;478837;481743;487261;490266;111315.44342278237;116405;119295;119578.40210799064;120551;1032.7624646331235;1074.7689246811817;1133.8582677165355;1152.3028567716578;1180.1149138146218;20385.388438346872;20417;20523;20562.794372056087;20616.79383206149;57690;58223;58449.123263150235;60285.69857150686;60715.69642151761;69694.65152674205;72128.27871721426;72152;72248.63875680798;72488.63755681188;19782.90108549448;20350.89824550927;20477.180912763324;20527.28154514585;20564.252038576888;22574.435639109153;22807.657885132066;22826.885865571232;22856.200032998764;23032.769672303064;17435.215415306597;18286.542836429195;18392;18518.16668249905;18634.254629814655;15856.207189640241;16186.838131618533;16238.431654892021;16664;16820.57948551296;18274.269029239214;18317.54206144857;18668.346607868658;18871.811281887556;18970.52573685669;19708.605827883075;20010.89994550018;20098.598028039065;20159.59680806405;20179.19283228713;17979.280828766703;18306.359277425225;18382.172802223664;18517.72223416677;18521.444356669323 16:52:52 INFO - PID 19290 | |3;mootools.html;1333.4799428222993;1386.9306534673265;1405.803187553742;1411.421317259923;1411.647088227943;14875.404983800649;15478.61303467414;15525.223738813056;15556.377744890191;15557.144357060357;14991.550253492413;16082.517524474255;16089.275982580783;16093;16110.83889161108;2827.406244688613;2867.09686448768;2869.2826793301674;2871.827690338576;2873.612062371584;2708.986455067722;2747.6290700755444;2805.3547684032683;2824.449232399683;2865.3123250419853;2822.887084516617;2845.815022023562;2846.6299381080535;2847.430513897229;2852.458032973728;2699.271196776869;2712.4575084982985;2717.945641087177;2722.4146808436085;2733.289344770374;2679.3301674581353;2692.434588736348;2717.619533265344;2745.3136715821047;2804.5232310507263;15068.924655376653;15164.696706065928;15172;15177.241137943169;15190.088594684354;8031.598420078973;8346.540940248284;8362.372822038371;8366.665333386658;8367.95816020922;4684.601341410001;4902.460729319772;4906.9263961040615;5216.4522725114075;5514.448555144493;2028.148177765342;2032.6849338352579;2044.1312442212;2045.3250427358935;2070.606584748893;26389.73610263892;26436.339091522674;26459.735402645918;26679.599806002927;26748.46503069947;6883.27725588816;6924.446044316443;6967.407770339477;7058.647067646597;7154.141503019671;17366.3053477862;17393.82606173922;17411.56471088233;17413.825861741472;17420.738688919675;7775;8028.31759300466;8037.357011439188;8038.316743076904;8057.355411567178;7313.305236002571;7418.517796343328;7429.591372474566;7592.468527203043;7606.885896711554;5128.820491282787;5224.16413373866;5235.869103272448;5250.579953603703;5317.73411329432;1239.578543295266;1299.0971274963808;1488.8660020598197;1540.191399515241;1552.7981362422813;925.4632313258294;931.753085432361;943.1982814607529;944.8299306124826;946.6308139825474;8534.103919088531;8839.779005524784;8997.910020899839;9022.458652480873;9082.500462474494;1004.8492726090999;1021.320821653592;1025.369397820335;1027.445179603006;1035.4822588705647;5230.869228269247;5338.8932221356135;5407.080796264644;5407.810726624549;5528.9723551381985;21032.684509732368;21143;22257.109715611517;22284.777152228595;22300.55398892013;21700.457488562595;21781.673274900888;21868.78131218699;21929.23247686323;21935.122595096018;10116.848247276297;10754.139668826476;10830.404327761891;10848.728781780519;10869.891301087046;8988.415752976036;9207.493587852732;9211.815763684823;9242.907570924473;9253.861192082259;18693;18782.154803033623;18915.621687566447;19115.808841911403;19434.90282548578;19309.806901930802;19509.219631214593;19541.796404802677;20026;20217.6967345487;12249.142560020713;12450.439730212343;12719;12730.427130778951;12741.299228542519;13721.45114195421;13992.16047037181;14004.43982240699;14008.929955350159;14010.159390436607;10215.540300686338;10331.675373041006;10526.31578947366;10533.78932421332;10714.624988125379;10401.843972340572;10459.68620941373;10506.21203409732;10524.684259472227;10656.467176640981;9844.163246124159;9993.850092248476;10002;10015.499225038573;10023.949880250551;7603.429742769204;7615.314621684075;7648.273414025659;7687;7758.612069396395;7420.740274090381;7483.850322993619;7496.100467943882;7527.6988920444755;7528.623568821647 16:52:52 INFO - PID 19290 | |4;prototype.html;506.57813844829707;522.1254398881877;524.4519477146382;530.7088329356318;531.6347520197153;6296.009395537123;6320.304766475696;6345.143405640237;6376.5855219410705;6389.584676995998;6212.192414986057;6223.471004964584;6246.377101281258;6272.1219029335925;6316.621002739839;5187.221916712504;5190.325257716491;5190.987757387311;5195;5224.943408106263;4867.9269810952865;4986.875328116808;5094.617903657222;5109.514596113382;5207.192885102815;4937.926071050149;4993.226049962243;5005.124103281929;5041.588355260515;5050.419201791786;4929.359183306147;4942.950570494285;4980.452150263468;4980.9252861207115;5022.773975171126;4455.22033644111;4476.820927162909;4508.864734057984;4526.366308716782;4527.977360113211;6144.600600960924;6220.346863579304;6230.657313847737;6247.656378899204;6266.561340706153;5743.913841292342;5747.62640428375;5750.223719797816;5779.797707080232;5785.623934444247;6462.838429039265;6484.708188131545;6514.348565143495;6535.2484464286745;6540.967295163541;4872.634552408584;4903.41159060915;4922.704637721748;4922.950770492285;4927.359443272351;6642.634655093968;6695.799126026227;6855.725770969155;6865.588064716133;6983.266756990544;5415.296011518556;5443.611878970837;5470.8085217017215;5475.123980163155;5482.342118945672;5508.779648814003;5518.144687573433;5533.723313834292;5628.380878103405;5629.437056294338;5836.620619659709;5949.940500595025;5964.880702385928;5969;5971.373005834412;6637.4026337628875;6709.161354830646;6710.429613482811;6717.178784910252;6718.338505514506;1920.7983161768093;1947.3476385410763;1949.4444083436154;1959.157562248247;1959.1673538745977;1433.4409580263705;1453.8618831211018;1469.5664778890252;1477.1211129378219;1482.5404124721372;5756.740946657411;5762.683052432073;5775.855603609859;5787.797427090031;5803.346046376762;6697.196336439659;6725.327467253235;6737.797866064026;6747.257801641816;6749.426298764562;5632.408597097327;5754;5786.855328616818;5810.58860572866;5851.32709738377;3522.154682876091;3523.71810255179;3556.822158892045;3588.569371675416;3632.9636703632627;6483.513736469784;6558.245801732766;6585.341465853377;6621.569597976116;6639.302873198341;6820.658967051628;6834.555753875982;6855.794326170223;6863.416609588141;6977.51157418986;6534.248561415498;6552.279249282575;6580.276169621242;6582.144321238208;6664.433523150587;6685.5597706837625;6691.966540167268;6714.563553369016;6771.492138089761;6845.589264644137;6676.933230667825;6677.796327212105;6748.460123190035;6757.493188010821;6764.864702706017;6175.783847565313;6295.811125666238;6404.327545607831;6432.774852880127;6513.837154071186;5348.893022139458;5393.271908292487;5395.892082158413;5439.211314359475;5489.533389661923;6652.5074335974;6660.136141459219;6709.899351509634;6710.932890671031;6729.528932974645;6248.343923887926;6318.641492079225;6329.588576742497;6459.450946669398;6506.479481641552;6447.453255963775;6508.316626754312;6518.804435866742;6542;6579;6414.486841052611;6476.902846457213;6537.378948999837;6545.836354091186;6552.508561857784;6455.612663240033;6494.512911531559;6499.155109835691;6562.5406221564035;6610.10763546915;6377.596928675869;6393.552451328363;6422.719934423212;6441.420272175526;6465.1595292611655 16:52:52 INFO - PID 19290 | |5;yui.html;912.2838571721201;919.599974011305;924.4360939826701;930.8045310484798;940.8024314893872;19583;20388.796112038894;23175.420614484625;24847.627285590686;25082.247532574005;20353.49116272094;20479.283225087125;20501.589968200627;20502.282420115298;22781.658275125887;998.6830553116772;1018.893016233296;1019.0268293779422;1020.5713600287897;1020.9897901021006;974.1719538392346;1005.8239808033602;1005.9295849290553;1009.399407352624;1014.6753039027515;1066.003286926726;1067.2582555124193;1069.8181309177419;1070.2240875365337;1070.3524367757511;1061.75048863517;1062.755566219766;1063.2344711807486;1065.2702898514542;1065.323519565074;777.4402430250211;778.2451022508158;778.6690656471034;780.1886038519932;781.2578050851657;20834.68747968782;20875.686864697043;21016.579668406546;21024.948752562465;21028.5794284115;20226.39320820378;20787.79212207889;21001.894990525103;21079.578408431746;21083.262085827075;17866.731999020027;17879.553011174696;17922.10389480521;17923.73114403285;17993.100344982828;3772.0569857535615;3796.962030379716;3809.942850857239;3842.5773164951834;3845.4616353710503;138281;140297;141025;142262.28868855693;143719.56280437167;17075.316987320617;17123.315067397412;17219.483415497554;17262.741058884127;17358.132093395532;32003.199920002186;32131.51802722961;32247.67752322494;32500.187495312806;32508.187295317333;7826.726064587712;8205.056418511827;8212.46618969765;8228.670853165806;8248.13394593571;3885.669718073939;4030.2745505809235;4037.515498140242;4050.9392359114636;4060.634542891154;5307.787688492417;5323.920141197885;5358.089124848785;5405.324334458192;5431.782728690808;1034.7206254311445;1048.6015314180563;1057.8254587992894;1063.239783554762;1063.3672964586058;669.8827705151579;679.599036568427;695.5027155583782;699.6676578625112;702.4240123099112;11555.422228888521;12190.2076365036;12502.81245781314;12734.089886516911;13413.195208287534;624.307019208678;639.229728177545;640.1645852162972;646.9288378278386;651.5829868883918;5655.971720141373;5661;5675.971620141955;5749.281339832521;5860.677662728505;129218;131844;134110;134140;134270;109112;111880.44059779649;112308;114419;115036;94725;95186;96476.51761741146;99555.00444995458;99771.99999999854;10721.30311529748;11004.284721493077;11023.228374013743;11050.557977680803;11269.943650281695;35685.46471802873;42920;45832;46362;48614;81389.18610813816;82829;83641;85703;85792;18877.71683424722;19011.714824277922;19071.237150513825;19278;19514.804851951867;21370;21494.462638434165;21775.23786667459;21789.891050544647;22056;18295.54261143482;19086.904565477085;19178.232870685017;19236.422907312804;19354.903225483784;19593.804061959767;19734.60530789405;19986.100625471598;20048.899755501712;20150.39548813538;17632.294708211528;18041.458756237334;18095.276188952295;18142.183601738216;18159.818401815814;12719.236845789283;12887.226766394046;12891.806622900851;13129.671758206121;13227.20636761759;12829.358532073546;13207.273599952096;13297.734045319234;13436.596902092953;13537.796933045815 16:52:52 INFO - PID 19290 | __end_tp_report 16:52:52 INFO - PID 19290 | __start_cc_report 16:52:52 INFO - PID 19290 | _x_x_mozilla_cycle_collect,268 16:52:52 INFO - PID 19290 | __end_cc_report 16:52:52 INFO - PID 19290 | __startTimestamp1506815572209__endTimestamp 16:52:52 INFO - PID 19290 | 16:52:52 INFO - PID 19290 | ------- Summary: start ------- 16:52:52 INFO - PID 19290 | Number of tests: 6 16:52:52 INFO - PID 19290 | 16:52:52 INFO - PID 19290 | [#0] dojo.html Cycles:165 Average:6210.64 Median:6455.48 stddev:734.98 (11.4%) stddev-sans-first:698.08 16:52:52 INFO - PID 19290 | Values: 3193.2 3226.0 3275.9 3277.6 3299.2 6106.4 6318.3 6376.7 6389.6 6398.8 6219.3 6253.6 6280.3 6286.7 6287.8 5709.7 5767.4 5787.8 5872.1 5881.2 5839.9 5872.5 5874.5 5914.7 5920.9 5649.4 5729.0 5743.0 5817.7 5882.9 5763.6 5812.2 5844.1 5844.1 5848.3 5756.2 5766.5 5779.1 5842.7 5845.2 6122.8 6126.3 6182.1 6191.3 6214.9 6695.5 6755.2 6794.9 6803.3 6816.1 6796.8 6820.7 6887.6 6913.3 6928.0 6254.9 6294.3 6306.0 6353.1 6380.8 6929.3 6943.2 6947.3 6954.9 6978.7 6521.9 6626.7 6631.1 6657.2 6674.0 6755.1 6796.0 6830.7 6832.1 6846.4 6667.3 6681.8 6701.7 6795.7 6814.1 6596.2 6618.6 6683.7 6749.1 6834.4 5265.1 5266.8 5282.9 5285.2 5298.3 4605.7 4628.0 4650.0 4659.3 4666.7 5838.7 5891.4 5897.4 5902.6 5913.8 6739.7 6763.1 6825.2 6831.2 6841.1 5655.3 5704.8 5772.9 5788.6 5856.9 5788.7 5814.1 5816.7 5818.0 5826.7 6504.3 6538.1 6631.7 6634.4 6642.6 6722.9 6729.6 6753.2 6827.5 6843.0 6455.5 6540.9 6543.9 6545.1 6549.5 6610.7 6634.7 6695.0 6698.6 6723.4 6712.4 6780.2 6818.0 6819.4 6819.5 6489.2 6496.7 6504.8 6573.6 6577.5 6411.5 6477.6 6561.3 6573.0 6580.3 6403.1 6406.9 6413.5 6417.1 6456.2 6599.2 6620.3 6640.8 6678.6 6695.4 6398.8 6443.7 6458.4 6471.1 6479.6 16:52:52 INFO - PID 19290 | 16:52:52 INFO - PID 19290 | [#1] ext.html Cycles:180 Average:37299.56 Median:35501.73 stddev:53380.24 (150.4%) stddev-sans-first:53459.94 16:52:52 INFO - PID 19290 | Values: 879.7 903.6 908.3 909.1 910.1 39172.6 42603.6 42995.0 43119.0 43231.6 39034.0 39372.8 39528.8 39541.6 39604.2 7260.4 7366.2 7388.7 7882.4 8101.1 7860.1 7939.0 7955.9 7968.0 7973.4 7930.9 7931.5 7943.6 7976.4 8006.5 7863.6 7881.7 7975.6 7975.7 7980.4 7941.6 7970.2 8011.4 8012.3 8013.8 38027.6 38258.4 38308.4 38320.6 38374.4 3588.9 3607.5 3612.7 3622.6 3627.3 31277.8 31366.4 31388.0 31432.0 31494.4 5234.9 5264.7 5279.1 5284.0 5292.1 187194.1 188597.0 189540.0 189572.0 190092.0 42380.4 43140.1 49336.0 49517.3 49518.0 50846.2 51326.7 51629.7 51664.5 51802.0 15790.8 15915.1 15942.7 15994.4 16021.0 12811.3 12834.2 12882.7 12964.5 12967.3 14266.9 14362.9 14419.6 14422.4 14449.2 5672.5 5750.7 5751.1 5773.9 5926.4 1002.1 1007.5 1017.3 1030.8 1032.0 22834.7 22879.2 23015.8 23107.0 23268.4 1102.0 1117.6 1119.3 1122.5 1123.8 11965.5 12052.6 12148.8 12182.6 12191.8 224492.0 226422.0 226670.0 226971.0 229034.0 52099.2 52132.0 52215.0 52382.5 52396.0 1417.6 1444.8 1456.8 1457.4 1459.6 22456.4 22525.9 22699.4 22885.4 23053.0 35890.5 36064.6 36075.5 36107.5 36126.0 199274.0 200315.0 201590.0 202035.0 202206.0 29953.3 30914.2 33408.2 33756.3 34124.0 42323.2 42424.0 42700.8 42809.4 43122.4 31569.4 31681.4 31845.2 31878.7 31975.4 31025.2 31078.5 31350.8 31493.7 31527.5 35332.0 35671.5 35840.3 35936.1 35941.6 30346.4 30863.7 30922.2 31092.4 31270.8 23533.9 23544.5 23599.0 23886.4 23941.2 16:52:52 INFO - PID 19290 | 16:52:52 INFO - PID 19290 | [#2] jquery.html Cycles:180 Average:35744.24 Median:22691.05 stddev:81044.14 (357.2%) stddev-sans-first:81228.45 16:52:52 INFO - PID 19290 | Values: 564.2 575.7 579.5 594.4 595.5 22178.6 23052.8 23085.8 23114.0 23146.7 23944.5 24591.4 26503.3 26610.0 26661.6 6576.3 6758.2 6758.5 6758.7 6758.9 6471.2 6547.2 6679.3 6683.2 6740.5 6693.2 6703.6 6712.1 6712.3 6717.3 6688.4 6698.1 6704.9 6718.3 6725.5 6197.0 6257.1 6270.6 6271.2 6277.1 28045.2 28621.3 28759.6 29008.4 29356.0 2047.3 2048.8 2061.5 2067.0 2075.5 19570.8 20196.0 20248.2 20251.2 20253.5 2456.4 2502.7 2549.1 2549.6 2550.1 117591.0 124155.4 133709.0 139227.0 143747.3 20526.9 20724.4 22194.3 22402.0 23130.4 41668.8 41865.0 41912.0 42006.0 42054.4 11297.8 11373.0 11425.1 11425.4 11470.4 9316.0 9432.7 9480.6 9488.6 9543.9 11332.4 11409.5 11570.3 11808.8 11826.3 4145.6 4303.6 4373.4 4378.3 4396.8 965.7 966.0 966.7 983.2 985.6 19130.7 19467.3 19480.6 19614.7 19846.3 1020.2 1023.2 1027.6 1029.5 1033.9 10707.4 10795.9 10805.5 10816.4 10832.0 472453.0 478837.0 481743.0 487261.0 490266.0 111315.4 116405.0 119295.0 119578.4 120551.0 1032.8 1074.8 1133.9 1152.3 1180.1 20385.4 20417.0 20523.0 20562.8 20616.8 57690.0 58223.0 58449.1 60285.7 60715.7 69694.7 72128.3 72152.0 72248.6 72488.6 19782.9 20350.9 20477.2 20527.3 20564.3 22574.4 22807.7 22826.9 22856.2 23032.8 17435.2 18286.5 18392.0 18518.2 18634.3 15856.2 16186.8 16238.4 16664.0 16820.6 18274.3 18317.5 18668.3 18871.8 18970.5 19708.6 20010.9 20098.6 20159.6 20179.2 17979.3 18306.4 18382.2 18517.7 18521.4 16:52:52 INFO - PID 19290 | 16:52:52 INFO - PID 19290 | [#3] mootools.html Cycles:180 Average:9480.19 Median:26569.67 stddev:6740.54 (25.4%) stddev-sans-first:6731.66 16:52:52 INFO - PID 19290 | Values: 1333.5 1386.9 1405.8 1411.4 1411.6 14875.4 15478.6 15525.2 15556.4 15557.1 14991.6 16082.5 16089.3 16093.0 16110.8 2827.4 2867.1 2869.3 2871.8 2873.6 2709.0 2747.6 2805.4 2824.4 2865.3 2822.9 2845.8 2846.6 2847.4 2852.5 2699.3 2712.5 2717.9 2722.4 2733.3 2679.3 2692.4 2717.6 2745.3 2804.5 15068.9 15164.7 15172.0 15177.2 15190.1 8031.6 8346.5 8362.4 8366.7 8368.0 4684.6 4902.5 4906.9 5216.5 5514.4 2028.1 2032.7 2044.1 2045.3 2070.6 26389.7 26436.3 26459.7 26679.6 26748.5 6883.3 6924.4 6967.4 7058.6 7154.1 17366.3 17393.8 17411.6 17413.8 17420.7 7775.0 8028.3 8037.4 8038.3 8057.4 7313.3 7418.5 7429.6 7592.5 7606.9 5128.8 5224.2 5235.9 5250.6 5317.7 1239.6 1299.1 1488.9 1540.2 1552.8 925.5 931.8 943.2 944.8 946.6 8534.1 8839.8 8997.9 9022.5 9082.5 1004.8 1021.3 1025.4 1027.4 1035.5 5230.9 5338.9 5407.1 5407.8 5529.0 21032.7 21143.0 22257.1 22284.8 22300.6 21700.5 21781.7 21868.8 21929.2 21935.1 10116.8 10754.1 10830.4 10848.7 10869.9 8988.4 9207.5 9211.8 9242.9 9253.9 18693.0 18782.2 18915.6 19115.8 19434.9 19309.8 19509.2 19541.8 20026.0 20217.7 12249.1 12450.4 12719.0 12730.4 12741.3 13721.5 13992.2 14004.4 14008.9 14010.2 10215.5 10331.7 10526.3 10533.8 10714.6 10401.8 10459.7 10506.2 10524.7 10656.5 9844.2 9993.9 10002.0 10015.5 10023.9 7603.4 7615.3 7648.3 7687.0 7758.6 7420.7 7483.9 7496.1 7527.7 7528.6 16:52:52 INFO - PID 19290 | 16:52:52 INFO - PID 19290 | [#4] prototype.html Cycles:180 Average:5591.05 Median:6238.52 stddev:1508.25 (24.2%) stddev-sans-first:1463.41 16:52:52 INFO - PID 19290 | Values: 506.6 522.1 524.5 530.7 531.6 6296.0 6320.3 6345.1 6376.6 6389.6 6212.2 6223.5 6246.4 6272.1 6316.6 5187.2 5190.3 5191.0 5195.0 5224.9 4867.9 4986.9 5094.6 5109.5 5207.2 4937.9 4993.2 5005.1 5041.6 5050.4 4929.4 4943.0 4980.5 4980.9 5022.8 4455.2 4476.8 4508.9 4526.4 4528.0 6144.6 6220.3 6230.7 6247.7 6266.6 5743.9 5747.6 5750.2 5779.8 5785.6 6462.8 6484.7 6514.3 6535.2 6541.0 4872.6 4903.4 4922.7 4923.0 4927.4 6642.6 6695.8 6855.7 6865.6 6983.3 5415.3 5443.6 5470.8 5475.1 5482.3 5508.8 5518.1 5533.7 5628.4 5629.4 5836.6 5949.9 5964.9 5969.0 5971.4 6637.4 6709.2 6710.4 6717.2 6718.3 1920.8 1947.3 1949.4 1959.2 1959.2 1433.4 1453.9 1469.6 1477.1 1482.5 5756.7 5762.7 5775.9 5787.8 5803.3 6697.2 6725.3 6737.8 6747.3 6749.4 5632.4 5754.0 5786.9 5810.6 5851.3 3522.2 3523.7 3556.8 3588.6 3633.0 6483.5 6558.2 6585.3 6621.6 6639.3 6820.7 6834.6 6855.8 6863.4 6977.5 6534.2 6552.3 6580.3 6582.1 6664.4 6685.6 6692.0 6714.6 6771.5 6845.6 6676.9 6677.8 6748.5 6757.5 6764.9 6175.8 6295.8 6404.3 6432.8 6513.8 5348.9 5393.3 5395.9 5439.2 5489.5 6652.5 6660.1 6709.9 6710.9 6729.5 6248.3 6318.6 6329.6 6459.5 6506.5 6447.5 6508.3 6518.8 6542.0 6579.0 6414.5 6476.9 6537.4 6545.8 6552.5 6455.6 6494.5 6499.2 6562.5 6610.1 6377.6 6393.6 6422.7 6441.4 6465.2 16:52:52 INFO - PID 19290 | 16:52:52 INFO - PID 19290 | [#5] yui.html Cycles:180 Average:26300.85 Median:20371.14 stddev:37343.76 (183.3%) stddev-sans-first:37399.86 16:52:52 INFO - PID 19290 | Values: 912.3 919.6 924.4 930.8 940.8 19583.0 20388.8 23175.4 24847.6 25082.2 20353.5 20479.3 20501.6 20502.3 22781.7 998.7 1018.9 1019.0 1020.6 1021.0 974.2 1005.8 1005.9 1009.4 1014.7 1066.0 1067.3 1069.8 1070.2 1070.4 1061.8 1062.8 1063.2 1065.3 1065.3 777.4 778.2 778.7 780.2 781.3 20834.7 20875.7 21016.6 21024.9 21028.6 20226.4 20787.8 21001.9 21079.6 21083.3 17866.7 17879.6 17922.1 17923.7 17993.1 3772.1 3797.0 3809.9 3842.6 3845.5 138281.0 140297.0 141025.0 142262.3 143719.6 17075.3 17123.3 17219.5 17262.7 17358.1 32003.2 32131.5 32247.7 32500.2 32508.2 7826.7 8205.1 8212.5 8228.7 8248.1 3885.7 4030.3 4037.5 4050.9 4060.6 5307.8 5323.9 5358.1 5405.3 5431.8 1034.7 1048.6 1057.8 1063.2 1063.4 669.9 679.6 695.5 699.7 702.4 11555.4 12190.2 12502.8 12734.1 13413.2 624.3 639.2 640.2 646.9 651.6 5656.0 5661.0 5676.0 5749.3 5860.7 129218.0 131844.0 134110.0 134140.0 134270.0 109112.0 111880.4 112308.0 114419.0 115036.0 94725.0 95186.0 96476.5 99555.0 99772.0 10721.3 11004.3 11023.2 11050.6 11269.9 35685.5 42920.0 45832.0 46362.0 48614.0 81389.2 82829.0 83641.0 85703.0 85792.0 18877.7 19011.7 19071.2 19278.0 19514.8 21370.0 21494.5 21775.2 21789.9 22056.0 18295.5 19086.9 19178.2 19236.4 19354.9 19593.8 19734.6 19986.1 20048.9 20150.4 17632.3 18041.5 18095.3 18142.2 18159.8 12719.2 12887.2 12891.8 13129.7 13227.2 12829.4 13207.3 13297.7 13436.6 13537.8 16:52:52 INFO - PID 19290 | -------- Summary: end -------- 16:52:52 INFO - PID 19290 | 16:52:52 INFO - TEST-INFO | 19290: exit 0 16:52:52 INFO - TEST-OK | dromaeo_css | took 1108318ms 16:52:52 INFO - TEST-START | kraken 16:52:52 INFO - Initialising browser for kraken test... 16:52:52 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:48868/getInfo.html -profile /tmp/tmpM4atMd/profile 16:52:52 INFO - TEST-INFO | started process 19656 (/builds/slave/test/build/application/firefox/firefox http://localhost:48868/getInfo.html) 16:52:58 INFO - TEST-INFO | 19656: exit 0 16:52:58 INFO - Browser initialized. 16:52:58 INFO - Running cycle 1/1 for kraken test... 16:52:58 INFO - Using env: {'DISPLAY': ':0', 16:52:58 INFO - 'HOME': '/home/cltbld', 16:52:58 INFO - 'JSGC_DISABLE_POISONING': '1', 16:52:58 INFO - 'LANG': 'en_US.UTF-8', 16:52:58 INFO - 'LANGUAGE': 'en_US:en', 16:52:58 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:52:58 INFO - 'LOGNAME': 'cltbld', 16:52:58 INFO - 'MAIL': '/var/mail/cltbld', 16:52:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:52:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:52:58 INFO - 'MOZ_CRASHREPORTER': '1', 16:52:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:52:58 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:52:58 INFO - 'MOZ_NO_REMOTE': '1', 16:52:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:52:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:52:58 INFO - 'NO_EM_RESTART': '1', 16:52:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:52:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:52:58 INFO - 'PWD': '/builds/slave/test', 16:52:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:52:58 INFO - 'RUST_BACKTRACE': 'full', 16:52:58 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:52:58 INFO - 'SHELL': '/bin/bash', 16:52:58 INFO - 'SHLVL': '1', 16:52:58 INFO - 'TERM': 'linux', 16:52:58 INFO - 'TMOUT': '86400', 16:52:58 INFO - 'USER': 'cltbld', 16:52:58 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1506810492.856627-1940248619', 16:52:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:52:58 INFO - '_': '/tools/buildbot/bin/python'} 16:52:58 INFO - TEST-INFO | started process 19893 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpM4atMd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:52:59 INFO - PID 19893 | 16:52:59 INFO - PID 19893 | (/builds/slave/test/build/application/firefox/firefox:19941): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:52:59 INFO - PID 19893 | 16:53:09 INFO - PID 19893 | RSS: Main: 152199168 16:53:09 INFO - PID 19893 | 16:53:12 INFO - PID 19893 | Cycle 1(1): loaded http://localhost:48868/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:48868/tests/kraken/driver.html?testName=audio-beat-detection) 16:53:13 INFO - PID 19893 | RSS: Main: 158781440 16:53:13 INFO - PID 19893 | 16:53:15 INFO - PID 19893 | Cycle 1(1): loaded http://localhost:48868/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:48868/tests/kraken/driver.html?testName=audio-dft) 16:53:16 INFO - PID 19893 | RSS: Main: 159387648 16:53:16 INFO - PID 19893 | 16:53:19 INFO - PID 19893 | Cycle 1(1): loaded http://localhost:48868/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:48868/tests/kraken/driver.html?testName=audio-fft) 16:53:19 INFO - PID 19893 | RSS: Main: 155377664 16:53:19 INFO - PID 19893 | 16:53:21 INFO - PID 19893 | Cycle 1(1): loaded http://localhost:48868/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:48868/tests/kraken/driver.html?testName=audio-oscillator) 16:53:22 INFO - PID 19893 | RSS: Main: 156225536 16:53:22 INFO - PID 19893 | 16:53:24 INFO - PID 19893 | Cycle 1(1): loaded http://localhost:48868/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:48868/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:53:25 INFO - PID 19893 | RSS: Main: 155754496 16:53:25 INFO - PID 19893 | 16:53:28 INFO - PID 19893 | Cycle 1(1): loaded http://localhost:48868/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:48868/tests/kraken/driver.html?testName=imaging-darkroom) 16:53:28 INFO - PID 19893 | RSS: Main: 158588928 16:53:28 INFO - PID 19893 | 16:53:32 INFO - PID 19893 | Cycle 1(1): loaded http://localhost:48868/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:48868/tests/kraken/driver.html?testName=imaging-desaturate) 16:53:33 INFO - PID 19893 | RSS: Main: 158801920 16:53:33 INFO - PID 19893 | 16:53:36 INFO - PID 19893 | Cycle 1(1): loaded http://localhost:48868/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:48868/tests/kraken/driver.html?testName=json-parse-financial) 16:53:36 INFO - PID 19893 | RSS: Main: 158183424 16:53:36 INFO - PID 19893 | 16:53:38 INFO - PID 19893 | Cycle 1(1): loaded http://localhost:48868/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:48868/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:53:38 INFO - PID 19893 | RSS: Main: 157962240 16:53:38 INFO - PID 19893 | 16:53:40 INFO - PID 19893 | Cycle 1(1): loaded http://localhost:48868/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:48868/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:53:41 INFO - PID 19893 | RSS: Main: 158224384 16:53:41 INFO - PID 19893 | 16:53:43 INFO - PID 19893 | Cycle 1(1): loaded http://localhost:48868/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:48868/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:53:43 INFO - PID 19893 | RSS: Main: 155348992 16:53:43 INFO - PID 19893 | 16:53:46 INFO - PID 19893 | Cycle 1(1): loaded http://localhost:48868/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:48868/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:53:47 INFO - PID 19893 | RSS: Main: 155656192 16:53:47 INFO - PID 19893 | 16:53:50 INFO - PID 19893 | Cycle 1(1): loaded http://localhost:48868/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:48868/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:53:50 INFO - PID 19893 | RSS: Main: 155901952 16:53:50 INFO - PID 19893 | 16:53:52 INFO - PID 19893 | Cycle 1(1): loaded http://localhost:48868/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:48868/tests/kraken/driver.html?testName=ai-astar) 16:53:52 INFO - PID 19893 | RSS: Main: 157147136 16:53:52 INFO - PID 19893 | 16:53:52 INFO - PID 19893 | __start_tp_report 16:53:52 INFO - PID 19893 | _x_x_mozilla_page_load 16:53:52 INFO - PID 19893 | _x_x_mozilla_page_load_details 16:53:52 INFO - PID 19893 | |i|pagename|runs| 16:53:52 INFO - PID 19893 | |0;ai-astar;110;109;112;108;109;112;108;107;108;110 16:53:52 INFO - PID 19893 | |1;audio-beat-detection;119;118;122;120;116;122;116;117;120;117 16:53:52 INFO - PID 19893 | |2;audio-dft;164;162;163;162;162;165;165;165;168;166 16:53:52 INFO - PID 19893 | |3;audio-fft;74;75;73;73;76;75;75;75;75;76 16:53:52 INFO - PID 19893 | |4;audio-oscillator;80;81;79;78;77;78;78;79;78;79 16:53:52 INFO - PID 19893 | |5;imaging-gaussian-blur;120;111;111;112;111;112;111;111;112;112 16:53:52 INFO - PID 19893 | |6;imaging-darkroom;147;148;148;149;147;147;148;148;148;148 16:53:52 INFO - PID 19893 | |7;imaging-desaturate;94;95;94;94;94;94;93;95;94;94 16:53:52 INFO - PID 19893 | |8;json-parse-financial;50;53;53;52;52;52;53;51;49;49 16:53:52 INFO - PID 19893 | |9;json-stringify-tinderbox;38;38;37;38;37;38;37;38;37;37 16:53:52 INFO - PID 19893 | |10;stanford-crypto-aes;76;74;75;73;74;75;77;74;75;73 16:53:52 INFO - PID 19893 | |11;stanford-crypto-ccm;108;112;118;111;110;120;110;114;111;111 16:53:52 INFO - PID 19893 | |12;stanford-crypto-pbkdf2;182;176;160;168;165;160;168;171;164;167 16:53:52 INFO - PID 19893 | |13;stanford-crypto-sha256-iterative;66;61;68;65;66;63;70;67;63;62 16:53:52 INFO - PID 19893 | __end_tp_report 16:53:52 INFO - PID 19893 | __start_cc_report 16:53:52 INFO - PID 19893 | _x_x_mozilla_cycle_collect,726 16:53:52 INFO - PID 19893 | __end_cc_report 16:53:52 INFO - PID 19893 | __startTimestamp1506815632643__endTimestamp 16:53:52 INFO - PID 19893 | 16:53:52 INFO - PID 19893 | ------- Summary: start ------- 16:53:52 INFO - PID 19893 | Number of tests: 14 16:53:52 INFO - PID 19893 | 16:53:52 INFO - PID 19893 | [#0] ai-astar Cycles:10 Average:109.30 Median:109.50 stddev:1.70 (1.6%) stddev-sans-first:1.79 16:53:52 INFO - PID 19893 | Values: 110.0 109.0 112.0 108.0 109.0 112.0 108.0 107.0 108.0 110.0 16:53:52 INFO - PID 19893 | 16:53:52 INFO - PID 19893 | [#1] audio-beat-detection Cycles:10 Average:118.70 Median:119.50 stddev:2.26 (1.9%) stddev-sans-first:2.40 16:53:52 INFO - PID 19893 | Values: 119.0 118.0 122.0 120.0 116.0 122.0 116.0 117.0 120.0 117.0 16:53:52 INFO - PID 19893 | 16:53:52 INFO - PID 19893 | [#2] audio-dft Cycles:10 Average:164.20 Median:165.00 stddev:1.99 (1.2%) stddev-sans-first:2.11 16:53:52 INFO - PID 19893 | Values: 164.0 162.0 163.0 162.0 162.0 165.0 165.0 165.0 168.0 166.0 16:53:52 INFO - PID 19893 | 16:53:52 INFO - PID 19893 | [#3] audio-fft Cycles:10 Average:74.70 Median:75.00 stddev:1.06 (1.4%) stddev-sans-first:1.09 16:53:52 INFO - PID 19893 | Values: 74.0 75.0 73.0 73.0 76.0 75.0 75.0 75.0 75.0 76.0 16:53:52 INFO - PID 19893 | 16:53:52 INFO - PID 19893 | [#4] audio-oscillator Cycles:10 Average:78.70 Median:79.00 stddev:1.16 (1.5%) stddev-sans-first:1.13 16:53:52 INFO - PID 19893 | Values: 80.0 81.0 79.0 78.0 77.0 78.0 78.0 79.0 78.0 79.0 16:53:52 INFO - PID 19893 | 16:53:52 INFO - PID 19893 | [#5] imaging-gaussian-blur Cycles:10 Average:112.30 Median:112.00 stddev:2.75 (2.5%) stddev-sans-first:0.53 16:53:52 INFO - PID 19893 | Values: 120.0 111.0 111.0 112.0 111.0 112.0 111.0 111.0 112.0 112.0 16:53:52 INFO - PID 19893 | 16:53:52 INFO - PID 19893 | [#6] imaging-darkroom Cycles:10 Average:147.80 Median:148.00 stddev:0.63 (0.4%) stddev-sans-first:0.60 16:53:52 INFO - PID 19893 | Values: 147.0 148.0 148.0 149.0 147.0 147.0 148.0 148.0 148.0 148.0 16:53:52 INFO - PID 19893 | 16:53:52 INFO - PID 19893 | [#7] imaging-desaturate Cycles:10 Average:94.10 Median:94.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 16:53:52 INFO - PID 19893 | Values: 94.0 95.0 94.0 94.0 94.0 94.0 93.0 95.0 94.0 94.0 16:53:52 INFO - PID 19893 | 16:53:52 INFO - PID 19893 | [#8] json-parse-financial Cycles:10 Average:51.40 Median:52.00 stddev:1.58 (3.0%) stddev-sans-first:1.59 16:53:52 INFO - PID 19893 | Values: 50.0 53.0 53.0 52.0 52.0 52.0 53.0 51.0 49.0 49.0 16:53:52 INFO - PID 19893 | 16:53:52 INFO - PID 19893 | [#9] json-stringify-tinderbox Cycles:10 Average:37.50 Median:38.00 stddev:0.53 (1.4%) stddev-sans-first:0.53 16:53:52 INFO - PID 19893 | Values: 38.0 38.0 37.0 38.0 37.0 38.0 37.0 38.0 37.0 37.0 16:53:52 INFO - PID 19893 | 16:53:52 INFO - PID 19893 | [#10] stanford-crypto-aes Cycles:10 Average:74.60 Median:75.00 stddev:1.26 (1.7%) stddev-sans-first:1.24 16:53:52 INFO - PID 19893 | Values: 76.0 74.0 75.0 73.0 74.0 75.0 77.0 74.0 75.0 73.0 16:53:52 INFO - PID 19893 | 16:53:52 INFO - PID 19893 | [#11] stanford-crypto-ccm Cycles:10 Average:112.50 Median:111.50 stddev:3.78 (3.4%) stddev-sans-first:3.64 16:53:52 INFO - PID 19893 | Values: 108.0 112.0 118.0 111.0 110.0 120.0 110.0 114.0 111.0 111.0 16:53:52 INFO - PID 19893 | 16:53:52 INFO - PID 19893 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.10 Median:168.00 stddev:6.85 (4.1%) stddev-sans-first:5.10 16:53:52 INFO - PID 19893 | Values: 182.0 176.0 160.0 168.0 165.0 160.0 168.0 171.0 164.0 167.0 16:53:52 INFO - PID 19893 | 16:53:52 INFO - PID 19893 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.10 Median:66.00 stddev:2.85 (4.3%) stddev-sans-first:3.00 16:53:52 INFO - PID 19893 | Values: 66.0 61.0 68.0 65.0 66.0 63.0 70.0 67.0 63.0 62.0 16:53:52 INFO - PID 19893 | -------- Summary: end -------- 16:53:52 INFO - PID 19893 | 16:53:52 INFO - PID 19893 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 16:53:53 INFO - TEST-INFO | 19893: exit 0 16:53:53 INFO - TEST-OK | kraken | took 60492ms 16:53:53 INFO - SUITE-END | took 1168s 16:53:53 INFO - Completed test suite (00:19:28) 16:53:53 INFO - javascript benchmark 16:53:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8764.743705729721, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3193.2016995751064, 3226.048315746854, 3275.9017229483125, 3277.6394596594387, 3299.224682199684, 6106.389361063892, 6318.336574659657, 6376.744930202792, 6389.616623002623, 6398.776042838502, 6219.284782250043, 6253.624782513052, 6280.277768056663, 6286.748530058792, 6287.7799277025315, 5709.71451427429, 5767.423257674241, 5787.768489260446, 5872.119182122673, 5881.206037184989, 5839.883202335951, 5872.471477567018, 5874.471297583216, 5914.674692891889, 5920.88158236835, 5649.350324712669, 5729.0, 5742.971285143548, 5817.709114544277, 5882.852928676774, 5763.567732420085, 5812.186293918855, 5844.064949608085, 5844.12338149281, 5848.298204215479, 5756.222909907193, 5766.481016708516, 5779.104238842986, 5842.678652674102, 5845.152452894349, 6122.846928826771, 6126.326104128542, 6182.103594978703, 6191.2570491540855, 6214.937850621481, 6695.497837662146, 6755.2231493378395, 6794.898076528856, 6803.319668033207, 6816.079829223041, 6796.830079248009, 6820.6930688119155, 6887.552309099892, 6913.308669133096, 6927.965360173217, 6254.874902501924, 6294.339094395114, 6305.96847015762, 6353.142325786006, 6380.840478988016, 6929.341712537301, 6943.201531823804, 6947.270536593686, 6954.895676564855, 6978.720851165896, 6521.902171467432, 6626.668666566652, 6631.1379520662, 6657.167854018247, 6673.965535342029, 6755.088063111417, 6796.0, 6830.692618832164, 6832.111825462658, 6846.418054465326, 6667.299933507009, 6681.799546013627, 6701.664916754143, 6795.728170873209, 6814.114165158498, 6596.175478065242, 6618.569792963538, 6683.699233534406, 6749.0888730020815, 6834.384905358542, 5265.104932161542, 5266.7629956652045, 5282.867928301746, 5285.207218917193, 5298.284731561267, 4605.723656580616, 4628.0, 4650.046740418206, 4659.277811939155, 4666.673332866734, 5838.67887266196, 5891.410858914074, 5897.351291357947, 5902.616329938626, 5913.822585322448, 6739.696713647898, 6763.086983257295, 6825.249222585511, 6831.248562658104, 6841.076454678555, 5655.293088363955, 5704.828855134353, 5772.942270577324, 5788.565857560616, 5856.882862342814, 5788.710564471759, 5814.06974884025, 5816.709164541756, 5818.0, 5826.70866456684, 6504.3495650435525, 6538.084668146464, 6631.668416579152, 6634.369734875179, 6642.634655094016, 6722.899156512754, 6729.629870357177, 6753.189617245864, 6827.522073455009, 6843.0419741236265, 6455.483561315177, 6540.934590654033, 6543.934560654333, 6545.14913061299, 6549.4760419167305, 6610.735570577123, 6634.701438435185, 6694.966525167538, 6698.598084114774, 6723.394894459522, 6712.429443497357, 6780.220274668279, 6818.0, 6819.352161544645, 6819.488538359544, 6489.1888513935755, 6496.6751662415745, 6504.772332968323, 6573.605583664996, 6577.473802095917, 6411.455026322815, 6477.643729594917, 6561.3438656134, 6573.0, 6580.30906754803, 6403.07155462446, 6406.87186256263, 6413.454856337264, 6417.101605775102, 6456.160699109086, 6599.241087274832, 6620.271770105381, 6640.800775976729, 6678.565893216925, 6695.430888374348, 6398.776042838478, 6443.710033048618, 6458.38645328693, 6471.094046833541, 6479.611223326616], "value": 6156.083963887476, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [879.7404765594149, 903.6159632156334, 908.3323757038576, 909.1272378516619, 910.094456016264, 39172.60827391725, 42603.57396426035, 42995.0, 43119.0, 43231.56768432315, 39034.0, 39372.80313598428, 39528.80235598825, 39541.604583954155, 39604.20791584167, 7260.382867456259, 7366.152892417387, 7388.667509962064, 7882.408819338572, 8101.108878023413, 7860.135385107634, 7939.0, 7955.880661790078, 7968.04383473981, 7973.362131029533, 7930.881036784453, 7931.524108553476, 7943.602819859013, 7976.401769867254, 8006.479578827358, 7863.646135923897, 7881.684732610689, 7975.561344126071, 7975.680972761083, 7980.4413691041655, 7941.5632140232265, 7970.163132871081, 8011.399145064085, 8012.278894899428, 8013.799655008613, 38027.61972380296, 38258.426123608166, 38308.425373619415, 38320.616793831985, 38374.424383634265, 3588.9102772430897, 3607.4769158471877, 3612.6748592626527, 3622.619624939396, 3627.292677927805, 31277.843610782027, 31366.37267254665, 31388.0, 31432.0, 31494.370112597848, 5234.895302093937, 5264.684118952875, 5279.076161671731, 5283.973580132075, 5292.073887069748, 187194.06402968033, 188597.0, 189540.0, 189572.0, 190092.0, 42380.364294535604, 43140.13719725651, 49336.0, 49517.257241141415, 49518.0, 50846.23730644043, 51326.74336628293, 51629.741851291255, 51664.48335516672, 51802.0, 15790.763138552931, 15915.124668143131, 15942.681146377008, 15994.360225590846, 16021.0, 12811.295378754072, 12834.229946203257, 12882.742345153045, 12964.546240881524, 12967.286799225945, 14266.928665356607, 14362.85637143636, 14419.63950901215, 14422.350994205286, 14449.205293708947, 5672.4894759471845, 5750.654960702372, 5751.13732940054, 5773.855653608609, 5926.407359264125, 1002.0931057393071, 1007.4811472091878, 1017.2675673515057, 1030.7577719235974, 1032.0144262229703, 22834.657480137812, 22879.19922802694, 23015.7698423017, 23107.0, 23268.41828954256, 1101.9889801102047, 1117.5529788084832, 1119.339589642115, 1122.5173175534599, 1123.7977164110375, 11965.521379144911, 12052.578159764542, 12148.785121487781, 12182.634520964386, 12191.817122743167, 224492.0, 226422.0, 226670.0, 226971.0, 229034.0, 52099.218511722356, 52132.0, 52215.0, 52382.476175238524, 52396.0, 1417.6455886028493, 1444.8193975753031, 1456.8251809782894, 1457.3514785920163, 1459.64238761502, 22456.438589035406, 22525.887370563698, 22699.432514187276, 22885.42786430286, 23053.0, 35890.46164307485, 36064.63935360718, 36075.458868116475, 36107.45838812472, 36126.0, 199274.0, 200315.0, 201590.0, 202035.0, 202206.0, 29953.251168720955, 30914.227144321572, 33408.1647958803, 33756.32487350288, 34124.0, 42323.153536929705, 42424.0, 42700.78649606732, 42809.357859631506, 43122.353164701926, 31569.368612628077, 31681.366372672877, 31845.20386990344, 31878.68121318757, 31975.36049278955, 31025.224369390944, 31078.53382199314, 31350.843245783624, 31493.685063149074, 31527.52708709417, 35332.0, 35671.46492802662, 35840.28319433545, 35936.10159746027, 35941.64058359383, 30346.393072138875, 30863.69136308608, 30922.226944326572, 31092.378152437275, 31270.843645781624, 23533.882330588236, 23544.529109417374, 23599.0, 23886.402839929142, 23941.16205932784], "value": 17134.54619707101, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [564.1763025982066, 575.6977586766944, 579.5161040531157, 594.408563479338, 595.4908553187026, 22178.556428871434, 23052.769472305274, 23085.76914230857, 23113.999999999978, 23146.65280020801, 23944.5211095778, 24591.385215369624, 26503.337416564595, 26610.0, 26661.600075998827, 6576.342365763421, 6758.222804377486, 6758.526903116784, 6758.66206689666, 6758.932410675904, 6471.223453185601, 6547.214334279893, 6679.332066793306, 6683.231428385725, 6740.494462915276, 6693.2302785179845, 6703.564268322543, 6712.0938673279215, 6712.261651218362, 6717.328267173292, 6688.431483323923, 6698.1292431983775, 6704.93295067048, 6718.2945790691765, 6725.461963042945, 6197.0, 6257.092721555395, 6270.561060725751, 6271.184745983039, 6277.089821975834, 28045.15864524047, 28621.28446788847, 28759.56860647092, 29008.41983160325, 29356.0, 2047.3448496481133, 2048.8360931125476, 2061.5361543652707, 2067.0284966065924, 2075.4915045813723, 19570.80429195718, 20196.0, 20248.190072397087, 20251.189952401885, 20253.48098892574, 2456.4350199454225, 2502.67465229519, 2549.120553409071, 2549.6303036059667, 2550.1202085280547, 117591.0, 124155.3792231033, 133709.0, 139227.0, 143747.281263593, 20526.897365513374, 20724.378268651966, 22194.334169974925, 22402.0, 23130.421739456313, 41668.79165604213, 41865.0, 41912.0, 42006.0, 42054.36918446226, 11297.774044519228, 11373.033292170092, 11425.143114266462, 11425.37160456183, 11470.426478676032, 9316.0, 9432.717018489457, 9480.573374198178, 9488.573014214377, 9543.9045609543, 11332.376719280353, 11409.486573104065, 11570.305781653127, 11808.76382472363, 11826.34955077479, 4145.606167414091, 4303.6443520291, 4373.365861949999, 4378.277584198635, 4396.824127034947, 965.6571916969375, 965.9758506037406, 966.6616684160629, 983.2478154212033, 985.610683779903, 19130.71303930442, 19467.318643847397, 19480.610387792163, 19614.70577941332, 19846.305379311943, 1020.2195320579763, 1023.1507848485887, 1027.5838285494387, 1029.5109822834095, 1033.9327943683786, 10707.357558546511, 10795.892041079645, 10805.459727013618, 10816.35101893873, 10832.0, 472453.0, 478837.0, 481743.0, 487261.0, 490266.0, 111315.44342278237, 116405.0, 119295.0, 119578.40210799064, 120551.0, 1032.7624646331235, 1074.7689246811817, 1133.8582677165355, 1152.3028567716578, 1180.1149138146218, 20385.388438346872, 20417.0, 20523.0, 20562.794372056087, 20616.79383206149, 57690.0, 58223.0, 58449.123263150235, 60285.69857150686, 60715.69642151761, 69694.65152674205, 72128.27871721426, 72152.0, 72248.63875680798, 72488.63755681188, 19782.90108549448, 20350.89824550927, 20477.180912763324, 20527.28154514585, 20564.252038576888, 22574.435639109153, 22807.657885132066, 22826.885865571232, 22856.200032998764, 23032.769672303064, 17435.215415306597, 18286.542836429195, 18392.0, 18518.16668249905, 18634.254629814655, 15856.207189640241, 16186.838131618533, 16238.431654892021, 16664.0, 16820.57948551296, 18274.269029239214, 18317.54206144857, 18668.346607868658, 18871.811281887556, 18970.52573685669, 19708.605827883075, 20010.89994550018, 20098.598028039065, 20159.59680806405, 20179.19283228713, 17979.280828766703, 18306.359277425225, 18382.172802223664, 18517.72223416677, 18521.444356669323], "value": 12884.801997601626, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1333.4799428222993, 1386.9306534673265, 1405.803187553742, 1411.421317259923, 1411.647088227943, 14875.404983800649, 15478.61303467414, 15525.223738813056, 15556.377744890191, 15557.144357060357, 14991.550253492413, 16082.517524474255, 16089.275982580783, 16093.0, 16110.83889161108, 2827.406244688613, 2867.09686448768, 2869.2826793301674, 2871.827690338576, 2873.612062371584, 2708.986455067722, 2747.6290700755444, 2805.3547684032683, 2824.449232399683, 2865.3123250419853, 2822.887084516617, 2845.815022023562, 2846.6299381080535, 2847.430513897229, 2852.458032973728, 2699.271196776869, 2712.4575084982985, 2717.945641087177, 2722.4146808436085, 2733.289344770374, 2679.3301674581353, 2692.434588736348, 2717.619533265344, 2745.3136715821047, 2804.5232310507263, 15068.924655376653, 15164.696706065928, 15172.0, 15177.241137943169, 15190.088594684354, 8031.598420078973, 8346.540940248284, 8362.372822038371, 8366.665333386658, 8367.95816020922, 4684.601341410001, 4902.460729319772, 4906.9263961040615, 5216.4522725114075, 5514.448555144493, 2028.148177765342, 2032.6849338352579, 2044.1312442212, 2045.3250427358935, 2070.606584748893, 26389.73610263892, 26436.339091522674, 26459.735402645918, 26679.599806002927, 26748.46503069947, 6883.27725588816, 6924.446044316443, 6967.407770339477, 7058.647067646597, 7154.141503019671, 17366.3053477862, 17393.82606173922, 17411.56471088233, 17413.825861741472, 17420.738688919675, 7775.0, 8028.31759300466, 8037.357011439188, 8038.316743076904, 8057.355411567178, 7313.305236002571, 7418.517796343328, 7429.591372474566, 7592.468527203043, 7606.885896711554, 5128.820491282787, 5224.16413373866, 5235.869103272448, 5250.579953603703, 5317.73411329432, 1239.578543295266, 1299.0971274963808, 1488.8660020598197, 1540.191399515241, 1552.7981362422813, 925.4632313258294, 931.753085432361, 943.1982814607529, 944.8299306124826, 946.6308139825474, 8534.103919088531, 8839.779005524784, 8997.910020899839, 9022.458652480873, 9082.500462474494, 1004.8492726090999, 1021.320821653592, 1025.369397820335, 1027.445179603006, 1035.4822588705647, 5230.869228269247, 5338.8932221356135, 5407.080796264644, 5407.810726624549, 5528.9723551381985, 21032.684509732368, 21143.0, 22257.109715611517, 22284.777152228595, 22300.55398892013, 21700.457488562595, 21781.673274900888, 21868.78131218699, 21929.23247686323, 21935.122595096018, 10116.848247276297, 10754.139668826476, 10830.404327761891, 10848.728781780519, 10869.891301087046, 8988.415752976036, 9207.493587852732, 9211.815763684823, 9242.907570924473, 9253.861192082259, 18693.0, 18782.154803033623, 18915.621687566447, 19115.808841911403, 19434.90282548578, 19309.806901930802, 19509.219631214593, 19541.796404802677, 20026.0, 20217.6967345487, 12249.142560020713, 12450.439730212343, 12719.0, 12730.427130778951, 12741.299228542519, 13721.45114195421, 13992.16047037181, 14004.43982240699, 14008.929955350159, 14010.159390436607, 10215.540300686338, 10331.675373041006, 10526.31578947366, 10533.78932421332, 10714.624988125379, 10401.843972340572, 10459.68620941373, 10506.21203409732, 10524.684259472227, 10656.467176640981, 9844.163246124159, 9993.850092248476, 10002.0, 10015.499225038573, 10023.949880250551, 7603.429742769204, 7615.314621684075, 7648.273414025659, 7687.0, 7758.612069396395, 7420.740274090381, 7483.850322993619, 7496.100467943882, 7527.6988920444755, 7528.623568821647], "value": 6803.0050810020475, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [506.57813844829707, 522.1254398881877, 524.4519477146382, 530.7088329356318, 531.6347520197153, 6296.009395537123, 6320.304766475696, 6345.143405640237, 6376.5855219410705, 6389.584676995998, 6212.192414986057, 6223.471004964584, 6246.377101281258, 6272.1219029335925, 6316.621002739839, 5187.221916712504, 5190.325257716491, 5190.987757387311, 5195.0, 5224.943408106263, 4867.9269810952865, 4986.875328116808, 5094.617903657222, 5109.514596113382, 5207.192885102815, 4937.926071050149, 4993.226049962243, 5005.124103281929, 5041.588355260515, 5050.419201791786, 4929.359183306147, 4942.950570494285, 4980.452150263468, 4980.9252861207115, 5022.773975171126, 4455.22033644111, 4476.820927162909, 4508.864734057984, 4526.366308716782, 4527.977360113211, 6144.600600960924, 6220.346863579304, 6230.657313847737, 6247.656378899204, 6266.561340706153, 5743.913841292342, 5747.62640428375, 5750.223719797816, 5779.797707080232, 5785.623934444247, 6462.838429039265, 6484.708188131545, 6514.348565143495, 6535.2484464286745, 6540.967295163541, 4872.634552408584, 4903.41159060915, 4922.704637721748, 4922.950770492285, 4927.359443272351, 6642.634655093968, 6695.799126026227, 6855.725770969155, 6865.588064716133, 6983.266756990544, 5415.296011518556, 5443.611878970837, 5470.8085217017215, 5475.123980163155, 5482.342118945672, 5508.779648814003, 5518.144687573433, 5533.723313834292, 5628.380878103405, 5629.437056294338, 5836.620619659709, 5949.940500595025, 5964.880702385928, 5969.0, 5971.373005834412, 6637.4026337628875, 6709.161354830646, 6710.429613482811, 6717.178784910252, 6718.338505514506, 1920.7983161768093, 1947.3476385410763, 1949.4444083436154, 1959.157562248247, 1959.1673538745977, 1433.4409580263705, 1453.8618831211018, 1469.5664778890252, 1477.1211129378219, 1482.5404124721372, 5756.740946657411, 5762.683052432073, 5775.855603609859, 5787.797427090031, 5803.346046376762, 6697.196336439659, 6725.327467253235, 6737.797866064026, 6747.257801641816, 6749.426298764562, 5632.408597097327, 5754.0, 5786.855328616818, 5810.58860572866, 5851.32709738377, 3522.154682876091, 3523.71810255179, 3556.822158892045, 3588.569371675416, 3632.9636703632627, 6483.513736469784, 6558.245801732766, 6585.341465853377, 6621.569597976116, 6639.302873198341, 6820.658967051628, 6834.555753875982, 6855.794326170223, 6863.416609588141, 6977.51157418986, 6534.248561415498, 6552.279249282575, 6580.276169621242, 6582.144321238208, 6664.433523150587, 6685.5597706837625, 6691.966540167268, 6714.563553369016, 6771.492138089761, 6845.589264644137, 6676.933230667825, 6677.796327212105, 6748.460123190035, 6757.493188010821, 6764.864702706017, 6175.783847565313, 6295.811125666238, 6404.327545607831, 6432.774852880127, 6513.837154071186, 5348.893022139458, 5393.271908292487, 5395.892082158413, 5439.211314359475, 5489.533389661923, 6652.5074335974, 6660.136141459219, 6709.899351509634, 6710.932890671031, 6729.528932974645, 6248.343923887926, 6318.641492079225, 6329.588576742497, 6459.450946669398, 6506.479481641552, 6447.453255963775, 6508.316626754312, 6518.804435866742, 6542.0, 6579.0, 6414.486841052611, 6476.902846457213, 6537.378948999837, 6545.836354091186, 6552.508561857784, 6455.612663240033, 6494.512911531559, 6499.155109835691, 6562.5406221564035, 6610.10763546915, 6377.596928675869, 6393.552451328363, 6422.719934423212, 6441.420272175526, 6465.1595292611655], "value": 5163.039044837262, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [912.2838571721201, 919.599974011305, 924.4360939826701, 930.8045310484798, 940.8024314893872, 19583.0, 20388.796112038894, 23175.420614484625, 24847.627285590686, 25082.247532574005, 20353.49116272094, 20479.283225087125, 20501.589968200627, 20502.282420115298, 22781.658275125887, 998.6830553116772, 1018.893016233296, 1019.0268293779422, 1020.5713600287897, 1020.9897901021006, 974.1719538392346, 1005.8239808033602, 1005.9295849290553, 1009.399407352624, 1014.6753039027515, 1066.003286926726, 1067.2582555124193, 1069.8181309177419, 1070.2240875365337, 1070.3524367757511, 1061.75048863517, 1062.755566219766, 1063.2344711807486, 1065.2702898514542, 1065.323519565074, 777.4402430250211, 778.2451022508158, 778.6690656471034, 780.1886038519932, 781.2578050851657, 20834.68747968782, 20875.686864697043, 21016.579668406546, 21024.948752562465, 21028.5794284115, 20226.39320820378, 20787.79212207889, 21001.894990525103, 21079.578408431746, 21083.262085827075, 17866.731999020027, 17879.553011174696, 17922.10389480521, 17923.73114403285, 17993.100344982828, 3772.0569857535615, 3796.962030379716, 3809.942850857239, 3842.5773164951834, 3845.4616353710503, 138281.0, 140297.0, 141025.0, 142262.28868855693, 143719.56280437167, 17075.316987320617, 17123.315067397412, 17219.483415497554, 17262.741058884127, 17358.132093395532, 32003.199920002186, 32131.51802722961, 32247.67752322494, 32500.187495312806, 32508.187295317333, 7826.726064587712, 8205.056418511827, 8212.46618969765, 8228.670853165806, 8248.13394593571, 3885.669718073939, 4030.2745505809235, 4037.515498140242, 4050.9392359114636, 4060.634542891154, 5307.787688492417, 5323.920141197885, 5358.089124848785, 5405.324334458192, 5431.782728690808, 1034.7206254311445, 1048.6015314180563, 1057.8254587992894, 1063.239783554762, 1063.3672964586058, 669.8827705151579, 679.599036568427, 695.5027155583782, 699.6676578625112, 702.4240123099112, 11555.422228888521, 12190.2076365036, 12502.81245781314, 12734.089886516911, 13413.195208287534, 624.307019208678, 639.229728177545, 640.1645852162972, 646.9288378278386, 651.5829868883918, 5655.971720141373, 5661.0, 5675.971620141955, 5749.281339832521, 5860.677662728505, 129218.0, 131844.0, 134110.0, 134140.0, 134270.0, 109112.0, 111880.44059779649, 112308.0, 114419.0, 115036.0, 94725.0, 95186.0, 96476.51761741146, 99555.00444995458, 99771.99999999854, 10721.30311529748, 11004.284721493077, 11023.228374013743, 11050.557977680803, 11269.943650281695, 35685.46471802873, 42920.0, 45832.0, 46362.0, 48614.0, 81389.18610813816, 82829.0, 83641.0, 85703.0, 85792.0, 18877.71683424722, 19011.714824277922, 19071.237150513825, 19278.0, 19514.804851951867, 21370.0, 21494.462638434165, 21775.23786667459, 21789.891050544647, 22056.0, 18295.54261143482, 19086.904565477085, 19178.232870685017, 19236.422907312804, 19354.903225483784, 19593.804061959767, 19734.60530789405, 19986.100625471598, 20048.899755501712, 20150.39548813538, 17632.294708211528, 18041.458756237334, 18095.276188952295, 18142.183601738216, 18159.818401815814, 12719.236845789283, 12887.226766394046, 12891.806622900851, 13129.671758206121, 13227.20636761759, 12829.358532073546, 13207.273599952096, 13297.734045319234, 13436.596902092953, 13537.796933045815], "value": 9496.164985794738, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1408.9999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 109.0, 112.0, 108.0, 109.0, 112.0, 108.0, 107.0, 108.0, 110.0], "value": 109.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 118.0, 122.0, 120.0, 116.0, 122.0, 116.0, 117.0, 120.0, 117.0], "value": 118.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 162.0, 163.0, 162.0, 162.0, 165.0, 165.0, 165.0, 168.0, 166.0], "value": 164.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 73.0, 73.0, 76.0, 75.0, 75.0, 75.0, 75.0, 76.0], "value": 74.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 81.0, 79.0, 78.0, 77.0, 78.0, 78.0, 79.0, 78.0, 79.0], "value": 78.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 111.0, 111.0, 112.0, 111.0, 112.0, 111.0, 111.0, 112.0, 112.0], "value": 112.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 148.0, 148.0, 149.0, 147.0, 147.0, 148.0, 148.0, 148.0, 148.0], "value": 147.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 94.0, 94.0, 94.0, 94.0, 93.0, 95.0, 94.0, 94.0], "value": 94.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 53.0, 53.0, 52.0, 52.0, 52.0, 53.0, 51.0, 49.0, 49.0], "value": 51.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 38.0, 37.0, 38.0, 37.0, 38.0, 37.0, 38.0, 37.0, 37.0], "value": 37.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 74.0, 75.0, 73.0, 74.0, 75.0, 77.0, 74.0, 75.0, 73.0], "value": 74.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 112.0, 118.0, 111.0, 110.0, 120.0, 110.0, 114.0, 111.0, 111.0], "value": 112.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 176.0, 160.0, 168.0, 165.0, 160.0, 168.0, 171.0, 164.0, 167.0], "value": 168.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 61.0, 68.0, 65.0, 66.0, 63.0, 70.0, 67.0, 63.0, 62.0], "value": 65.1, "unit": "score"}]}]} 16:53:53 INFO - Return code: 0 16:53:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:53:53 INFO - # TBPL SUCCESS # 16:53:53 INFO - Running post-action listener: _package_coverage_data 16:53:53 INFO - Running post-action listener: _resource_record_post_action 16:53:53 INFO - [mozharness: 2017-09-30 23:53:53.458233Z] Finished run-tests step (success) 16:53:53 INFO - Running post-run listener: _resource_record_post_run 16:53:53 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 12288; Write bytes: 432607232; Read time: 80; Write time: 227828 16:53:53 INFO - TinderboxPrint: CPU usage
13.1% 16:53:53 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 80 16:53:53 INFO - TinderboxPrint: I/O write bytes / time
432,607,232 / 227,828 16:53:53 INFO - TinderboxPrint: CPU idle
8,223.3 (86.9%) 16:53:53 INFO - TinderboxPrint: CPU user
1,192.0 (12.6%) 16:53:53 INFO - TinderboxPrint: Swap in / out
0 / 0 16:53:53 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 109178880; Read time: 0; Write time: 58984 16:53:53 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 16:53:53 INFO - run-tests - Wall time: 1169s; CPU: 13.0%; Read bytes: 8192; Write bytes: 323428352; Read time: 72; Write time: 168844 16:53:54 INFO - Running post-run listener: _upload_blobber_files 16:53:54 INFO - Blob upload gear active. 16:53:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:53:54 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 16:53:54 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'] 16:53:54 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 16:53:54 INFO - (blobuploader) - INFO - Open directory for files ... 16:53:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:53:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:53:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:53:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:53:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:53:55 INFO - (blobuploader) - INFO - Done attempting. 16:53:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:53:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:53:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:54:02 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:54:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:54:02 INFO - (blobuploader) - INFO - Done attempting. 16:54:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:54:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:54:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:54:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:54:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:54:03 INFO - (blobuploader) - INFO - Done attempting. 16:54:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:54:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:54:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:54:04 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:54:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:54:04 INFO - (blobuploader) - INFO - Done attempting. 16:54:04 INFO - (blobuploader) - INFO - Iteration through files over. 16:54:04 INFO - Return code: 0 16:54:04 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:54:04 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:54:04 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bba3e921e0a8710806622a489e7501be3bf7b53e976cff9e1d2bae8eccccc164714bb63a31e45925ade2dc6e52cd2e21ee30cd5473bc91122b4fe4fc4c0fdaef", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b9fc2fe01fbce75fa5162748259e6b4ff1b9609f7c5c1a0541f41ba21d9e9e1f5a7a7c8ea799540966df75ebf8e297303b64fbb1694e2930e45a7afaa0097c10", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f958f2db6daaf4a2195a55cca105a500ad20cd94e7b7b9a7fae2f5d0a904a20051e944000d4bb9611f6f13d965ef03a5792a051150b4c29ab5ad53125244b5e1", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5aa09c620c6bd780df66ebd8bca98d87fa8545bde31a118c882931bbcf69d5efe1c0edc9a7c48657c12813fd1baf92f2522c1201e1883d86aa3cfd49e8b67c1c"} 16:54:04 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:54:04 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:54:04 INFO - Contents: 16:54:04 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bba3e921e0a8710806622a489e7501be3bf7b53e976cff9e1d2bae8eccccc164714bb63a31e45925ade2dc6e52cd2e21ee30cd5473bc91122b4fe4fc4c0fdaef", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b9fc2fe01fbce75fa5162748259e6b4ff1b9609f7c5c1a0541f41ba21d9e9e1f5a7a7c8ea799540966df75ebf8e297303b64fbb1694e2930e45a7afaa0097c10", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f958f2db6daaf4a2195a55cca105a500ad20cd94e7b7b9a7fae2f5d0a904a20051e944000d4bb9611f6f13d965ef03a5792a051150b4c29ab5ad53125244b5e1", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5aa09c620c6bd780df66ebd8bca98d87fa8545bde31a118c882931bbcf69d5efe1c0edc9a7c48657c12813fd1baf92f2522c1201e1883d86aa3cfd49e8b67c1c"} 16:54:04 INFO - Running post-run listener: copy_logs_to_upload_dir 16:54:04 INFO - Copying logs to upload dir... 16:54:04 INFO - mkdir: /builds/slave/test/build/upload/logs 16:54:04 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1244.752029 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2017-09-30 16:54:05.056099) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-30 16:54:05.061350) ========= 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=ed14279e38b05397cf4aa391000001a4-1506810492.856627-1940248619 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/bba3e921e0a8710806622a489e7501be3bf7b53e976cff9e1d2bae8eccccc164714bb63a31e45925ade2dc6e52cd2e21ee30cd5473bc91122b4fe4fc4c0fdaef", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b9fc2fe01fbce75fa5162748259e6b4ff1b9609f7c5c1a0541f41ba21d9e9e1f5a7a7c8ea799540966df75ebf8e297303b64fbb1694e2930e45a7afaa0097c10", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f958f2db6daaf4a2195a55cca105a500ad20cd94e7b7b9a7fae2f5d0a904a20051e944000d4bb9611f6f13d965ef03a5792a051150b4c29ab5ad53125244b5e1", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5aa09c620c6bd780df66ebd8bca98d87fa8545bde31a118c882931bbcf69d5efe1c0edc9a7c48657c12813fd1baf92f2522c1201e1883d86aa3cfd49e8b67c1c"} build_url:https://queue.taskcluster.net/v1/task/T48oeLAPQg6zMtCYv_JcQg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011415 build_url: 'https://queue.taskcluster.net/v1/task/T48oeLAPQg6zMtCYv_JcQg/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bba3e921e0a8710806622a489e7501be3bf7b53e976cff9e1d2bae8eccccc164714bb63a31e45925ade2dc6e52cd2e21ee30cd5473bc91122b4fe4fc4c0fdaef", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b9fc2fe01fbce75fa5162748259e6b4ff1b9609f7c5c1a0541f41ba21d9e9e1f5a7a7c8ea799540966df75ebf8e297303b64fbb1694e2930e45a7afaa0097c10", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f958f2db6daaf4a2195a55cca105a500ad20cd94e7b7b9a7fae2f5d0a904a20051e944000d4bb9611f6f13d965ef03a5792a051150b4c29ab5ad53125244b5e1", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5aa09c620c6bd780df66ebd8bca98d87fa8545bde31a118c882931bbcf69d5efe1c0edc9a7c48657c12813fd1baf92f2522c1201e1883d86aa3cfd49e8b67c1c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-30 16:54:05.093200) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-30 16:54:05.093569) ========= 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=ed14279e38b05397cf4aa391000001a4-1506810492.856627-1940248619 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004503 ========= master_lag: 0.07 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-30 16:54:05.172795) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-30 16:54:05.176113) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-30 16:54:05.176402) ========= ========= Total master_lag: 0.29 =========