builder: mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s slave: talos-linux64-ix-082 starttime: 1503935014.43 results: success (0) revision: e2efa420beb1a578c7350ba925c82230da6b1267 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-28 08:43:34.426219) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-28 08:43:34.426686) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-28 08:43:34.426968) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-28 08:43:34.438389) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-28 08:43:34.438789) ========= 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=51fc86400edbde9650d2781a000001d0-1503935013.520396-1280810185 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-28 08:43:34-- 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% 45.7M=0s 2017-08-28 08:43:34 (45.7 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.178940 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-28 08:43:34.633108) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-28 08:43:34.636442) ========= 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=51fc86400edbde9650d2781a000001d0-1503935013.520396-1280810185 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018401 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-28 08:43:34.686160) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-28 08:43:34.689550) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev e2efa420beb1a578c7350ba925c82230da6b1267 --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 e2efa420beb1a578c7350ba925c82230da6b1267 --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=51fc86400edbde9650d2781a000001d0-1503935013.520396-1280810185 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-28 08:43:34,704 truncating revision to first 12 chars 2017-08-28 08:43:34,704 Setting DEBUG logging. 2017-08-28 08:43:34,704 attempt 1/10 2017-08-28 08:43:34,704 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/e2efa420beb1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-28 08:43:35,020 unpacking tar archive at: mozilla-central-e2efa420beb1/testing/mozharness/ program finished with exit code 0 elapsedTime=0.627642 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-28 08:43:35.332050) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-28 08:43:35.335507) ========= script_repo_revision: e2efa420beb1a578c7350ba925c82230da6b1267 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-28 08:43:35.335972) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-28 08:43:35.336262) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-28 08:43:35.348810) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2017-08-28 08:43:35.349225) ========= /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=51fc86400edbde9650d2781a000001d0-1503935013.520396-1280810185 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:43:35 INFO - MultiFileLogger online at 20170828 08:43:35 in /builds/slave/test 08:43:35 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 08:43:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:43:35 INFO - {'append_to_log': False, 08:43:35 INFO - 'base_work_dir': '/builds/slave/test', 08:43:35 INFO - 'blob_upload_branch': 'Firefox', 08:43:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:43:35 INFO - 'branch': 'Firefox', 08:43:35 INFO - 'buildbot_json_path': 'buildprops.json', 08:43:35 INFO - 'code_coverage': False, 08:43:35 INFO - 'config_files': ('talos/linux_config.py',), 08:43:35 INFO - 'default_actions': ('clobber', 08:43:35 INFO - 'read-buildbot-config', 08:43:35 INFO - 'download-and-extract', 08:43:35 INFO - 'populate-webroot', 08:43:35 INFO - 'create-virtualenv', 08:43:35 INFO - 'install', 08:43:35 INFO - 'setup-mitmproxy', 08:43:35 INFO - 'run-tests'), 08:43:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:43:35 INFO - 'disable_ccov_upload': False, 08:43:35 INFO - 'download_minidump_stackwalk': True, 08:43:35 INFO - 'download_symbols': 'ondemand', 08:43:35 INFO - 'enable_stylo': False, 08:43:35 INFO - 'enable_webrender': False, 08:43:35 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 08:43:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:43:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:43:35 INFO - 'gecko_profile': False, 08:43:35 INFO - 'gecko_profile_interval': 0, 08:43:35 INFO - 'installer_path': 'installer.exe', 08:43:35 INFO - 'log_level': 'info', 08:43:35 INFO - 'log_name': 'talos', 08:43:35 INFO - 'log_to_console': True, 08:43:35 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:43:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:43:35 INFO - 'opt_config_files': (), 08:43:35 INFO - 'pip_index': False, 08:43:35 INFO - 'suite': 'dromaeojs-e10s', 08:43:35 INFO - 'system_bits': '32', 08:43:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:43:35 INFO - 'title': 'talos-linux64-ix-082', 08:43:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:43:35 INFO - 'use_talos_json': True, 08:43:35 INFO - 'verify': 'False', 08:43:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:43:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:43:35 INFO - 'work_dir': 'build'} 08:43:35 INFO - [mozharness: 2017-08-28 15:43:35.503646Z] Running clobber step. 08:43:35 INFO - Running pre-action listener: _resource_record_pre_action 08:43:35 INFO - Running main action method: clobber 08:43:35 INFO - rmtree: /builds/slave/test/build 08:43:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:43:36 INFO - Running post-action listener: _resource_record_post_action 08:43:36 INFO - [mozharness: 2017-08-28 15:43:36.638180Z] Finished clobber step (success) 08:43:36 INFO - [mozharness: 2017-08-28 15:43:36.638278Z] Running read-buildbot-config step. 08:43:36 INFO - Running pre-action listener: _resource_record_pre_action 08:43:36 INFO - Running main action method: read_buildbot_config 08:43:36 INFO - Using buildbot properties: 08:43:36 INFO - { 08:43:36 INFO - "project": "", 08:43:36 INFO - "product": "firefox", 08:43:36 INFO - "who": "archaeopteryx@coole-files.de", 08:43:36 INFO - "installer_path": "public/build/target.tar.bz2", 08:43:36 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s", 08:43:36 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 08:43:36 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos dromaeojs-e10s", 08:43:36 INFO - "stage_platform": "linux64-qr", 08:43:36 INFO - "basedir": "/builds/slave/test", 08:43:36 INFO - "buildnumber": 21, 08:43:36 INFO - "platform": "ubuntu64_hw_qr", 08:43:36 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 08:43:36 INFO - "slavebuilddir": "test", 08:43:36 INFO - "taskId": "Z6xIlgV9R8iBMTaCCCbYJw", 08:43:36 INFO - "branch": "mozilla-central", 08:43:36 INFO - "script_repo_revision": "production", 08:43:36 INFO - "revision": "e2efa420beb1a578c7350ba925c82230da6b1267", 08:43:36 INFO - "slavename": "talos-linux64-ix-082", 08:43:36 INFO - "repo_path": "mozilla-central" 08:43:36 INFO - } 08:43:36 INFO - Finding installer, test and symbols from parent task. 08:43:36 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Z6xIlgV9R8iBMTaCCCbYJw'}, attempt #1 08:43:37 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Z6xIlgV9R8iBMTaCCCbYJw'}, attempt #1 08:43:39 INFO - Task dependencies: TTT3heApSneArmZvclHtxg 08:43:39 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TTT3heApSneArmZvclHtxg'}, attempt #1 08:43:39 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/TTT3heApSneArmZvclHtxg/artifacts/public/build/target.tar.bz2 08:43:39 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/TTT3heApSneArmZvclHtxg/artifacts/public/build/target.test_packages.json 08:43:39 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/TTT3heApSneArmZvclHtxg/artifacts/public/build/target.crashreporter-symbols.zip 08:43:39 INFO - Running post-action listener: _resource_record_post_action 08:43:39 INFO - [mozharness: 2017-08-28 15:43:39.969591Z] Finished read-buildbot-config step (success) 08:43:39 INFO - [mozharness: 2017-08-28 15:43:39.969810Z] Running download-and-extract step. 08:43:39 INFO - Running pre-action listener: _resource_record_pre_action 08:43:39 INFO - Running main action method: download_and_extract 08:43:39 INFO - mkdir: /builds/slave/test/build/tests 08:43: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')]} 08:43:39 INFO - trying https://queue.taskcluster.net/v1/task/TTT3heApSneArmZvclHtxg/artifacts/public/build/target.test_packages.json 08:43:39 INFO - Downloading https://queue.taskcluster.net/v1/task/TTT3heApSneArmZvclHtxg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 08:43:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TTT3heApSneArmZvclHtxg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 08:43:40 INFO - Downloaded 989 bytes. 08:43:40 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 08:43:40 INFO - Using the following test package requirements: 08:43:40 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 08:43:40 INFO - u'common': [u'target.common.tests.zip'], 08:43:40 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 08:43:40 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 08:43:40 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 08:43:40 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 08:43:40 INFO - u'mozbase': [u'target.common.tests.zip'], 08:43:40 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 08:43:40 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 08:43:40 INFO - u'web-platform': [u'target.common.tests.zip', 08:43:40 INFO - u'target.web-platform.tests.tar.gz'], 08:43:40 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 08:43:40 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 08:43:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TTT3heApSneArmZvclHtxg/artifacts/public/build/target.common.tests.zip 08:43:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TTT3heApSneArmZvclHtxg/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:43:40 INFO - Fetch https://queue.taskcluster.net/v1/task/TTT3heApSneArmZvclHtxg/artifacts/public/build/target.common.tests.zip into memory 08:43:41 INFO - Content-Length response header: 54977551 08:43:41 INFO - Bytes received: 54977551 08:43:52 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 08:43:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TTT3heApSneArmZvclHtxg/artifacts/public/build/target.common.tests.zip 08:43:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TTT3heApSneArmZvclHtxg/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:43:52 INFO - Fetch https://queue.taskcluster.net/v1/task/TTT3heApSneArmZvclHtxg/artifacts/public/build/target.common.tests.zip into memory 08:43:53 INFO - Content-Length response header: 54977551 08:43:53 INFO - Bytes received: 54977551 08:44:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TTT3heApSneArmZvclHtxg/artifacts/public/build/target.talos.tests.zip 08:44:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TTT3heApSneArmZvclHtxg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 08:44:07 INFO - Fetch https://queue.taskcluster.net/v1/task/TTT3heApSneArmZvclHtxg/artifacts/public/build/target.talos.tests.zip into memory 08:44:08 INFO - Content-Length response header: 14496611 08:44:08 INFO - Bytes received: 14496611 08:44:08 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')]} 08:44:08 INFO - trying https://queue.taskcluster.net/v1/task/TTT3heApSneArmZvclHtxg/artifacts/public/build/target.tar.bz2 08:44:08 INFO - Downloading https://queue.taskcluster.net/v1/task/TTT3heApSneArmZvclHtxg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 08:44:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TTT3heApSneArmZvclHtxg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 08:44:09 INFO - Downloaded 61077617 bytes. 08:44:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TTT3heApSneArmZvclHtxg/artifacts/public/build/target.tar.bz2 08:44:09 INFO - mkdir: /builds/slave/test/properties 08:44:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:44:09 INFO - Writing to file /builds/slave/test/properties/build_url 08:44:09 INFO - Contents: 08:44:09 INFO - build_url:https://queue.taskcluster.net/v1/task/TTT3heApSneArmZvclHtxg/artifacts/public/build/target.tar.bz2 08:44:09 INFO - Running post-action listener: _resource_record_post_action 08:44:09 INFO - Running post-action listener: find_tests_for_verification 08:44:09 INFO - Running post-action listener: set_extra_try_arguments 08:44:09 INFO - [mozharness: 2017-08-28 15:44:09.441268Z] Finished download-and-extract step (success) 08:44:09 INFO - [mozharness: 2017-08-28 15:44:09.441366Z] Running populate-webroot step. 08:44:09 INFO - Running pre-action listener: _resource_record_pre_action 08:44:09 INFO - Running main action method: populate_webroot 08:44:09 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:44:09 INFO - 'chromez-stylo-e10s': {'talos_options': ['--stylo'], 08:44:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:44:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:44:09 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--stylo'], 08:44:09 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:44:09 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:44:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:44:09 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 08:44:09 INFO - 'talos_options': ['--stylo'], 08:44:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:44:09 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:44:09 INFO - 'tests': ['damp', 'tps']}, 08:44:09 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 08:44:09 INFO - 'talos_options': ['--stylo'], 08:44:09 INFO - 'tests': ['damp', 'tps']}, 08:44:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:44:09 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 08:44:09 INFO - 'g4-stylo-e10s': {'talos_options': ['--stylo'], 08:44:09 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 08:44:09 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 08:44:09 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 08:44:09 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 08:44:09 INFO - 'talos_options': ['--stylo'], 08:44:09 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 08:44:09 INFO - 'other-e10s': {'tests': ['a11yr', 08:44:09 INFO - 'ts_paint', 08:44:09 INFO - 'tpaint', 08:44:09 INFO - 'sessionrestore', 08:44:09 INFO - 'sessionrestore_many_windows', 08:44:09 INFO - 'sessionrestore_no_auto_restore', 08:44:09 INFO - 'tabpaint']}, 08:44:09 INFO - 'other-stylo-e10s': {'talos_options': ['--stylo'], 08:44:09 INFO - 'tests': ['a11yr', 08:44:09 INFO - 'ts_paint', 08:44:09 INFO - 'tpaint', 08:44:09 INFO - 'sessionrestore', 08:44:09 INFO - 'sessionrestore_many_windows', 08:44:09 INFO - 'sessionrestore_no_auto_restore', 08:44:09 INFO - 'tabpaint']}, 08:44:09 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 08:44:09 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 08:44:09 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--stylo'], 08:44:09 INFO - 'tests': ['bloom_basic_singleton']}, 08:44:09 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--stylo'], 08:44:09 INFO - 'tests': ['bloom_basic']}, 08:44:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:44:09 INFO - 'tsvgr_opacity', 08:44:09 INFO - 'tart', 08:44:09 INFO - 'tscrollx', 08:44:09 INFO - 'tsvg_static']}, 08:44:09 INFO - 'svgr-stylo-e10s': {'talos_options': ['--stylo'], 08:44:09 INFO - 'tests': ['tsvgx', 08:44:09 INFO - 'tsvgr_opacity', 08:44:09 INFO - 'tart', 08:44:09 INFO - 'tscrollx', 08:44:09 INFO - 'tsvg_static']}, 08:44:09 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:44:09 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 08:44:09 INFO - 'talos_options': ['--stylo'], 08:44:09 INFO - 'tests': ['tp5o']}, 08:44:09 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 08:44:09 INFO - 'talos_options': ['--mitmproxy', 08:44:09 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 08:44:09 INFO - '--firstNonBlankPaint'], 08:44:09 INFO - 'tests': ['tp6_google', 08:44:09 INFO - 'tp6_youtube', 08:44:09 INFO - 'tp6_amazon', 08:44:09 INFO - 'tp6_facebook']}, 08:44:09 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 08:44:09 INFO - 'talos_options': ['--stylo', 08:44:09 INFO - '--mitmproxy', 08:44:09 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 08:44:09 INFO - '--firstNonBlankPaint'], 08:44:09 INFO - 'tests': ['tp6_google', 08:44:09 INFO - 'tp6_youtube', 08:44:09 INFO - 'tp6_amazon', 08:44:09 INFO - 'tp6_facebook']}, 08:44:09 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 08:44:09 INFO - 'talos_options': ['--stylo', 08:44:09 INFO - '--stylo-threads=1', 08:44:09 INFO - '--mitmproxy', 08:44:09 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 08:44:09 INFO - '--firstNonBlankPaint'], 08:44:09 INFO - 'tests': ['tp6_google', 08:44:09 INFO - 'tp6_youtube', 08:44:09 INFO - 'tp6_amazon', 08:44:09 INFO - 'tp6_facebook']}, 08:44:09 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:44:09 INFO - 'talos_options': ['--xperf_path', 08:44:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:44:09 INFO - 'tests': ['tp5n']}, 08:44:09 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 08:44:09 INFO - 'talos_options': ['--stylo', 08:44:09 INFO - '--xperf_path', 08:44:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:44:09 INFO - 'tests': ['tp5n']}}} 08:44:09 INFO - Running post-action listener: _resource_record_post_action 08:44:09 INFO - [mozharness: 2017-08-28 15:44:09.455527Z] Finished populate-webroot step (success) 08:44:09 INFO - [mozharness: 2017-08-28 15:44:09.455617Z] Running create-virtualenv step. 08:44:09 INFO - Running pre-action listener: _resource_record_pre_action 08:44:09 INFO - Running main action method: create_virtualenv 08:44:09 INFO - Creating virtualenv /builds/slave/test/build/venv 08:44:09 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 08:44:09 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 08:44:09 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 08:44:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:44:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:44:10 INFO - Installing distribute.............................................................................................................................................................................................done. 08:44:10 INFO - Installing pip...............done. 08:44:10 INFO - Return code: 0 08:44:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:44:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:44:10 INFO - Reading from file tmpfile_stdout 08:44:10 INFO - Output received: 08:44:10 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 08:44:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:44:10 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')]} 08:44:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:10 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')]} 08:44:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:10 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 0x18491f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a14a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bb7f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bb5130>, '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 0x1bb6030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bb64c0>, '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': '51fc86400edbde9650d2781a000001d0-1503935013.520396-1280810185', '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 08:44:10 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 08:44:10 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 08:44:10 INFO - Using env: {'DISPLAY': ':0', 08:44:10 INFO - 'HOME': '/home/cltbld', 08:44:10 INFO - 'LANG': 'en_US.UTF-8', 08:44:10 INFO - 'LANGUAGE': 'en_US:en', 08:44:10 INFO - 'LOGNAME': 'cltbld', 08:44:10 INFO - 'MAIL': '/var/mail/cltbld', 08:44:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:10 INFO - 'MOZ_NO_REMOTE': '1', 08:44:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:44:10 INFO - 'NO_EM_RESTART': '1', 08:44:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:44:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:10 INFO - 'PWD': '/builds/slave/test', 08:44:10 INFO - 'SHELL': '/bin/bash', 08:44:10 INFO - 'SHLVL': '1', 08:44:10 INFO - 'TERM': 'linux', 08:44:10 INFO - 'TMOUT': '86400', 08:44:10 INFO - 'USER': 'cltbld', 08:44:10 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1503935013.520396-1280810185', 08:44:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:10 INFO - '_': '/tools/buildbot/bin/python'} 08:44:11 INFO - Ignoring indexes: http://pypi.python.org/simple/ 08:44:11 INFO - Downloading/unpacking pip>=1.5 08:44:11 INFO - Running setup.py egg_info for package pip 08:44:11 INFO - warning: no files found matching 'pip/cacert.pem' 08:44:11 INFO - warning: no files found matching '*.html' under directory 'docs' 08:44:11 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 08:44:11 INFO - no previously-included directories found matching 'docs/_build/_sources' 08:44:11 INFO - Installing collected packages: pip 08:44:11 INFO - Found existing installation: pip 1.1 08:44:11 INFO - Uninstalling pip: 08:44:11 INFO - Successfully uninstalled pip 08:44:11 INFO - Running setup.py install for pip 08:44:11 INFO - warning: no files found matching 'pip/cacert.pem' 08:44:11 INFO - warning: no files found matching '*.html' under directory 'docs' 08:44:11 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 08:44:11 INFO - no previously-included directories found matching 'docs/_build/_sources' 08:44:11 INFO - Installing pip script to /builds/slave/test/build/venv/bin 08:44:11 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 08:44:11 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 08:44:11 INFO - Successfully installed pip 08:44:11 INFO - Cleaning up... 08:44:11 INFO - Return code: 0 08:44:11 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:44: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')]} 08:44:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44: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')]} 08:44:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:11 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 0x18491f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a14a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bb7f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bb5130>, '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 0x1bb6030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bb64c0>, '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': '51fc86400edbde9650d2781a000001d0-1503935013.520396-1280810185', '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 08:44:11 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 08:44:11 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 08:44:11 INFO - Using env: {'DISPLAY': ':0', 08:44:11 INFO - 'HOME': '/home/cltbld', 08:44:11 INFO - 'LANG': 'en_US.UTF-8', 08:44:11 INFO - 'LANGUAGE': 'en_US:en', 08:44:11 INFO - 'LOGNAME': 'cltbld', 08:44:11 INFO - 'MAIL': '/var/mail/cltbld', 08:44:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:11 INFO - 'MOZ_NO_REMOTE': '1', 08:44:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:44:11 INFO - 'NO_EM_RESTART': '1', 08:44:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:44:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:11 INFO - 'PWD': '/builds/slave/test', 08:44:11 INFO - 'SHELL': '/bin/bash', 08:44:11 INFO - 'SHLVL': '1', 08:44:11 INFO - 'TERM': 'linux', 08:44:11 INFO - 'TMOUT': '86400', 08:44:11 INFO - 'USER': 'cltbld', 08:44:11 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1503935013.520396-1280810185', 08:44:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:11 INFO - '_': '/tools/buildbot/bin/python'} 08:44:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:11 INFO - Downloading/unpacking psutil>=3.1.1 08:44:11 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 08:44:11 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 08:44:11 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 08:44:11 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 08:44:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:44:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:44:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:44:13 INFO - Installing collected packages: psutil 08:44:13 INFO - Running setup.py install for psutil 08:44:13 INFO - building 'psutil._psutil_linux' extension 08:44:13 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 08:44:13 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 08:44:13 INFO - building 'psutil._psutil_posix' extension 08:44:13 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 08:44:13 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 08:44:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:44:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:44:13 INFO - Successfully installed psutil 08:44:13 INFO - Cleaning up... 08:44:13 INFO - Return code: 0 08:44:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:44:13 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')]} 08:44:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:13 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')]} 08:44:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:13 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 0x18491f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a14a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bb7f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bb5130>, '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 0x1bb6030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bb64c0>, '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': '51fc86400edbde9650d2781a000001d0-1503935013.520396-1280810185', '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 08:44:13 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 08:44:13 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 08:44:13 INFO - Using env: {'DISPLAY': ':0', 08:44:13 INFO - 'HOME': '/home/cltbld', 08:44:13 INFO - 'LANG': 'en_US.UTF-8', 08:44:13 INFO - 'LANGUAGE': 'en_US:en', 08:44:13 INFO - 'LOGNAME': 'cltbld', 08:44:13 INFO - 'MAIL': '/var/mail/cltbld', 08:44:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:13 INFO - 'MOZ_NO_REMOTE': '1', 08:44:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:44:13 INFO - 'NO_EM_RESTART': '1', 08:44:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:44:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:13 INFO - 'PWD': '/builds/slave/test', 08:44:13 INFO - 'SHELL': '/bin/bash', 08:44:13 INFO - 'SHLVL': '1', 08:44:13 INFO - 'TERM': 'linux', 08:44:13 INFO - 'TMOUT': '86400', 08:44:13 INFO - 'USER': 'cltbld', 08:44:13 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1503935013.520396-1280810185', 08:44:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:13 INFO - '_': '/tools/buildbot/bin/python'} 08:44:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:13 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:44:13 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 08:44:13 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 08:44:13 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 08:44:13 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 08:44:14 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:44:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:44:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:44:14 INFO - Installing collected packages: mozsystemmonitor 08:44:14 INFO - Running setup.py install for mozsystemmonitor 08:44:15 INFO - Successfully installed mozsystemmonitor 08:44:15 INFO - Cleaning up... 08:44:15 INFO - Return code: 0 08:44:15 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:44:15 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')]} 08:44:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:15 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')]} 08:44:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:15 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 0x18491f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a14a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bb7f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bb5130>, '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 0x1bb6030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bb64c0>, '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': '51fc86400edbde9650d2781a000001d0-1503935013.520396-1280810185', '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 08:44:15 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 08:44:15 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 08:44:15 INFO - Using env: {'DISPLAY': ':0', 08:44:15 INFO - 'HOME': '/home/cltbld', 08:44:15 INFO - 'LANG': 'en_US.UTF-8', 08:44:15 INFO - 'LANGUAGE': 'en_US:en', 08:44:15 INFO - 'LOGNAME': 'cltbld', 08:44:15 INFO - 'MAIL': '/var/mail/cltbld', 08:44:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:15 INFO - 'MOZ_NO_REMOTE': '1', 08:44:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:44:15 INFO - 'NO_EM_RESTART': '1', 08:44:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:44:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:15 INFO - 'PWD': '/builds/slave/test', 08:44:15 INFO - 'SHELL': '/bin/bash', 08:44:15 INFO - 'SHLVL': '1', 08:44:15 INFO - 'TERM': 'linux', 08:44:15 INFO - 'TMOUT': '86400', 08:44:15 INFO - 'USER': 'cltbld', 08:44:15 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1503935013.520396-1280810185', 08:44:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:15 INFO - '_': '/tools/buildbot/bin/python'} 08:44:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:15 INFO - Downloading/unpacking jsonschema==2.5.1 08:44:15 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 08:44:15 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 08:44:15 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 08:44:15 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 08:44:16 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:44:16 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:44:16 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 08:44:16 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 08:44:16 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 08:44:16 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 08:44:16 INFO - Downloading functools32-3.2.3-2.tar.gz 08:44:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:44:16 INFO - warning: no files found matching '*.txt' 08:44:16 INFO - no previously-included directories found matching 'build' 08:44:16 INFO - no previously-included directories found matching 'dist' 08:44:16 INFO - no previously-included directories found matching '.git*' 08:44:16 INFO - Installing collected packages: jsonschema, functools32 08:44:16 INFO - Running setup.py install for functools32 08:44:16 INFO - warning: no files found matching '*.txt' 08:44:16 INFO - no previously-included directories found matching 'build' 08:44:16 INFO - no previously-included directories found matching 'dist' 08:44:16 INFO - no previously-included directories found matching '.git*' 08:44:16 INFO - Successfully installed jsonschema functools32 08:44:16 INFO - Cleaning up... 08:44:16 INFO - Return code: 0 08:44:16 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:44:16 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')]} 08:44:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:16 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')]} 08:44:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:16 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 0x18491f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a14a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bb7f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bb5130>, '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 0x1bb6030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bb64c0>, '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': '51fc86400edbde9650d2781a000001d0-1503935013.520396-1280810185', '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 08:44:16 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 08:44:16 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 08:44:16 INFO - Using env: {'DISPLAY': ':0', 08:44:16 INFO - 'HOME': '/home/cltbld', 08:44:16 INFO - 'LANG': 'en_US.UTF-8', 08:44:16 INFO - 'LANGUAGE': 'en_US:en', 08:44:16 INFO - 'LOGNAME': 'cltbld', 08:44:16 INFO - 'MAIL': '/var/mail/cltbld', 08:44:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:16 INFO - 'MOZ_NO_REMOTE': '1', 08:44:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:44:16 INFO - 'NO_EM_RESTART': '1', 08:44:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:44:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:16 INFO - 'PWD': '/builds/slave/test', 08:44:16 INFO - 'SHELL': '/bin/bash', 08:44:16 INFO - 'SHLVL': '1', 08:44:16 INFO - 'TERM': 'linux', 08:44:16 INFO - 'TMOUT': '86400', 08:44:16 INFO - 'USER': 'cltbld', 08:44:16 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1503935013.520396-1280810185', 08:44:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:16 INFO - '_': '/tools/buildbot/bin/python'} 08:44:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:17 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:44:17 INFO - Cleaning up... 08:44:17 INFO - Return code: 0 08:44:17 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:44:17 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')]} 08:44:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:17 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')]} 08:44:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:17 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 0x18491f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a14a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bb7f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bb5130>, '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 0x1bb6030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bb64c0>, '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': '51fc86400edbde9650d2781a000001d0-1503935013.520396-1280810185', '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 08:44:17 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 08:44:17 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 08:44:17 INFO - Using env: {'DISPLAY': ':0', 08:44:17 INFO - 'HOME': '/home/cltbld', 08:44:17 INFO - 'LANG': 'en_US.UTF-8', 08:44:17 INFO - 'LANGUAGE': 'en_US:en', 08:44:17 INFO - 'LOGNAME': 'cltbld', 08:44:17 INFO - 'MAIL': '/var/mail/cltbld', 08:44:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:17 INFO - 'MOZ_NO_REMOTE': '1', 08:44:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:44:17 INFO - 'NO_EM_RESTART': '1', 08:44:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:44:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:17 INFO - 'PWD': '/builds/slave/test', 08:44:17 INFO - 'SHELL': '/bin/bash', 08:44:17 INFO - 'SHLVL': '1', 08:44:17 INFO - 'TERM': 'linux', 08:44:17 INFO - 'TMOUT': '86400', 08:44:17 INFO - 'USER': 'cltbld', 08:44:17 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1503935013.520396-1280810185', 08:44:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:17 INFO - '_': '/tools/buildbot/bin/python'} 08:44:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:17 INFO - Downloading/unpacking blobuploader==1.2.4 08:44:17 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 08:44:17 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 08:44:17 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 08:44:17 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 08:44:18 INFO - Downloading blobuploader-1.2.4.tar.gz 08:44:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:44:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:44:18 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 08:44:18 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 08:44:18 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 08:44:18 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 08:44:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:44:18 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:44:18 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 08:44:18 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 08:44:18 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 08:44:18 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 08:44:18 INFO - Downloading docopt-0.6.1.tar.gz 08:44:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:44:18 INFO - Installing collected packages: blobuploader, requests, docopt 08:44:18 INFO - Running setup.py install for blobuploader 08:44:18 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:44:19 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:44:19 INFO - Running setup.py install for requests 08:44:19 INFO - Running setup.py install for docopt 08:44:19 INFO - Successfully installed blobuploader requests docopt 08:44:19 INFO - Cleaning up... 08:44:19 INFO - Return code: 0 08:44:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:44:19 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')]} 08:44:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:19 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')]} 08:44:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:19 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 0x18491f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a14a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bb7f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bb5130>, '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 0x1bb6030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bb64c0>, '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': '51fc86400edbde9650d2781a000001d0-1503935013.520396-1280810185', '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 08:44:19 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 08:44:19 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 08:44:19 INFO - Using env: {'DISPLAY': ':0', 08:44:19 INFO - 'HOME': '/home/cltbld', 08:44:19 INFO - 'LANG': 'en_US.UTF-8', 08:44:19 INFO - 'LANGUAGE': 'en_US:en', 08:44:19 INFO - 'LOGNAME': 'cltbld', 08:44:19 INFO - 'MAIL': '/var/mail/cltbld', 08:44:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:19 INFO - 'MOZ_NO_REMOTE': '1', 08:44:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:44:19 INFO - 'NO_EM_RESTART': '1', 08:44:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:44:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:19 INFO - 'PWD': '/builds/slave/test', 08:44:19 INFO - 'SHELL': '/bin/bash', 08:44:19 INFO - 'SHLVL': '1', 08:44:19 INFO - 'TERM': 'linux', 08:44:19 INFO - 'TMOUT': '86400', 08:44:19 INFO - 'USER': 'cltbld', 08:44:19 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1503935013.520396-1280810185', 08:44:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:19 INFO - '_': '/tools/buildbot/bin/python'} 08:44:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:44:19 INFO - Running setup.py (path:/tmp/pip-FaMA7L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:44:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:44:19 INFO - Running setup.py (path:/tmp/pip-eJy2LZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:44:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:44:19 INFO - Running setup.py (path:/tmp/pip-i3bLN1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:44:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:44:19 INFO - Running setup.py (path:/tmp/pip-pJmWaz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:44:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:44:19 INFO - Running setup.py (path:/tmp/pip-3BStWk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:44:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:44:20 INFO - Running setup.py (path:/tmp/pip-fcCf2z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:44:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:44:20 INFO - Running setup.py (path:/tmp/pip-dhGDLX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:44:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:44:20 INFO - Running setup.py (path:/tmp/pip-VMoUs6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:44:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:44:20 INFO - Running setup.py (path:/tmp/pip-gdxT7c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:44:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:44:20 INFO - Running setup.py (path:/tmp/pip-uvUQNa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:44:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:44:20 INFO - Running setup.py (path:/tmp/pip-38uvH7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:44:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:44:20 INFO - Running setup.py (path:/tmp/pip-2o2GWG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:44:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:44:20 INFO - Running setup.py (path:/tmp/pip-YlUNV6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:44:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:44:20 INFO - Running setup.py (path:/tmp/pip-ALyY4K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:44:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:44:20 INFO - Running setup.py (path:/tmp/pip-79gZts-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:44:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:44:20 INFO - Running setup.py (path:/tmp/pip-qJeZlu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:44:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:44:20 INFO - Running setup.py (path:/tmp/pip-LFZr_T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:44:20 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:44:20 INFO - Running setup.py install for manifestparser 08:44:20 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:44:21 INFO - Running setup.py install for mozcrash 08:44:21 INFO - Running setup.py install for mozdebug 08:44:21 INFO - Running setup.py install for mozdevice 08:44:21 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:44:21 INFO - Running setup.py install for mozfile 08:44:21 INFO - Running setup.py install for mozhttpd 08:44:21 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:44:21 INFO - Running setup.py install for mozinfo 08:44:21 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:44:21 INFO - Running setup.py install for mozInstall 08:44:21 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:44:21 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:44:21 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:44:21 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:44:21 INFO - Running setup.py install for mozleak 08:44:21 INFO - Running setup.py install for mozlog 08:44:21 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:44:21 INFO - Running setup.py install for moznetwork 08:44:21 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:44:22 INFO - Running setup.py install for mozprocess 08:44:22 INFO - Running setup.py install for mozprofile 08:44:22 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:44:22 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:44:22 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:44:22 INFO - Running setup.py install for mozrunner 08:44:22 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:44:22 INFO - Running setup.py install for mozscreenshot 08:44:22 INFO - Running setup.py install for moztest 08:44:22 INFO - Running setup.py install for mozversion 08:44:22 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:44:22 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:44:22 INFO - Cleaning up... 08:44:22 INFO - Return code: 0 08:44:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:44:22 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')]} 08:44:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:22 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')]} 08:44:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:22 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 0x18491f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a14a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bb7f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bb5130>, '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 0x1bb6030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bb64c0>, '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': '51fc86400edbde9650d2781a000001d0-1503935013.520396-1280810185', '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 08:44:22 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 08:44:22 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 08:44:22 INFO - Using env: {'DISPLAY': ':0', 08:44:22 INFO - 'HOME': '/home/cltbld', 08:44:22 INFO - 'LANG': 'en_US.UTF-8', 08:44:22 INFO - 'LANGUAGE': 'en_US:en', 08:44:22 INFO - 'LOGNAME': 'cltbld', 08:44:22 INFO - 'MAIL': '/var/mail/cltbld', 08:44:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:22 INFO - 'MOZ_NO_REMOTE': '1', 08:44:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:44:22 INFO - 'NO_EM_RESTART': '1', 08:44:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:44:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:22 INFO - 'PWD': '/builds/slave/test', 08:44:22 INFO - 'SHELL': '/bin/bash', 08:44:22 INFO - 'SHLVL': '1', 08:44:22 INFO - 'TERM': 'linux', 08:44:22 INFO - 'TMOUT': '86400', 08:44:22 INFO - 'USER': 'cltbld', 08:44:22 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1503935013.520396-1280810185', 08:44:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:22 INFO - '_': '/tools/buildbot/bin/python'} 08:44:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:44:22 INFO - Running setup.py (path:/tmp/pip-MfL9CI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:44:22 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)) 08:44:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:44:22 INFO - Running setup.py (path:/tmp/pip-lwXcEo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:44:23 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)) 08:44:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:44:23 INFO - Running setup.py (path:/tmp/pip-uaXtaL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:44:23 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)) 08:44:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:44:23 INFO - Running setup.py (path:/tmp/pip-EwyKh3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:44:23 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)) 08:44:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:44:23 INFO - Running setup.py (path:/tmp/pip-IC2ZjM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:44:23 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)) 08:44:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:44:23 INFO - Running setup.py (path:/tmp/pip-UcnMqj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:44:23 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)) 08:44:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:44:23 INFO - Running setup.py (path:/tmp/pip-9NXWig-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:44:23 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)) 08:44:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:44:23 INFO - Running setup.py (path:/tmp/pip-RdlFCJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:44:23 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)) 08:44:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:44:23 INFO - Running setup.py (path:/tmp/pip-E76dSk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:44:23 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)) 08:44:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:44:23 INFO - Running setup.py (path:/tmp/pip-jD8yH5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:44:23 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)) 08:44:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:44:23 INFO - Running setup.py (path:/tmp/pip-Gw45E0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:44:23 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)) 08:44:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:44:23 INFO - Running setup.py (path:/tmp/pip-imFCb9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:44:23 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)) 08:44:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:44:23 INFO - Running setup.py (path:/tmp/pip-FxnWCC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:44:23 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)) 08:44:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:44:23 INFO - Running setup.py (path:/tmp/pip-XeMBao-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:44:23 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)) 08:44:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:44:23 INFO - Running setup.py (path:/tmp/pip-pEWv1k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:44:23 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)) 08:44:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:44:23 INFO - Running setup.py (path:/tmp/pip-MWrfHu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:44:24 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)) 08:44:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:44:24 INFO - Running setup.py (path:/tmp/pip-cUVGyq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:44:24 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)) 08:44:24 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)) 08:44:24 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)) 08:44:24 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)) 08:44:24 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)) 08:44:24 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)) 08:44:24 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)) 08:44:24 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:44:24 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 08:44:24 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 08:44:24 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 08:44:24 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 08:44:25 INFO - Downloading blessings-1.6.tar.gz 08:44:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:44:25 INFO - Installing collected packages: blessings 08:44:25 INFO - Running setup.py install for blessings 08:44:25 INFO - Successfully installed blessings 08:44:25 INFO - Cleaning up... 08:44:25 INFO - Return code: 0 08:44:25 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:44:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:44:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:44:25 INFO - Reading from file tmpfile_stdout 08:44:25 INFO - Current package versions: 08:44:25 INFO - argparse == 1.2.1 08:44:25 INFO - blessings == 1.6 08:44:25 INFO - blobuploader == 1.2.4 08:44:25 INFO - docopt == 0.6.1 08:44:25 INFO - functools32 == 3.2.3-2 08:44:25 INFO - jsonschema == 2.5.1 08:44:25 INFO - manifestparser == 1.1 08:44:25 INFO - mozInstall == 1.13 08:44:25 INFO - mozcrash == 1.0 08:44:25 INFO - mozdebug == 0.1 08:44:25 INFO - mozdevice == 0.51 08:44:25 INFO - mozfile == 1.2 08:44:25 INFO - mozhttpd == 0.7 08:44:25 INFO - mozinfo == 0.10 08:44:25 INFO - mozleak == 0.1 08:44:25 INFO - mozlog == 3.5 08:44:25 INFO - moznetwork == 0.27 08:44:25 INFO - mozprocess == 0.25 08:44:25 INFO - mozprofile == 0.28 08:44:25 INFO - mozrunner == 6.13 08:44:25 INFO - mozscreenshot == 0.1 08:44:25 INFO - mozsystemmonitor == 0.3 08:44:25 INFO - moztest == 0.8 08:44:25 INFO - mozversion == 1.4 08:44:25 INFO - psutil == 3.1.1 08:44:25 INFO - requests == 1.2.3 08:44:25 INFO - wsgiref == 0.1.2 08:44:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:44:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:25 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 0x18491f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a14a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bb7f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bb5130>, '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 0x1bb6030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bb64c0>, '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': '51fc86400edbde9650d2781a000001d0-1503935013.520396-1280810185', '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 08:44:25 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 08:44:25 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 08:44:25 INFO - Using env: {'DISPLAY': ':0', 08:44:25 INFO - 'HOME': '/home/cltbld', 08:44:25 INFO - 'LANG': 'en_US.UTF-8', 08:44:25 INFO - 'LANGUAGE': 'en_US:en', 08:44:25 INFO - 'LOGNAME': 'cltbld', 08:44:25 INFO - 'MAIL': '/var/mail/cltbld', 08:44:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:25 INFO - 'MOZ_NO_REMOTE': '1', 08:44:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:44:25 INFO - 'NO_EM_RESTART': '1', 08:44:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:44:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:25 INFO - 'PWD': '/builds/slave/test', 08:44:25 INFO - 'SHELL': '/bin/bash', 08:44:25 INFO - 'SHLVL': '1', 08:44:25 INFO - 'TERM': 'linux', 08:44:25 INFO - 'TMOUT': '86400', 08:44:25 INFO - 'USER': 'cltbld', 08:44:25 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1503935013.520396-1280810185', 08:44:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:25 INFO - '_': '/tools/buildbot/bin/python'} 08:44:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:25 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)) 08:44:25 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)) 08:44:25 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)) 08:44:25 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)) 08:44:25 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)) 08:44:25 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)) 08:44:25 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)) 08:44:25 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)) 08:44:25 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)) 08:44:25 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 08:44:25 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 08:44:25 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 08:44:25 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 08:44:25 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 08:44:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 08:44:27 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)) 08:44:27 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)) 08:44:27 INFO - Installing collected packages: simplejson 08:44:27 INFO - Running setup.py install for simplejson 08:44:27 INFO - building 'simplejson._speedups' extension 08:44:27 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 08:44:27 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 08:44:28 INFO - Successfully installed simplejson 08:44:28 INFO - Cleaning up... 08:44:28 INFO - Return code: 0 08:44:28 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:44:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18491f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a14a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bb7f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bb5130>, '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 0x1bb6030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bb64c0>, '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': '51fc86400edbde9650d2781a000001d0-1503935013.520396-1280810185', '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 08:44:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:44:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:44:28 INFO - Using env: {'DISPLAY': ':0', 08:44:28 INFO - 'HOME': '/home/cltbld', 08:44:28 INFO - 'LANG': 'en_US.UTF-8', 08:44:28 INFO - 'LANGUAGE': 'en_US:en', 08:44:28 INFO - 'LOGNAME': 'cltbld', 08:44:28 INFO - 'MAIL': '/var/mail/cltbld', 08:44:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:28 INFO - 'MOZ_NO_REMOTE': '1', 08:44:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:44:28 INFO - 'NO_EM_RESTART': '1', 08:44:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:44:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:28 INFO - 'PWD': '/builds/slave/test', 08:44:28 INFO - 'SHELL': '/bin/bash', 08:44:28 INFO - 'SHLVL': '1', 08:44:28 INFO - 'TERM': 'linux', 08:44:28 INFO - 'TMOUT': '86400', 08:44:28 INFO - 'USER': 'cltbld', 08:44:28 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1503935013.520396-1280810185', 08:44:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:28 INFO - '_': '/tools/buildbot/bin/python'} 08:44:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:28 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:44:28 INFO - Cleaning up... 08:44:28 INFO - Return code: 0 08:44:28 INFO - Running post-action listener: _resource_record_post_action 08:44:28 INFO - Running post-action listener: _start_resource_monitoring 08:44:28 INFO - Starting resource monitoring. 08:44:28 INFO - [mozharness: 2017-08-28 15:44:28.347368Z] Finished create-virtualenv step (success) 08:44:28 INFO - [mozharness: 2017-08-28 15:44:28.347833Z] Running install step. 08:44:28 INFO - Running pre-action listener: _resource_record_pre_action 08:44:28 INFO - Running main action method: install 08:44:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:44:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:44:28 INFO - Reading from file tmpfile_stdout 08:44:28 INFO - Detecting whether we're running mozinstall >=1.0... 08:44:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:44:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:44:28 INFO - Reading from file tmpfile_stdout 08:44:28 INFO - Output received: 08:44:28 INFO - Usage: mozinstall [options] installer 08:44:28 INFO - Options: 08:44:28 INFO - -h, --help show this help message and exit 08:44:28 INFO - -d DEST, --destination=DEST 08:44:28 INFO - Directory to install application into. [default: 08:44:28 INFO - "/builds/slave/test"] 08:44:28 INFO - --app=APP Application being installed. [default: firefox] 08:44:28 INFO - mkdir: /builds/slave/test/build/application 08:44:28 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'] 08:44:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 08:44:46 INFO - Reading from file tmpfile_stdout 08:44:46 INFO - Output received: 08:44:46 INFO - /builds/slave/test/build/application/firefox/firefox 08:44:46 INFO - Running post-action listener: _resource_record_post_action 08:44:46 INFO - [mozharness: 2017-08-28 15:44:46.373926Z] Finished install step (success) 08:44:46 INFO - [mozharness: 2017-08-28 15:44:46.374206Z] Running setup-mitmproxy step. 08:44:46 INFO - Running pre-action listener: _resource_record_pre_action 08:44:46 INFO - Running main action method: setup_mitmproxy 08:44:46 INFO - Skipping: mitmproxy is not required 08:44:46 INFO - Running post-action listener: _resource_record_post_action 08:44:46 INFO - [mozharness: 2017-08-28 15:44:46.375614Z] Finished setup-mitmproxy step (success) 08:44:46 INFO - [mozharness: 2017-08-28 15:44:46.375868Z] Running run-tests step. 08:44:46 INFO - Running pre-action listener: _resource_record_pre_action 08:44:46 INFO - Running pre-action listener: _set_gcov_prefix 08:44:46 INFO - Running main action method: run_tests 08:44:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:44:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:44:46 INFO - Python 2.7.3 08:44:46 INFO - Return code: 0 08:44:46 INFO - grabbing minidump binary from tooltool 08:44:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:46 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 0x1bb5130>, '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 0x1bb6030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bb64c0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:44:46 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 08:44:46 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 08:44:46 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 08:44:46 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:44:46 INFO - Return code: 0 08:44:46 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:44:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:44:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:44:46 INFO - ENV: RUST_BACKTRACE is now full 08:44:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:44:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:44:46 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/TTT3heApSneArmZvclHtxg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-082', '--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 08:44:46 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/TTT3heApSneArmZvclHtxg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-082 --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 08:44:46 INFO - Using env: {'DISPLAY': ':0', 08:44:46 INFO - 'HOME': '/home/cltbld', 08:44:46 INFO - 'LANG': 'en_US.UTF-8', 08:44:46 INFO - 'LANGUAGE': 'en_US:en', 08:44:46 INFO - 'LOGNAME': 'cltbld', 08:44:46 INFO - 'MAIL': '/var/mail/cltbld', 08:44:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:44:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:44:46 INFO - 'MOZ_ACCELERATED': '1', 08:44:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:46 INFO - 'MOZ_NO_REMOTE': '1', 08:44:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:44:46 INFO - 'MOZ_WEBRENDER': '1', 08:44:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:44:46 INFO - 'NO_EM_RESTART': '1', 08:44:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:44:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:46 INFO - 'PWD': '/builds/slave/test', 08:44:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:44:46 INFO - 'RUST_BACKTRACE': 'full', 08:44:46 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 08:44:46 INFO - 'SHELL': '/bin/bash', 08:44:46 INFO - 'SHLVL': '1', 08:44:46 INFO - 'TERM': 'linux', 08:44:46 INFO - 'TMOUT': '86400', 08:44:46 INFO - 'USER': 'cltbld', 08:44:46 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1503935013.520396-1280810185', 08:44:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:46 INFO - '_': '/tools/buildbot/bin/python'} 08:44:46 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/TTT3heApSneArmZvclHtxg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-082', '--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 08:44:46 INFO - mozversion application_buildid: 20170828145411 08:44:46 INFO - mozversion application_changeset: e2efa420beb1a578c7350ba925c82230da6b1267 08:44:46 INFO - mozversion application_display_name: Nightly 08:44:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:44:46 INFO - mozversion application_name: Firefox 08:44:46 INFO - mozversion application_remotingname: firefox 08:44:46 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:44:46 INFO - mozversion application_vendor: Mozilla 08:44:46 INFO - mozversion application_version: 57.0a1 08:44:46 INFO - mozversion platform_buildid: 20170828145411 08:44:46 INFO - mozversion platform_changeset: e2efa420beb1a578c7350ba925c82230da6b1267 08:44:46 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:44:46 INFO - mozversion platform_version: 57.0a1 08:44:46 INFO - using testdate: 1503935086 08:44:46 INFO - actual date: 1503935086 08:44:46 INFO - starting webserver on 'localhost:44789' 08:44:46 INFO - SUITE-START | Running 2 tests 08:44:46 INFO - TEST-START | dromaeo_css 08:44:46 INFO - Initialising browser for dromaeo_css test... 08:44:46 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:44789/getInfo.html -profile /tmp/tmpNq55UC/profile 08:44:46 INFO - TEST-INFO | started process 4306 (/builds/slave/test/build/application/firefox/firefox http://localhost:44789/getInfo.html) 08:44:53 INFO - TEST-INFO | 4306: exit 0 08:44:53 INFO - Browser initialized. 08:44:53 INFO - Running cycle 1/1 for dromaeo_css test... 08:44:53 INFO - Using env: {'DISPLAY': ':0', 08:44:53 INFO - 'HOME': '/home/cltbld', 08:44:53 INFO - 'JSGC_DISABLE_POISONING': '1', 08:44:53 INFO - 'LANG': 'en_US.UTF-8', 08:44:53 INFO - 'LANGUAGE': 'en_US:en', 08:44:53 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 08:44:53 INFO - 'LOGNAME': 'cltbld', 08:44:53 INFO - 'MAIL': '/var/mail/cltbld', 08:44:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:44:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:44:53 INFO - 'MOZ_ACCELERATED': '1', 08:44:53 INFO - 'MOZ_CRASHREPORTER': '1', 08:44:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:53 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 08:44:53 INFO - 'MOZ_NO_REMOTE': '1', 08:44:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:44:53 INFO - 'MOZ_WEBRENDER': '1', 08:44:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:44:53 INFO - 'NO_EM_RESTART': '1', 08:44:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:44:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:53 INFO - 'PWD': '/builds/slave/test', 08:44:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:44:53 INFO - 'RUST_BACKTRACE': 'full', 08:44:53 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 08:44:53 INFO - 'SHELL': '/bin/bash', 08:44:53 INFO - 'SHLVL': '1', 08:44:53 INFO - 'TERM': 'linux', 08:44:53 INFO - 'TMOUT': '86400', 08:44:53 INFO - 'USER': 'cltbld', 08:44:53 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1503935013.520396-1280810185', 08:44:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:53 INFO - '_': '/tools/buildbot/bin/python'} 08:44:53 INFO - TEST-INFO | started process 4488 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNq55UC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 08:44:54 INFO - PID 4488 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 08:44:54 INFO - PID 4488 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 08:44:55 INFO - PID 4488 | 08:44:55 INFO - PID 4488 | (/builds/slave/test/build/application/firefox/firefox:4547): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 08:44:55 INFO - PID 4488 | 08:45:05 INFO - PID 4488 | RSS: Main: 202178560 08:45:05 INFO - PID 4488 | 08:45:08 INFO - PID 4488 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 08:48:05 INFO - PID 4488 | Cycle 1(1): loaded http://localhost:44789/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44789/tests/dromaeo/cssquery-ext.html) 08:48:06 INFO - PID 4488 | RSS: Main: 217104384 08:48:06 INFO - PID 4488 | 08:51:08 INFO - PID 4488 | Cycle 1(1): loaded http://localhost:44789/tests/dromaeo/cssquery-ext.html (next: http://localhost:44789/tests/dromaeo/cssquery-jquery.html) 08:51:09 INFO - PID 4488 | RSS: Main: 210890752 08:51:09 INFO - PID 4488 | 08:54:11 INFO - PID 4488 | Cycle 1(1): loaded http://localhost:44789/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44789/tests/dromaeo/cssquery-mootools.html) 08:54:11 INFO - PID 4488 | RSS: Main: 216952832 08:54:11 INFO - PID 4488 | 08:57:13 INFO - PID 4488 | Cycle 1(1): loaded http://localhost:44789/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44789/tests/dromaeo/cssquery-prototype.html) 08:57:14 INFO - PID 4488 | RSS: Main: 214765568 08:57:14 INFO - PID 4488 | 09:00:18 INFO - PID 4488 | Cycle 1(1): loaded http://localhost:44789/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44789/tests/dromaeo/cssquery-yui.html) 09:00:19 INFO - PID 4488 | RSS: Main: 216199168 09:00:19 INFO - PID 4488 | 09:03:21 INFO - PID 4488 | Cycle 1(1): loaded http://localhost:44789/tests/dromaeo/cssquery-yui.html (next: http://localhost:44789/tests/dromaeo/cssquery-dojo.html) 09:03:21 INFO - PID 4488 | RSS: Main: 218808320 09:03:21 INFO - PID 4488 | 09:03:21 INFO - PID 4488 | __start_tp_report 09:03:21 INFO - PID 4488 | _x_x_mozilla_page_load 09:03:21 INFO - PID 4488 | _x_x_mozilla_page_load_details 09:03:21 INFO - PID 4488 | |i|pagename|runs| 09:03:21 INFO - PID 4488 | |0;dojo.html;3292.0617623977173;3306.7850589711666;3316.5356850040985;3340.1148695595666;3369.730421566275;6180.567360284782;6241.843953901155;6256.937430625692;6297.307296197415;6299.716512756925;6211.534134939875;6258.186435763344;6417.647029413381;6420.678966051702;6441;5838.941610583904;5858.605013249232;5906.114082887569;5907.497862681677;5923.022701254288;5651.1523271509395;5785.3925337839555;5873.7356818943035;5874.148248503964;5913.2608423947;5782.017057100282;5814.273215848019;5915.467607915287;5916.615419997686;5923;5744.540436765049;5785.82642520725;5857.297124345106;5862.530997520188;5878.382769809194;5569.693666848321;5711.428857114297;5795.507381872546;5797.507211886995;5853.356130825606;6211.223597050369;6217.968910155466;6306.747730090791;6317.6209427434505;6349.238091429012;6765.729370825161;6853.485988550879;6862.794116176523;6863.039174515572;6875.621840798754;6857.108575885103;6868.553544019623;6925.342092501204;6951.65241737911;6965.965170174168;6130.386961303879;6271.937280627227;6279.843003924939;6358.745650173987;6423.197100362455;6958.338957799001;6971.407430368374;6984.79045628632;6988.510804243654;7128.92871071293;6513.934860651333;6629.403353698191;6775.627340496221;6780.932190678129;6786.117804685458;6732;6747.595144291358;6760.492963027793;6765.560238584476;6768.830779230559;6638.236602790645;6659.367360100783;6699.899501507482;6708.262091169968;6714.194296684332;6683.030960510796;6727.899081513781;6764.864702705919;6771.356721111487;6771.52599318043;5300.1254792958725;5356.625036247503;5359.839204823861;5362.758675859595;5402.91895621566;4693.084848454519;4702.106599746037;4734.597559207437;4761.738104404221;4770.045990801854;5793.797217097382;5900.291964964232;5903.498202652822;5917.467427931507;6002.459778619857;6788.898166527407;6867.416269617039;6869.244383117853;6873.312668733087;6882.655867206619;5932.881342373128;5935.643861368331;5949.851253718605;5965.880682386415;5971.880562388728;5837.7956771513645;5839.416058394127;5930.43660852227;5934.643921364732;5952.494038006731;6467.191601049869;6612.107365505692;6615.702293396808;6689.096971908827;6700.363465470772;6783.4573234142135;6798.864022719419;6867.2102708189195;6900.758473453405;6902.413294869993;6584.045313429621;6647.368499992493;6649.434798042027;6670.966645166937;6686.364795344434;6647.700853461509;6654.600723956578;6778.389944904982;6779.118714567272;6801.1158549388265;6754.290799465985;6800.047993281044;6812.250652428328;6906.136732908387;6909.550879193038;6441.3880681335195;6566.737330506918;6576.112224849584;6587.835304117435;6603.636799976048;6494.318096599789;6501.8699626008165;6506.284308726131;6512.804615861532;6518.087467754613;6376.075469057054;6397.328280530477;6433.002884552901;6441.291457939529;6457.225132984072;6412.743490260337;6425.325340839145;6481.092647029325;6695.263521012782;6776;6334.8733025338315;6444.45222156122;6450.129232553545;6540.411362977355;6559.212894452506 09:03:21 INFO - PID 4488 | |1;ext.html;747.9027726395568;785.1873311122989;807.2653884964682;834.1950018232409;836.2473773603758;36693;37351.25297494052;37364.06589835256;37459.62540374595;37566.24867502648;34159.82920085396;34300.828495857546;34470.13824654385;34539.481907771406;34540.654593454055;6716.899246511294;6830.248672646005;6831.897521537181;6838.931610683904;6878.449724022092;6714.127163468766;6818.4886133540185;6821.045053692487;6843.828904277383;6848.041274221613;7249.093863267091;7419.924111003901;7486.251374862524;7493.850122997536;7503.287187717158;7379.077615298088;7453.329200371993;7460.403167746567;7462.552246865205;7463.328300452931;7220.097487814023;7318.634068296564;7399.593022383767;7400.740974065882;7404.4816862819625;34013;34244.6575534244;34273.48589771155;34286.828565857264;34380;3255.3489302139665;3315.436375816117;3322.4185767490835;3326;3329.5671562696934;28252.717472825214;28421.289467763265;28434.289142771595;28645.99739009125;28794.71205287941;5045.873853153664;5061.759868832123;5081.872953176163;5126.564242039394;5203.4796520348045;216028;222964;225220;225390.99999999837;225827;42981.35527967083;43017.13965720668;43182.56817431786;43403.56596434058;43645.563544364784;44180.11639767187;44609.77695111569;44693.55306446894;44789;44866.55133448689;13836.100653457494;13987.300634968211;13988.230647314293;14012.439502419993;14017.088889222168;11298.152638552141;11326.886731132583;11402.429878506042;11421.37182454973;11429.714257143638;12522.937385313015;12601.117921745574;12786.424610892533;12814.46226452813;12826.935865320615;5018.698878067255;5096.21008743638;5244.790208391698;5245.449227831099;5279.208118782137;904.8009437923646;913.3104506097853;920.8204400142042;944.631593678466;959.2901253072815;21620.35138945834;22368.776312236994;22396.328110156723;26483.73516264851;27664.170074897786;999.1457304005116;1013.1793247469574;1022.7903279827617;1031.138998935897;1034.3018462537907;11620.360880151504;11648;11650.53397864076;11772.52909883595;11863.703407414883;233629;236514;237810;238804;241293;38535;39452.60547394547;45029.32456013162;45164.54835451603;45632.54367456283;1298.2989185839729;1310.698539335958;1320.966975825612;1322.147215046309;1326.5091915990952;25439.491210176064;25512.617310740727;25626.743732562434;25691.486170276865;25828.096016639327;33306.83346583252;33527.664723352456;33592.83203583967;33601;33649.66350336465;195545;196317;196649;196777;197519;28675.13974580766;29427.705722942497;29538.704612953596;29691.55462668105;29731;35456;35906.28187436289;36588.45117323189;36789.81605091957;36893.63106368902;27655.30861728473;27926;28004.159875203775;28068.71931280661;28577;27881.860590696917;27911.860440697666;28361;28412.857935710188;28555.714442855304;29813.25466863259;30795.846020769754;30944;31015;31278.843605781825;27515.44969100647;27710.722892770813;27901.720982789913;27908.86045569759;28210.71789282081;21497.247596334055;21649.567008660055;21748.238811641517;21946.780532194472;22000.779992199874 09:03:21 INFO - PID 4488 | |2;jquery.html;535.5822458482382;543.7943324104405;548.3173449055926;549.6592112890008;562.3898070593406;21199;21330.680039799397;21951.780482195172;22009.119635214593;22010;21412.46468838276;21987.450313742163;22139.114435422605;22229.2219772308;22273.777262227373;6373.298937116925;6523.021546767975;6524.673766311689;6524.739010439601;6531.216254049521;6171.321154672983;6447.2907980122145;6448.806535803933;6487.902681459782;6495.707693153796;6369.07648390983;6369.20384951881;6416.45460135889;6419.871602567946;6425.261094974067;6354.332795056499;6389.1694079769795;6390.616563006211;6394.040893865911;6396.71214795333;5883.617564858313;5887.8233652990475;5899.351071382144;5955.315138759055;5959.851003724898;27270.5909411359;27368.58947115795;27796.722032779617;28031;28546.857265713537;2054.599353126141;2105.7578378486364;2107.7786832382685;2113.608982338272;2114.0381126587517;18200.1809918554;18725.53186170343;18819.435416937515;18843;18868.15093320804;2381.6903802505735;2418.6128457608124;2443.8167137464584;2478.8016958643266;2481.689788776403;133164.99999999904;136066.31966840103;136154;136368.31815840857;137047;13654.93172534131;14197.78703319451;14273.214973176575;14374.568762937128;15005.549833505012;22204.888975555343;22223.22218722337;22329.665055024187;22379.216727414463;22500.21249256268;8820.735377938672;9045;9109.590068446936;9149.313801464918;9234.215091717278;8050.637721302555;8094.352451803841;8170.101288858221;8213.055498617736;8259.132791056853;7386.0767404074495;8260.421770476009;8464.746057618282;8621.913780862236;8759.956200218958;2434.160214725917;2476.715177754545;2478.1821998740375;2604.882780274892;2699.0688212567024;899.8245342158348;907.8683590879285;923.986140207884;931.8043210925781;942.6323733743845;18982.905085474486;18993.525161871064;18997.145128469252;19083.522911927033;19193.136308866135;972.6304004478253;981.9901800982042;995.1640621877658;998.3460833154296;1002.6190047781796;10297.897021029843;10589.470526473646;10696.090832279187;10812.513436895359;10820.837687434694;480714;483606;520411;521118;521848;108499;108774;110660.4466977676;114340;115779.42110289395;1089.3518356577824;1104.4422566603814;1131.2409203688344;1133.2520536445907;1133.7675776465805;16890.40883569069;16969;17324.393646222827;17389.739153912447;17535;52249.738751306;60125;60153.69923150356;63186;63513.68243158755;68936;69560.6521967387;69626.65186674034;70249;71145.6442717783;14019.228942408265;14367.28163591837;14451.988360814643;14456.349464273922;14511.492097776949;22109.889450553288;22486.88756556207;22565.548689026455;22670;22678.206262780725;17988;18171.727424088385;18270.360537381126;18270.543236418664;18551.35070272534;15920.124393158487;16128.596785080466;16524.917375413046;16615.41846035383;16988.320467181173;18501.53746156303;18585.44243672692;18596;18709.25162993465;18779.436616901516;13543.322833858465;13800.033997620472;13912.652183695489;14009.789853152008;14097.295135242992;16881.66236675284;16893.155342233084;16978.830211697725;17364.305427782747;18173.182206800462 09:03:21 INFO - PID 4488 | |3;mootools.html;1227.7298994221273;1272.8154417609453;1284.0562186792706;1287.0475847872576;1298.902582306327;13950.302484875767;14625.488107916224;14630.634234144152;14641.853581464182;14735.705285894275;13874.236916969563;14722.705545889075;14731.263436828169;14827.925860370684;14842;2687.543117669996;2746.134967485246;2748.3266599683107;2748.917532474029;2755.793315501345;2593.714691383946;2700.2304343262176;2729.344957210275;2731.4810186064683;2733.180045986206;2272.2728726807427;2362.232274510782;2638.327226557235;2742.204760619418;2749.917502474929;2675.8260713053783;2688.233853351795;2714.9185524434297;2716.415970566328;2718.306831757889;2668.2662267876294;2674.1041751013436;2678.544647409945;2690.045034012917;2695.231858920198;13627.863721362857;13971.161730296215;14010;14010.509632162928;14020.088694234842;7626.8474630507635;7862.842743145162;7889.7633071007385;7895.328897043758;7906.604669766489;5085.796568137307;5142.588592912558;5262.342207224097;5318.867028324285;5328.067588172094;1799.5321216483678;1803.5130514761072;1820.144532069925;1859.1633764805892;1888.291890541047;24621.876890615433;24810;24891;25525.61711574328;25686;6371.840703982438;6459.418652321314;6630.303818099127;6664.266930637626;6700.497462690318;16176.514704558882;16223.594410139605;16229.188540572924;16327;16369.09969951664;7242.493025488166;7391.704331826667;7433.293837085468;7439.479236453396;7442.367398771165;6770.72917083321;6785.694643740945;6891;6896.655167241618;6968.44252459802;4957.380327459067;5036.798528058836;5103.566196873233;5120.897582048338;5149.073166830007;1438.216172186161;1444.2489905249422;1450.6010847016944;1470.3016067367914;1477.8743806776329;807.386386346381;816.4937738601986;832.0473058348158;843.1905370843936;850.2177996243471;6877.6217308047535;6878.965605171942;6914.515983881181;6949.7567585134275;6969.860602787916;686.7973947685445;700.3416788219058;704.7110684619385;704.8132244955091;716.3481232078888;4263.76549289792;4317;4373;4520.480144783326;4922.901541969212;20356.592868142554;21233.68149477759;21261.78738212629;21303.78696213018;21335.78664213338;19995.40013799559;20665.89667051655;20917.477063073544;20925.16299348009;20943;8720.694775682694;10607.628732994308;10911.727206819893;10939.12487001038;11032.889671103347;7285.456052570937;8036.758897233092;8128.796780080545;8186.836263274582;8513.74458766238;17831.840930339487;17832.286708531512;18160.364387246384;18520.629587407908;18566.628667426845;18152.546186345447;18247.361342352953;18685.439436816916;19138;19301.710474343177;9813.411195328303;10378.169746420117;10378.27352085377;10450.16398688088;10450.634227801991;12268.570600029314;12377.690557736129;12411.937940310241;12483.875161248272;12501;10256.333338332985;10282.897171028493;10493.737656558647;10790.89209107899;10814.35113893169;9733.270004749531;9749.853752193865;9802.36284641496;9978.451185184884;10185.23610729213;8393.83212335762;8578.613962371583;8782.473051616924;8863.512506812187;8908.242799361868;6156.507479401547;6190.566660333733;6199.28708198563;6199.752009919553;6276.278228003838;6241.21984751906;6280.434760871543;6311.495080393649;6388.0737293091315;6451.96768517035 09:03:21 INFO - PID 4488 | |4;prototype.html;491.5669179334837;493.2981214808909;508.54993330902187;523.7643060622719;527.0644605824662;6364.618122912622;6427.775027874025;6433.195850518685;6437.35626437356;6491;6069.180660610817;6203.379662033794;6233.758445608281;6324.335944725806;6346.904796428045;5050.545450909417;5064.366954130743;5079.009593129341;5110.948890511085;5143.3313669223135;4959.479254678261;4986.5761410280165;5002.524760147799;5028.647994640377;5044.117279476095;4856.17445034345;4941.431735350427;4949.480304568023;5011.824586139486;5025.271335656327;4984.90030199394;4995.950040499603;5025.070361983045;5032.572231360302;5066.721330326849;4429.357743127261;4495.213337665896;4527.977360113211;4531.52418996004;4534.659900507442;6176.4441200292185;6234.251889773211;6246.156768836195;6248;6258.15514905491;5626.549876009909;5661.179129026268;5720.342160651495;5760.654360738327;5831.533477321804;6696.229933557605;6703.1285932828905;6736.629485378302;6745.763898263586;6779.864402711967;4905.583025442842;4907.779149938226;4918.114739346916;4937.679050861719;4970.627202959792;6955.6869940852275;6994.91578805286;7055.788326350218;7059.152901651783;7115.217326094125;5005.5745261653165;5014.974925125351;5042.268871013755;5065.189569668835;5106.642535022587;5098.745062746848;5162.716050617252;5180.818671346485;5197.558207552401;5199.922001169985;5646.814169024551;5776.5089967352405;5809.50619197373;5817.156512305692;5820.214271073351;6622.03980422836;6663.400293973566;6690.022939791746;6779.084823548757;6785.762498312634;1746.3014794082214;1761.938332158394;1771.636814453034;1774.698301288784;1775.742517334979;1107.8227483602745;1109.4785450838094;1111.5109351885144;1121.0919155484085;1176.3823992404057;5803.970980145073;5842.970785146047;5873.442023007808;5881.411858814085;5908.14331921869;6770.5599136056;6777.949417840243;6800.469894273848;6810.353016463428;6842.4868134890085;5631.887362252732;5781.571739869265;5881.794137205263;5899.498542623839;5920.467157955719;3584.426491761306;3622.855085796539;3624.873129440457;3638.3632864248652;3645.0134676601315;6565.457117577365;6573.375529324707;6604.438622717027;6630.204375474877;6697.598144111272;7007.4394048476;7054.2945705430075;7115.011695088076;7161.889907064413;7175.784726458214;6667.233268174222;6672.432843208285;6685.933140668531;6709.5303328767495;6716.932830671631;6771.984202369487;6806.353396427331;6836.623985680835;6886.311368863074;6922.03091567191;6778.796891010343;6841.418479429304;6843.3840954314355;6865.9999999998;6880.449564034765;6505.869882602226;6599.340065993362;6630.635315057718;6645.737309911101;6675.332466753286;5004.3994720633755;5014.623903207201;5015.322931404214;5015.3730783652045;5044.672096313728;6813.965930170318;6883;6888.758893438906;6921.792346229621;6989.755358562629;6568.456412743097;6593.406593406617;6636.7013484392355;6676.56602320867;6690.163729533809;6547.312532184052;6583.210014798255;6604.867902641824;6673.366030227121;6680.231773345933;6486.837829054311;6609.438197753245;6632.369924857131;6642.701078451578;6653.9667301663185;6321.778737744157;6359.459445947146;6564.343565643589;6569.277379488157;6807.965960170168;6355.713992870239;6398.71205795731;6428.871422571428;6465.806025819233;6488.837779055561 09:03:21 INFO - PID 4488 | |5;yui.html;784.2353705137488;806.9952908628757;812.8780682897568;824.3364091906017;833.6998680475028;14403.855961440382;14693.77959330611;14808.703825923476;14825.851741482582;14825.851741482595;12011.339376334297;12050.337231452266;12841.422136003903;13384.263865487394;14008.579742607713;776.8679324514832;781.3553818100062;790.0519376747925;791.9406044546653;792.1009654042646;790.9248621380959;791.5923299500733;791.9524828510279;792.2908996448205;792.7066985215479;818.8485130250892;825.7440193540021;825.9628316725732;826.4710585225461;826.7065191856894;796.7530065679629;814.6944895664126;819.1685439279166;820.1306614988072;824.332290844415;578.5545130249726;580.8402689260445;581.2095550051926;582.7872826418369;583.3612194646855;14729.337179826933;15279.694406111816;15810;15814.76277855833;15873.603159920978;18615.720764188547;19119.522011949673;19356.903215483973;19450.416487505256;19520.609587808165;14632.780508292384;14807.629809254855;14986.700265994728;15008;15008.399664013426;3628.872989445383;3664.743467957244;3679.6504332088407;3682.907927301812;3694.2426802505693;122223.88700768164;123092.9999999991;126889.36555317257;126960.36519817435;127374.36312818469;14041.157530547995;14152.929235353964;14248.430062797579;14277.64305892361;14291.571252862223;24331.39171520726;25427.49145017089;25532.361690957503;25618;25629.743702562737;7507.286807753255;7565.962170189224;7616.3906887448875;7685.923140768633;7692.884606730903;3982.542007669097;4062.0048088218578;4078.53096893861;4094.5086589609436;4100.097978444737;4456.509783923766;4569.177548041384;4600.907981840344;4601.930971035437;4610.170169369546;848.5587494502824;861.9353548483889;935.9953200234091;973.3429934794127;992.1071036067452;633.4235845380681;650.5056157320471;665.6106177885991;670.9429698475684;670.969806358715;10658.614380130533;10879.945600272107;11011.174161937726;11600;11825.172237943261;607.480604083511;610.5634471353004;614.5237440983274;632.9525285603598;639.6961443314482;5071.898562028812;5151.6136289778415;5151.896962060813;5309.601779866525;5348.251244825728;107750;108746;112266;114130;115402;95955.04044959461;96925;98939.50530247303;100946;102017;66163.66918165475;88741.55629221942;89403;93605;93856;11573.884261157618;11697.181197316106;11748.58879939198;11813.704657383634;11898.524059037542;38992.61007390003;40328.39507407333;40838;42108;42493;51565;52273.47726522686;52335.214971774694;52686.736566316926;52868.73565632147;17683;18083;18216;18385;18703.719444208076;20186.495337616678;20702.275420360216;21069.683954740383;21194.47013824605;21552.137914483246;16022.198890055217;16199.919000405394;16256.837431626005;16298.837011629732;16862.66274674475;16624.251908663897;16679.416220432227;16763.49709508717;17048.31806727717;17236.741448878027;16077.67844643124;16080.276387562353;16254.349826007301;16358.263878125275;16364.754528682317;11039.779204416027;11141.554337826396;11341.716457088309;11443.084553235554;11536.711582210512;11998.880011199777;12293.07801914878;12793.936030319788;12874.098813083368;12898.67753306175 09:03:21 INFO - PID 4488 | __end_tp_report 09:03:21 INFO - PID 4488 | __start_cc_report 09:03:21 INFO - PID 4488 | _x_x_mozilla_cycle_collect,364 09:03:21 INFO - PID 4488 | __end_cc_report 09:03:21 INFO - PID 4488 | __startTimestamp1503936201490__endTimestamp 09:03:21 INFO - PID 4488 | 09:03:21 INFO - PID 4488 | ------- Summary: start ------- 09:03:21 INFO - PID 4488 | Number of tests: 6 09:03:21 INFO - PID 4488 | 09:03:21 INFO - PID 4488 | [#0] dojo.html Cycles:165 Average:6248.02 Median:6441.39 stddev:724.40 (11.2%) stddev-sans-first:688.51 09:03:21 INFO - PID 4488 | Values: 3292.1 3306.8 3316.5 3340.1 3369.7 6180.6 6241.8 6256.9 6297.3 6299.7 6211.5 6258.2 6417.6 6420.7 6441.0 5838.9 5858.6 5906.1 5907.5 5923.0 5651.2 5785.4 5873.7 5874.1 5913.3 5782.0 5814.3 5915.5 5916.6 5923.0 5744.5 5785.8 5857.3 5862.5 5878.4 5569.7 5711.4 5795.5 5797.5 5853.4 6211.2 6218.0 6306.7 6317.6 6349.2 6765.7 6853.5 6862.8 6863.0 6875.6 6857.1 6868.6 6925.3 6951.7 6966.0 6130.4 6271.9 6279.8 6358.7 6423.2 6958.3 6971.4 6984.8 6988.5 7128.9 6513.9 6629.4 6775.6 6780.9 6786.1 6732.0 6747.6 6760.5 6765.6 6768.8 6638.2 6659.4 6699.9 6708.3 6714.2 6683.0 6727.9 6764.9 6771.4 6771.5 5300.1 5356.6 5359.8 5362.8 5402.9 4693.1 4702.1 4734.6 4761.7 4770.0 5793.8 5900.3 5903.5 5917.5 6002.5 6788.9 6867.4 6869.2 6873.3 6882.7 5932.9 5935.6 5949.9 5965.9 5971.9 5837.8 5839.4 5930.4 5934.6 5952.5 6467.2 6612.1 6615.7 6689.1 6700.4 6783.5 6798.9 6867.2 6900.8 6902.4 6584.0 6647.4 6649.4 6671.0 6686.4 6647.7 6654.6 6778.4 6779.1 6801.1 6754.3 6800.0 6812.3 6906.1 6909.6 6441.4 6566.7 6576.1 6587.8 6603.6 6494.3 6501.9 6506.3 6512.8 6518.1 6376.1 6397.3 6433.0 6441.3 6457.2 6412.7 6425.3 6481.1 6695.3 6776.0 6334.9 6444.5 6450.1 6540.4 6559.2 09:03:21 INFO - PID 4488 | 09:03:21 INFO - PID 4488 | [#1] ext.html Cycles:180 Average:36424.06 Median:3318.93 stddev:57085.29 (1720.0%) stddev-sans-first:57182.58 09:03:21 INFO - PID 4488 | Values: 747.9 785.2 807.3 834.2 836.2 36693.0 37351.3 37364.1 37459.6 37566.2 34159.8 34300.8 34470.1 34539.5 34540.7 6716.9 6830.2 6831.9 6838.9 6878.4 6714.1 6818.5 6821.0 6843.8 6848.0 7249.1 7419.9 7486.3 7493.9 7503.3 7379.1 7453.3 7460.4 7462.6 7463.3 7220.1 7318.6 7399.6 7400.7 7404.5 34013.0 34244.7 34273.5 34286.8 34380.0 3255.3 3315.4 3322.4 3326.0 3329.6 28252.7 28421.3 28434.3 28646.0 28794.7 5045.9 5061.8 5081.9 5126.6 5203.5 216028.0 222964.0 225220.0 225391.0 225827.0 42981.4 43017.1 43182.6 43403.6 43645.6 44180.1 44609.8 44693.6 44789.0 44866.6 13836.1 13987.3 13988.2 14012.4 14017.1 11298.2 11326.9 11402.4 11421.4 11429.7 12522.9 12601.1 12786.4 12814.5 12826.9 5018.7 5096.2 5244.8 5245.4 5279.2 904.8 913.3 920.8 944.6 959.3 21620.4 22368.8 22396.3 26483.7 27664.2 999.1 1013.2 1022.8 1031.1 1034.3 11620.4 11648.0 11650.5 11772.5 11863.7 233629.0 236514.0 237810.0 238804.0 241293.0 38535.0 39452.6 45029.3 45164.5 45632.5 1298.3 1310.7 1321.0 1322.1 1326.5 25439.5 25512.6 25626.7 25691.5 25828.1 33306.8 33527.7 33592.8 33601.0 33649.7 195545.0 196317.0 196649.0 196777.0 197519.0 28675.1 29427.7 29538.7 29691.6 29731.0 35456.0 35906.3 36588.5 36789.8 36893.6 27655.3 27926.0 28004.2 28068.7 28577.0 27881.9 27911.9 28361.0 28412.9 28555.7 29813.3 30795.8 30944.0 31015.0 31278.8 27515.4 27710.7 27901.7 27908.9 28210.7 21497.2 21649.6 21748.2 21946.8 22000.8 09:03:21 INFO - PID 4488 | 09:03:21 INFO - PID 4488 | [#2] jquery.html Cycles:180 Average:34597.56 Median:22433.05 stddev:84953.73 (378.7%) stddev-sans-first:85153.55 09:03:21 INFO - PID 4488 | Values: 535.6 543.8 548.3 549.7 562.4 21199.0 21330.7 21951.8 22009.1 22010.0 21412.5 21987.5 22139.1 22229.2 22273.8 6373.3 6523.0 6524.7 6524.7 6531.2 6171.3 6447.3 6448.8 6487.9 6495.7 6369.1 6369.2 6416.5 6419.9 6425.3 6354.3 6389.2 6390.6 6394.0 6396.7 5883.6 5887.8 5899.4 5955.3 5959.9 27270.6 27368.6 27796.7 28031.0 28546.9 2054.6 2105.8 2107.8 2113.6 2114.0 18200.2 18725.5 18819.4 18843.0 18868.2 2381.7 2418.6 2443.8 2478.8 2481.7 133165.0 136066.3 136154.0 136368.3 137047.0 13654.9 14197.8 14273.2 14374.6 15005.5 22204.9 22223.2 22329.7 22379.2 22500.2 8820.7 9045.0 9109.6 9149.3 9234.2 8050.6 8094.4 8170.1 8213.1 8259.1 7386.1 8260.4 8464.7 8621.9 8760.0 2434.2 2476.7 2478.2 2604.9 2699.1 899.8 907.9 924.0 931.8 942.6 18982.9 18993.5 18997.1 19083.5 19193.1 972.6 982.0 995.2 998.3 1002.6 10297.9 10589.5 10696.1 10812.5 10820.8 480714.0 483606.0 520411.0 521118.0 521848.0 108499.0 108774.0 110660.4 114340.0 115779.4 1089.4 1104.4 1131.2 1133.3 1133.8 16890.4 16969.0 17324.4 17389.7 17535.0 52249.7 60125.0 60153.7 63186.0 63513.7 68936.0 69560.7 69626.7 70249.0 71145.6 14019.2 14367.3 14452.0 14456.3 14511.5 22109.9 22486.9 22565.5 22670.0 22678.2 17988.0 18171.7 18270.4 18270.5 18551.4 15920.1 16128.6 16524.9 16615.4 16988.3 18501.5 18585.4 18596.0 18709.3 18779.4 13543.3 13800.0 13912.7 14009.8 14097.3 16881.7 16893.2 16978.8 17364.3 18173.2 09:03:21 INFO - PID 4488 | 09:03:21 INFO - PID 4488 | [#3] mootools.html Cycles:180 Average:8770.84 Median:2697.73 stddev:6401.67 (237.3%) stddev-sans-first:6394.54 09:03:21 INFO - PID 4488 | Values: 1227.7 1272.8 1284.1 1287.0 1298.9 13950.3 14625.5 14630.6 14641.9 14735.7 13874.2 14722.7 14731.3 14827.9 14842.0 2687.5 2746.1 2748.3 2748.9 2755.8 2593.7 2700.2 2729.3 2731.5 2733.2 2272.3 2362.2 2638.3 2742.2 2749.9 2675.8 2688.2 2714.9 2716.4 2718.3 2668.3 2674.1 2678.5 2690.0 2695.2 13627.9 13971.2 14010.0 14010.5 14020.1 7626.8 7862.8 7889.8 7895.3 7906.6 5085.8 5142.6 5262.3 5318.9 5328.1 1799.5 1803.5 1820.1 1859.2 1888.3 24621.9 24810.0 24891.0 25525.6 25686.0 6371.8 6459.4 6630.3 6664.3 6700.5 16176.5 16223.6 16229.2 16327.0 16369.1 7242.5 7391.7 7433.3 7439.5 7442.4 6770.7 6785.7 6891.0 6896.7 6968.4 4957.4 5036.8 5103.6 5120.9 5149.1 1438.2 1444.2 1450.6 1470.3 1477.9 807.4 816.5 832.0 843.2 850.2 6877.6 6879.0 6914.5 6949.8 6969.9 686.8 700.3 704.7 704.8 716.3 4263.8 4317.0 4373.0 4520.5 4922.9 20356.6 21233.7 21261.8 21303.8 21335.8 19995.4 20665.9 20917.5 20925.2 20943.0 8720.7 10607.6 10911.7 10939.1 11032.9 7285.5 8036.8 8128.8 8186.8 8513.7 17831.8 17832.3 18160.4 18520.6 18566.6 18152.5 18247.4 18685.4 19138.0 19301.7 9813.4 10378.2 10378.3 10450.2 10450.6 12268.6 12377.7 12411.9 12483.9 12501.0 10256.3 10282.9 10493.7 10790.9 10814.4 9733.3 9749.9 9802.4 9978.5 10185.2 8393.8 8578.6 8782.5 8863.5 8908.2 6156.5 6190.6 6199.3 6199.8 6276.3 6241.2 6280.4 6311.5 6388.1 6452.0 09:03:21 INFO - PID 4488 | 09:03:21 INFO - PID 4488 | [#4] prototype.html Cycles:180 Average:5598.02 Median:6247.08 stddev:1595.35 (25.5%) stddev-sans-first:1553.11 09:03:21 INFO - PID 4488 | Values: 491.6 493.3 508.5 523.8 527.1 6364.6 6427.8 6433.2 6437.4 6491.0 6069.2 6203.4 6233.8 6324.3 6346.9 5050.5 5064.4 5079.0 5110.9 5143.3 4959.5 4986.6 5002.5 5028.6 5044.1 4856.2 4941.4 4949.5 5011.8 5025.3 4984.9 4996.0 5025.1 5032.6 5066.7 4429.4 4495.2 4528.0 4531.5 4534.7 6176.4 6234.3 6246.2 6248.0 6258.2 5626.5 5661.2 5720.3 5760.7 5831.5 6696.2 6703.1 6736.6 6745.8 6779.9 4905.6 4907.8 4918.1 4937.7 4970.6 6955.7 6994.9 7055.8 7059.2 7115.2 5005.6 5015.0 5042.3 5065.2 5106.6 5098.7 5162.7 5180.8 5197.6 5199.9 5646.8 5776.5 5809.5 5817.2 5820.2 6622.0 6663.4 6690.0 6779.1 6785.8 1746.3 1761.9 1771.6 1774.7 1775.7 1107.8 1109.5 1111.5 1121.1 1176.4 5804.0 5843.0 5873.4 5881.4 5908.1 6770.6 6777.9 6800.5 6810.4 6842.5 5631.9 5781.6 5881.8 5899.5 5920.5 3584.4 3622.9 3624.9 3638.4 3645.0 6565.5 6573.4 6604.4 6630.2 6697.6 7007.4 7054.3 7115.0 7161.9 7175.8 6667.2 6672.4 6685.9 6709.5 6716.9 6772.0 6806.4 6836.6 6886.3 6922.0 6778.8 6841.4 6843.4 6866.0 6880.4 6505.9 6599.3 6630.6 6645.7 6675.3 5004.4 5014.6 5015.3 5015.4 5044.7 6814.0 6883.0 6888.8 6921.8 6989.8 6568.5 6593.4 6636.7 6676.6 6690.2 6547.3 6583.2 6604.9 6673.4 6680.2 6486.8 6609.4 6632.4 6642.7 6654.0 6321.8 6359.5 6564.3 6569.3 6808.0 6355.7 6398.7 6428.9 6465.8 6488.8 09:03:21 INFO - PID 4488 | 09:03:21 INFO - PID 4488 | [#5] yui.html Cycles:180 Average:22210.80 Median:25623.87 stddev:31946.22 (124.7%) stddev-sans-first:31995.33 09:03:21 INFO - PID 4488 | Values: 784.2 807.0 812.9 824.3 833.7 14403.9 14693.8 14808.7 14825.9 14825.9 12011.3 12050.3 12841.4 13384.3 14008.6 776.9 781.4 790.1 791.9 792.1 790.9 791.6 792.0 792.3 792.7 818.8 825.7 826.0 826.5 826.7 796.8 814.7 819.2 820.1 824.3 578.6 580.8 581.2 582.8 583.4 14729.3 15279.7 15810.0 15814.8 15873.6 18615.7 19119.5 19356.9 19450.4 19520.6 14632.8 14807.6 14986.7 15008.0 15008.4 3628.9 3664.7 3679.7 3682.9 3694.2 122223.9 123093.0 126889.4 126960.4 127374.4 14041.2 14152.9 14248.4 14277.6 14291.6 24331.4 25427.5 25532.4 25618.0 25629.7 7507.3 7566.0 7616.4 7685.9 7692.9 3982.5 4062.0 4078.5 4094.5 4100.1 4456.5 4569.2 4600.9 4601.9 4610.2 848.6 861.9 936.0 973.3 992.1 633.4 650.5 665.6 670.9 671.0 10658.6 10879.9 11011.2 11600.0 11825.2 607.5 610.6 614.5 633.0 639.7 5071.9 5151.6 5151.9 5309.6 5348.3 107750.0 108746.0 112266.0 114130.0 115402.0 95955.0 96925.0 98939.5 100946.0 102017.0 66163.7 88741.6 89403.0 93605.0 93856.0 11573.9 11697.2 11748.6 11813.7 11898.5 38992.6 40328.4 40838.0 42108.0 42493.0 51565.0 52273.5 52335.2 52686.7 52868.7 17683.0 18083.0 18216.0 18385.0 18703.7 20186.5 20702.3 21069.7 21194.5 21552.1 16022.2 16199.9 16256.8 16298.8 16862.7 16624.3 16679.4 16763.5 17048.3 17236.7 16077.7 16080.3 16254.3 16358.3 16364.8 11039.8 11141.6 11341.7 11443.1 11536.7 11998.9 12293.1 12793.9 12874.1 12898.7 09:03:21 INFO - PID 4488 | -------- Summary: end -------- 09:03:21 INFO - PID 4488 | 09:03:22 INFO - TEST-INFO | 4488: exit 0 09:03:22 INFO - TEST-OK | dromaeo_css | took 1115662ms 09:03:22 INFO - TEST-START | kraken 09:03:22 INFO - Initialising browser for kraken test... 09:03:22 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:44789/getInfo.html -profile /tmp/tmpDPiWDS/profile 09:03:22 INFO - TEST-INFO | started process 4866 (/builds/slave/test/build/application/firefox/firefox http://localhost:44789/getInfo.html) 09:03:28 INFO - TEST-INFO | 4866: exit 0 09:03:28 INFO - Browser initialized. 09:03:28 INFO - Running cycle 1/1 for kraken test... 09:03:28 INFO - Using env: {'DISPLAY': ':0', 09:03:28 INFO - 'HOME': '/home/cltbld', 09:03:28 INFO - 'JSGC_DISABLE_POISONING': '1', 09:03:28 INFO - 'LANG': 'en_US.UTF-8', 09:03:28 INFO - 'LANGUAGE': 'en_US:en', 09:03:28 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 09:03:28 INFO - 'LOGNAME': 'cltbld', 09:03:28 INFO - 'MAIL': '/var/mail/cltbld', 09:03:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:03:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:03:28 INFO - 'MOZ_ACCELERATED': '1', 09:03:28 INFO - 'MOZ_CRASHREPORTER': '1', 09:03:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:28 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 09:03:28 INFO - 'MOZ_NO_REMOTE': '1', 09:03:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:03:28 INFO - 'MOZ_WEBRENDER': '1', 09:03:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:03:28 INFO - 'NO_EM_RESTART': '1', 09:03:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:03:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:28 INFO - 'PWD': '/builds/slave/test', 09:03:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:03:28 INFO - 'RUST_BACKTRACE': 'full', 09:03:28 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 09:03:28 INFO - 'SHELL': '/bin/bash', 09:03:28 INFO - 'SHLVL': '1', 09:03:28 INFO - 'TERM': 'linux', 09:03:28 INFO - 'TMOUT': '86400', 09:03:28 INFO - 'USER': 'cltbld', 09:03:28 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1503935013.520396-1280810185', 09:03:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:28 INFO - '_': '/tools/buildbot/bin/python'} 09:03:28 INFO - TEST-INFO | started process 5050 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDPiWDS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 09:03:29 INFO - PID 5050 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 09:03:29 INFO - PID 5050 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 09:03:29 INFO - PID 5050 | 09:03:29 INFO - PID 5050 | (/builds/slave/test/build/application/firefox/firefox:5110): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 09:03:29 INFO - PID 5050 | 09:03:40 INFO - PID 5050 | RSS: Main: 202792960 09:03:40 INFO - PID 5050 | 09:03:43 INFO - PID 5050 | Cycle 1(1): loaded http://localhost:44789/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44789/tests/kraken/driver.html?testName=audio-beat-detection) 09:03:43 INFO - PID 5050 | RSS: Main: 208490496 09:03:43 INFO - PID 5050 | 09:03:46 INFO - PID 5050 | Cycle 1(1): loaded http://localhost:44789/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44789/tests/kraken/driver.html?testName=audio-dft) 09:03:47 INFO - PID 5050 | RSS: Main: 209035264 09:03:47 INFO - PID 5050 | 09:03:50 INFO - PID 5050 | Cycle 1(1): loaded http://localhost:44789/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44789/tests/kraken/driver.html?testName=audio-fft) 09:03:50 INFO - PID 5050 | RSS: Main: 203427840 09:03:50 INFO - PID 5050 | 09:03:53 INFO - PID 5050 | Cycle 1(1): loaded http://localhost:44789/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44789/tests/kraken/driver.html?testName=audio-oscillator) 09:03:53 INFO - PID 5050 | RSS: Main: 203943936 09:03:53 INFO - PID 5050 | 09:03:56 INFO - PID 5050 | Cycle 1(1): loaded http://localhost:44789/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44789/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:03:56 INFO - PID 5050 | RSS: Main: 202604544 09:03:56 INFO - PID 5050 | 09:03:59 INFO - PID 5050 | Cycle 1(1): loaded http://localhost:44789/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44789/tests/kraken/driver.html?testName=imaging-darkroom) 09:04:00 INFO - PID 5050 | RSS: Main: 207421440 09:04:00 INFO - PID 5050 | 09:04:03 INFO - PID 5050 | Cycle 1(1): loaded http://localhost:44789/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44789/tests/kraken/driver.html?testName=imaging-desaturate) 09:04:04 INFO - PID 5050 | RSS: Main: 206544896 09:04:04 INFO - PID 5050 | 09:04:07 INFO - PID 5050 | Cycle 1(1): loaded http://localhost:44789/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44789/tests/kraken/driver.html?testName=json-parse-financial) 09:04:08 INFO - PID 5050 | RSS: Main: 207511552 09:04:08 INFO - PID 5050 | 09:04:09 INFO - PID 5050 | Cycle 1(1): loaded http://localhost:44789/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44789/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:04:10 INFO - PID 5050 | RSS: Main: 208203776 09:04:10 INFO - PID 5050 | 09:04:12 INFO - PID 5050 | Cycle 1(1): loaded http://localhost:44789/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44789/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:04:12 INFO - PID 5050 | RSS: Main: 207953920 09:04:12 INFO - PID 5050 | 09:04:14 INFO - PID 5050 | Cycle 1(1): loaded http://localhost:44789/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44789/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:04:15 INFO - PID 5050 | RSS: Main: 207216640 09:04:15 INFO - PID 5050 | 09:04:18 INFO - PID 5050 | Cycle 1(1): loaded http://localhost:44789/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44789/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:04:18 INFO - PID 5050 | RSS: Main: 208031744 09:04:18 INFO - PID 5050 | 09:04:21 INFO - PID 5050 | Cycle 1(1): loaded http://localhost:44789/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44789/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:04:22 INFO - PID 5050 | RSS: Main: 208080896 09:04:22 INFO - PID 5050 | 09:04:24 INFO - PID 5050 | Cycle 1(1): loaded http://localhost:44789/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44789/tests/kraken/driver.html?testName=ai-astar) 09:04:24 INFO - PID 5050 | RSS: Main: 210870272 09:04:24 INFO - PID 5050 | 09:04:24 INFO - PID 5050 | __start_tp_report 09:04:24 INFO - PID 5050 | _x_x_mozilla_page_load 09:04:24 INFO - PID 5050 | _x_x_mozilla_page_load_details 09:04:24 INFO - PID 5050 | |i|pagename|runs| 09:04:24 INFO - PID 5050 | |0;ai-astar;102;109;102;111;117;112;116;99;120;114 09:04:24 INFO - PID 5050 | |1;audio-beat-detection;150;124;149;173;120;143;148;121;167;122 09:04:24 INFO - PID 5050 | |2;audio-dft;172;171;162;168;164;168;165;164;162;164 09:04:24 INFO - PID 5050 | |3;audio-fft;76;80;90;168;86;76;73;73;171;76 09:04:24 INFO - PID 5050 | |4;audio-oscillator;82;90;136;81;80;83;133;82;79;131 09:04:24 INFO - PID 5050 | |5;imaging-gaussian-blur;116;115;116;117;114;126;115;117;114;122 09:04:24 INFO - PID 5050 | |6;imaging-darkroom;156;154;149;149;149;148;149;149;148;148 09:04:24 INFO - PID 5050 | |7;imaging-desaturate;104;94;96;94;99;102;94;94;95;96 09:04:24 INFO - PID 5050 | |8;json-parse-financial;56;57;52;53;53;53;65;56;58;52 09:04:24 INFO - PID 5050 | |9;json-stringify-tinderbox;43;43;39;39;39;47;48;40;39;41 09:04:24 INFO - PID 5050 | |10;stanford-crypto-aes;76;76;77;83;81;76;80;80;75;76 09:04:24 INFO - PID 5050 | |11;stanford-crypto-ccm;120;126;119;125;126;138;131;128;133;165 09:04:24 INFO - PID 5050 | |12;stanford-crypto-pbkdf2;179;170;170;170;168;172;180;174;175;172 09:04:24 INFO - PID 5050 | |13;stanford-crypto-sha256-iterative;65;66;63;64;63;65;65;65;70;62 09:04:24 INFO - PID 5050 | __end_tp_report 09:04:24 INFO - PID 5050 | __start_cc_report 09:04:24 INFO - PID 5050 | _x_x_mozilla_cycle_collect,942 09:04:24 INFO - PID 5050 | __end_cc_report 09:04:24 INFO - PID 5050 | __startTimestamp1503936264587__endTimestamp 09:04:24 INFO - PID 5050 | 09:04:24 INFO - PID 5050 | ------- Summary: start ------- 09:04:24 INFO - PID 5050 | Number of tests: 14 09:04:24 INFO - PID 5050 | 09:04:24 INFO - PID 5050 | [#0] ai-astar Cycles:10 Average:110.20 Median:115.00 stddev:7.11 (6.2%) stddev-sans-first:6.90 09:04:24 INFO - PID 5050 | Values: 102.0 109.0 102.0 111.0 117.0 112.0 116.0 99.0 120.0 114.0 09:04:24 INFO - PID 5050 | 09:04:24 INFO - PID 5050 | [#1] audio-beat-detection Cycles:10 Average:141.70 Median:148.50 stddev:19.39 (13.1%) stddev-sans-first:20.33 09:04:24 INFO - PID 5050 | Values: 150.0 124.0 149.0 173.0 120.0 143.0 148.0 121.0 167.0 122.0 09:04:24 INFO - PID 5050 | 09:04:24 INFO - PID 5050 | [#2] audio-dft Cycles:10 Average:166.00 Median:166.50 stddev:3.56 (2.1%) stddev-sans-first:3.04 09:04:24 INFO - PID 5050 | Values: 172.0 171.0 162.0 168.0 164.0 168.0 165.0 164.0 162.0 164.0 09:04:24 INFO - PID 5050 | 09:04:24 INFO - PID 5050 | [#3] audio-fft Cycles:10 Average:96.90 Median:76.00 stddev:38.66 (50.9%) stddev-sans-first:40.26 09:04:24 INFO - PID 5050 | Values: 76.0 80.0 90.0 168.0 86.0 76.0 73.0 73.0 171.0 76.0 09:04:24 INFO - PID 5050 | 09:04:24 INFO - PID 5050 | [#4] audio-oscillator Cycles:10 Average:97.70 Median:81.50 stddev:24.79 (30.4%) stddev-sans-first:25.64 09:04:24 INFO - PID 5050 | Values: 82.0 90.0 136.0 81.0 80.0 83.0 133.0 82.0 79.0 131.0 09:04:24 INFO - PID 5050 | 09:04:24 INFO - PID 5050 | [#5] imaging-gaussian-blur Cycles:10 Average:117.20 Median:116.50 stddev:3.85 (3.3%) stddev-sans-first:4.06 09:04:24 INFO - PID 5050 | Values: 116.0 115.0 116.0 117.0 114.0 126.0 115.0 117.0 114.0 122.0 09:04:24 INFO - PID 5050 | 09:04:24 INFO - PID 5050 | [#6] imaging-darkroom Cycles:10 Average:149.90 Median:149.00 stddev:2.77 (1.9%) stddev-sans-first:1.86 09:04:24 INFO - PID 5050 | Values: 156.0 154.0 149.0 149.0 149.0 148.0 149.0 149.0 148.0 148.0 09:04:24 INFO - PID 5050 | 09:04:24 INFO - PID 5050 | [#7] imaging-desaturate Cycles:10 Average:96.80 Median:94.50 stddev:3.65 (3.9%) stddev-sans-first:2.78 09:04:24 INFO - PID 5050 | Values: 104.0 94.0 96.0 94.0 99.0 102.0 94.0 94.0 95.0 96.0 09:04:24 INFO - PID 5050 | 09:04:24 INFO - PID 5050 | [#8] json-parse-financial Cycles:10 Average:55.50 Median:56.00 stddev:3.98 (7.1%) stddev-sans-first:4.22 09:04:24 INFO - PID 5050 | Values: 56.0 57.0 52.0 53.0 53.0 53.0 65.0 56.0 58.0 52.0 09:04:24 INFO - PID 5050 | 09:04:24 INFO - PID 5050 | [#9] json-stringify-tinderbox Cycles:10 Average:41.80 Median:42.00 stddev:3.39 (8.1%) stddev-sans-first:3.57 09:04:24 INFO - PID 5050 | Values: 43.0 43.0 39.0 39.0 39.0 47.0 48.0 40.0 39.0 41.0 09:04:24 INFO - PID 5050 | 09:04:24 INFO - PID 5050 | [#10] stanford-crypto-aes Cycles:10 Average:78.00 Median:78.50 stddev:2.75 (3.5%) stddev-sans-first:2.82 09:04:24 INFO - PID 5050 | Values: 76.0 76.0 77.0 83.0 81.0 76.0 80.0 80.0 75.0 76.0 09:04:24 INFO - PID 5050 | 09:04:24 INFO - PID 5050 | [#11] stanford-crypto-ccm Cycles:10 Average:131.10 Median:129.50 stddev:13.20 (10.2%) stddev-sans-first:13.38 09:04:24 INFO - PID 5050 | Values: 120.0 126.0 119.0 125.0 126.0 138.0 131.0 128.0 133.0 165.0 09:04:24 INFO - PID 5050 | 09:04:24 INFO - PID 5050 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.00 Median:173.00 stddev:4.00 (2.3%) stddev-sans-first:3.61 09:04:24 INFO - PID 5050 | Values: 179.0 170.0 170.0 170.0 168.0 172.0 180.0 174.0 175.0 172.0 09:04:24 INFO - PID 5050 | 09:04:24 INFO - PID 5050 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.80 Median:65.00 stddev:2.20 (3.4%) stddev-sans-first:2.33 09:04:24 INFO - PID 5050 | Values: 65.0 66.0 63.0 64.0 63.0 65.0 65.0 65.0 70.0 62.0 09:04:24 INFO - PID 5050 | -------- Summary: end -------- 09:04:24 INFO - PID 5050 | 09:04:25 INFO - PID 5050 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 09:04:25 INFO - TEST-INFO | 5050: exit 0 09:04:25 INFO - TEST-OK | kraken | took 63133ms 09:04:25 INFO - SUITE-END | took 1178s 09:04:25 INFO - Completed test suite (00:19:39) 09:04:25 INFO - javascript benchmark 09:04:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8142.249897679758, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3292.0617623977173, 3306.7850589711666, 3316.5356850040985, 3340.1148695595666, 3369.730421566275, 6180.567360284782, 6241.843953901155, 6256.937430625692, 6297.307296197415, 6299.716512756925, 6211.534134939875, 6258.186435763344, 6417.647029413381, 6420.678966051702, 6441.0, 5838.941610583904, 5858.605013249232, 5906.114082887569, 5907.497862681677, 5923.022701254288, 5651.1523271509395, 5785.3925337839555, 5873.7356818943035, 5874.148248503964, 5913.2608423947, 5782.017057100282, 5814.273215848019, 5915.467607915287, 5916.615419997686, 5923.0, 5744.540436765049, 5785.82642520725, 5857.297124345106, 5862.530997520188, 5878.382769809194, 5569.693666848321, 5711.428857114297, 5795.507381872546, 5797.507211886995, 5853.356130825606, 6211.223597050369, 6217.968910155466, 6306.747730090791, 6317.6209427434505, 6349.238091429012, 6765.729370825161, 6853.485988550879, 6862.794116176523, 6863.039174515572, 6875.621840798754, 6857.108575885103, 6868.553544019623, 6925.342092501204, 6951.65241737911, 6965.965170174168, 6130.386961303879, 6271.937280627227, 6279.843003924939, 6358.745650173987, 6423.197100362455, 6958.338957799001, 6971.407430368374, 6984.79045628632, 6988.510804243654, 7128.92871071293, 6513.934860651333, 6629.403353698191, 6775.627340496221, 6780.932190678129, 6786.117804685458, 6732.0, 6747.595144291358, 6760.492963027793, 6765.560238584476, 6768.830779230559, 6638.236602790645, 6659.367360100783, 6699.899501507482, 6708.262091169968, 6714.194296684332, 6683.030960510796, 6727.899081513781, 6764.864702705919, 6771.356721111487, 6771.52599318043, 5300.1254792958725, 5356.625036247503, 5359.839204823861, 5362.758675859595, 5402.91895621566, 4693.084848454519, 4702.106599746037, 4734.597559207437, 4761.738104404221, 4770.045990801854, 5793.797217097382, 5900.291964964232, 5903.498202652822, 5917.467427931507, 6002.459778619857, 6788.898166527407, 6867.416269617039, 6869.244383117853, 6873.312668733087, 6882.655867206619, 5932.881342373128, 5935.643861368331, 5949.851253718605, 5965.880682386415, 5971.880562388728, 5837.7956771513645, 5839.416058394127, 5930.43660852227, 5934.643921364732, 5952.494038006731, 6467.191601049869, 6612.107365505692, 6615.702293396808, 6689.096971908827, 6700.363465470772, 6783.4573234142135, 6798.864022719419, 6867.2102708189195, 6900.758473453405, 6902.413294869993, 6584.045313429621, 6647.368499992493, 6649.434798042027, 6670.966645166937, 6686.364795344434, 6647.700853461509, 6654.600723956578, 6778.389944904982, 6779.118714567272, 6801.1158549388265, 6754.290799465985, 6800.047993281044, 6812.250652428328, 6906.136732908387, 6909.550879193038, 6441.3880681335195, 6566.737330506918, 6576.112224849584, 6587.835304117435, 6603.636799976048, 6494.318096599789, 6501.8699626008165, 6506.284308726131, 6512.804615861532, 6518.087467754613, 6376.075469057054, 6397.328280530477, 6433.002884552901, 6441.291457939529, 6457.225132984072, 6412.743490260337, 6425.325340839145, 6481.092647029325, 6695.263521012782, 6776.0, 6334.8733025338315, 6444.45222156122, 6450.129232553545, 6540.411362977355, 6559.212894452506], "value": 6195.821070372606, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [747.9027726395568, 785.1873311122989, 807.2653884964682, 834.1950018232409, 836.2473773603758, 36693.0, 37351.25297494052, 37364.06589835256, 37459.62540374595, 37566.24867502648, 34159.82920085396, 34300.828495857546, 34470.13824654385, 34539.481907771406, 34540.654593454055, 6716.899246511294, 6830.248672646005, 6831.897521537181, 6838.931610683904, 6878.449724022092, 6714.127163468766, 6818.4886133540185, 6821.045053692487, 6843.828904277383, 6848.041274221613, 7249.093863267091, 7419.924111003901, 7486.251374862524, 7493.850122997536, 7503.287187717158, 7379.077615298088, 7453.329200371993, 7460.403167746567, 7462.552246865205, 7463.328300452931, 7220.097487814023, 7318.634068296564, 7399.593022383767, 7400.740974065882, 7404.4816862819625, 34013.0, 34244.6575534244, 34273.48589771155, 34286.828565857264, 34380.0, 3255.3489302139665, 3315.436375816117, 3322.4185767490835, 3326.0, 3329.5671562696934, 28252.717472825214, 28421.289467763265, 28434.289142771595, 28645.99739009125, 28794.71205287941, 5045.873853153664, 5061.759868832123, 5081.872953176163, 5126.564242039394, 5203.4796520348045, 216028.0, 222964.0, 225220.0, 225390.99999999837, 225827.0, 42981.35527967083, 43017.13965720668, 43182.56817431786, 43403.56596434058, 43645.563544364784, 44180.11639767187, 44609.77695111569, 44693.55306446894, 44789.0, 44866.55133448689, 13836.100653457494, 13987.300634968211, 13988.230647314293, 14012.439502419993, 14017.088889222168, 11298.152638552141, 11326.886731132583, 11402.429878506042, 11421.37182454973, 11429.714257143638, 12522.937385313015, 12601.117921745574, 12786.424610892533, 12814.46226452813, 12826.935865320615, 5018.698878067255, 5096.21008743638, 5244.790208391698, 5245.449227831099, 5279.208118782137, 904.8009437923646, 913.3104506097853, 920.8204400142042, 944.631593678466, 959.2901253072815, 21620.35138945834, 22368.776312236994, 22396.328110156723, 26483.73516264851, 27664.170074897786, 999.1457304005116, 1013.1793247469574, 1022.7903279827617, 1031.138998935897, 1034.3018462537907, 11620.360880151504, 11648.0, 11650.53397864076, 11772.52909883595, 11863.703407414883, 233629.0, 236514.0, 237810.0, 238804.0, 241293.0, 38535.0, 39452.60547394547, 45029.32456013162, 45164.54835451603, 45632.54367456283, 1298.2989185839729, 1310.698539335958, 1320.966975825612, 1322.147215046309, 1326.5091915990952, 25439.491210176064, 25512.617310740727, 25626.743732562434, 25691.486170276865, 25828.096016639327, 33306.83346583252, 33527.664723352456, 33592.83203583967, 33601.0, 33649.66350336465, 195545.0, 196317.0, 196649.0, 196777.0, 197519.0, 28675.13974580766, 29427.705722942497, 29538.704612953596, 29691.55462668105, 29731.0, 35456.0, 35906.28187436289, 36588.45117323189, 36789.81605091957, 36893.63106368902, 27655.30861728473, 27926.0, 28004.159875203775, 28068.71931280661, 28577.0, 27881.860590696917, 27911.860440697666, 28361.0, 28412.857935710188, 28555.714442855304, 29813.25466863259, 30795.846020769754, 30944.0, 31015.0, 31278.843605781825, 27515.44969100647, 27710.722892770813, 27901.720982789913, 27908.86045569759, 28210.71789282081, 21497.247596334055, 21649.567008660055, 21748.238811641517, 21946.780532194472, 22000.779992199874], "value": 15787.725267211465, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [535.5822458482382, 543.7943324104405, 548.3173449055926, 549.6592112890008, 562.3898070593406, 21199.0, 21330.680039799397, 21951.780482195172, 22009.119635214593, 22010.0, 21412.46468838276, 21987.450313742163, 22139.114435422605, 22229.2219772308, 22273.777262227373, 6373.298937116925, 6523.021546767975, 6524.673766311689, 6524.739010439601, 6531.216254049521, 6171.321154672983, 6447.2907980122145, 6448.806535803933, 6487.902681459782, 6495.707693153796, 6369.07648390983, 6369.20384951881, 6416.45460135889, 6419.871602567946, 6425.261094974067, 6354.332795056499, 6389.1694079769795, 6390.616563006211, 6394.040893865911, 6396.71214795333, 5883.617564858313, 5887.8233652990475, 5899.351071382144, 5955.315138759055, 5959.851003724898, 27270.5909411359, 27368.58947115795, 27796.722032779617, 28031.0, 28546.857265713537, 2054.599353126141, 2105.7578378486364, 2107.7786832382685, 2113.608982338272, 2114.0381126587517, 18200.1809918554, 18725.53186170343, 18819.435416937515, 18843.0, 18868.15093320804, 2381.6903802505735, 2418.6128457608124, 2443.8167137464584, 2478.8016958643266, 2481.689788776403, 133164.99999999904, 136066.31966840103, 136154.0, 136368.31815840857, 137047.0, 13654.93172534131, 14197.78703319451, 14273.214973176575, 14374.568762937128, 15005.549833505012, 22204.888975555343, 22223.22218722337, 22329.665055024187, 22379.216727414463, 22500.21249256268, 8820.735377938672, 9045.0, 9109.590068446936, 9149.313801464918, 9234.215091717278, 8050.637721302555, 8094.352451803841, 8170.101288858221, 8213.055498617736, 8259.132791056853, 7386.0767404074495, 8260.421770476009, 8464.746057618282, 8621.913780862236, 8759.956200218958, 2434.160214725917, 2476.715177754545, 2478.1821998740375, 2604.882780274892, 2699.0688212567024, 899.8245342158348, 907.8683590879285, 923.986140207884, 931.8043210925781, 942.6323733743845, 18982.905085474486, 18993.525161871064, 18997.145128469252, 19083.522911927033, 19193.136308866135, 972.6304004478253, 981.9901800982042, 995.1640621877658, 998.3460833154296, 1002.6190047781796, 10297.897021029843, 10589.470526473646, 10696.090832279187, 10812.513436895359, 10820.837687434694, 480714.0, 483606.0, 520411.0, 521118.0, 521848.0, 108499.0, 108774.0, 110660.4466977676, 114340.0, 115779.42110289395, 1089.3518356577824, 1104.4422566603814, 1131.2409203688344, 1133.2520536445907, 1133.7675776465805, 16890.40883569069, 16969.0, 17324.393646222827, 17389.739153912447, 17535.0, 52249.738751306, 60125.0, 60153.69923150356, 63186.0, 63513.68243158755, 68936.0, 69560.6521967387, 69626.65186674034, 70249.0, 71145.6442717783, 14019.228942408265, 14367.28163591837, 14451.988360814643, 14456.349464273922, 14511.492097776949, 22109.889450553288, 22486.88756556207, 22565.548689026455, 22670.0, 22678.206262780725, 17988.0, 18171.727424088385, 18270.360537381126, 18270.543236418664, 18551.35070272534, 15920.124393158487, 16128.596785080466, 16524.917375413046, 16615.41846035383, 16988.320467181173, 18501.53746156303, 18585.44243672692, 18596.0, 18709.25162993465, 18779.436616901516, 13543.322833858465, 13800.033997620472, 13912.652183695489, 14009.789853152008, 14097.295135242992, 16881.66236675284, 16893.155342233084, 16978.830211697725, 17364.305427782747, 18173.182206800462], "value": 11539.500796219914, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1227.7298994221273, 1272.8154417609453, 1284.0562186792706, 1287.0475847872576, 1298.902582306327, 13950.302484875767, 14625.488107916224, 14630.634234144152, 14641.853581464182, 14735.705285894275, 13874.236916969563, 14722.705545889075, 14731.263436828169, 14827.925860370684, 14842.0, 2687.543117669996, 2746.134967485246, 2748.3266599683107, 2748.917532474029, 2755.793315501345, 2593.714691383946, 2700.2304343262176, 2729.344957210275, 2731.4810186064683, 2733.180045986206, 2272.2728726807427, 2362.232274510782, 2638.327226557235, 2742.204760619418, 2749.917502474929, 2675.8260713053783, 2688.233853351795, 2714.9185524434297, 2716.415970566328, 2718.306831757889, 2668.2662267876294, 2674.1041751013436, 2678.544647409945, 2690.045034012917, 2695.231858920198, 13627.863721362857, 13971.161730296215, 14010.0, 14010.509632162928, 14020.088694234842, 7626.8474630507635, 7862.842743145162, 7889.7633071007385, 7895.328897043758, 7906.604669766489, 5085.796568137307, 5142.588592912558, 5262.342207224097, 5318.867028324285, 5328.067588172094, 1799.5321216483678, 1803.5130514761072, 1820.144532069925, 1859.1633764805892, 1888.291890541047, 24621.876890615433, 24810.0, 24891.0, 25525.61711574328, 25686.0, 6371.840703982438, 6459.418652321314, 6630.303818099127, 6664.266930637626, 6700.497462690318, 16176.514704558882, 16223.594410139605, 16229.188540572924, 16327.0, 16369.09969951664, 7242.493025488166, 7391.704331826667, 7433.293837085468, 7439.479236453396, 7442.367398771165, 6770.72917083321, 6785.694643740945, 6891.0, 6896.655167241618, 6968.44252459802, 4957.380327459067, 5036.798528058836, 5103.566196873233, 5120.897582048338, 5149.073166830007, 1438.216172186161, 1444.2489905249422, 1450.6010847016944, 1470.3016067367914, 1477.8743806776329, 807.386386346381, 816.4937738601986, 832.0473058348158, 843.1905370843936, 850.2177996243471, 6877.6217308047535, 6878.965605171942, 6914.515983881181, 6949.7567585134275, 6969.860602787916, 686.7973947685445, 700.3416788219058, 704.7110684619385, 704.8132244955091, 716.3481232078888, 4263.76549289792, 4317.0, 4373.0, 4520.480144783326, 4922.901541969212, 20356.592868142554, 21233.68149477759, 21261.78738212629, 21303.78696213018, 21335.78664213338, 19995.40013799559, 20665.89667051655, 20917.477063073544, 20925.16299348009, 20943.0, 8720.694775682694, 10607.628732994308, 10911.727206819893, 10939.12487001038, 11032.889671103347, 7285.456052570937, 8036.758897233092, 8128.796780080545, 8186.836263274582, 8513.74458766238, 17831.840930339487, 17832.286708531512, 18160.364387246384, 18520.629587407908, 18566.628667426845, 18152.546186345447, 18247.361342352953, 18685.439436816916, 19138.0, 19301.710474343177, 9813.411195328303, 10378.169746420117, 10378.27352085377, 10450.16398688088, 10450.634227801991, 12268.570600029314, 12377.690557736129, 12411.937940310241, 12483.875161248272, 12501.0, 10256.333338332985, 10282.897171028493, 10493.737656558647, 10790.89209107899, 10814.35113893169, 9733.270004749531, 9749.853752193865, 9802.36284641496, 9978.451185184884, 10185.23610729213, 8393.83212335762, 8578.613962371583, 8782.473051616924, 8863.512506812187, 8908.242799361868, 6156.507479401547, 6190.566660333733, 6199.28708198563, 6199.752009919553, 6276.278228003838, 6241.21984751906, 6280.434760871543, 6311.495080393649, 6388.0737293091315, 6451.96768517035], "value": 6229.485580748241, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [491.5669179334837, 493.2981214808909, 508.54993330902187, 523.7643060622719, 527.0644605824662, 6364.618122912622, 6427.775027874025, 6433.195850518685, 6437.35626437356, 6491.0, 6069.180660610817, 6203.379662033794, 6233.758445608281, 6324.335944725806, 6346.904796428045, 5050.545450909417, 5064.366954130743, 5079.009593129341, 5110.948890511085, 5143.3313669223135, 4959.479254678261, 4986.5761410280165, 5002.524760147799, 5028.647994640377, 5044.117279476095, 4856.17445034345, 4941.431735350427, 4949.480304568023, 5011.824586139486, 5025.271335656327, 4984.90030199394, 4995.950040499603, 5025.070361983045, 5032.572231360302, 5066.721330326849, 4429.357743127261, 4495.213337665896, 4527.977360113211, 4531.52418996004, 4534.659900507442, 6176.4441200292185, 6234.251889773211, 6246.156768836195, 6248.0, 6258.15514905491, 5626.549876009909, 5661.179129026268, 5720.342160651495, 5760.654360738327, 5831.533477321804, 6696.229933557605, 6703.1285932828905, 6736.629485378302, 6745.763898263586, 6779.864402711967, 4905.583025442842, 4907.779149938226, 4918.114739346916, 4937.679050861719, 4970.627202959792, 6955.6869940852275, 6994.91578805286, 7055.788326350218, 7059.152901651783, 7115.217326094125, 5005.5745261653165, 5014.974925125351, 5042.268871013755, 5065.189569668835, 5106.642535022587, 5098.745062746848, 5162.716050617252, 5180.818671346485, 5197.558207552401, 5199.922001169985, 5646.814169024551, 5776.5089967352405, 5809.50619197373, 5817.156512305692, 5820.214271073351, 6622.03980422836, 6663.400293973566, 6690.022939791746, 6779.084823548757, 6785.762498312634, 1746.3014794082214, 1761.938332158394, 1771.636814453034, 1774.698301288784, 1775.742517334979, 1107.8227483602745, 1109.4785450838094, 1111.5109351885144, 1121.0919155484085, 1176.3823992404057, 5803.970980145073, 5842.970785146047, 5873.442023007808, 5881.411858814085, 5908.14331921869, 6770.5599136056, 6777.949417840243, 6800.469894273848, 6810.353016463428, 6842.4868134890085, 5631.887362252732, 5781.571739869265, 5881.794137205263, 5899.498542623839, 5920.467157955719, 3584.426491761306, 3622.855085796539, 3624.873129440457, 3638.3632864248652, 3645.0134676601315, 6565.457117577365, 6573.375529324707, 6604.438622717027, 6630.204375474877, 6697.598144111272, 7007.4394048476, 7054.2945705430075, 7115.011695088076, 7161.889907064413, 7175.784726458214, 6667.233268174222, 6672.432843208285, 6685.933140668531, 6709.5303328767495, 6716.932830671631, 6771.984202369487, 6806.353396427331, 6836.623985680835, 6886.311368863074, 6922.03091567191, 6778.796891010343, 6841.418479429304, 6843.3840954314355, 6865.9999999998, 6880.449564034765, 6505.869882602226, 6599.340065993362, 6630.635315057718, 6645.737309911101, 6675.332466753286, 5004.3994720633755, 5014.623903207201, 5015.322931404214, 5015.3730783652045, 5044.672096313728, 6813.965930170318, 6883.0, 6888.758893438906, 6921.792346229621, 6989.755358562629, 6568.456412743097, 6593.406593406617, 6636.7013484392355, 6676.56602320867, 6690.163729533809, 6547.312532184052, 6583.210014798255, 6604.867902641824, 6673.366030227121, 6680.231773345933, 6486.837829054311, 6609.438197753245, 6632.369924857131, 6642.701078451578, 6653.9667301663185, 6321.778737744157, 6359.459445947146, 6564.343565643589, 6569.277379488157, 6807.965960170168, 6355.713992870239, 6398.71205795731, 6428.871422571428, 6465.806025819233, 6488.837779055561], "value": 5117.618986391158, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [784.2353705137488, 806.9952908628757, 812.8780682897568, 824.3364091906017, 833.6998680475028, 14403.855961440382, 14693.77959330611, 14808.703825923476, 14825.851741482582, 14825.851741482595, 12011.339376334297, 12050.337231452266, 12841.422136003903, 13384.263865487394, 14008.579742607713, 776.8679324514832, 781.3553818100062, 790.0519376747925, 791.9406044546653, 792.1009654042646, 790.9248621380959, 791.5923299500733, 791.9524828510279, 792.2908996448205, 792.7066985215479, 818.8485130250892, 825.7440193540021, 825.9628316725732, 826.4710585225461, 826.7065191856894, 796.7530065679629, 814.6944895664126, 819.1685439279166, 820.1306614988072, 824.332290844415, 578.5545130249726, 580.8402689260445, 581.2095550051926, 582.7872826418369, 583.3612194646855, 14729.337179826933, 15279.694406111816, 15810.0, 15814.76277855833, 15873.603159920978, 18615.720764188547, 19119.522011949673, 19356.903215483973, 19450.416487505256, 19520.609587808165, 14632.780508292384, 14807.629809254855, 14986.700265994728, 15008.0, 15008.399664013426, 3628.872989445383, 3664.743467957244, 3679.6504332088407, 3682.907927301812, 3694.2426802505693, 122223.88700768164, 123092.9999999991, 126889.36555317257, 126960.36519817435, 127374.36312818469, 14041.157530547995, 14152.929235353964, 14248.430062797579, 14277.64305892361, 14291.571252862223, 24331.39171520726, 25427.49145017089, 25532.361690957503, 25618.0, 25629.743702562737, 7507.286807753255, 7565.962170189224, 7616.3906887448875, 7685.923140768633, 7692.884606730903, 3982.542007669097, 4062.0048088218578, 4078.53096893861, 4094.5086589609436, 4100.097978444737, 4456.509783923766, 4569.177548041384, 4600.907981840344, 4601.930971035437, 4610.170169369546, 848.5587494502824, 861.9353548483889, 935.9953200234091, 973.3429934794127, 992.1071036067452, 633.4235845380681, 650.5056157320471, 665.6106177885991, 670.9429698475684, 670.969806358715, 10658.614380130533, 10879.945600272107, 11011.174161937726, 11600.0, 11825.172237943261, 607.480604083511, 610.5634471353004, 614.5237440983274, 632.9525285603598, 639.6961443314482, 5071.898562028812, 5151.6136289778415, 5151.896962060813, 5309.601779866525, 5348.251244825728, 107750.0, 108746.0, 112266.0, 114130.0, 115402.0, 95955.04044959461, 96925.0, 98939.50530247303, 100946.0, 102017.0, 66163.66918165475, 88741.55629221942, 89403.0, 93605.0, 93856.0, 11573.884261157618, 11697.181197316106, 11748.58879939198, 11813.704657383634, 11898.524059037542, 38992.61007390003, 40328.39507407333, 40838.0, 42108.0, 42493.0, 51565.0, 52273.47726522686, 52335.214971774694, 52686.736566316926, 52868.73565632147, 17683.0, 18083.0, 18216.0, 18385.0, 18703.719444208076, 20186.495337616678, 20702.275420360216, 21069.683954740383, 21194.47013824605, 21552.137914483246, 16022.198890055217, 16199.919000405394, 16256.837431626005, 16298.837011629732, 16862.66274674475, 16624.251908663897, 16679.416220432227, 16763.49709508717, 17048.31806727717, 17236.741448878027, 16077.67844643124, 16080.276387562353, 16254.349826007301, 16358.263878125275, 16364.754528682317, 11039.779204416027, 11141.554337826396, 11341.716457088309, 11443.084553235554, 11536.711582210512, 11998.880011199777, 12293.07801914878, 12793.936030319788, 12874.098813083368, 12898.67753306175], "value": 8096.877393507975, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1520.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 109.0, 102.0, 111.0, 117.0, 112.0, 116.0, 99.0, 120.0, 114.0], "value": 110.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 124.0, 149.0, 173.0, 120.0, 143.0, 148.0, 121.0, 167.0, 122.0], "value": 141.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 171.0, 162.0, 168.0, 164.0, 168.0, 165.0, 164.0, 162.0, 164.0], "value": 166.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 80.0, 90.0, 168.0, 86.0, 76.0, 73.0, 73.0, 171.0, 76.0], "value": 96.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 90.0, 136.0, 81.0, 80.0, 83.0, 133.0, 82.0, 79.0, 131.0], "value": 97.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 115.0, 116.0, 117.0, 114.0, 126.0, 115.0, 117.0, 114.0, 122.0], "value": 117.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 154.0, 149.0, 149.0, 149.0, 148.0, 149.0, 149.0, 148.0, 148.0], "value": 149.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 94.0, 96.0, 94.0, 99.0, 102.0, 94.0, 94.0, 95.0, 96.0], "value": 96.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 57.0, 52.0, 53.0, 53.0, 53.0, 65.0, 56.0, 58.0, 52.0], "value": 55.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [43.0, 43.0, 39.0, 39.0, 39.0, 47.0, 48.0, 40.0, 39.0, 41.0], "value": 41.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 77.0, 83.0, 81.0, 76.0, 80.0, 80.0, 75.0, 76.0], "value": 78.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 126.0, 119.0, 125.0, 126.0, 138.0, 131.0, 128.0, 133.0, 165.0], "value": 131.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 170.0, 170.0, 170.0, 168.0, 172.0, 180.0, 174.0, 175.0, 172.0], "value": 173.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 63.0, 64.0, 63.0, 65.0, 65.0, 65.0, 70.0, 62.0], "value": 64.8, "unit": "score"}]}]} 09:04:25 INFO - Return code: 0 09:04:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:04:25 INFO - # TBPL SUCCESS # 09:04:25 INFO - Running post-action listener: _package_coverage_data 09:04:25 INFO - Running post-action listener: _resource_record_post_action 09:04:25 INFO - [mozharness: 2017-08-28 16:04:25.867470Z] Finished run-tests step (success) 09:04:25 INFO - Running post-run listener: _resource_record_post_run 09:04:25 INFO - Total resource usage - Wall time: 1197s; CPU: 13.0%; Read bytes: 7352320; Write bytes: 439332864; Read time: 3696; Write time: 283492 09:04:25 INFO - TinderboxPrint: CPU usage
13.3% 09:04:25 INFO - TinderboxPrint: I/O read bytes / time
7,352,320 / 3,696 09:04:25 INFO - TinderboxPrint: I/O write bytes / time
439,332,864 / 283,492 09:04:25 INFO - TinderboxPrint: CPU idle
8,273.8 (86.7%) 09:04:25 INFO - TinderboxPrint: CPU user
1,203.8 (12.6%) 09:04:25 INFO - TinderboxPrint: Swap in / out
0 / 0 09:04:25 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 115838976; Read time: 0; Write time: 79220 09:04:25 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 09:04:25 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 7348224; Write bytes: 322379776; Read time: 3684; Write time: 204236 09:04:26 INFO - Running post-run listener: _upload_blobber_files 09:04:26 INFO - Blob upload gear active. 09:04:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:04:26 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 09:04:26 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'] 09:04:26 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 09:04:27 INFO - (blobuploader) - INFO - Open directory for files ... 09:04:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:04:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:04:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:04:30 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:04:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:04:30 INFO - (blobuploader) - INFO - Done attempting. 09:04:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:04:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:04:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:04:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:04:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:04:31 INFO - (blobuploader) - INFO - Done attempting. 09:04:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:04:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:04:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:04:32 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:04:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:04:32 INFO - (blobuploader) - INFO - Done attempting. 09:04:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:04:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:04:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:04:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:04:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:04:33 INFO - (blobuploader) - INFO - Done attempting. 09:04:33 INFO - (blobuploader) - INFO - Iteration through files over. 09:04:33 INFO - Return code: 0 09:04:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:04:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:04:33 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fab9df3390a25982c955c8a3b70dd79f1079077dd435483ff0748d3242b3b5e8baecce28f246abfe58444a540194d0af24a5afbad989c8edb0ac4bff4877ba6b", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d610ff28b98823d1d6036ff4b5740a552a70d41359c2361acfde41cda5c05195225d110c41e051038d3187397834db0a7e710584682392f83fadf6bb002a3d88", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/df41da0a7a6b6f136798a8667fa437b9da7aa8a5d0e90eb02a841fefaafc533e01f987cdff11ebb432c60468968f58db1b6ee7aedc8f2b3d1a54b81a7ba30d5f", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ba3b810cc37b6ce2ababd726327798b9255c26a616b32d3214a7260c745db3776f1f17e7d59c49786136d93e9b8d67e846175c2cdd232ea91e07e1aa26921a0c"} 09:04:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:04:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:04:33 INFO - Contents: 09:04:33 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fab9df3390a25982c955c8a3b70dd79f1079077dd435483ff0748d3242b3b5e8baecce28f246abfe58444a540194d0af24a5afbad989c8edb0ac4bff4877ba6b", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d610ff28b98823d1d6036ff4b5740a552a70d41359c2361acfde41cda5c05195225d110c41e051038d3187397834db0a7e710584682392f83fadf6bb002a3d88", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/df41da0a7a6b6f136798a8667fa437b9da7aa8a5d0e90eb02a841fefaafc533e01f987cdff11ebb432c60468968f58db1b6ee7aedc8f2b3d1a54b81a7ba30d5f", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ba3b810cc37b6ce2ababd726327798b9255c26a616b32d3214a7260c745db3776f1f17e7d59c49786136d93e9b8d67e846175c2cdd232ea91e07e1aa26921a0c"} 09:04:33 INFO - Running post-run listener: copy_logs_to_upload_dir 09:04:33 INFO - Copying logs to upload dir... 09:04:33 INFO - mkdir: /builds/slave/test/build/upload/logs 09:04:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1258.040451 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2017-08-28 09:04:33.420773) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-28 09:04:33.425904) ========= 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=51fc86400edbde9650d2781a000001d0-1503935013.520396-1280810185 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/fab9df3390a25982c955c8a3b70dd79f1079077dd435483ff0748d3242b3b5e8baecce28f246abfe58444a540194d0af24a5afbad989c8edb0ac4bff4877ba6b", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d610ff28b98823d1d6036ff4b5740a552a70d41359c2361acfde41cda5c05195225d110c41e051038d3187397834db0a7e710584682392f83fadf6bb002a3d88", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/df41da0a7a6b6f136798a8667fa437b9da7aa8a5d0e90eb02a841fefaafc533e01f987cdff11ebb432c60468968f58db1b6ee7aedc8f2b3d1a54b81a7ba30d5f", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ba3b810cc37b6ce2ababd726327798b9255c26a616b32d3214a7260c745db3776f1f17e7d59c49786136d93e9b8d67e846175c2cdd232ea91e07e1aa26921a0c"} build_url:https://queue.taskcluster.net/v1/task/TTT3heApSneArmZvclHtxg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011377 build_url: 'https://queue.taskcluster.net/v1/task/TTT3heApSneArmZvclHtxg/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fab9df3390a25982c955c8a3b70dd79f1079077dd435483ff0748d3242b3b5e8baecce28f246abfe58444a540194d0af24a5afbad989c8edb0ac4bff4877ba6b", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d610ff28b98823d1d6036ff4b5740a552a70d41359c2361acfde41cda5c05195225d110c41e051038d3187397834db0a7e710584682392f83fadf6bb002a3d88", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/df41da0a7a6b6f136798a8667fa437b9da7aa8a5d0e90eb02a841fefaafc533e01f987cdff11ebb432c60468968f58db1b6ee7aedc8f2b3d1a54b81a7ba30d5f", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ba3b810cc37b6ce2ababd726327798b9255c26a616b32d3214a7260c745db3776f1f17e7d59c49786136d93e9b8d67e846175c2cdd232ea91e07e1aa26921a0c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-28 09:04:33.460223) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-28 09:04:33.460611) ========= 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=51fc86400edbde9650d2781a000001d0-1503935013.520396-1280810185 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004461 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-28 09:04:33.546299) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-28 09:04:33.546625) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-28 09:04:33.546935) ========= ========= Total master_lag: 0.20 =========