builder: mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s slave: talos-linux64-ix-018 starttime: 1504787517.99 results: success (0) revision: 6469a2cb7df662afb23493b5566f99b6802fcb5a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-07 05:31:57.992338) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-07 05:31:57.992769) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-07 05:31:57.993036) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-07 05:31:58.251178) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 05:31:58.251518) ========= 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=62c568580acbfdaac3eec7be000001dc-1504787515.81045-1663338310 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-07 05:31:58-- 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% 28.0M=0s 2017-09-07 05:31:58 (28.0 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.221129 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 05:31:58.501929) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 05:31:58.502220) ========= 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=62c568580acbfdaac3eec7be000001dc-1504787515.81045-1663338310 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.084376 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 05:31:58.601591) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 05:31:58.601931) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 6469a2cb7df662afb23493b5566f99b6802fcb5a --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 6469a2cb7df662afb23493b5566f99b6802fcb5a --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=62c568580acbfdaac3eec7be000001dc-1504787515.81045-1663338310 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-07 05:31:58,888 truncating revision to first 12 chars 2017-09-07 05:31:58,888 Setting DEBUG logging. 2017-09-07 05:31:58,889 attempt 1/10 2017-09-07 05:31:58,889 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/6469a2cb7df6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-07 05:31:59,433 unpacking tar archive at: mozilla-central-6469a2cb7df6/testing/mozharness/ program finished with exit code 0 elapsedTime=0.868454 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 05:31:59.486275) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-07 05:31:59.486569) ========= script_repo_revision: 6469a2cb7df662afb23493b5566f99b6802fcb5a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-07 05:31:59.487026) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-07 05:31:59.487284) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-07 05:31:59.504944) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2017-09-07 05:31:59.505448) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=62c568580acbfdaac3eec7be000001dc-1504787515.81045-1663338310 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:32:00 INFO - MultiFileLogger online at 20170907 05:32:00 in /builds/slave/test 05:32:00 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:32:00 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:32:00 INFO - {'append_to_log': False, 05:32:00 INFO - 'base_work_dir': '/builds/slave/test', 05:32:00 INFO - 'blob_upload_branch': 'Firefox', 05:32:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:32:00 INFO - 'branch': 'Firefox', 05:32:00 INFO - 'buildbot_json_path': 'buildprops.json', 05:32:00 INFO - 'code_coverage': False, 05:32:00 INFO - 'config_files': ('talos/linux_config.py',), 05:32:00 INFO - 'default_actions': ('clobber', 05:32:00 INFO - 'read-buildbot-config', 05:32:00 INFO - 'download-and-extract', 05:32:00 INFO - 'populate-webroot', 05:32:00 INFO - 'create-virtualenv', 05:32:00 INFO - 'install', 05:32:00 INFO - 'setup-mitmproxy', 05:32:00 INFO - 'run-tests'), 05:32:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:32:00 INFO - 'disable_ccov_upload': False, 05:32:00 INFO - 'disable_stylo': False, 05:32:00 INFO - 'download_minidump_stackwalk': True, 05:32:00 INFO - 'download_symbols': 'ondemand', 05:32:00 INFO - 'enable_stylo': False, 05:32:00 INFO - 'enable_webrender': False, 05:32:00 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 05:32:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:32:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:32:00 INFO - 'gecko_profile': False, 05:32:00 INFO - 'gecko_profile_interval': 0, 05:32:00 INFO - 'installer_path': 'installer.exe', 05:32:00 INFO - 'log_level': 'info', 05:32:00 INFO - 'log_name': 'talos', 05:32:00 INFO - 'log_to_console': True, 05:32:00 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:32:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:32:00 INFO - 'opt_config_files': (), 05:32:00 INFO - 'pip_index': False, 05:32:00 INFO - 'suite': 'dromaeojs-e10s', 05:32:00 INFO - 'system_bits': '32', 05:32:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:32:00 INFO - 'title': 'talos-linux64-ix-018', 05:32:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:32:00 INFO - 'use_talos_json': True, 05:32:00 INFO - 'verify': 'False', 05:32:00 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:32:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:32:00 INFO - 'work_dir': 'build'} 05:32:00 INFO - [mozharness: 2017-09-07 12:32:00.045650Z] Running clobber step. 05:32:00 INFO - Running pre-action listener: _resource_record_pre_action 05:32:00 INFO - Running main action method: clobber 05:32:00 INFO - rmtree: /builds/slave/test/build 05:32:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:32:02 INFO - Running post-action listener: _resource_record_post_action 05:32:02 INFO - [mozharness: 2017-09-07 12:32:02.806875Z] Finished clobber step (success) 05:32:02 INFO - [mozharness: 2017-09-07 12:32:02.807002Z] Running read-buildbot-config step. 05:32:02 INFO - Running pre-action listener: _resource_record_pre_action 05:32:02 INFO - Running main action method: read_buildbot_config 05:32:02 INFO - Using buildbot properties: 05:32:02 INFO - { 05:32:02 INFO - "project": "", 05:32:02 INFO - "product": "firefox", 05:32:02 INFO - "who": "archaeopteryx@coole-files.de", 05:32:02 INFO - "installer_path": "public/build/target.tar.bz2", 05:32:02 INFO - "builddir": "mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s", 05:32:02 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 05:32:02 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo-sequential mozilla-central talos dromaeojs-e10s", 05:32:02 INFO - "stage_platform": "linux64-stylosequential", 05:32:02 INFO - "basedir": "/builds/slave/test", 05:32:02 INFO - "buildnumber": 62, 05:32:02 INFO - "platform": "ubuntu64_hw_styloseq", 05:32:02 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 05:32:02 INFO - "slavebuilddir": "test", 05:32:02 INFO - "taskId": "S3PlTzSeQdWq9LZKDqzZvA", 05:32:02 INFO - "branch": "mozilla-central", 05:32:02 INFO - "script_repo_revision": "production", 05:32:02 INFO - "revision": "6469a2cb7df662afb23493b5566f99b6802fcb5a", 05:32:02 INFO - "slavename": "talos-linux64-ix-018", 05:32:02 INFO - "repo_path": "mozilla-central" 05:32:02 INFO - } 05:32:02 INFO - Finding installer, test and symbols from parent task. 05:32:02 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/S3PlTzSeQdWq9LZKDqzZvA'}, attempt #1 05:32:03 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/S3PlTzSeQdWq9LZKDqzZvA'}, attempt #1 05:32:03 INFO - Task dependencies: BaAK-As6Q66QQapQlMtaJw 05:32:03 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BaAK-As6Q66QQapQlMtaJw'}, attempt #1 05:32:04 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/BaAK-As6Q66QQapQlMtaJw/artifacts/public/build/target.tar.bz2 05:32:04 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/BaAK-As6Q66QQapQlMtaJw/artifacts/public/build/target.test_packages.json 05:32:04 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/BaAK-As6Q66QQapQlMtaJw/artifacts/public/build/target.crashreporter-symbols.zip 05:32:04 INFO - Running post-action listener: _resource_record_post_action 05:32:04 INFO - [mozharness: 2017-09-07 12:32:04.285443Z] Finished read-buildbot-config step (success) 05:32:04 INFO - [mozharness: 2017-09-07 12:32:04.285661Z] Running download-and-extract step. 05:32:04 INFO - Running pre-action listener: _resource_record_pre_action 05:32:04 INFO - Running main action method: download_and_extract 05:32:04 INFO - mkdir: /builds/slave/test/build/tests 05:32:04 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')]} 05:32:04 INFO - trying https://queue.taskcluster.net/v1/task/BaAK-As6Q66QQapQlMtaJw/artifacts/public/build/target.test_packages.json 05:32:04 INFO - Downloading https://queue.taskcluster.net/v1/task/BaAK-As6Q66QQapQlMtaJw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 05:32:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BaAK-As6Q66QQapQlMtaJw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 05:32:04 INFO - Downloaded 989 bytes. 05:32:04 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 05:32:04 INFO - Using the following test package requirements: 05:32:04 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 05:32:04 INFO - u'common': [u'target.common.tests.zip'], 05:32:04 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 05:32:04 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 05:32:04 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 05:32:04 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 05:32:04 INFO - u'mozbase': [u'target.common.tests.zip'], 05:32:04 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 05:32:04 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 05:32:04 INFO - u'web-platform': [u'target.common.tests.zip', 05:32:04 INFO - u'target.web-platform.tests.tar.gz'], 05:32:04 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 05:32:04 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 05:32:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BaAK-As6Q66QQapQlMtaJw/artifacts/public/build/target.common.tests.zip 05:32:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BaAK-As6Q66QQapQlMtaJw/artifacts/public/build/target.common.tests.zip'}, attempt #1 05:32:04 INFO - Fetch https://queue.taskcluster.net/v1/task/BaAK-As6Q66QQapQlMtaJw/artifacts/public/build/target.common.tests.zip into memory 05:32:06 INFO - Content-Length response header: 55021200 05:32:06 INFO - Bytes received: 55021200 05:32:17 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 05:32:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BaAK-As6Q66QQapQlMtaJw/artifacts/public/build/target.common.tests.zip 05:32:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BaAK-As6Q66QQapQlMtaJw/artifacts/public/build/target.common.tests.zip'}, attempt #1 05:32:17 INFO - Fetch https://queue.taskcluster.net/v1/task/BaAK-As6Q66QQapQlMtaJw/artifacts/public/build/target.common.tests.zip into memory 05:32:18 INFO - Content-Length response header: 55021200 05:32:18 INFO - Bytes received: 55021200 05:32:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BaAK-As6Q66QQapQlMtaJw/artifacts/public/build/target.talos.tests.zip 05:32:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BaAK-As6Q66QQapQlMtaJw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 05:32:32 INFO - Fetch https://queue.taskcluster.net/v1/task/BaAK-As6Q66QQapQlMtaJw/artifacts/public/build/target.talos.tests.zip into memory 05:32:32 INFO - Content-Length response header: 14496665 05:32:32 INFO - Bytes received: 14496665 05:32:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:33 INFO - trying https://queue.taskcluster.net/v1/task/BaAK-As6Q66QQapQlMtaJw/artifacts/public/build/target.tar.bz2 05:32:33 INFO - Downloading https://queue.taskcluster.net/v1/task/BaAK-As6Q66QQapQlMtaJw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 05:32:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BaAK-As6Q66QQapQlMtaJw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 05:32:34 INFO - Downloaded 62783660 bytes. 05:32:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BaAK-As6Q66QQapQlMtaJw/artifacts/public/build/target.tar.bz2 05:32:34 INFO - mkdir: /builds/slave/test/properties 05:32:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:32:34 INFO - Writing to file /builds/slave/test/properties/build_url 05:32:34 INFO - Contents: 05:32:34 INFO - build_url:https://queue.taskcluster.net/v1/task/BaAK-As6Q66QQapQlMtaJw/artifacts/public/build/target.tar.bz2 05:32:34 INFO - Running post-action listener: _resource_record_post_action 05:32:34 INFO - Running post-action listener: find_tests_for_verification 05:32:34 INFO - Running post-action listener: set_extra_try_arguments 05:32:34 INFO - [mozharness: 2017-09-07 12:32:34.231853Z] Finished download-and-extract step (success) 05:32:34 INFO - [mozharness: 2017-09-07 12:32:34.231963Z] Running populate-webroot step. 05:32:34 INFO - Running pre-action listener: _resource_record_pre_action 05:32:34 INFO - Running main action method: populate_webroot 05:32:34 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:32:34 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 05:32:34 INFO - 'tests': ['tresize', 05:32:34 INFO - 'tcanvasmark']}, 05:32:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:32:34 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 05:32:34 INFO - 'tests': ['dromaeo_css', 05:32:34 INFO - 'kraken']}, 05:32:34 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:32:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:32:34 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 05:32:34 INFO - 'talos_options': ['--disable-stylo'], 05:32:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:32:34 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:32:34 INFO - 'tests': ['damp', 'tps']}, 05:32:34 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 05:32:34 INFO - 'talos_options': ['--disable-stylo'], 05:32:34 INFO - 'tests': ['damp', 'tps']}, 05:32:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:32:34 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 05:32:34 INFO - 'tests': ['dromaeo_dom']}, 05:32:34 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 05:32:34 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 05:32:34 INFO - 'tests': ['basic_compositor_video', 05:32:34 INFO - 'glvideo']}, 05:32:34 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 05:32:34 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 05:32:34 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 05:32:34 INFO - 'talos_options': ['--disable-stylo'], 05:32:34 INFO - 'tests': ['ts_paint_webext', 05:32:34 INFO - 'tp5o_webext']}, 05:32:34 INFO - 'other-e10s': {'tests': ['a11yr', 05:32:34 INFO - 'ts_paint', 05:32:34 INFO - 'tpaint', 05:32:34 INFO - 'sessionrestore', 05:32:34 INFO - 'sessionrestore_many_windows', 05:32:34 INFO - 'sessionrestore_no_auto_restore', 05:32:34 INFO - 'tabpaint']}, 05:32:34 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 05:32:34 INFO - 'tests': ['a11yr', 05:32:34 INFO - 'ts_paint', 05:32:34 INFO - 'tpaint', 05:32:34 INFO - 'sessionrestore', 05:32:34 INFO - 'sessionrestore_many_windows', 05:32:34 INFO - 'sessionrestore_no_auto_restore', 05:32:34 INFO - 'tabpaint']}, 05:32:34 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 05:32:34 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 05:32:34 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 05:32:34 INFO - 'tests': ['bloom_basic_singleton']}, 05:32:34 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 05:32:34 INFO - 'tests': ['bloom_basic']}, 05:32:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:32:34 INFO - 'tsvgr_opacity', 05:32:34 INFO - 'tart', 05:32:34 INFO - 'tscrollx', 05:32:34 INFO - 'tsvg_static']}, 05:32:34 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 05:32:34 INFO - 'tests': ['tsvgx', 05:32:34 INFO - 'tsvgr_opacity', 05:32:34 INFO - 'tart', 05:32:34 INFO - 'tscrollx', 05:32:34 INFO - 'tsvg_static']}, 05:32:34 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:32:34 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 05:32:34 INFO - 'talos_options': ['--disable-stylo'], 05:32:34 INFO - 'tests': ['tp5o']}, 05:32:34 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 05:32:34 INFO - 'talos_options': ['--mitmproxy', 05:32:34 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 05:32:34 INFO - '--firstNonBlankPaint'], 05:32:34 INFO - 'tests': ['tp6_google', 05:32:34 INFO - 'tp6_youtube', 05:32:34 INFO - 'tp6_amazon', 05:32:34 INFO - 'tp6_facebook']}, 05:32:34 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 05:32:34 INFO - 'talos_options': ['--disable-stylo', 05:32:34 INFO - '--mitmproxy', 05:32:34 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 05:32:34 INFO - '--firstNonBlankPaint'], 05:32:34 INFO - 'tests': ['tp6_google', 05:32:34 INFO - 'tp6_youtube', 05:32:34 INFO - 'tp6_amazon', 05:32:34 INFO - 'tp6_facebook']}, 05:32:34 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 05:32:34 INFO - 'talos_options': ['--stylo-threads=1', 05:32:34 INFO - '--mitmproxy', 05:32:34 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 05:32:34 INFO - '--firstNonBlankPaint'], 05:32:34 INFO - 'tests': ['tp6_google', 05:32:34 INFO - 'tp6_youtube', 05:32:34 INFO - 'tp6_amazon', 05:32:34 INFO - 'tp6_facebook']}, 05:32:34 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:32:34 INFO - 'talos_options': ['--xperf_path', 05:32:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:32:34 INFO - 'tests': ['tp5n']}, 05:32:34 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 05:32:34 INFO - 'talos_options': ['--disable-stylo', 05:32:34 INFO - '--xperf_path', 05:32:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:32:34 INFO - 'tests': ['tp5n']}}} 05:32:34 INFO - Running post-action listener: _resource_record_post_action 05:32:34 INFO - [mozharness: 2017-09-07 12:32:34.248284Z] Finished populate-webroot step (success) 05:32:34 INFO - [mozharness: 2017-09-07 12:32:34.248389Z] Running create-virtualenv step. 05:32:34 INFO - Running pre-action listener: _resource_record_pre_action 05:32:34 INFO - Running main action method: create_virtualenv 05:32:34 INFO - Creating virtualenv /builds/slave/test/build/venv 05:32:34 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:32:34 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 05:32:34 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 05:32:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:32:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:32:35 INFO - Installing distribute.............................................................................................................................................................................................done. 05:32:35 INFO - Installing pip...............done. 05:32:35 INFO - Return code: 0 05:32:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:32:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:32:35 INFO - Reading from file tmpfile_stdout 05:32:35 INFO - Output received: 05:32:35 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 05:32:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:32:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x230a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24d3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2676a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x267a090>, '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 0x267ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2674f90>, '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': '62c568580acbfdaac3eec7be000001dc-1504787515.81045-1663338310', '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 05:32:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:32:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:32:35 INFO - Using env: {'DISPLAY': ':0', 05:32:35 INFO - 'HOME': '/home/cltbld', 05:32:35 INFO - 'LANG': 'en_US.UTF-8', 05:32:35 INFO - 'LANGUAGE': 'en_US:en', 05:32:35 INFO - 'LOGNAME': 'cltbld', 05:32:35 INFO - 'MAIL': '/var/mail/cltbld', 05:32:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:35 INFO - 'MOZ_NO_REMOTE': '1', 05:32:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:32:35 INFO - 'NO_EM_RESTART': '1', 05:32:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:32:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:35 INFO - 'PWD': '/builds/slave/test', 05:32:35 INFO - 'SHELL': '/bin/bash', 05:32:35 INFO - 'SHLVL': '1', 05:32:35 INFO - 'TERM': 'linux', 05:32:35 INFO - 'TMOUT': '86400', 05:32:35 INFO - 'USER': 'cltbld', 05:32:35 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1504787515.81045-1663338310', 05:32:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:35 INFO - '_': '/tools/buildbot/bin/python'} 05:32:36 INFO - Ignoring indexes: http://pypi.python.org/simple/ 05:32:36 INFO - Downloading/unpacking pip>=1.5 05:32:36 INFO - Running setup.py egg_info for package pip 05:32:36 INFO - warning: no files found matching 'pip/cacert.pem' 05:32:36 INFO - warning: no files found matching '*.html' under directory 'docs' 05:32:36 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 05:32:36 INFO - no previously-included directories found matching 'docs/_build/_sources' 05:32:36 INFO - Installing collected packages: pip 05:32:36 INFO - Found existing installation: pip 1.1 05:32:36 INFO - Uninstalling pip: 05:32:36 INFO - Successfully uninstalled pip 05:32:36 INFO - Running setup.py install for pip 05:32:36 INFO - warning: no files found matching 'pip/cacert.pem' 05:32:36 INFO - warning: no files found matching '*.html' under directory 'docs' 05:32:36 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 05:32:36 INFO - no previously-included directories found matching 'docs/_build/_sources' 05:32:36 INFO - Installing pip script to /builds/slave/test/build/venv/bin 05:32:36 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 05:32:36 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 05:32:36 INFO - Successfully installed pip 05:32:36 INFO - Cleaning up... 05:32:36 INFO - Return code: 0 05:32:36 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:32:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x230a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24d3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2676a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x267a090>, '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 0x267ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2674f90>, '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': '62c568580acbfdaac3eec7be000001dc-1504787515.81045-1663338310', '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 05:32:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:32:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:32:36 INFO - Using env: {'DISPLAY': ':0', 05:32:36 INFO - 'HOME': '/home/cltbld', 05:32:36 INFO - 'LANG': 'en_US.UTF-8', 05:32:36 INFO - 'LANGUAGE': 'en_US:en', 05:32:36 INFO - 'LOGNAME': 'cltbld', 05:32:36 INFO - 'MAIL': '/var/mail/cltbld', 05:32:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:36 INFO - 'MOZ_NO_REMOTE': '1', 05:32:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:32:36 INFO - 'NO_EM_RESTART': '1', 05:32:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:32:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:36 INFO - 'PWD': '/builds/slave/test', 05:32:36 INFO - 'SHELL': '/bin/bash', 05:32:36 INFO - 'SHLVL': '1', 05:32:36 INFO - 'TERM': 'linux', 05:32:36 INFO - 'TMOUT': '86400', 05:32:36 INFO - 'USER': 'cltbld', 05:32:36 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1504787515.81045-1663338310', 05:32:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:36 INFO - '_': '/tools/buildbot/bin/python'} 05:32:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:37 INFO - Downloading/unpacking psutil>=3.1.1 05:32:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:32:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:32:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:32:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:32:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:32:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:32:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:32:38 INFO - Installing collected packages: psutil 05:32:38 INFO - Running setup.py install for psutil 05:32:38 INFO - building 'psutil._psutil_linux' extension 05:32:38 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 05:32:39 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 05:32:39 INFO - building 'psutil._psutil_posix' extension 05:32:39 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 05:32:39 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 05:32:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:32:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:32:39 INFO - Successfully installed psutil 05:32:39 INFO - Cleaning up... 05:32:39 INFO - Return code: 0 05:32:39 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:32:39 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')]} 05:32:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:39 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')]} 05:32:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:39 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 0x230a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24d3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2676a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x267a090>, '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 0x267ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2674f90>, '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': '62c568580acbfdaac3eec7be000001dc-1504787515.81045-1663338310', '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 05:32:39 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 05:32:39 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 05:32:39 INFO - Using env: {'DISPLAY': ':0', 05:32:39 INFO - 'HOME': '/home/cltbld', 05:32:39 INFO - 'LANG': 'en_US.UTF-8', 05:32:39 INFO - 'LANGUAGE': 'en_US:en', 05:32:39 INFO - 'LOGNAME': 'cltbld', 05:32:39 INFO - 'MAIL': '/var/mail/cltbld', 05:32:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:39 INFO - 'MOZ_NO_REMOTE': '1', 05:32:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:32:39 INFO - 'NO_EM_RESTART': '1', 05:32:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:32:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:39 INFO - 'PWD': '/builds/slave/test', 05:32:39 INFO - 'SHELL': '/bin/bash', 05:32:39 INFO - 'SHLVL': '1', 05:32:39 INFO - 'TERM': 'linux', 05:32:39 INFO - 'TMOUT': '86400', 05:32:39 INFO - 'USER': 'cltbld', 05:32:39 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1504787515.81045-1663338310', 05:32:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:39 INFO - '_': '/tools/buildbot/bin/python'} 05:32:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:40 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:32:40 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 05:32:40 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 05:32:40 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 05:32:40 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 05:32:41 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:32:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:32:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:32:41 INFO - Installing collected packages: mozsystemmonitor 05:32:41 INFO - Running setup.py install for mozsystemmonitor 05:32:41 INFO - Successfully installed mozsystemmonitor 05:32:41 INFO - Cleaning up... 05:32:41 INFO - Return code: 0 05:32:41 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:32:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:41 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 0x230a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24d3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2676a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x267a090>, '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 0x267ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2674f90>, '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': '62c568580acbfdaac3eec7be000001dc-1504787515.81045-1663338310', '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 05:32:41 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 05:32:41 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 05:32:41 INFO - Using env: {'DISPLAY': ':0', 05:32:41 INFO - 'HOME': '/home/cltbld', 05:32:41 INFO - 'LANG': 'en_US.UTF-8', 05:32:41 INFO - 'LANGUAGE': 'en_US:en', 05:32:41 INFO - 'LOGNAME': 'cltbld', 05:32:41 INFO - 'MAIL': '/var/mail/cltbld', 05:32:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:41 INFO - 'MOZ_NO_REMOTE': '1', 05:32:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:32:41 INFO - 'NO_EM_RESTART': '1', 05:32:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:32:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:41 INFO - 'PWD': '/builds/slave/test', 05:32:41 INFO - 'SHELL': '/bin/bash', 05:32:41 INFO - 'SHLVL': '1', 05:32:41 INFO - 'TERM': 'linux', 05:32:41 INFO - 'TMOUT': '86400', 05:32:41 INFO - 'USER': 'cltbld', 05:32:41 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1504787515.81045-1663338310', 05:32:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:41 INFO - '_': '/tools/buildbot/bin/python'} 05:32:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:41 INFO - Downloading/unpacking jsonschema==2.5.1 05:32:41 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 05:32:41 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 05:32:41 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 05:32:41 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 05:32:42 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:32:42 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:32:42 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 05:32:42 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 05:32:42 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 05:32:42 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 05:32:42 INFO - Downloading functools32-3.2.3-2.tar.gz 05:32:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:32:42 INFO - warning: no files found matching '*.txt' 05:32:42 INFO - no previously-included directories found matching 'build' 05:32:42 INFO - no previously-included directories found matching 'dist' 05:32:42 INFO - no previously-included directories found matching '.git*' 05:32:42 INFO - Installing collected packages: jsonschema, functools32 05:32:43 INFO - Running setup.py install for functools32 05:32:43 INFO - warning: no files found matching '*.txt' 05:32:43 INFO - no previously-included directories found matching 'build' 05:32:43 INFO - no previously-included directories found matching 'dist' 05:32:43 INFO - no previously-included directories found matching '.git*' 05:32:43 INFO - Successfully installed jsonschema functools32 05:32:43 INFO - Cleaning up... 05:32:43 INFO - Return code: 0 05:32:43 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:32:43 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')]} 05:32:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:43 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')]} 05:32:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:43 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 0x230a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24d3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2676a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x267a090>, '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 0x267ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2674f90>, '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': '62c568580acbfdaac3eec7be000001dc-1504787515.81045-1663338310', '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 05:32:43 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 05:32:43 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 05:32:43 INFO - Using env: {'DISPLAY': ':0', 05:32:43 INFO - 'HOME': '/home/cltbld', 05:32:43 INFO - 'LANG': 'en_US.UTF-8', 05:32:43 INFO - 'LANGUAGE': 'en_US:en', 05:32:43 INFO - 'LOGNAME': 'cltbld', 05:32:43 INFO - 'MAIL': '/var/mail/cltbld', 05:32:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:43 INFO - 'MOZ_NO_REMOTE': '1', 05:32:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:32:43 INFO - 'NO_EM_RESTART': '1', 05:32:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:32:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:43 INFO - 'PWD': '/builds/slave/test', 05:32:43 INFO - 'SHELL': '/bin/bash', 05:32:43 INFO - 'SHLVL': '1', 05:32:43 INFO - 'TERM': 'linux', 05:32:43 INFO - 'TMOUT': '86400', 05:32:43 INFO - 'USER': 'cltbld', 05:32:43 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1504787515.81045-1663338310', 05:32:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:43 INFO - '_': '/tools/buildbot/bin/python'} 05:32:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:43 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:32:43 INFO - Cleaning up... 05:32:43 INFO - Return code: 0 05:32:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:32:43 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')]} 05:32:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:43 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')]} 05:32:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:43 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 0x230a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24d3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2676a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x267a090>, '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 0x267ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2674f90>, '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': '62c568580acbfdaac3eec7be000001dc-1504787515.81045-1663338310', '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 05:32:43 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 05:32:43 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 05:32:43 INFO - Using env: {'DISPLAY': ':0', 05:32:43 INFO - 'HOME': '/home/cltbld', 05:32:43 INFO - 'LANG': 'en_US.UTF-8', 05:32:43 INFO - 'LANGUAGE': 'en_US:en', 05:32:43 INFO - 'LOGNAME': 'cltbld', 05:32:43 INFO - 'MAIL': '/var/mail/cltbld', 05:32:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:43 INFO - 'MOZ_NO_REMOTE': '1', 05:32:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:32:43 INFO - 'NO_EM_RESTART': '1', 05:32:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:32:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:43 INFO - 'PWD': '/builds/slave/test', 05:32:43 INFO - 'SHELL': '/bin/bash', 05:32:43 INFO - 'SHLVL': '1', 05:32:43 INFO - 'TERM': 'linux', 05:32:43 INFO - 'TMOUT': '86400', 05:32:43 INFO - 'USER': 'cltbld', 05:32:43 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1504787515.81045-1663338310', 05:32:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:43 INFO - '_': '/tools/buildbot/bin/python'} 05:32:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:43 INFO - Downloading/unpacking blobuploader==1.2.4 05:32:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:32:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:32:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:32:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:32:44 INFO - Downloading blobuploader-1.2.4.tar.gz 05:32:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:32:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:32:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:32:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:32:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:32:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:32:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:32:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:32:45 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 05:32:45 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 05:32:45 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 05:32:45 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 05:32:45 INFO - Downloading docopt-0.6.1.tar.gz 05:32:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:32:45 INFO - Installing collected packages: blobuploader, requests, docopt 05:32:45 INFO - Running setup.py install for blobuploader 05:32:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:32:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:32:45 INFO - Running setup.py install for requests 05:32:45 INFO - Running setup.py install for docopt 05:32:45 INFO - Successfully installed blobuploader requests docopt 05:32:45 INFO - Cleaning up... 05:32:45 INFO - Return code: 0 05:32:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:32:45 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')]} 05:32:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:45 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')]} 05:32:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:45 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 0x230a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24d3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2676a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x267a090>, '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 0x267ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2674f90>, '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': '62c568580acbfdaac3eec7be000001dc-1504787515.81045-1663338310', '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 05:32:45 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 05:32:45 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 05:32:45 INFO - Using env: {'DISPLAY': ':0', 05:32:45 INFO - 'HOME': '/home/cltbld', 05:32:45 INFO - 'LANG': 'en_US.UTF-8', 05:32:45 INFO - 'LANGUAGE': 'en_US:en', 05:32:45 INFO - 'LOGNAME': 'cltbld', 05:32:45 INFO - 'MAIL': '/var/mail/cltbld', 05:32:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:45 INFO - 'MOZ_NO_REMOTE': '1', 05:32:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:32:45 INFO - 'NO_EM_RESTART': '1', 05:32:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:32:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:45 INFO - 'PWD': '/builds/slave/test', 05:32:45 INFO - 'SHELL': '/bin/bash', 05:32:45 INFO - 'SHLVL': '1', 05:32:45 INFO - 'TERM': 'linux', 05:32:45 INFO - 'TMOUT': '86400', 05:32:45 INFO - 'USER': 'cltbld', 05:32:45 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1504787515.81045-1663338310', 05:32:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:45 INFO - '_': '/tools/buildbot/bin/python'} 05:32:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:32:46 INFO - Running setup.py (path:/tmp/pip-Jp3brv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:32:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:32:46 INFO - Running setup.py (path:/tmp/pip-Mn2JNE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:32:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:32:46 INFO - Running setup.py (path:/tmp/pip-iXj0m2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:32:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:32:46 INFO - Running setup.py (path:/tmp/pip-JCrwcm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:32:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:32:46 INFO - Running setup.py (path:/tmp/pip-iOSD3m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:32:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:32:46 INFO - Running setup.py (path:/tmp/pip-wNosrC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:32:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:32:46 INFO - Running setup.py (path:/tmp/pip-1vlasY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:32:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:32:46 INFO - Running setup.py (path:/tmp/pip-m1w9_T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:32:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:32:46 INFO - Running setup.py (path:/tmp/pip-1q6tnw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:32:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:32:46 INFO - Running setup.py (path:/tmp/pip-iZCFCl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:32:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:32:46 INFO - Running setup.py (path:/tmp/pip-x8egXH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:32:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:32:46 INFO - Running setup.py (path:/tmp/pip-eQ3lKd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:32:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:32:46 INFO - Running setup.py (path:/tmp/pip-FVnxZq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:32:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:32:47 INFO - Running setup.py (path:/tmp/pip-QHnkvg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:32:47 INFO - Running setup.py (path:/tmp/pip-EdxpXd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:32:47 INFO - Running setup.py (path:/tmp/pip-SXwGoD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:32:47 INFO - Running setup.py (path:/tmp/pip-gDiF9F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:32:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:32:47 INFO - Running setup.py install for manifestparser 05:32:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:32:47 INFO - Running setup.py install for mozcrash 05:32:47 INFO - Running setup.py install for mozdebug 05:32:47 INFO - Running setup.py install for mozdevice 05:32:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:32:47 INFO - Running setup.py install for mozfile 05:32:47 INFO - Running setup.py install for mozhttpd 05:32:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:32:47 INFO - Running setup.py install for mozinfo 05:32:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:32:47 INFO - Running setup.py install for mozInstall 05:32:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:32:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:32:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:32:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:32:48 INFO - Running setup.py install for mozleak 05:32:48 INFO - Running setup.py install for mozlog 05:32:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:32:48 INFO - Running setup.py install for moznetwork 05:32:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:32:48 INFO - Running setup.py install for mozprocess 05:32:48 INFO - Running setup.py install for mozprofile 05:32:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:32:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:32:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:32:48 INFO - Running setup.py install for mozrunner 05:32:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:32:48 INFO - Running setup.py install for mozscreenshot 05:32:48 INFO - Running setup.py install for moztest 05:32:48 INFO - Running setup.py install for mozversion 05:32:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:32:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:32:49 INFO - Cleaning up... 05:32:49 INFO - Return code: 0 05:32:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:32:49 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')]} 05:32:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:49 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')]} 05:32:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:49 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 0x230a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24d3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2676a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x267a090>, '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 0x267ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2674f90>, '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': '62c568580acbfdaac3eec7be000001dc-1504787515.81045-1663338310', '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 05:32:49 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 05:32:49 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 05:32:49 INFO - Using env: {'DISPLAY': ':0', 05:32:49 INFO - 'HOME': '/home/cltbld', 05:32:49 INFO - 'LANG': 'en_US.UTF-8', 05:32:49 INFO - 'LANGUAGE': 'en_US:en', 05:32:49 INFO - 'LOGNAME': 'cltbld', 05:32:49 INFO - 'MAIL': '/var/mail/cltbld', 05:32:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:49 INFO - 'MOZ_NO_REMOTE': '1', 05:32:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:32:49 INFO - 'NO_EM_RESTART': '1', 05:32:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:32:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:49 INFO - 'PWD': '/builds/slave/test', 05:32:49 INFO - 'SHELL': '/bin/bash', 05:32:49 INFO - 'SHLVL': '1', 05:32:49 INFO - 'TERM': 'linux', 05:32:49 INFO - 'TMOUT': '86400', 05:32:49 INFO - 'USER': 'cltbld', 05:32:49 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1504787515.81045-1663338310', 05:32:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:49 INFO - '_': '/tools/buildbot/bin/python'} 05:32:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:32:49 INFO - Running setup.py (path:/tmp/pip-6gl5zn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:32:49 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)) 05:32:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:32:49 INFO - Running setup.py (path:/tmp/pip-seQfGx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:32:49 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)) 05:32:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:32:49 INFO - Running setup.py (path:/tmp/pip-0kVSuG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:32:49 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)) 05:32:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:32:49 INFO - Running setup.py (path:/tmp/pip-CRCL1B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:32:49 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)) 05:32:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:32:49 INFO - Running setup.py (path:/tmp/pip-hfVZCk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:32:49 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)) 05:32:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:32:49 INFO - Running setup.py (path:/tmp/pip-lXDEGk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:32:49 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)) 05:32:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:32:49 INFO - Running setup.py (path:/tmp/pip-x10iVS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:32:49 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)) 05:32:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:32:49 INFO - Running setup.py (path:/tmp/pip-5O2x3C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:32:49 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)) 05:32:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:32:49 INFO - Running setup.py (path:/tmp/pip-gYrcOW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:32:49 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)) 05:32:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:32:49 INFO - Running setup.py (path:/tmp/pip-_eVEkJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:32:49 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)) 05:32:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:32:49 INFO - Running setup.py (path:/tmp/pip-xVBqdR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:32:50 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)) 05:32:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:32:50 INFO - Running setup.py (path:/tmp/pip-a7iMwQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:32:50 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)) 05:32:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:32:50 INFO - Running setup.py (path:/tmp/pip-Ix4l7W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:32:50 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)) 05:32:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:32:50 INFO - Running setup.py (path:/tmp/pip-l3FTeW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:32:50 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)) 05:32:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:32:50 INFO - Running setup.py (path:/tmp/pip-vc6EI1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:32:50 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)) 05:32:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:32:50 INFO - Running setup.py (path:/tmp/pip-qtj3OE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:32:50 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)) 05:32:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:32:50 INFO - Running setup.py (path:/tmp/pip-N8yPC3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:32:50 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)) 05:32:50 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)) 05:32:50 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)) 05:32:50 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)) 05:32:50 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)) 05:32:50 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)) 05:32:50 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)) 05:32:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:32: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 05:32: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 05:32: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 05:32: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 05:32:51 INFO - Downloading blessings-1.6.tar.gz 05:32:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:32:51 INFO - Installing collected packages: blessings 05:32:51 INFO - Running setup.py install for blessings 05:32:51 INFO - Successfully installed blessings 05:32:51 INFO - Cleaning up... 05:32:51 INFO - Return code: 0 05:32:51 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:32:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:32:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:32:52 INFO - Reading from file tmpfile_stdout 05:32:52 INFO - Current package versions: 05:32:52 INFO - argparse == 1.2.1 05:32:52 INFO - blessings == 1.6 05:32:52 INFO - blobuploader == 1.2.4 05:32:52 INFO - docopt == 0.6.1 05:32:52 INFO - functools32 == 3.2.3-2 05:32:52 INFO - jsonschema == 2.5.1 05:32:52 INFO - manifestparser == 1.1 05:32:52 INFO - mozInstall == 1.13 05:32:52 INFO - mozcrash == 1.0 05:32:52 INFO - mozdebug == 0.1 05:32:52 INFO - mozdevice == 0.51 05:32:52 INFO - mozfile == 1.2 05:32:52 INFO - mozhttpd == 0.7 05:32:52 INFO - mozinfo == 0.10 05:32:52 INFO - mozleak == 0.1 05:32:52 INFO - mozlog == 3.5 05:32:52 INFO - moznetwork == 0.27 05:32:52 INFO - mozprocess == 0.25 05:32:52 INFO - mozprofile == 0.28 05:32:52 INFO - mozrunner == 6.13 05:32:52 INFO - mozscreenshot == 0.1 05:32:52 INFO - mozsystemmonitor == 0.3 05:32:52 INFO - moztest == 0.8 05:32:52 INFO - mozversion == 1.4 05:32:52 INFO - psutil == 3.1.1 05:32:52 INFO - requests == 1.2.3 05:32:52 INFO - wsgiref == 0.1.2 05:32:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:32:52 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')]} 05:32:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:52 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')]} 05:32:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:52 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 0x230a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24d3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2676a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x267a090>, '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 0x267ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2674f90>, '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': '62c568580acbfdaac3eec7be000001dc-1504787515.81045-1663338310', '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 05:32:52 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 05:32:52 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 05:32:52 INFO - Using env: {'DISPLAY': ':0', 05:32:52 INFO - 'HOME': '/home/cltbld', 05:32:52 INFO - 'LANG': 'en_US.UTF-8', 05:32:52 INFO - 'LANGUAGE': 'en_US:en', 05:32:52 INFO - 'LOGNAME': 'cltbld', 05:32:52 INFO - 'MAIL': '/var/mail/cltbld', 05:32:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:52 INFO - 'MOZ_NO_REMOTE': '1', 05:32:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:32:52 INFO - 'NO_EM_RESTART': '1', 05:32:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:32:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:52 INFO - 'PWD': '/builds/slave/test', 05:32:52 INFO - 'SHELL': '/bin/bash', 05:32:52 INFO - 'SHLVL': '1', 05:32:52 INFO - 'TERM': 'linux', 05:32:52 INFO - 'TMOUT': '86400', 05:32:52 INFO - 'USER': 'cltbld', 05:32:52 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1504787515.81045-1663338310', 05:32:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:52 INFO - '_': '/tools/buildbot/bin/python'} 05:32:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:52 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)) 05:32:52 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)) 05:32:52 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)) 05:32:52 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)) 05:32:52 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)) 05:32:52 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)) 05:32:52 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)) 05:32:52 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)) 05:32:52 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)) 05:32:52 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 05:32: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 05:32: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 05:32: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 05:32: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 05:32:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 05:32:53 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)) 05:32:53 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)) 05:32:53 INFO - Installing collected packages: simplejson 05:32:53 INFO - Running setup.py install for simplejson 05:32:53 INFO - building 'simplejson._speedups' extension 05:32:53 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 05:32:54 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 05:32:54 INFO - Successfully installed simplejson 05:32:54 INFO - Cleaning up... 05:32:54 INFO - Return code: 0 05:32:54 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:32:54 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')]} 05:32:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:54 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')]} 05:32:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:54 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 0x230a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24d3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2676a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x267a090>, '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 0x267ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2674f90>, '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': '62c568580acbfdaac3eec7be000001dc-1504787515.81045-1663338310', '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 05:32:54 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 05:32:54 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 05:32:54 INFO - Using env: {'DISPLAY': ':0', 05:32:54 INFO - 'HOME': '/home/cltbld', 05:32:54 INFO - 'LANG': 'en_US.UTF-8', 05:32:54 INFO - 'LANGUAGE': 'en_US:en', 05:32:54 INFO - 'LOGNAME': 'cltbld', 05:32:54 INFO - 'MAIL': '/var/mail/cltbld', 05:32:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:54 INFO - 'MOZ_NO_REMOTE': '1', 05:32:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:32:54 INFO - 'NO_EM_RESTART': '1', 05:32:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:32:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:54 INFO - 'PWD': '/builds/slave/test', 05:32:54 INFO - 'SHELL': '/bin/bash', 05:32:54 INFO - 'SHLVL': '1', 05:32:54 INFO - 'TERM': 'linux', 05:32:54 INFO - 'TMOUT': '86400', 05:32:54 INFO - 'USER': 'cltbld', 05:32:54 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1504787515.81045-1663338310', 05:32:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:54 INFO - '_': '/tools/buildbot/bin/python'} 05:32:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:54 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:32:54 INFO - Cleaning up... 05:32:54 INFO - Return code: 0 05:32:54 INFO - Running post-action listener: _resource_record_post_action 05:32:54 INFO - Running post-action listener: _start_resource_monitoring 05:32:54 INFO - Starting resource monitoring. 05:32:54 INFO - [mozharness: 2017-09-07 12:32:54.764112Z] Finished create-virtualenv step (success) 05:32:54 INFO - [mozharness: 2017-09-07 12:32:54.765199Z] Running install step. 05:32:54 INFO - Running pre-action listener: _resource_record_pre_action 05:32:54 INFO - Running main action method: install 05:32:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:32:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:32:54 INFO - Reading from file tmpfile_stdout 05:32:54 INFO - Detecting whether we're running mozinstall >=1.0... 05:32:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:32:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:32:55 INFO - Reading from file tmpfile_stdout 05:32:55 INFO - Output received: 05:32:55 INFO - Usage: mozinstall [options] installer 05:32:55 INFO - Options: 05:32:55 INFO - -h, --help show this help message and exit 05:32:55 INFO - -d DEST, --destination=DEST 05:32:55 INFO - Directory to install application into. [default: 05:32:55 INFO - "/builds/slave/test"] 05:32:55 INFO - --app=APP Application being installed. [default: firefox] 05:32:55 INFO - mkdir: /builds/slave/test/build/application 05:32:55 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'] 05:32:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 05:33:11 INFO - Reading from file tmpfile_stdout 05:33:11 INFO - Output received: 05:33:11 INFO - /builds/slave/test/build/application/firefox/firefox 05:33:11 INFO - Running post-action listener: _resource_record_post_action 05:33:11 INFO - [mozharness: 2017-09-07 12:33:11.628660Z] Finished install step (success) 05:33:11 INFO - [mozharness: 2017-09-07 12:33:11.628806Z] Running setup-mitmproxy step. 05:33:11 INFO - Running pre-action listener: _resource_record_pre_action 05:33:11 INFO - Running main action method: setup_mitmproxy 05:33:11 INFO - Skipping: mitmproxy is not required 05:33:11 INFO - Running post-action listener: _resource_record_post_action 05:33:11 INFO - [mozharness: 2017-09-07 12:33:11.629711Z] Finished setup-mitmproxy step (success) 05:33:11 INFO - [mozharness: 2017-09-07 12:33:11.629934Z] Running run-tests step. 05:33:11 INFO - Running pre-action listener: _resource_record_pre_action 05:33:11 INFO - Running pre-action listener: _set_gcov_prefix 05:33:11 INFO - Running main action method: run_tests 05:33:11 WARNING - Try message not found. 05:33:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:33:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:33:11 INFO - Python 2.7.3 05:33:11 INFO - Return code: 0 05:33:11 INFO - grabbing minidump binary from tooltool 05:33:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:33:11 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 0x267a090>, '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 0x267ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2674f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:33:11 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 05:33:11 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 05:33:11 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 05:33:11 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:33:11 INFO - Return code: 0 05:33:11 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:33:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:33:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:33:11 INFO - ENV: RUST_BACKTRACE is now full 05:33:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:33:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:33:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BaAK-As6Q66QQapQlMtaJw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--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 05:33:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/BaAK-As6Q66QQapQlMtaJw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-018 --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 05:33:11 INFO - Using env: {'DISPLAY': ':0', 05:33:11 INFO - 'HOME': '/home/cltbld', 05:33:11 INFO - 'LANG': 'en_US.UTF-8', 05:33:11 INFO - 'LANGUAGE': 'en_US:en', 05:33:11 INFO - 'LOGNAME': 'cltbld', 05:33:11 INFO - 'MAIL': '/var/mail/cltbld', 05:33:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:33:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:33:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:33:11 INFO - 'MOZ_NO_REMOTE': '1', 05:33:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:33:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:33:11 INFO - 'NO_EM_RESTART': '1', 05:33:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:33:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:33:11 INFO - 'PWD': '/builds/slave/test', 05:33:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:33:11 INFO - 'RUST_BACKTRACE': 'full', 05:33:11 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 05:33:11 INFO - 'SHELL': '/bin/bash', 05:33:11 INFO - 'SHLVL': '1', 05:33:11 INFO - 'STYLO_FORCE_ENABLED': '1', 05:33:11 INFO - 'STYLO_THREADS': '1', 05:33:11 INFO - 'TERM': 'linux', 05:33:11 INFO - 'TMOUT': '86400', 05:33:11 INFO - 'USER': 'cltbld', 05:33:11 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1504787515.81045-1663338310', 05:33:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:33:11 INFO - '_': '/tools/buildbot/bin/python'} 05:33:11 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BaAK-As6Q66QQapQlMtaJw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--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 05:33:12 INFO - mozversion application_buildid: 20170907103107 05:33:12 INFO - mozversion application_changeset: 6469a2cb7df662afb23493b5566f99b6802fcb5a 05:33:12 INFO - mozversion application_display_name: Nightly 05:33:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:33:12 INFO - mozversion application_name: Firefox 05:33:12 INFO - mozversion application_remotingname: firefox 05:33:12 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:33:12 INFO - mozversion application_vendor: Mozilla 05:33:12 INFO - mozversion application_version: 57.0a1 05:33:12 INFO - mozversion platform_buildid: 20170907103107 05:33:12 INFO - mozversion platform_changeset: 6469a2cb7df662afb23493b5566f99b6802fcb5a 05:33:12 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:33:12 INFO - mozversion platform_version: 57.0a1 05:33:12 INFO - using testdate: 1504787592 05:33:12 INFO - actual date: 1504787592 05:33:12 INFO - starting webserver on 'localhost:33977' 05:33:12 INFO - SUITE-START | Running 2 tests 05:33:12 INFO - TEST-START | dromaeo_css 05:33:12 INFO - Initialising browser for dromaeo_css test... 05:33:12 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:33977/getInfo.html -profile /tmp/tmpyj160Y/profile 05:33:12 INFO - TEST-INFO | started process 3557 (/builds/slave/test/build/application/firefox/firefox http://localhost:33977/getInfo.html) 05:33:20 INFO - TEST-INFO | 3557: exit 0 05:33:20 INFO - Browser initialized. 05:33:20 INFO - Running cycle 1/1 for dromaeo_css test... 05:33:20 INFO - Using env: {'DISPLAY': ':0', 05:33:20 INFO - 'HOME': '/home/cltbld', 05:33:20 INFO - 'JSGC_DISABLE_POISONING': '1', 05:33:20 INFO - 'LANG': 'en_US.UTF-8', 05:33:20 INFO - 'LANGUAGE': 'en_US:en', 05:33:20 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 05:33:20 INFO - 'LOGNAME': 'cltbld', 05:33:20 INFO - 'MAIL': '/var/mail/cltbld', 05:33:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:33:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:33:20 INFO - 'MOZ_CRASHREPORTER': '1', 05:33:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:33:20 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 05:33:20 INFO - 'MOZ_NO_REMOTE': '1', 05:33:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:33:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:33:20 INFO - 'NO_EM_RESTART': '1', 05:33:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:33:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:33:20 INFO - 'PWD': '/builds/slave/test', 05:33:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:33:20 INFO - 'RUST_BACKTRACE': 'full', 05:33:20 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 05:33:20 INFO - 'SHELL': '/bin/bash', 05:33:20 INFO - 'SHLVL': '1', 05:33:20 INFO - 'STYLO_FORCE_ENABLED': '1', 05:33:20 INFO - 'STYLO_THREADS': '1', 05:33:20 INFO - 'TERM': 'linux', 05:33:20 INFO - 'TMOUT': '86400', 05:33:20 INFO - 'USER': 'cltbld', 05:33:20 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1504787515.81045-1663338310', 05:33:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:33:20 INFO - '_': '/tools/buildbot/bin/python'} 05:33:20 INFO - TEST-INFO | started process 3740 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpyj160Y/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 05:33:21 INFO - PID 3740 | 05:33:21 INFO - PID 3740 | (/builds/slave/test/build/application/firefox/firefox:3791): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 05:33:21 INFO - PID 3740 | 05:33:31 INFO - PID 3740 | RSS: Main: 171999232 05:33:31 INFO - PID 3740 | 05:36:29 INFO - PID 3740 | Cycle 1(1): loaded http://localhost:33977/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33977/tests/dromaeo/cssquery-ext.html) 05:36:30 INFO - PID 3740 | RSS: Main: 173891584 05:36:30 INFO - PID 3740 | 05:39:32 INFO - PID 3740 | Cycle 1(1): loaded http://localhost:33977/tests/dromaeo/cssquery-ext.html (next: http://localhost:33977/tests/dromaeo/cssquery-jquery.html) 05:39:32 INFO - PID 3740 | RSS: Main: 170594304 05:39:32 INFO - PID 3740 | 05:42:34 INFO - PID 3740 | Cycle 1(1): loaded http://localhost:33977/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33977/tests/dromaeo/cssquery-mootools.html) 05:42:35 INFO - PID 3740 | RSS: Main: 171335680 05:42:35 INFO - PID 3740 | 05:45:36 INFO - PID 3740 | Cycle 1(1): loaded http://localhost:33977/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33977/tests/dromaeo/cssquery-prototype.html) 05:45:37 INFO - PID 3740 | RSS: Main: 171597824 05:45:37 INFO - PID 3740 | 05:48:40 INFO - PID 3740 | Cycle 1(1): loaded http://localhost:33977/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33977/tests/dromaeo/cssquery-yui.html) 05:48:41 INFO - PID 3740 | RSS: Main: 172367872 05:48:41 INFO - PID 3740 | 05:51:43 INFO - PID 3740 | Cycle 1(1): loaded http://localhost:33977/tests/dromaeo/cssquery-yui.html (next: http://localhost:33977/tests/dromaeo/cssquery-dojo.html) 05:51:43 INFO - PID 3740 | RSS: Main: 175276032 05:51:43 INFO - PID 3740 | 05:51:43 INFO - PID 3740 | __start_tp_report 05:51:43 INFO - PID 3740 | _x_x_mozilla_page_load 05:51:43 INFO - PID 3740 | _x_x_mozilla_page_load_details 05:51:43 INFO - PID 3740 | |i|pagename|runs| 05:51:43 INFO - PID 3740 | |0;dojo.html;3294.9341013179737;3296.505524171375;3320.7675462717607;3326.268220991381;3373.460246360581;6481.675916204194;6529.085927970082;6529.706163222654;6538.3788540088735;6566.376194261544;6349.968250158754;6447.871042579157;6449.645269510187;6463.48292136628;6464.935350646492;5895.88208235835;5899.056151015839;5917.230760001194;5923.14114453404;5955.583109182359;5897.911531327012;5944.256967879015;5950.404959504037;5967.075103358965;5975.701214939258;5987.251593550806;6008.75964961401;6019.819405417845;6034.185384973016;6049.939500605004;5897.292324921015;5900.616459930091;5903.0555111182;5991.730372133221;5997.6701281429505;5687.260656114679;5802.5938184327115;5848.970755146197;5877.38287479817;5979.611325263887;6405.423511883906;6438.323975982501;6487.156669632965;6508.64202468864;6512.023196520512;6695.631740254284;6870.587764734132;6888.690008949559;6991.021257024021;7004.859902801966;6962.686679099453;7080.327368899998;7081.398081163107;7083.929160708379;7085.645717714094;6447.097406363066;6495.837604059889;6531.248906375733;6533.1180290661105;6535.477161827042;7146.749863754743;7183.5330703504105;7228.96385518069;7263.782086537412;7263.854722905512;6632.701528431232;6741.629210393476;6745.7976260711275;6843.178818541807;6943.89584156238;6879.828004299932;6973.12835895513;6980.336867997532;6982.511224214256;6983.650817459106;6868.347506986828;6905.551139175937;6923.134608173978;6959.304069593;6978.267281935425;6888.104546408935;6892.586444813327;6943.166819981533;6986.7205311787975;7021.648917554102;5424.321959755031;5467.289252397163;5508.669479831223;5510.393856675762;5525.226468294442;4785.688930219525;4817.855464336076;4835.44392394872;4835.975820120947;4836.153673107192;5888.499477544371;5889.381614930457;5971.552133589998;5972.104184372379;5975.462208401178;6910.2744211858035;6911.205211400753;6923.342282483257;6930.930690693029;7009.509334346547;6044.335123136451;6078.665673388006;6089.269287685506;6093.817185484442;6099.634021958696;6043.607165534227;6065.302490213682;6072.939270607326;6088.995315772936;6095.847603809941;6715.832104197434;6776.9322306777285;6779.559328643622;6863.313668633096;6879.380855722909;6830.248672646105;6912.135983002125;6921.065656136358;6993.580385177008;7093;6678.766243181465;6689.063531105552;6692.431143352869;6696.59820410777;6698.7320507179165;6425.325340839145;6771.525993180627;6783.660816959034;6871.587704737732;6932.896006559805;7003.984422258642;7020.052292940388;7091.60996145217;7096.787096387116;7132.572045677276;6570.671466426564;6649.335066493312;6659.667016649051;6745.291744366771;6799.966000169968;6672.499562532732;6684.665766711548;6691.464682825265;6696.933030669826;6701.731930722716;6543.116679248241;6547.869042619216;6572.80281591553;6601.3728695773825;6622.933770662232;6358.872822543431;6723.327667233238;6754.831129221612;6808.93191068083;6835.282295359115;6460.806175814733;6466.385693359123;6556.508261880283;6560.606363618198;6567 05:51:43 INFO - PID 3740 | |1;ext.html;766.1266156581495;805.7341077444444;826.0541679776655;854.0861278432078;857.3398483167965;39621;40217.79891100548;40271.19457610846;40305.79847100768;40425.59574404255;36598.63401365985;36835.815820920856;36869;36901.07747306319;36901.815490922505;6753.493487988396;6838.9658051709675;6863.691133898961;6870.1068861048;6872.862542749167;6740.629265390402;6830.624315662637;6832.45340372769;6836.726530938757;6838.452923766112;6786.491013174032;7464.440166987471;7715.421343399267;7721.613919304041;7725.459217854753;7623.199564045751;7631.923680763177;7671.654775535114;7674.424418168604;7676.1172465166665;7541.77374678755;7607.239276072349;7610.08678958523;7613.847723045564;7617.085949686018;35837.64162358395;36122.63877361245;36162.09594760126;36164;36205.63794362049;3510.8420121094355;3535.5757309122814;3541.752077354586;3544.3974524330924;3544.5392099027217;29895.70104298951;29926.25184370408;29939.850300748574;29989.100326990225;29996.95010674638;5122.103631864409;5150.742462876841;5197;5198.792048318062;5224.451432599561;175207;175566;175824;176053;176147.11926440414;46514.30228546575;46560;46609;46625.53374466212;46774.29838552424;48869.755651221516;49217.50782492129;49348.25977610339;49363.753181234584;49678.25482617764;14663.340149693076;14735.92632036833;14766.778498322534;14794.852051479347;14799.482018129314;11847.88152118485;11870.109741769229;11900.285982841056;11913.821292680617;11915.761684766256;14003.649908752363;14107.224102674452;14173.149611023371;14181.432742690176;14321.283935803169;5574;5641.407652196543;5703.287089113861;5760.135979603009;5795.594308398456;996.242855429864;1016.588281745894;1016.8627235323137;1029.6602121299952;1041.1358572384902;22350.77649223487;27639.585406218925;27925.581116283272;28227.306361618368;28428.147155585364;1085.9185561082793;1095.326374279828;1100.2848148703247;1104.9005589496983;1104.9558017679365;12136.757264854654;12285.57000504978;12326.570173817037;12391.690207744878;12425.068119891044;235975;240793;241897;241904;242473;41554.79222603868;42112;45930;48138.51861481411;48321;1402.3549167383119;1408.9647758806111;1411.4848080450768;1417.2630232279157;1421.1260075053767;26578.006649500978;26977.0558030468;27108.45783084367;27304.72695273022;27377.589336159574;35995.82002089973;36008;36085.639143609274;36222.09444763797;36283;204288;204517;205487;205544;205671;31415.528767068055;32029.03912882617;32189.678103218666;32551.348973019933;32575.18562035968;39734.20531589294;40079.39880901731;40205.79897100496;40278.1944361117;40424;29278;29300;29824;30057.098287051424;30200;29194;30108.84945575258;30624.846875765477;30681.079567613007;30703.539446907867;33403.83298083494;33507.162320942174;33511.497327540594;33535.329293414485;36153.27693446169;31521.52717709282;31917.8404107978;31965.840170798998;32367.51448728224;32528.674713252563;23237;23951.88024059938;24376.51246975086;24875.378115547257;25415.110471133423 05:51:43 INFO - PID 3740 | |2;jquery.html;556.4880310114694;558.5587385965088;567.7899177304398;572.4790440698963;586.870888404551;21216.151353945843;22102;22239.332820015406;22264.777352226472;22340.776592234073;22755.658665120038;23303.300900972998;23318.067277308888;23322;23341.649875251886;7123.643817809115;7313.268673132698;7324;7324.670389832444;7328.230535793745;6295.653739044351;7002.229754726976;7011.544249623784;7023.192332881707;7023.61370124643;6971.128608923885;6994.650267486631;7001.159860816683;7003.789886303393;7013.263607321234;6991.790246292594;6995.79012629622;7009.684564194598;7011.824704382431;7021.719131234744;6867.2102708188695;6875.07186529817;6876;6884.759033433856;6885.242623311432;29962.25094372636;30797.384052319052;31340.843295783376;31728;31766;2069.844761642883;2113.8520303578757;2115.672070829024;2120.4910821402905;2120.7879212078824;20408.28571000008;21047.47381315464;21175.5764884703;21234.5753084939;21342.146314147416;2444.7310795812446;2478.4175718706088;2561.8334808237405;2640.524705553;2642.6036094585775;133348;137851.31074344664;139290.91063634053;139370;139731;20352.592948140955;21731.891340543196;21982.34052978413;22486.30164380285;22667.88666056659;22293.108275669118;22939;22998;42645.57354426478;42841.57158428438;9560.808783824423;9889.406635601887;10049.69850904474;10054.547545360476;10078.042585954323;8982.730518084469;9001.18989290967;9061.040120056123;9087.000429952699;9090.36367454285;9358.57886395114;9428.95285523568;9529.80814412689;9577.23382129428;9612.086851749073;2819.957700634492;2821.971780282212;2831.2780241038404;2836.234216761463;2892.580575816537;963.7397902566267;979.1285755677453;982.4449186209769;995.1640621877658;1000.214831357381;20098.296559620343;20159.0928408222;20248.392548223575;20272.290469833486;20531;1022.9897701022894;1027.6968294353185;1034.4982683398546;1035.6789395287335;1038.5949479702922;11130.554777808959;11134.554617815358;11197.552097916156;11336;11366.374849383363;440773;451966;455008;455804;458477;107160;112946.43526782314;113338;114075;114082;1165.81929800881;1166.807476766324;1191.9463624136936;1193.8806119388166;1195.4142470189718;18009.54976125555;18047.36834210849;18075.252967890712;18198.818011819712;18284.908575457037;44694.3295850569;48592.75703621459;53853;55601;57273.71363143158;65755;65755.6712216455;66044.33955660382;66091;66211.66894165498;14442.566722998326;14655.120692758468;14678.706425871209;14695.853041469449;14715.92642036783;22830.543389132457;23044.308670739905;23199.76800231976;23266.41833954097;23271.883640581687;17917.283308667505;18081.909590451964;18111.45665630033;18156.546086347418;18428.447146585622;15326;15392.615184620474;15620.140892251035;15731.528054158394;15897.52307430779;19753;19829;19838;19902.50243743918;20006;14400.783988239975;14594.197319147124;14635.048721833047;14651.12093274407;14665.266736663338;18380.724289135407;18743.250269989592;18752.812471875106;18767.436976890716;18767.812321876605 05:51:43 INFO - PID 3740 | |3;mootools.html;1328.6944002879338;1375.7386096641637;1394.6234516680497;1402.9789553156704;1406.4233664197682;12283.754324913496;14899.404023839046;15448.922755386235;15518.767218491732;15529.22353882307;12463.314517701545;15150.621234469143;15591.84408155918;15596.06423614584;15601;2685.2212858271128;2748.848813315267;2751.17464760572;2752.7522522972927;2753;2578.5358635445614;2685.5568831142837;2695.3935364543017;2698.2040298112006;2768.8338699678084;2682.557378032622;2696.164189101375;2698.1500827239456;2709.376843326036;2715.266877942954;2615.028465260465;2620.2008387441824;2624.514464824014;2637.3670319123366;2637.577987521997;2578.7034491033583;2583.225032490245;2596.402827349701;2601.648777415063;2609.3346196719904;14888.330025148894;15287.312070956834;15287.61780955485;15299.847001529954;15303.540893773205;7172.856542869114;7346.853062938711;7361.227071157505;7387.4459415543515;7399.889001664926;5158.458361872024;5263.6578622389425;5296.9735151324385;5389.299391079174;5424.972875135639;1878.1642169234697;1888.9433317000512;1899.5061284066105;1943.805619438059;1966.488712934633;25688.486230275477;25872;25876.74123258762;25893.611595826078;25942.870285648452;6760.560563563451;6776.627285499345;6842.384185423236;6930.93069069313;6943.9652801735665;17191.742123868153;17266.827331726774;17291.135443227788;17294.913525432294;17297.91351043237;7458.962705186439;7492.213317601682;7541.321281084619;7654.617269136521;7659.808504787425;7030.437564994788;7059.89410158848;7249.057622509145;7322.121345438475;7325.5970921598755;4714.245720684674;4999;5102.107131252016;5134.255532947702;5151.896962060738;1313.7438199551186;1419.389662445138;1447.9348429320705;1448.1962510806393;1462.8829693624484;908.3278374003322;921.2307723051193;927.1238679447991;929.9953500232591;936.1434287626745;8469;8865.290776737846;8875.378723489423;8910.955445222862;8921.063288354759;981.47000619666;995.4773743784426;997.3666721631816;998.1765043838863;999.9500024998721;4571.22289210835;5544.833654990357;5789;5807.709614519257;6193.442590166816;21655.133794648355;21955;22077.668834967488;22171.334859954226;22377.888110559343;20609.381718548466;21914.671279930815;21932;21962.560748784934;22065.227717029826;11098.999999999838;11357.829632555518;11446.14153938458;11686.590969316034;11708.238964467282;8569.614367353359;9053.321000925089;9164.716939628324;9265.351425400157;9343.953280233556;18819.435416937515;18908.43274701761;18968.146433410806;19101;19463;19049.619007619494;19158.904205478884;19214.903925480285;19480.610387792447;19555.315563955763;11944.701382465508;12559.120861539604;12586.18189817659;12612;12653.746925061263;13960.301984900918;13986.44054237819;14068.788968165281;14095.083819551697;14140.78788818148;10816.351018938889;11295.717607059889;11307;11451.198416110792;11617.128715346551;10921.726956826144;11127.55489780432;11205.43972801373;11229.27009744364;11312.264702794291;9748.268879834182;9864.408135511892;9920.305578609428;9926.205903527845;9936.056074672895;6935.237123916466;6975.372216500539;7026.473014524034;7051.294870513113;7069.974853646089;7040.718371265297;7081.8937715935335;7098.609576473344;7108.182559005781;7165.820854478679 05:51:43 INFO - PID 3740 | |4;prototype.html;493.0316565375507;493.4994716016614;515.4098557152063;520.4743209358547;526.1949217696923;6293.307736149026;6363.268224154223;6441.935580644192;6446;6503.902441463376;6179.227596550431;6248.84377890553;6336.778212762554;6367.172267605205;6399.976003839387;5118.59051275897;5143.7685304161405;5170.3713330301025;5176.842126302952;5181;4905.994271174419;5059.721715305656;5113.795448182068;5137.743112844343;5170.327857378555;5002.349694539723;5041.344625198719;5092.541671249587;5130.127878260705;5152.562032227265;5021.447640759514;5049.621278404097;5079.720615366172;5105.2086926526445;5139.897202055957;4533.433320834895;4573.382593349888;4574.862754117382;4590.219662657323;4609.469910960249;6291.716872740738;6339.0808332791485;6375.553711240215;6388.488920886318;6401.295857455676;5850.385709500484;5854.151148083547;5860.06239001762;5867.677277749722;5888.646681199141;6685.76599819004;6715.395614394679;6732.394084532416;6781.152355955505;6781.847085995392;4924.030387844855;4938.580220681247;4977.377827771528;5000.874978125539;5016.974915125437;7053.788386348418;7080.645967701595;7136.6431678415875;7212.74755383559;7229.530080544748;5065.822696205689;5071.112555302807;5106.770195341144;5107.591392688576;5148.330717006766;5132.666376685504;5157.432682404932;5190.636655434083;5234.162533994542;5238.716514453907;5764.884702305931;5821.388754180834;5861.88276234473;5883.499902508249;5908.911366329508;6585;6726.562773419712;6760.599987004641;6761.661916904233;6763.323667633197;1673.1550566963606;1738.4350086221775;1770.8577967211077;1794.23744908416;1942.9028548572514;1419.602511296818;1429.306786208688;1451.2235953764684;1451.702401007791;1478.7486127358375;5827.329857066492;5832.387599302097;5833.82498525045;5920.230370051866;5943.851403714855;6674.399304062657;6686.832829179212;6720.63968015992;6828.897566536506;6852.794416167423;5851.882962340729;5857.531397488191;5869.677167755729;5873.471387575139;5917.852053698692;3548.8048157351595;3600.1719604491113;3614.7769121989786;3623.58328792187;3643.198496330856;6526.216853977553;6694.196696396463;6697.388018672722;6721.428678562279;6759.729610815512;7031.507794454441;7054.365107140382;7074.787756367317;7128.607926563985;7144.607046612382;6678.83302917431;6704.899426508607;6728.865422691617;6747.224069232003;6757.695903684346;6936.34104760047;6971.233164352018;6972;6992.741306564867;7016.649167541705;6806.829829254309;6914.619695916572;6923.203831559234;6930.61881596517;6990.755323563651;6657.2677005528385;6678.79599732097;6683.198016237888;6694.866102678016;6735.797926062226;5006.523075692752;5016.824411145592;5097.541221290102;5133;5159.845204643867;7003.299670032956;7042.225355211025;7056.435485161072;7063.187733410724;7066.929330706627;6779.491538134561;6789.740924356238;6792.796216113525;6806.502569434717;6827.453803695593;6697.933020669731;6791.626460544519;6798.150231221098;6808.468094678856;6810.216825065181;6453.41919227253;6569.638669873203;6596.175478065242;6604.768833090819;6619.205695316593;6573.244076931022;6711.758324709945;6719.932800671931;6760.797176084725;6831.316868313129;6538.574992625654;6540.215174179129;6541.182352205974;6557.573757705733;6562.5406221564035 05:51:43 INFO - PID 3740 | |5;yui.html;830.6377935257661;845.6152450634962;872.3326655108843;893.977650558736;917.8210249001445;15957.52127436176;16325.673486530262;16328.918355408221;16362.509124726248;19809.900950495263;21439.249626263085;21951.780482195172;21977.120915163374;22071.33785986423;22100.33698989033;970.1559643110486;979.0601023017912;979.8824141103078;981.7496538382702;985.5121714751208;942.1708896171394;986.7779749556364;988.1304452082194;988.3378136648427;988.7330420786384;1044.979100417995;1064;1064.9893501064967;1066.9146468282559;1067.3222503710122;1043.405259002369;1044.3159730376615;1051.9158467322595;1053.3837704942619;1053.5522402978743;773.1032002876636;776.7863837444692;779.6803310642609;779.9064112306503;780.2470615855669;19658.60682786336;19713.605727885504;19809.9009504953;19855.205791768312;20559.280425185047;20877.164913403445;20908.68636970447;21082.578348432944;21391.78608213929;21411.250606228707;16484.258208380652;16606.25271862769;16719.414820481346;16831;16918.323267069303;3677.7241706871823;3696.796676182809;3708.5178926739623;3711.5731690855628;3712.795796231206;123758;124271.98021381827;126586.367068165;127825.36087319597;128717;16484.175791210393;16486.422975195812;16558.254878530253;16670.916645416695;16774.245158967875;29150.562741558893;30099.398012040074;30297.24256893595;30356.24109397283;30404.391912161635;8188.836223275501;8268.503889766633;8314.41799074059;8323.458975166715;8347.29048030912;3846;3918.5885482024546;3925.5878132796215;3929.44987701722;3930.3514920038447;5149.382074151126;5291.417944026154;5332.226827110125;5341.092014357569;5358.651687640291;903.6728485577484;906.9047749986288;914.8124634449922;919.691903212418;920.1994264989369;675.7972608217515;684.4729558240226;689.6379400814512;697.6720941157648;702.255609054404;11615.883841161649;11787.646370608896;11941.52233910634;12953.158044727063;13039.348032598333;697.4803771190495;698.2912343970874;703.4618516834626;705.5131958948309;706.7844307486163;5361.678299302054;5370.812021579226;5455.09990851513;5512.751926163332;5514.5864060194845;109683.16108936149;110154;111694;114087.42956285166;116933;96429;98916.50541747245;99005;100540;102478.48760756149;83449.58275208707;88405.55797221101;90902.09097909069;91201.5439922796;93909;12282.570110046105;12575.87424125747;12618.116731828684;12630.43163057683;13197.340132993504;34740.47889281713;35609.82195089008;38834.80582597069;40020.399694005195;42873;70266;70477.64761176161;70477.64761176161;71591;72121.6393918027;19391.2243510258;19517.8048219516;19521.121549530588;19726;19790;20517.89741051285;21201.787982119982;21277.6808347878;21393.465163370416;21428.464288392915;18555.628887422445;18606.81393186051;19229.711554326976;19394.0302984853;19487.512812179808;18583.81416185821;19205.32781352702;19235.711464327767;19248.422547323604;19566;18004.459866204033;18154.18306176199;18217.54456138554;18304.542386439913;19066;11571.768564628492;11740.765184696087;11837.526498939946;12459.813102803646;12689.873101269239;13773.071769952416;13801.171929684251;13839.930800345934;13869.583912482642;13969.860301397262 05:51:43 INFO - PID 3740 | __end_tp_report 05:51:43 INFO - PID 3740 | __start_cc_report 05:51:43 INFO - PID 3740 | _x_x_mozilla_cycle_collect,375 05:51:43 INFO - PID 3740 | __end_cc_report 05:51:43 INFO - PID 3740 | __startTimestamp1504788703552__endTimestamp 05:51:43 INFO - PID 3740 | 05:51:43 INFO - PID 3740 | ------- Summary: start ------- 05:51:43 INFO - PID 3740 | Number of tests: 6 05:51:43 INFO - PID 3740 | 05:51:43 INFO - PID 3740 | [#0] dojo.html Cycles:165 Average:6378.49 Median:6566.38 stddev:752.57 (11.5%) stddev-sans-first:714.95 05:51:43 INFO - PID 3740 | Values: 3294.9 3296.5 3320.8 3326.3 3373.5 6481.7 6529.1 6529.7 6538.4 6566.4 6350.0 6447.9 6449.6 6463.5 6464.9 5895.9 5899.1 5917.2 5923.1 5955.6 5897.9 5944.3 5950.4 5967.1 5975.7 5987.3 6008.8 6019.8 6034.2 6049.9 5897.3 5900.6 5903.1 5991.7 5997.7 5687.3 5802.6 5849.0 5877.4 5979.6 6405.4 6438.3 6487.2 6508.6 6512.0 6695.6 6870.6 6888.7 6991.0 7004.9 6962.7 7080.3 7081.4 7083.9 7085.6 6447.1 6495.8 6531.2 6533.1 6535.5 7146.7 7183.5 7229.0 7263.8 7263.9 6632.7 6741.6 6745.8 6843.2 6943.9 6879.8 6973.1 6980.3 6982.5 6983.7 6868.3 6905.6 6923.1 6959.3 6978.3 6888.1 6892.6 6943.2 6986.7 7021.6 5424.3 5467.3 5508.7 5510.4 5525.2 4785.7 4817.9 4835.4 4836.0 4836.2 5888.5 5889.4 5971.6 5972.1 5975.5 6910.3 6911.2 6923.3 6930.9 7009.5 6044.3 6078.7 6089.3 6093.8 6099.6 6043.6 6065.3 6072.9 6089.0 6095.8 6715.8 6776.9 6779.6 6863.3 6879.4 6830.2 6912.1 6921.1 6993.6 7093.0 6678.8 6689.1 6692.4 6696.6 6698.7 6425.3 6771.5 6783.7 6871.6 6932.9 7004.0 7020.1 7091.6 7096.8 7132.6 6570.7 6649.3 6659.7 6745.3 6800.0 6672.5 6684.7 6691.5 6696.9 6701.7 6543.1 6547.9 6572.8 6601.4 6622.9 6358.9 6723.3 6754.8 6808.9 6835.3 6460.8 6466.4 6556.5 6560.6 6567.0 05:51:43 INFO - PID 3740 | 05:51:43 INFO - PID 3740 | [#1] ext.html Cycles:180 Average:36853.95 Median:33509.33 stddev:54113.83 (161.5%) stddev-sans-first:54197.79 05:51:43 INFO - PID 3740 | Values: 766.1 805.7 826.1 854.1 857.3 39621.0 40217.8 40271.2 40305.8 40425.6 36598.6 36835.8 36869.0 36901.1 36901.8 6753.5 6839.0 6863.7 6870.1 6872.9 6740.6 6830.6 6832.5 6836.7 6838.5 6786.5 7464.4 7715.4 7721.6 7725.5 7623.2 7631.9 7671.7 7674.4 7676.1 7541.8 7607.2 7610.1 7613.8 7617.1 35837.6 36122.6 36162.1 36164.0 36205.6 3510.8 3535.6 3541.8 3544.4 3544.5 29895.7 29926.3 29939.9 29989.1 29997.0 5122.1 5150.7 5197.0 5198.8 5224.5 175207.0 175566.0 175824.0 176053.0 176147.1 46514.3 46560.0 46609.0 46625.5 46774.3 48869.8 49217.5 49348.3 49363.8 49678.3 14663.3 14735.9 14766.8 14794.9 14799.5 11847.9 11870.1 11900.3 11913.8 11915.8 14003.6 14107.2 14173.1 14181.4 14321.3 5574.0 5641.4 5703.3 5760.1 5795.6 996.2 1016.6 1016.9 1029.7 1041.1 22350.8 27639.6 27925.6 28227.3 28428.1 1085.9 1095.3 1100.3 1104.9 1105.0 12136.8 12285.6 12326.6 12391.7 12425.1 235975.0 240793.0 241897.0 241904.0 242473.0 41554.8 42112.0 45930.0 48138.5 48321.0 1402.4 1409.0 1411.5 1417.3 1421.1 26578.0 26977.1 27108.5 27304.7 27377.6 35995.8 36008.0 36085.6 36222.1 36283.0 204288.0 204517.0 205487.0 205544.0 205671.0 31415.5 32029.0 32189.7 32551.3 32575.2 39734.2 40079.4 40205.8 40278.2 40424.0 29278.0 29300.0 29824.0 30057.1 30200.0 29194.0 30108.8 30624.8 30681.1 30703.5 33403.8 33507.2 33511.5 33535.3 36153.3 31521.5 31917.8 31965.8 32367.5 32528.7 23237.0 23951.9 24376.5 24875.4 25415.1 05:51:43 INFO - PID 3740 | 05:51:43 INFO - PID 3740 | [#2] jquery.html Cycles:180 Average:33878.67 Median:22793.10 stddev:76544.58 (335.8%) stddev-sans-first:76718.42 05:51:43 INFO - PID 3740 | Values: 556.5 558.6 567.8 572.5 586.9 21216.2 22102.0 22239.3 22264.8 22340.8 22755.7 23303.3 23318.1 23322.0 23341.6 7123.6 7313.3 7324.0 7324.7 7328.2 6295.7 7002.2 7011.5 7023.2 7023.6 6971.1 6994.7 7001.2 7003.8 7013.3 6991.8 6995.8 7009.7 7011.8 7021.7 6867.2 6875.1 6876.0 6884.8 6885.2 29962.3 30797.4 31340.8 31728.0 31766.0 2069.8 2113.9 2115.7 2120.5 2120.8 20408.3 21047.5 21175.6 21234.6 21342.1 2444.7 2478.4 2561.8 2640.5 2642.6 133348.0 137851.3 139290.9 139370.0 139731.0 20352.6 21731.9 21982.3 22486.3 22667.9 22293.1 22939.0 22998.0 42645.6 42841.6 9560.8 9889.4 10049.7 10054.5 10078.0 8982.7 9001.2 9061.0 9087.0 9090.4 9358.6 9429.0 9529.8 9577.2 9612.1 2820.0 2822.0 2831.3 2836.2 2892.6 963.7 979.1 982.4 995.2 1000.2 20098.3 20159.1 20248.4 20272.3 20531.0 1023.0 1027.7 1034.5 1035.7 1038.6 11130.6 11134.6 11197.6 11336.0 11366.4 440773.0 451966.0 455008.0 455804.0 458477.0 107160.0 112946.4 113338.0 114075.0 114082.0 1165.8 1166.8 1191.9 1193.9 1195.4 18009.5 18047.4 18075.3 18198.8 18284.9 44694.3 48592.8 53853.0 55601.0 57273.7 65755.0 65755.7 66044.3 66091.0 66211.7 14442.6 14655.1 14678.7 14695.9 14715.9 22830.5 23044.3 23199.8 23266.4 23271.9 17917.3 18081.9 18111.5 18156.5 18428.4 15326.0 15392.6 15620.1 15731.5 15897.5 19753.0 19829.0 19838.0 19902.5 20006.0 14400.8 14594.2 14635.0 14651.1 14665.3 18380.7 18743.3 18752.8 18767.4 18767.8 05:51:43 INFO - PID 3740 | 05:51:43 INFO - PID 3740 | [#3] mootools.html Cycles:180 Average:9366.31 Median:2612.18 stddev:6701.11 (256.5%) stddev-sans-first:6692.70 05:51:43 INFO - PID 3740 | Values: 1328.7 1375.7 1394.6 1403.0 1406.4 12283.8 14899.4 15448.9 15518.8 15529.2 12463.3 15150.6 15591.8 15596.1 15601.0 2685.2 2748.8 2751.2 2752.8 2753.0 2578.5 2685.6 2695.4 2698.2 2768.8 2682.6 2696.2 2698.2 2709.4 2715.3 2615.0 2620.2 2624.5 2637.4 2637.6 2578.7 2583.2 2596.4 2601.6 2609.3 14888.3 15287.3 15287.6 15299.8 15303.5 7172.9 7346.9 7361.2 7387.4 7399.9 5158.5 5263.7 5297.0 5389.3 5425.0 1878.2 1888.9 1899.5 1943.8 1966.5 25688.5 25872.0 25876.7 25893.6 25942.9 6760.6 6776.6 6842.4 6930.9 6944.0 17191.7 17266.8 17291.1 17294.9 17297.9 7459.0 7492.2 7541.3 7654.6 7659.8 7030.4 7059.9 7249.1 7322.1 7325.6 4714.2 4999.0 5102.1 5134.3 5151.9 1313.7 1419.4 1447.9 1448.2 1462.9 908.3 921.2 927.1 930.0 936.1 8469.0 8865.3 8875.4 8911.0 8921.1 981.5 995.5 997.4 998.2 1000.0 4571.2 5544.8 5789.0 5807.7 6193.4 21655.1 21955.0 22077.7 22171.3 22377.9 20609.4 21914.7 21932.0 21962.6 22065.2 11099.0 11357.8 11446.1 11686.6 11708.2 8569.6 9053.3 9164.7 9265.4 9344.0 18819.4 18908.4 18968.1 19101.0 19463.0 19049.6 19158.9 19214.9 19480.6 19555.3 11944.7 12559.1 12586.2 12612.0 12653.7 13960.3 13986.4 14068.8 14095.1 14140.8 10816.4 11295.7 11307.0 11451.2 11617.1 10921.7 11127.6 11205.4 11229.3 11312.3 9748.3 9864.4 9920.3 9926.2 9936.1 6935.2 6975.4 7026.5 7051.3 7070.0 7040.7 7081.9 7098.6 7108.2 7165.8 05:51:43 INFO - PID 3740 | 05:51:43 INFO - PID 3740 | [#4] prototype.html Cycles:180 Average:5669.08 Median:6351.17 stddev:1589.92 (25.0%) stddev-sans-first:1546.19 05:51:43 INFO - PID 3740 | Values: 493.0 493.5 515.4 520.5 526.2 6293.3 6363.3 6441.9 6446.0 6503.9 6179.2 6248.8 6336.8 6367.2 6400.0 5118.6 5143.8 5170.4 5176.8 5181.0 4906.0 5059.7 5113.8 5137.7 5170.3 5002.3 5041.3 5092.5 5130.1 5152.6 5021.4 5049.6 5079.7 5105.2 5139.9 4533.4 4573.4 4574.9 4590.2 4609.5 6291.7 6339.1 6375.6 6388.5 6401.3 5850.4 5854.2 5860.1 5867.7 5888.6 6685.8 6715.4 6732.4 6781.2 6781.8 4924.0 4938.6 4977.4 5000.9 5017.0 7053.8 7080.6 7136.6 7212.7 7229.5 5065.8 5071.1 5106.8 5107.6 5148.3 5132.7 5157.4 5190.6 5234.2 5238.7 5764.9 5821.4 5861.9 5883.5 5908.9 6585.0 6726.6 6760.6 6761.7 6763.3 1673.2 1738.4 1770.9 1794.2 1942.9 1419.6 1429.3 1451.2 1451.7 1478.7 5827.3 5832.4 5833.8 5920.2 5943.9 6674.4 6686.8 6720.6 6828.9 6852.8 5851.9 5857.5 5869.7 5873.5 5917.9 3548.8 3600.2 3614.8 3623.6 3643.2 6526.2 6694.2 6697.4 6721.4 6759.7 7031.5 7054.4 7074.8 7128.6 7144.6 6678.8 6704.9 6728.9 6747.2 6757.7 6936.3 6971.2 6972.0 6992.7 7016.6 6806.8 6914.6 6923.2 6930.6 6990.8 6657.3 6678.8 6683.2 6694.9 6735.8 5006.5 5016.8 5097.5 5133.0 5159.8 7003.3 7042.2 7056.4 7063.2 7066.9 6779.5 6789.7 6792.8 6806.5 6827.5 6697.9 6791.6 6798.2 6808.5 6810.2 6453.4 6569.6 6596.2 6604.8 6619.2 6573.2 6711.8 6719.9 6760.8 6831.3 6538.6 6540.2 6541.2 6557.6 6562.5 05:51:43 INFO - PID 3740 | 05:51:43 INFO - PID 3740 | [#5] yui.html Cycles:180 Average:23980.68 Median:21419.86 stddev:32641.22 (152.4%) stddev-sans-first:32686.50 05:51:43 INFO - PID 3740 | Values: 830.6 845.6 872.3 894.0 917.8 15957.5 16325.7 16328.9 16362.5 19809.9 21439.2 21951.8 21977.1 22071.3 22100.3 970.2 979.1 979.9 981.7 985.5 942.2 986.8 988.1 988.3 988.7 1045.0 1064.0 1065.0 1066.9 1067.3 1043.4 1044.3 1051.9 1053.4 1053.6 773.1 776.8 779.7 779.9 780.2 19658.6 19713.6 19809.9 19855.2 20559.3 20877.2 20908.7 21082.6 21391.8 21411.3 16484.3 16606.3 16719.4 16831.0 16918.3 3677.7 3696.8 3708.5 3711.6 3712.8 123758.0 124272.0 126586.4 127825.4 128717.0 16484.2 16486.4 16558.3 16670.9 16774.2 29150.6 30099.4 30297.2 30356.2 30404.4 8188.8 8268.5 8314.4 8323.5 8347.3 3846.0 3918.6 3925.6 3929.4 3930.4 5149.4 5291.4 5332.2 5341.1 5358.7 903.7 906.9 914.8 919.7 920.2 675.8 684.5 689.6 697.7 702.3 11615.9 11787.6 11941.5 12953.2 13039.3 697.5 698.3 703.5 705.5 706.8 5361.7 5370.8 5455.1 5512.8 5514.6 109683.2 110154.0 111694.0 114087.4 116933.0 96429.0 98916.5 99005.0 100540.0 102478.5 83449.6 88405.6 90902.1 91201.5 93909.0 12282.6 12575.9 12618.1 12630.4 13197.3 34740.5 35609.8 38834.8 40020.4 42873.0 70266.0 70477.6 70477.6 71591.0 72121.6 19391.2 19517.8 19521.1 19726.0 19790.0 20517.9 21201.8 21277.7 21393.5 21428.5 18555.6 18606.8 19229.7 19394.0 19487.5 18583.8 19205.3 19235.7 19248.4 19566.0 18004.5 18154.2 18217.5 18304.5 19066.0 11571.8 11740.8 11837.5 12459.8 12689.9 13773.1 13801.2 13839.9 13869.6 13969.9 05:51:43 INFO - PID 3740 | -------- Summary: end -------- 05:51:43 INFO - PID 3740 | 05:51:44 INFO - TEST-INFO | 3740: exit 0 05:51:44 INFO - TEST-OK | dromaeo_css | took 1112282ms 05:51:44 INFO - TEST-START | kraken 05:51:44 INFO - Initialising browser for kraken test... 05:51:44 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:33977/getInfo.html -profile /tmp/tmphVTSZQ/profile 05:51:44 INFO - TEST-INFO | started process 4157 (/builds/slave/test/build/application/firefox/firefox http://localhost:33977/getInfo.html) 05:51:50 INFO - TEST-INFO | 4157: exit 0 05:51:50 INFO - Browser initialized. 05:51:50 INFO - Running cycle 1/1 for kraken test... 05:51:50 INFO - Using env: {'DISPLAY': ':0', 05:51:50 INFO - 'HOME': '/home/cltbld', 05:51:50 INFO - 'JSGC_DISABLE_POISONING': '1', 05:51:50 INFO - 'LANG': 'en_US.UTF-8', 05:51:50 INFO - 'LANGUAGE': 'en_US:en', 05:51:50 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 05:51:50 INFO - 'LOGNAME': 'cltbld', 05:51:50 INFO - 'MAIL': '/var/mail/cltbld', 05:51:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:51:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:51:50 INFO - 'MOZ_CRASHREPORTER': '1', 05:51:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:51:50 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 05:51:50 INFO - 'MOZ_NO_REMOTE': '1', 05:51:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:51:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:51:50 INFO - 'NO_EM_RESTART': '1', 05:51:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:51:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:51:50 INFO - 'PWD': '/builds/slave/test', 05:51:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:51:50 INFO - 'RUST_BACKTRACE': 'full', 05:51:50 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 05:51:50 INFO - 'SHELL': '/bin/bash', 05:51:50 INFO - 'SHLVL': '1', 05:51:50 INFO - 'STYLO_FORCE_ENABLED': '1', 05:51:50 INFO - 'STYLO_THREADS': '1', 05:51:50 INFO - 'TERM': 'linux', 05:51:50 INFO - 'TMOUT': '86400', 05:51:50 INFO - 'USER': 'cltbld', 05:51:50 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1504787515.81045-1663338310', 05:51:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:51:50 INFO - '_': '/tools/buildbot/bin/python'} 05:51:50 INFO - TEST-INFO | started process 4341 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphVTSZQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 05:51:51 INFO - PID 4341 | 05:51:51 INFO - PID 4341 | (/builds/slave/test/build/application/firefox/firefox:4392): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 05:51:51 INFO - PID 4341 | 05:52:01 INFO - PID 4341 | RSS: Main: 170393600 05:52:01 INFO - PID 4341 | 05:52:04 INFO - PID 4341 | Cycle 1(1): loaded http://localhost:33977/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33977/tests/kraken/driver.html?testName=audio-beat-detection) 05:52:05 INFO - PID 4341 | RSS: Main: 175964160 05:52:05 INFO - PID 4341 | 05:52:08 INFO - PID 4341 | Cycle 1(1): loaded http://localhost:33977/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33977/tests/kraken/driver.html?testName=audio-dft) 05:52:08 INFO - PID 4341 | RSS: Main: 177168384 05:52:08 INFO - PID 4341 | 05:52:11 INFO - PID 4341 | Cycle 1(1): loaded http://localhost:33977/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33977/tests/kraken/driver.html?testName=audio-fft) 05:52:12 INFO - PID 4341 | RSS: Main: 171003904 05:52:12 INFO - PID 4341 | 05:52:14 INFO - PID 4341 | Cycle 1(1): loaded http://localhost:33977/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33977/tests/kraken/driver.html?testName=audio-oscillator) 05:52:15 INFO - PID 4341 | RSS: Main: 172597248 05:52:15 INFO - PID 4341 | 05:52:17 INFO - PID 4341 | Cycle 1(1): loaded http://localhost:33977/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33977/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:52:17 INFO - PID 4341 | RSS: Main: 172830720 05:52:17 INFO - PID 4341 | 05:52:20 INFO - PID 4341 | Cycle 1(1): loaded http://localhost:33977/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33977/tests/kraken/driver.html?testName=imaging-darkroom) 05:52:21 INFO - PID 4341 | RSS: Main: 175022080 05:52:21 INFO - PID 4341 | 05:52:25 INFO - PID 4341 | Cycle 1(1): loaded http://localhost:33977/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33977/tests/kraken/driver.html?testName=imaging-desaturate) 05:52:25 INFO - PID 4341 | RSS: Main: 175255552 05:52:25 INFO - PID 4341 | 05:52:28 INFO - PID 4341 | Cycle 1(1): loaded http://localhost:33977/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33977/tests/kraken/driver.html?testName=json-parse-financial) 05:52:29 INFO - PID 4341 | RSS: Main: 175603712 05:52:29 INFO - PID 4341 | 05:52:30 INFO - PID 4341 | Cycle 1(1): loaded http://localhost:33977/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33977/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:52:31 INFO - PID 4341 | RSS: Main: 175730688 05:52:31 INFO - PID 4341 | 05:52:33 INFO - PID 4341 | Cycle 1(1): loaded http://localhost:33977/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33977/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:52:33 INFO - PID 4341 | RSS: Main: 175071232 05:52:33 INFO - PID 4341 | 05:52:35 INFO - PID 4341 | Cycle 1(1): loaded http://localhost:33977/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33977/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:52:36 INFO - PID 4341 | RSS: Main: 172019712 05:52:36 INFO - PID 4341 | 05:52:38 INFO - PID 4341 | Cycle 1(1): loaded http://localhost:33977/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33977/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:52:39 INFO - PID 4341 | RSS: Main: 172687360 05:52:39 INFO - PID 4341 | 05:52:42 INFO - PID 4341 | Cycle 1(1): loaded http://localhost:33977/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33977/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:52:43 INFO - PID 4341 | RSS: Main: 172597248 05:52:43 INFO - PID 4341 | 05:52:45 INFO - PID 4341 | Cycle 1(1): loaded http://localhost:33977/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33977/tests/kraken/driver.html?testName=ai-astar) 05:52:45 INFO - PID 4341 | RSS: Main: 174346240 05:52:45 INFO - PID 4341 | 05:52:45 INFO - PID 4341 | __start_tp_report 05:52:45 INFO - PID 4341 | _x_x_mozilla_page_load 05:52:45 INFO - PID 4341 | _x_x_mozilla_page_load_details 05:52:45 INFO - PID 4341 | |i|pagename|runs| 05:52:45 INFO - PID 4341 | |0;ai-astar;99;109;100;108;106;110;115;98;109;108 05:52:45 INFO - PID 4341 | |1;audio-beat-detection;146;118;143;179;115;148;145;119;179;118 05:52:45 INFO - PID 4341 | |2;audio-dft;162;160;161;163;161;161;164;163;162;161 05:52:45 INFO - PID 4341 | |3;audio-fft;73;73;74;189;74;75;75;73;153;74 05:52:45 INFO - PID 4341 | |4;audio-oscillator;80;83;127;79;79;80;124;79;79;128 05:52:45 INFO - PID 4341 | |5;imaging-gaussian-blur;115;113;113;114;114;114;115;114;113;114 05:52:45 INFO - PID 4341 | |6;imaging-darkroom;148;149;148;148;149;148;147;148;150;149 05:52:45 INFO - PID 4341 | |7;imaging-desaturate;94;94;94;94;94;94;94;94;94;94 05:52:45 INFO - PID 4341 | |8;json-parse-financial;53;53;55;53;53;52;53;52;52;53 05:52:45 INFO - PID 4341 | |9;json-stringify-tinderbox;37;38;38;37;37;38;38;38;37;38 05:52:45 INFO - PID 4341 | |10;stanford-crypto-aes;76;75;75;77;76;75;76;77;75;76 05:52:45 INFO - PID 4341 | |11;stanford-crypto-ccm;120;118;120;115;119;123;124;119;119;156 05:52:45 INFO - PID 4341 | |12;stanford-crypto-pbkdf2;172;172;168;173;170;181;171;172;173;164 05:52:45 INFO - PID 4341 | |13;stanford-crypto-sha256-iterative;66;64;66;64;68;63;62;70;63;62 05:52:45 INFO - PID 4341 | __end_tp_report 05:52:45 INFO - PID 4341 | __start_cc_report 05:52:45 INFO - PID 4341 | _x_x_mozilla_cycle_collect,943 05:52:45 INFO - PID 4341 | __end_cc_report 05:52:45 INFO - PID 4341 | __startTimestamp1504788765370__endTimestamp 05:52:45 INFO - PID 4341 | 05:52:45 INFO - PID 4341 | ------- Summary: start ------- 05:52:45 INFO - PID 4341 | Number of tests: 14 05:52:45 INFO - PID 4341 | 05:52:45 INFO - PID 4341 | [#0] ai-astar Cycles:10 Average:106.20 Median:109.50 stddev:5.49 (5.0%) stddev-sans-first:5.17 05:52:45 INFO - PID 4341 | Values: 99.0 109.0 100.0 108.0 106.0 110.0 115.0 98.0 109.0 108.0 05:52:45 INFO - PID 4341 | 05:52:45 INFO - PID 4341 | [#1] audio-beat-detection Cycles:10 Average:141.00 Median:145.50 stddev:24.04 (16.5%) stddev-sans-first:25.43 05:52:45 INFO - PID 4341 | Values: 146.0 118.0 143.0 179.0 115.0 148.0 145.0 119.0 179.0 118.0 05:52:45 INFO - PID 4341 | 05:52:45 INFO - PID 4341 | [#2] audio-dft Cycles:10 Average:161.80 Median:162.00 stddev:1.23 (0.8%) stddev-sans-first:1.30 05:52:45 INFO - PID 4341 | Values: 162.0 160.0 161.0 163.0 161.0 161.0 164.0 163.0 162.0 161.0 05:52:45 INFO - PID 4341 | 05:52:45 INFO - PID 4341 | [#3] audio-fft Cycles:10 Average:93.30 Median:74.00 stddev:41.83 (56.5%) stddev-sans-first:43.72 05:52:45 INFO - PID 4341 | Values: 73.0 73.0 74.0 189.0 74.0 75.0 75.0 73.0 153.0 74.0 05:52:45 INFO - PID 4341 | 05:52:45 INFO - PID 4341 | [#4] audio-oscillator Cycles:10 Average:93.80 Median:79.00 stddev:22.50 (28.5%) stddev-sans-first:23.31 05:52:45 INFO - PID 4341 | Values: 80.0 83.0 127.0 79.0 79.0 80.0 124.0 79.0 79.0 128.0 05:52:45 INFO - PID 4341 | 05:52:45 INFO - PID 4341 | [#5] imaging-gaussian-blur Cycles:10 Average:113.90 Median:114.00 stddev:0.74 (0.6%) stddev-sans-first:0.67 05:52:45 INFO - PID 4341 | Values: 115.0 113.0 113.0 114.0 114.0 114.0 115.0 114.0 113.0 114.0 05:52:45 INFO - PID 4341 | 05:52:45 INFO - PID 4341 | [#6] imaging-darkroom Cycles:10 Average:148.40 Median:148.50 stddev:0.84 (0.6%) stddev-sans-first:0.88 05:52:45 INFO - PID 4341 | Values: 148.0 149.0 148.0 148.0 149.0 148.0 147.0 148.0 150.0 149.0 05:52:45 INFO - PID 4341 | 05:52:45 INFO - PID 4341 | [#7] imaging-desaturate Cycles:10 Average:94.00 Median:94.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 05:52:45 INFO - PID 4341 | Values: 94.0 94.0 94.0 94.0 94.0 94.0 94.0 94.0 94.0 94.0 05:52:45 INFO - PID 4341 | 05:52:45 INFO - PID 4341 | [#8] json-parse-financial Cycles:10 Average:52.90 Median:53.00 stddev:0.88 (1.7%) stddev-sans-first:0.93 05:52:45 INFO - PID 4341 | Values: 53.0 53.0 55.0 53.0 53.0 52.0 53.0 52.0 52.0 53.0 05:52:45 INFO - PID 4341 | 05:52:45 INFO - PID 4341 | [#9] json-stringify-tinderbox Cycles:10 Average:37.60 Median:38.00 stddev:0.52 (1.4%) stddev-sans-first:0.50 05:52:45 INFO - PID 4341 | Values: 37.0 38.0 38.0 37.0 37.0 38.0 38.0 38.0 37.0 38.0 05:52:45 INFO - PID 4341 | 05:52:45 INFO - PID 4341 | [#10] stanford-crypto-aes Cycles:10 Average:75.80 Median:76.00 stddev:0.79 (1.0%) stddev-sans-first:0.83 05:52:45 INFO - PID 4341 | Values: 76.0 75.0 75.0 77.0 76.0 75.0 76.0 77.0 75.0 76.0 05:52:45 INFO - PID 4341 | 05:52:45 INFO - PID 4341 | [#11] stanford-crypto-ccm Cycles:10 Average:123.30 Median:120.00 stddev:11.76 (9.8%) stddev-sans-first:12.41 05:52:45 INFO - PID 4341 | Values: 120.0 118.0 120.0 115.0 119.0 123.0 124.0 119.0 119.0 156.0 05:52:45 INFO - PID 4341 | 05:52:45 INFO - PID 4341 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.60 Median:172.00 stddev:4.30 (2.5%) stddev-sans-first:4.56 05:52:45 INFO - PID 4341 | Values: 172.0 172.0 168.0 173.0 170.0 181.0 171.0 172.0 173.0 164.0 05:52:45 INFO - PID 4341 | 05:52:45 INFO - PID 4341 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.80 Median:65.00 stddev:2.66 (4.1%) stddev-sans-first:2.78 05:52:45 INFO - PID 4341 | Values: 66.0 64.0 66.0 64.0 68.0 63.0 62.0 70.0 63.0 62.0 05:52:45 INFO - PID 4341 | -------- Summary: end -------- 05:52:45 INFO - PID 4341 | 05:52:45 INFO - PID 4341 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 05:52:46 INFO - TEST-INFO | 4341: exit 0 05:52:46 INFO - TEST-OK | kraken | took 61748ms 05:52:46 INFO - SUITE-END | took 1174s 05:52:46 INFO - Completed test suite (00:19:34) 05:52:46 INFO - javascript benchmark 05:52:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8625.071720023394, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3294.9341013179737, 3296.505524171375, 3320.7675462717607, 3326.268220991381, 3373.460246360581, 6481.675916204194, 6529.085927970082, 6529.706163222654, 6538.3788540088735, 6566.376194261544, 6349.968250158754, 6447.871042579157, 6449.645269510187, 6463.48292136628, 6464.935350646492, 5895.88208235835, 5899.056151015839, 5917.230760001194, 5923.14114453404, 5955.583109182359, 5897.911531327012, 5944.256967879015, 5950.404959504037, 5967.075103358965, 5975.701214939258, 5987.251593550806, 6008.75964961401, 6019.819405417845, 6034.185384973016, 6049.939500605004, 5897.292324921015, 5900.616459930091, 5903.0555111182, 5991.730372133221, 5997.6701281429505, 5687.260656114679, 5802.5938184327115, 5848.970755146197, 5877.38287479817, 5979.611325263887, 6405.423511883906, 6438.323975982501, 6487.156669632965, 6508.64202468864, 6512.023196520512, 6695.631740254284, 6870.587764734132, 6888.690008949559, 6991.021257024021, 7004.859902801966, 6962.686679099453, 7080.327368899998, 7081.398081163107, 7083.929160708379, 7085.645717714094, 6447.097406363066, 6495.837604059889, 6531.248906375733, 6533.1180290661105, 6535.477161827042, 7146.749863754743, 7183.5330703504105, 7228.96385518069, 7263.782086537412, 7263.854722905512, 6632.701528431232, 6741.629210393476, 6745.7976260711275, 6843.178818541807, 6943.89584156238, 6879.828004299932, 6973.12835895513, 6980.336867997532, 6982.511224214256, 6983.650817459106, 6868.347506986828, 6905.551139175937, 6923.134608173978, 6959.304069593, 6978.267281935425, 6888.104546408935, 6892.586444813327, 6943.166819981533, 6986.7205311787975, 7021.648917554102, 5424.321959755031, 5467.289252397163, 5508.669479831223, 5510.393856675762, 5525.226468294442, 4785.688930219525, 4817.855464336076, 4835.44392394872, 4835.975820120947, 4836.153673107192, 5888.499477544371, 5889.381614930457, 5971.552133589998, 5972.104184372379, 5975.462208401178, 6910.2744211858035, 6911.205211400753, 6923.342282483257, 6930.930690693029, 7009.509334346547, 6044.335123136451, 6078.665673388006, 6089.269287685506, 6093.817185484442, 6099.634021958696, 6043.607165534227, 6065.302490213682, 6072.939270607326, 6088.995315772936, 6095.847603809941, 6715.832104197434, 6776.9322306777285, 6779.559328643622, 6863.313668633096, 6879.380855722909, 6830.248672646105, 6912.135983002125, 6921.065656136358, 6993.580385177008, 7093.0, 6678.766243181465, 6689.063531105552, 6692.431143352869, 6696.59820410777, 6698.7320507179165, 6425.325340839145, 6771.525993180627, 6783.660816959034, 6871.587704737732, 6932.896006559805, 7003.984422258642, 7020.052292940388, 7091.60996145217, 7096.787096387116, 7132.572045677276, 6570.671466426564, 6649.335066493312, 6659.667016649051, 6745.291744366771, 6799.966000169968, 6672.499562532732, 6684.665766711548, 6691.464682825265, 6696.933030669826, 6701.731930722716, 6543.116679248241, 6547.869042619216, 6572.80281591553, 6601.3728695773825, 6622.933770662232, 6358.872822543431, 6723.327667233238, 6754.831129221612, 6808.93191068083, 6835.282295359115, 6460.806175814733, 6466.385693359123, 6556.508261880283, 6560.606363618198, 6567.0], "value": 6322.777302594013, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [766.1266156581495, 805.7341077444444, 826.0541679776655, 854.0861278432078, 857.3398483167965, 39621.0, 40217.79891100548, 40271.19457610846, 40305.79847100768, 40425.59574404255, 36598.63401365985, 36835.815820920856, 36869.0, 36901.07747306319, 36901.815490922505, 6753.493487988396, 6838.9658051709675, 6863.691133898961, 6870.1068861048, 6872.862542749167, 6740.629265390402, 6830.624315662637, 6832.45340372769, 6836.726530938757, 6838.452923766112, 6786.491013174032, 7464.440166987471, 7715.421343399267, 7721.613919304041, 7725.459217854753, 7623.199564045751, 7631.923680763177, 7671.654775535114, 7674.424418168604, 7676.1172465166665, 7541.77374678755, 7607.239276072349, 7610.08678958523, 7613.847723045564, 7617.085949686018, 35837.64162358395, 36122.63877361245, 36162.09594760126, 36164.0, 36205.63794362049, 3510.8420121094355, 3535.5757309122814, 3541.752077354586, 3544.3974524330924, 3544.5392099027217, 29895.70104298951, 29926.25184370408, 29939.850300748574, 29989.100326990225, 29996.95010674638, 5122.103631864409, 5150.742462876841, 5197.0, 5198.792048318062, 5224.451432599561, 175207.0, 175566.0, 175824.0, 176053.0, 176147.11926440414, 46514.30228546575, 46560.0, 46609.0, 46625.53374466212, 46774.29838552424, 48869.755651221516, 49217.50782492129, 49348.25977610339, 49363.753181234584, 49678.25482617764, 14663.340149693076, 14735.92632036833, 14766.778498322534, 14794.852051479347, 14799.482018129314, 11847.88152118485, 11870.109741769229, 11900.285982841056, 11913.821292680617, 11915.761684766256, 14003.649908752363, 14107.224102674452, 14173.149611023371, 14181.432742690176, 14321.283935803169, 5574.0, 5641.407652196543, 5703.287089113861, 5760.135979603009, 5795.594308398456, 996.242855429864, 1016.588281745894, 1016.8627235323137, 1029.6602121299952, 1041.1358572384902, 22350.77649223487, 27639.585406218925, 27925.581116283272, 28227.306361618368, 28428.147155585364, 1085.9185561082793, 1095.326374279828, 1100.2848148703247, 1104.9005589496983, 1104.9558017679365, 12136.757264854654, 12285.57000504978, 12326.570173817037, 12391.690207744878, 12425.068119891044, 235975.0, 240793.0, 241897.0, 241904.0, 242473.0, 41554.79222603868, 42112.0, 45930.0, 48138.51861481411, 48321.0, 1402.3549167383119, 1408.9647758806111, 1411.4848080450768, 1417.2630232279157, 1421.1260075053767, 26578.006649500978, 26977.0558030468, 27108.45783084367, 27304.72695273022, 27377.589336159574, 35995.82002089973, 36008.0, 36085.639143609274, 36222.09444763797, 36283.0, 204288.0, 204517.0, 205487.0, 205544.0, 205671.0, 31415.528767068055, 32029.03912882617, 32189.678103218666, 32551.348973019933, 32575.18562035968, 39734.20531589294, 40079.39880901731, 40205.79897100496, 40278.1944361117, 40424.0, 29278.0, 29300.0, 29824.0, 30057.098287051424, 30200.0, 29194.0, 30108.84945575258, 30624.846875765477, 30681.079567613007, 30703.539446907867, 33403.83298083494, 33507.162320942174, 33511.497327540594, 33535.329293414485, 36153.27693446169, 31521.52717709282, 31917.8404107978, 31965.840170798998, 32367.51448728224, 32528.674713252563, 23237.0, 23951.88024059938, 24376.51246975086, 24875.378115547257, 25415.110471133423], "value": 16675.599657494848, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [556.4880310114694, 558.5587385965088, 567.7899177304398, 572.4790440698963, 586.870888404551, 21216.151353945843, 22102.0, 22239.332820015406, 22264.777352226472, 22340.776592234073, 22755.658665120038, 23303.300900972998, 23318.067277308888, 23322.0, 23341.649875251886, 7123.643817809115, 7313.268673132698, 7324.0, 7324.670389832444, 7328.230535793745, 6295.653739044351, 7002.229754726976, 7011.544249623784, 7023.192332881707, 7023.61370124643, 6971.128608923885, 6994.650267486631, 7001.159860816683, 7003.789886303393, 7013.263607321234, 6991.790246292594, 6995.79012629622, 7009.684564194598, 7011.824704382431, 7021.719131234744, 6867.2102708188695, 6875.07186529817, 6876.0, 6884.759033433856, 6885.242623311432, 29962.25094372636, 30797.384052319052, 31340.843295783376, 31728.0, 31766.0, 2069.844761642883, 2113.8520303578757, 2115.672070829024, 2120.4910821402905, 2120.7879212078824, 20408.28571000008, 21047.47381315464, 21175.5764884703, 21234.5753084939, 21342.146314147416, 2444.7310795812446, 2478.4175718706088, 2561.8334808237405, 2640.524705553, 2642.6036094585775, 133348.0, 137851.31074344664, 139290.91063634053, 139370.0, 139731.0, 20352.592948140955, 21731.891340543196, 21982.34052978413, 22486.30164380285, 22667.88666056659, 22293.108275669118, 22939.0, 22998.0, 42645.57354426478, 42841.57158428438, 9560.808783824423, 9889.406635601887, 10049.69850904474, 10054.547545360476, 10078.042585954323, 8982.730518084469, 9001.18989290967, 9061.040120056123, 9087.000429952699, 9090.36367454285, 9358.57886395114, 9428.95285523568, 9529.80814412689, 9577.23382129428, 9612.086851749073, 2819.957700634492, 2821.971780282212, 2831.2780241038404, 2836.234216761463, 2892.580575816537, 963.7397902566267, 979.1285755677453, 982.4449186209769, 995.1640621877658, 1000.214831357381, 20098.296559620343, 20159.0928408222, 20248.392548223575, 20272.290469833486, 20531.0, 1022.9897701022894, 1027.6968294353185, 1034.4982683398546, 1035.6789395287335, 1038.5949479702922, 11130.554777808959, 11134.554617815358, 11197.552097916156, 11336.0, 11366.374849383363, 440773.0, 451966.0, 455008.0, 455804.0, 458477.0, 107160.0, 112946.43526782314, 113338.0, 114075.0, 114082.0, 1165.81929800881, 1166.807476766324, 1191.9463624136936, 1193.8806119388166, 1195.4142470189718, 18009.54976125555, 18047.36834210849, 18075.252967890712, 18198.818011819712, 18284.908575457037, 44694.3295850569, 48592.75703621459, 53853.0, 55601.0, 57273.71363143158, 65755.0, 65755.6712216455, 66044.33955660382, 66091.0, 66211.66894165498, 14442.566722998326, 14655.120692758468, 14678.706425871209, 14695.853041469449, 14715.92642036783, 22830.543389132457, 23044.308670739905, 23199.76800231976, 23266.41833954097, 23271.883640581687, 17917.283308667505, 18081.909590451964, 18111.45665630033, 18156.546086347418, 18428.447146585622, 15326.0, 15392.615184620474, 15620.140892251035, 15731.528054158394, 15897.52307430779, 19753.0, 19829.0, 19838.0, 19902.50243743918, 20006.0, 14400.783988239975, 14594.197319147124, 14635.048721833047, 14651.12093274407, 14665.266736663338, 18380.724289135407, 18743.250269989592, 18752.812471875106, 18767.436976890716, 18767.812321876605], "value": 12304.143570386404, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1328.6944002879338, 1375.7386096641637, 1394.6234516680497, 1402.9789553156704, 1406.4233664197682, 12283.754324913496, 14899.404023839046, 15448.922755386235, 15518.767218491732, 15529.22353882307, 12463.314517701545, 15150.621234469143, 15591.84408155918, 15596.06423614584, 15601.0, 2685.2212858271128, 2748.848813315267, 2751.17464760572, 2752.7522522972927, 2753.0, 2578.5358635445614, 2685.5568831142837, 2695.3935364543017, 2698.2040298112006, 2768.8338699678084, 2682.557378032622, 2696.164189101375, 2698.1500827239456, 2709.376843326036, 2715.266877942954, 2615.028465260465, 2620.2008387441824, 2624.514464824014, 2637.3670319123366, 2637.577987521997, 2578.7034491033583, 2583.225032490245, 2596.402827349701, 2601.648777415063, 2609.3346196719904, 14888.330025148894, 15287.312070956834, 15287.61780955485, 15299.847001529954, 15303.540893773205, 7172.856542869114, 7346.853062938711, 7361.227071157505, 7387.4459415543515, 7399.889001664926, 5158.458361872024, 5263.6578622389425, 5296.9735151324385, 5389.299391079174, 5424.972875135639, 1878.1642169234697, 1888.9433317000512, 1899.5061284066105, 1943.805619438059, 1966.488712934633, 25688.486230275477, 25872.0, 25876.74123258762, 25893.611595826078, 25942.870285648452, 6760.560563563451, 6776.627285499345, 6842.384185423236, 6930.93069069313, 6943.9652801735665, 17191.742123868153, 17266.827331726774, 17291.135443227788, 17294.913525432294, 17297.91351043237, 7458.962705186439, 7492.213317601682, 7541.321281084619, 7654.617269136521, 7659.808504787425, 7030.437564994788, 7059.89410158848, 7249.057622509145, 7322.121345438475, 7325.5970921598755, 4714.245720684674, 4999.0, 5102.107131252016, 5134.255532947702, 5151.896962060738, 1313.7438199551186, 1419.389662445138, 1447.9348429320705, 1448.1962510806393, 1462.8829693624484, 908.3278374003322, 921.2307723051193, 927.1238679447991, 929.9953500232591, 936.1434287626745, 8469.0, 8865.290776737846, 8875.378723489423, 8910.955445222862, 8921.063288354759, 981.47000619666, 995.4773743784426, 997.3666721631816, 998.1765043838863, 999.9500024998721, 4571.22289210835, 5544.833654990357, 5789.0, 5807.709614519257, 6193.442590166816, 21655.133794648355, 21955.0, 22077.668834967488, 22171.334859954226, 22377.888110559343, 20609.381718548466, 21914.671279930815, 21932.0, 21962.560748784934, 22065.227717029826, 11098.999999999838, 11357.829632555518, 11446.14153938458, 11686.590969316034, 11708.238964467282, 8569.614367353359, 9053.321000925089, 9164.716939628324, 9265.351425400157, 9343.953280233556, 18819.435416937515, 18908.43274701761, 18968.146433410806, 19101.0, 19463.0, 19049.619007619494, 19158.904205478884, 19214.903925480285, 19480.610387792447, 19555.315563955763, 11944.701382465508, 12559.120861539604, 12586.18189817659, 12612.0, 12653.746925061263, 13960.301984900918, 13986.44054237819, 14068.788968165281, 14095.083819551697, 14140.78788818148, 10816.351018938889, 11295.717607059889, 11307.0, 11451.198416110792, 11617.128715346551, 10921.726956826144, 11127.55489780432, 11205.43972801373, 11229.27009744364, 11312.264702794291, 9748.268879834182, 9864.408135511892, 9920.305578609428, 9926.205903527845, 9936.056074672895, 6935.237123916466, 6975.372216500539, 7026.473014524034, 7051.294870513113, 7069.974853646089, 7040.718371265297, 7081.8937715935335, 7098.609576473344, 7108.182559005781, 7165.820854478679], "value": 6681.6996440703915, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [493.0316565375507, 493.4994716016614, 515.4098557152063, 520.4743209358547, 526.1949217696923, 6293.307736149026, 6363.268224154223, 6441.935580644192, 6446.0, 6503.902441463376, 6179.227596550431, 6248.84377890553, 6336.778212762554, 6367.172267605205, 6399.976003839387, 5118.59051275897, 5143.7685304161405, 5170.3713330301025, 5176.842126302952, 5181.0, 4905.994271174419, 5059.721715305656, 5113.795448182068, 5137.743112844343, 5170.327857378555, 5002.349694539723, 5041.344625198719, 5092.541671249587, 5130.127878260705, 5152.562032227265, 5021.447640759514, 5049.621278404097, 5079.720615366172, 5105.2086926526445, 5139.897202055957, 4533.433320834895, 4573.382593349888, 4574.862754117382, 4590.219662657323, 4609.469910960249, 6291.716872740738, 6339.0808332791485, 6375.553711240215, 6388.488920886318, 6401.295857455676, 5850.385709500484, 5854.151148083547, 5860.06239001762, 5867.677277749722, 5888.646681199141, 6685.76599819004, 6715.395614394679, 6732.394084532416, 6781.152355955505, 6781.847085995392, 4924.030387844855, 4938.580220681247, 4977.377827771528, 5000.874978125539, 5016.974915125437, 7053.788386348418, 7080.645967701595, 7136.6431678415875, 7212.74755383559, 7229.530080544748, 5065.822696205689, 5071.112555302807, 5106.770195341144, 5107.591392688576, 5148.330717006766, 5132.666376685504, 5157.432682404932, 5190.636655434083, 5234.162533994542, 5238.716514453907, 5764.884702305931, 5821.388754180834, 5861.88276234473, 5883.499902508249, 5908.911366329508, 6585.0, 6726.562773419712, 6760.599987004641, 6761.661916904233, 6763.323667633197, 1673.1550566963606, 1738.4350086221775, 1770.8577967211077, 1794.23744908416, 1942.9028548572514, 1419.602511296818, 1429.306786208688, 1451.2235953764684, 1451.702401007791, 1478.7486127358375, 5827.329857066492, 5832.387599302097, 5833.82498525045, 5920.230370051866, 5943.851403714855, 6674.399304062657, 6686.832829179212, 6720.63968015992, 6828.897566536506, 6852.794416167423, 5851.882962340729, 5857.531397488191, 5869.677167755729, 5873.471387575139, 5917.852053698692, 3548.8048157351595, 3600.1719604491113, 3614.7769121989786, 3623.58328792187, 3643.198496330856, 6526.216853977553, 6694.196696396463, 6697.388018672722, 6721.428678562279, 6759.729610815512, 7031.507794454441, 7054.365107140382, 7074.787756367317, 7128.607926563985, 7144.607046612382, 6678.83302917431, 6704.899426508607, 6728.865422691617, 6747.224069232003, 6757.695903684346, 6936.34104760047, 6971.233164352018, 6972.0, 6992.741306564867, 7016.649167541705, 6806.829829254309, 6914.619695916572, 6923.203831559234, 6930.61881596517, 6990.755323563651, 6657.2677005528385, 6678.79599732097, 6683.198016237888, 6694.866102678016, 6735.797926062226, 5006.523075692752, 5016.824411145592, 5097.541221290102, 5133.0, 5159.845204643867, 7003.299670032956, 7042.225355211025, 7056.435485161072, 7063.187733410724, 7066.929330706627, 6779.491538134561, 6789.740924356238, 6792.796216113525, 6806.502569434717, 6827.453803695593, 6697.933020669731, 6791.626460544519, 6798.150231221098, 6808.468094678856, 6810.216825065181, 6453.41919227253, 6569.638669873203, 6596.175478065242, 6604.768833090819, 6619.205695316593, 6573.244076931022, 6711.758324709945, 6719.932800671931, 6760.797176084725, 6831.316868313129, 6538.574992625654, 6540.215174179129, 6541.182352205974, 6557.573757705733, 6562.5406221564035], "value": 5207.7116240639325, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [830.6377935257661, 845.6152450634962, 872.3326655108843, 893.977650558736, 917.8210249001445, 15957.52127436176, 16325.673486530262, 16328.918355408221, 16362.509124726248, 19809.900950495263, 21439.249626263085, 21951.780482195172, 21977.120915163374, 22071.33785986423, 22100.33698989033, 970.1559643110486, 979.0601023017912, 979.8824141103078, 981.7496538382702, 985.5121714751208, 942.1708896171394, 986.7779749556364, 988.1304452082194, 988.3378136648427, 988.7330420786384, 1044.979100417995, 1064.0, 1064.9893501064967, 1066.9146468282559, 1067.3222503710122, 1043.405259002369, 1044.3159730376615, 1051.9158467322595, 1053.3837704942619, 1053.5522402978743, 773.1032002876636, 776.7863837444692, 779.6803310642609, 779.9064112306503, 780.2470615855669, 19658.60682786336, 19713.605727885504, 19809.9009504953, 19855.205791768312, 20559.280425185047, 20877.164913403445, 20908.68636970447, 21082.578348432944, 21391.78608213929, 21411.250606228707, 16484.258208380652, 16606.25271862769, 16719.414820481346, 16831.0, 16918.323267069303, 3677.7241706871823, 3696.796676182809, 3708.5178926739623, 3711.5731690855628, 3712.795796231206, 123758.0, 124271.98021381827, 126586.367068165, 127825.36087319597, 128717.0, 16484.175791210393, 16486.422975195812, 16558.254878530253, 16670.916645416695, 16774.245158967875, 29150.562741558893, 30099.398012040074, 30297.24256893595, 30356.24109397283, 30404.391912161635, 8188.836223275501, 8268.503889766633, 8314.41799074059, 8323.458975166715, 8347.29048030912, 3846.0, 3918.5885482024546, 3925.5878132796215, 3929.44987701722, 3930.3514920038447, 5149.382074151126, 5291.417944026154, 5332.226827110125, 5341.092014357569, 5358.651687640291, 903.6728485577484, 906.9047749986288, 914.8124634449922, 919.691903212418, 920.1994264989369, 675.7972608217515, 684.4729558240226, 689.6379400814512, 697.6720941157648, 702.255609054404, 11615.883841161649, 11787.646370608896, 11941.52233910634, 12953.158044727063, 13039.348032598333, 697.4803771190495, 698.2912343970874, 703.4618516834626, 705.5131958948309, 706.7844307486163, 5361.678299302054, 5370.812021579226, 5455.09990851513, 5512.751926163332, 5514.5864060194845, 109683.16108936149, 110154.0, 111694.0, 114087.42956285166, 116933.0, 96429.0, 98916.50541747245, 99005.0, 100540.0, 102478.48760756149, 83449.58275208707, 88405.55797221101, 90902.09097909069, 91201.5439922796, 93909.0, 12282.570110046105, 12575.87424125747, 12618.116731828684, 12630.43163057683, 13197.340132993504, 34740.47889281713, 35609.82195089008, 38834.80582597069, 40020.399694005195, 42873.0, 70266.0, 70477.64761176161, 70477.64761176161, 71591.0, 72121.6393918027, 19391.2243510258, 19517.8048219516, 19521.121549530588, 19726.0, 19790.0, 20517.89741051285, 21201.787982119982, 21277.6808347878, 21393.465163370416, 21428.464288392915, 18555.628887422445, 18606.81393186051, 19229.711554326976, 19394.0302984853, 19487.512812179808, 18583.81416185821, 19205.32781352702, 19235.711464327767, 19248.422547323604, 19566.0, 18004.459866204033, 18154.18306176199, 18217.54456138554, 18304.542386439913, 19066.0, 11571.768564628492, 11740.765184696087, 11837.526498939946, 12459.813102803646, 12689.873101269239, 13773.071769952416, 13801.171929684251, 13839.930800345934, 13869.583912482642, 13969.860301397262], "value": 9119.641312672227, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1478.3999999999996, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 109.0, 100.0, 108.0, 106.0, 110.0, 115.0, 98.0, 109.0, 108.0], "value": 106.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 118.0, 143.0, 179.0, 115.0, 148.0, 145.0, 119.0, 179.0, 118.0], "value": 141.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 160.0, 161.0, 163.0, 161.0, 161.0, 164.0, 163.0, 162.0, 161.0], "value": 161.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 74.0, 189.0, 74.0, 75.0, 75.0, 73.0, 153.0, 74.0], "value": 93.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 83.0, 127.0, 79.0, 79.0, 80.0, 124.0, 79.0, 79.0, 128.0], "value": 93.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 113.0, 113.0, 114.0, 114.0, 114.0, 115.0, 114.0, 113.0, 114.0], "value": 113.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 149.0, 148.0, 148.0, 149.0, 148.0, 147.0, 148.0, 150.0, 149.0], "value": 148.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0], "value": 94.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 55.0, 53.0, 53.0, 52.0, 53.0, 52.0, 52.0, 53.0], "value": 52.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [37.0, 38.0, 38.0, 37.0, 37.0, 38.0, 38.0, 38.0, 37.0, 38.0], "value": 37.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 75.0, 77.0, 76.0, 75.0, 76.0, 77.0, 75.0, 76.0], "value": 75.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 118.0, 120.0, 115.0, 119.0, 123.0, 124.0, 119.0, 119.0, 156.0], "value": 123.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 172.0, 168.0, 173.0, 170.0, 181.0, 171.0, 172.0, 173.0, 164.0], "value": 171.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 66.0, 64.0, 68.0, 63.0, 62.0, 70.0, 63.0, 62.0], "value": 64.8, "unit": "score"}]}]} 05:52:46 INFO - Return code: 0 05:52:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:52:46 INFO - # TBPL SUCCESS # 05:52:46 INFO - Running post-action listener: _package_coverage_data 05:52:46 INFO - Running post-action listener: _resource_record_post_action 05:52:46 INFO - [mozharness: 2017-09-07 12:52:46.655650Z] Finished run-tests step (success) 05:52:46 INFO - Running post-run listener: _resource_record_post_run 05:52:46 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 9089024; Write bytes: 407048192; Read time: 4060; Write time: 366048 05:52:46 INFO - TinderboxPrint: CPU usage
13.1% 05:52:46 INFO - TinderboxPrint: I/O read bytes / time
9,089,024 / 4,060 05:52:46 INFO - TinderboxPrint: I/O write bytes / time
407,048,192 / 366,048 05:52:46 INFO - TinderboxPrint: CPU idle
8,247.7 (86.9%) 05:52:46 INFO - TinderboxPrint: CPU user
1,192.4 (12.6%) 05:52:46 INFO - TinderboxPrint: Swap in / out
0 / 0 05:52:46 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 4096; Write bytes: 119664640; Read time: 96; Write time: 96136 05:52:46 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 05:52:46 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 7340032; Write bytes: 287383552; Read time: 3920; Write time: 269912 05:52:47 INFO - Running post-run listener: _upload_blobber_files 05:52:47 INFO - Blob upload gear active. 05:52:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:52:47 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 05:52:47 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', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:52:47 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 -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:52:47 INFO - (blobuploader) - INFO - Open directory for files ... 05:52:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:52:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:52:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:52:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:52:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:52:48 INFO - (blobuploader) - INFO - Done attempting. 05:52:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:52:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:52:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:52:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:52:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:52:49 INFO - (blobuploader) - INFO - Done attempting. 05:52:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:52:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:52:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:52:53 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:52:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:52:53 INFO - (blobuploader) - INFO - Done attempting. 05:52:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:52:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:52:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:52:54 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:52:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:52:54 INFO - (blobuploader) - INFO - Done attempting. 05:52:54 INFO - (blobuploader) - INFO - Iteration through files over. 05:52:54 INFO - Return code: 0 05:52:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:52:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:52:54 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/82978826fd4974ff3228c5404bf415951f6a933b7ea7dcd1536785885e6172ebb3a1db943ad42dba53dc1513a340dfbaf4b03c7b5f026c1f5c4b207eafcb3533", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8507863a92fac72ab2d0871323e95671a76b8036797fc33b35867b0c8a945f98ac30aca20944bf7e09ec5455a3978116fe32e76314c624f892721d8463f03a67", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/32c3b728362ae9eb7f8a22f45312487f5a1bf424455933ced5849e010c3118b2b636afe7efac5052446c5a79c51b080d5dc3f757c26ec207eed8b6c274dc6328", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2f8e9dabac6d592fb138a685a852e19f378c506f9c58b266f30a2e01bfccaa1f36b7331651dddc3a20d687a0a1ac9e1f5461cd14e42603c92911e51e1b92340a"} 05:52:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:52:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:52:54 INFO - Contents: 05:52:54 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/82978826fd4974ff3228c5404bf415951f6a933b7ea7dcd1536785885e6172ebb3a1db943ad42dba53dc1513a340dfbaf4b03c7b5f026c1f5c4b207eafcb3533", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8507863a92fac72ab2d0871323e95671a76b8036797fc33b35867b0c8a945f98ac30aca20944bf7e09ec5455a3978116fe32e76314c624f892721d8463f03a67", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/32c3b728362ae9eb7f8a22f45312487f5a1bf424455933ced5849e010c3118b2b636afe7efac5052446c5a79c51b080d5dc3f757c26ec207eed8b6c274dc6328", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2f8e9dabac6d592fb138a685a852e19f378c506f9c58b266f30a2e01bfccaa1f36b7331651dddc3a20d687a0a1ac9e1f5461cd14e42603c92911e51e1b92340a"} 05:52:54 INFO - Running post-run listener: copy_logs_to_upload_dir 05:52:54 INFO - Copying logs to upload dir... 05:52:54 INFO - mkdir: /builds/slave/test/build/upload/logs 05:52:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1254.461481 ========= master_lag: -0.20 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2017-09-07 05:52:53.770487) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-07 05:52:53.778080) ========= 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=62c568580acbfdaac3eec7be000001dc-1504787515.81045-1663338310 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/sha512/82978826fd4974ff3228c5404bf415951f6a933b7ea7dcd1536785885e6172ebb3a1db943ad42dba53dc1513a340dfbaf4b03c7b5f026c1f5c4b207eafcb3533", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8507863a92fac72ab2d0871323e95671a76b8036797fc33b35867b0c8a945f98ac30aca20944bf7e09ec5455a3978116fe32e76314c624f892721d8463f03a67", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/32c3b728362ae9eb7f8a22f45312487f5a1bf424455933ced5849e010c3118b2b636afe7efac5052446c5a79c51b080d5dc3f757c26ec207eed8b6c274dc6328", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2f8e9dabac6d592fb138a685a852e19f378c506f9c58b266f30a2e01bfccaa1f36b7331651dddc3a20d687a0a1ac9e1f5461cd14e42603c92911e51e1b92340a"} build_url:https://queue.taskcluster.net/v1/task/BaAK-As6Q66QQapQlMtaJw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011300 build_url: 'https://queue.taskcluster.net/v1/task/BaAK-As6Q66QQapQlMtaJw/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/82978826fd4974ff3228c5404bf415951f6a933b7ea7dcd1536785885e6172ebb3a1db943ad42dba53dc1513a340dfbaf4b03c7b5f026c1f5c4b207eafcb3533", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8507863a92fac72ab2d0871323e95671a76b8036797fc33b35867b0c8a945f98ac30aca20944bf7e09ec5455a3978116fe32e76314c624f892721d8463f03a67", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/32c3b728362ae9eb7f8a22f45312487f5a1bf424455933ced5849e010c3118b2b636afe7efac5052446c5a79c51b080d5dc3f757c26ec207eed8b6c274dc6328", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2f8e9dabac6d592fb138a685a852e19f378c506f9c58b266f30a2e01bfccaa1f36b7331651dddc3a20d687a0a1ac9e1f5461cd14e42603c92911e51e1b92340a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-07 05:52:53.809187) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 05:52:53.809474) ========= 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=62c568580acbfdaac3eec7be000001dc-1504787515.81045-1663338310 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004499 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 05:52:53.891449) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-07 05:52:53.894771) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-07 05:52:53.895080) ========= ========= Total master_lag: -0.04 =========