builder: mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s slave: talos-linux64-ix-018 starttime: 1503969797.24 results: success (0) revision: 1b4c59eef820b46eb0037aca68f83a15088db45f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-28 18:23:17.244147) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-28 18:23:17.244637) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-28 18:23:17.244917) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-28 18:23:17.261127) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-28 18:23:17.261455) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=62c568580acbfdaac3eec7be000001dc-1503964946.701655-1770442037 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-28 18:23:17-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 225M=0s 2017-08-28 18:23:17 (225 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.099924 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-28 18:23:17.381140) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-28 18:23:17.381457) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=62c568580acbfdaac3eec7be000001dc-1503964946.701655-1770442037 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017836 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-28 18:23:17.436479) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 13 secs) (at 2017-08-28 18:23:17.436812) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1b4c59eef820b46eb0037aca68f83a15088db45f --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 1b4c59eef820b46eb0037aca68f83a15088db45f --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=62c568580acbfdaac3eec7be000001dc-1503964946.701655-1770442037 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-28 18:23:17,618 truncating revision to first 12 chars 2017-08-28 18:23:17,618 Setting DEBUG logging. 2017-08-28 18:23:17,618 attempt 1/10 2017-08-28 18:23:17,618 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1b4c59eef820?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-28 18:23:18,372 attempt 1/10 2017-08-28 18:23:18,885 current task status: no status available at this point. state: STARTED 2017-08-28 18:23:18,885 sleeping for 11.00s (attempt 1/10) 2017-08-28 18:23:29,897 attempt 2/10 2017-08-28 18:23:30,337 current task status: Task completed! Check 's3_urls' for upload locations. state: SUCCESS 2017-08-28 18:23:30,748 unpacking tar archive at: mozilla-central-1b4c59eef820/testing/mozharness/ program finished with exit code 0 elapsedTime=13.408949 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 13 secs) (at 2017-08-28 18:23:30.860079) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-28 18:23:30.863398) ========= script_repo_revision: 1b4c59eef820b46eb0037aca68f83a15088db45f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-28 18:23:30.863785) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-28 18:23:30.864063) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-28 18:23:30.876018) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2017-08-28 18:23:30.876290) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=62c568580acbfdaac3eec7be000001dc-1503964946.701655-1770442037 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:23:31 INFO - MultiFileLogger online at 20170828 18:23:31 in /builds/slave/test 18:23:31 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 18:23:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:23:31 INFO - {'append_to_log': False, 18:23:31 INFO - 'base_work_dir': '/builds/slave/test', 18:23:31 INFO - 'blob_upload_branch': 'Firefox', 18:23:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:23:31 INFO - 'branch': 'Firefox', 18:23:31 INFO - 'buildbot_json_path': 'buildprops.json', 18:23:31 INFO - 'code_coverage': False, 18:23:31 INFO - 'config_files': ('talos/linux_config.py',), 18:23:31 INFO - 'default_actions': ('clobber', 18:23:31 INFO - 'read-buildbot-config', 18:23:31 INFO - 'download-and-extract', 18:23:31 INFO - 'populate-webroot', 18:23:31 INFO - 'create-virtualenv', 18:23:31 INFO - 'install', 18:23:31 INFO - 'setup-mitmproxy', 18:23:31 INFO - 'run-tests'), 18:23:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:23:31 INFO - 'disable_ccov_upload': False, 18:23:31 INFO - 'download_minidump_stackwalk': True, 18:23:31 INFO - 'download_symbols': 'ondemand', 18:23:31 INFO - 'enable_stylo': False, 18:23:31 INFO - 'enable_webrender': False, 18:23:31 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 18:23:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:23:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:23:31 INFO - 'gecko_profile': False, 18:23:31 INFO - 'gecko_profile_interval': 0, 18:23:31 INFO - 'installer_path': 'installer.exe', 18:23:31 INFO - 'log_level': 'info', 18:23:31 INFO - 'log_name': 'talos', 18:23:31 INFO - 'log_to_console': True, 18:23:31 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:23:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:23:31 INFO - 'opt_config_files': (), 18:23:31 INFO - 'pip_index': False, 18:23:31 INFO - 'suite': 'dromaeojs-e10s', 18:23:31 INFO - 'system_bits': '32', 18:23:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:23:31 INFO - 'title': 'talos-linux64-ix-018', 18:23:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:23:31 INFO - 'use_talos_json': True, 18:23:31 INFO - 'verify': 'False', 18:23:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:23:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:23:31 INFO - 'work_dir': 'build'} 18:23:31 INFO - [mozharness: 2017-08-29 01:23:31.203210Z] Running clobber step. 18:23:31 INFO - Running pre-action listener: _resource_record_pre_action 18:23:31 INFO - Running main action method: clobber 18:23:31 INFO - rmtree: /builds/slave/test/build 18:23:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:23:32 INFO - Running post-action listener: _resource_record_post_action 18:23:32 INFO - [mozharness: 2017-08-29 01:23:32.213682Z] Finished clobber step (success) 18:23:32 INFO - [mozharness: 2017-08-29 01:23:32.213780Z] Running read-buildbot-config step. 18:23:32 INFO - Running pre-action listener: _resource_record_pre_action 18:23:32 INFO - Running main action method: read_buildbot_config 18:23:32 INFO - Using buildbot properties: 18:23:32 INFO - { 18:23:32 INFO - "project": "", 18:23:32 INFO - "product": "firefox", 18:23:32 INFO - "who": "kwierso@gmail.com", 18:23:32 INFO - "installer_path": "public/build/target.tar.bz2", 18:23:32 INFO - "builddir": "mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s", 18:23:32 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 18:23:32 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo mozilla-central talos dromaeojs-e10s", 18:23:32 INFO - "stage_platform": "linux64-stylo", 18:23:32 INFO - "basedir": "/builds/slave/test", 18:23:32 INFO - "buildnumber": 61, 18:23:32 INFO - "platform": "ubuntu64_hw_stylo", 18:23:32 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 18:23:32 INFO - "slavebuilddir": "test", 18:23:32 INFO - "taskId": "DYctuFumQ4Wa8d8xr7QPxQ", 18:23:32 INFO - "branch": "mozilla-central", 18:23:32 INFO - "script_repo_revision": "production", 18:23:32 INFO - "revision": "1b4c59eef820b46eb0037aca68f83a15088db45f", 18:23:32 INFO - "slavename": "talos-linux64-ix-018", 18:23:32 INFO - "repo_path": "mozilla-central" 18:23:32 INFO - } 18:23:32 INFO - Finding installer, test and symbols from parent task. 18:23:32 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DYctuFumQ4Wa8d8xr7QPxQ'}, attempt #1 18:23:32 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DYctuFumQ4Wa8d8xr7QPxQ'}, attempt #1 18:23:33 INFO - Task dependencies: A3hmUCGWRMeTZ6zQMbT4dg 18:23:33 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/A3hmUCGWRMeTZ6zQMbT4dg'}, attempt #1 18:23:33 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/A3hmUCGWRMeTZ6zQMbT4dg/artifacts/public/build/target.tar.bz2 18:23:33 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/A3hmUCGWRMeTZ6zQMbT4dg/artifacts/public/build/target.test_packages.json 18:23:33 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/A3hmUCGWRMeTZ6zQMbT4dg/artifacts/public/build/target.crashreporter-symbols.zip 18:23:33 INFO - Running post-action listener: _resource_record_post_action 18:23:33 INFO - [mozharness: 2017-08-29 01:23:33.541249Z] Finished read-buildbot-config step (success) 18:23:33 INFO - [mozharness: 2017-08-29 01:23:33.541513Z] Running download-and-extract step. 18:23:33 INFO - Running pre-action listener: _resource_record_pre_action 18:23:33 INFO - Running main action method: download_and_extract 18:23:33 INFO - mkdir: /builds/slave/test/build/tests 18:23:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:23:33 INFO - trying https://queue.taskcluster.net/v1/task/A3hmUCGWRMeTZ6zQMbT4dg/artifacts/public/build/target.test_packages.json 18:23:33 INFO - Downloading https://queue.taskcluster.net/v1/task/A3hmUCGWRMeTZ6zQMbT4dg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 18:23:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/A3hmUCGWRMeTZ6zQMbT4dg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 18:23:34 INFO - Downloaded 989 bytes. 18:23:34 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 18:23:34 INFO - Using the following test package requirements: 18:23:34 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 18:23:34 INFO - u'common': [u'target.common.tests.zip'], 18:23:34 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 18:23:34 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 18:23:34 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 18:23:34 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 18:23:34 INFO - u'mozbase': [u'target.common.tests.zip'], 18:23:34 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 18:23:34 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 18:23:34 INFO - u'web-platform': [u'target.common.tests.zip', 18:23:34 INFO - u'target.web-platform.tests.tar.gz'], 18:23:34 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 18:23:34 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 18:23:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/A3hmUCGWRMeTZ6zQMbT4dg/artifacts/public/build/target.common.tests.zip 18:23:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A3hmUCGWRMeTZ6zQMbT4dg/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:23:34 INFO - Fetch https://queue.taskcluster.net/v1/task/A3hmUCGWRMeTZ6zQMbT4dg/artifacts/public/build/target.common.tests.zip into memory 18:23:35 INFO - Content-Length response header: 54977777 18:23:35 INFO - Bytes received: 54977777 18:23:46 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 18:23:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/A3hmUCGWRMeTZ6zQMbT4dg/artifacts/public/build/target.common.tests.zip 18:23:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A3hmUCGWRMeTZ6zQMbT4dg/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:23:46 INFO - Fetch https://queue.taskcluster.net/v1/task/A3hmUCGWRMeTZ6zQMbT4dg/artifacts/public/build/target.common.tests.zip into memory 18:23:47 INFO - Content-Length response header: 54977777 18:23:47 INFO - Bytes received: 54977777 18:24:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/A3hmUCGWRMeTZ6zQMbT4dg/artifacts/public/build/target.talos.tests.zip 18:24:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A3hmUCGWRMeTZ6zQMbT4dg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 18:24:01 INFO - Fetch https://queue.taskcluster.net/v1/task/A3hmUCGWRMeTZ6zQMbT4dg/artifacts/public/build/target.talos.tests.zip into memory 18:24:01 INFO - Content-Length response header: 14496611 18:24:01 INFO - Bytes received: 14496611 18:24:02 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')]} 18:24:02 INFO - trying https://queue.taskcluster.net/v1/task/A3hmUCGWRMeTZ6zQMbT4dg/artifacts/public/build/target.tar.bz2 18:24:02 INFO - Downloading https://queue.taskcluster.net/v1/task/A3hmUCGWRMeTZ6zQMbT4dg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 18:24:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/A3hmUCGWRMeTZ6zQMbT4dg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 18:24:27 INFO - Downloaded 61258746 bytes. 18:24:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/A3hmUCGWRMeTZ6zQMbT4dg/artifacts/public/build/target.tar.bz2 18:24:27 INFO - mkdir: /builds/slave/test/properties 18:24:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:24:27 INFO - Writing to file /builds/slave/test/properties/build_url 18:24:27 INFO - Contents: 18:24:27 INFO - build_url:https://queue.taskcluster.net/v1/task/A3hmUCGWRMeTZ6zQMbT4dg/artifacts/public/build/target.tar.bz2 18:24:27 INFO - Running post-action listener: _resource_record_post_action 18:24:27 INFO - Running post-action listener: find_tests_for_verification 18:24:27 INFO - Running post-action listener: set_extra_try_arguments 18:24:27 INFO - [mozharness: 2017-08-29 01:24:27.346794Z] Finished download-and-extract step (success) 18:24:27 INFO - [mozharness: 2017-08-29 01:24:27.347020Z] Running populate-webroot step. 18:24:27 INFO - Running pre-action listener: _resource_record_pre_action 18:24:27 INFO - Running main action method: populate_webroot 18:24:27 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:24:27 INFO - 'chromez-stylo-e10s': {'talos_options': ['--stylo'], 18:24:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:24:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:24:27 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--stylo'], 18:24:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:24:27 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:24:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:24:27 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:24:27 INFO - 'talos_options': ['--stylo'], 18:24:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:24:27 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:24:27 INFO - 'tests': ['damp', 'tps']}, 18:24:27 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:24:27 INFO - 'talos_options': ['--stylo'], 18:24:27 INFO - 'tests': ['damp', 'tps']}, 18:24:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:24:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:24:27 INFO - 'g4-stylo-e10s': {'talos_options': ['--stylo'], 18:24:27 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 18:24:27 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 18:24:27 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:24:27 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:24:27 INFO - 'talos_options': ['--stylo'], 18:24:27 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:24:27 INFO - 'other-e10s': {'tests': ['a11yr', 18:24:27 INFO - 'ts_paint', 18:24:27 INFO - 'tpaint', 18:24:27 INFO - 'sessionrestore', 18:24:27 INFO - 'sessionrestore_many_windows', 18:24:27 INFO - 'sessionrestore_no_auto_restore', 18:24:27 INFO - 'tabpaint']}, 18:24:27 INFO - 'other-stylo-e10s': {'talos_options': ['--stylo'], 18:24:27 INFO - 'tests': ['a11yr', 18:24:27 INFO - 'ts_paint', 18:24:27 INFO - 'tpaint', 18:24:27 INFO - 'sessionrestore', 18:24:27 INFO - 'sessionrestore_many_windows', 18:24:27 INFO - 'sessionrestore_no_auto_restore', 18:24:27 INFO - 'tabpaint']}, 18:24:27 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 18:24:27 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 18:24:27 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--stylo'], 18:24:27 INFO - 'tests': ['bloom_basic_singleton']}, 18:24:27 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--stylo'], 18:24:27 INFO - 'tests': ['bloom_basic']}, 18:24:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:24:27 INFO - 'tsvgr_opacity', 18:24:27 INFO - 'tart', 18:24:27 INFO - 'tscrollx', 18:24:27 INFO - 'tsvg_static']}, 18:24:27 INFO - 'svgr-stylo-e10s': {'talos_options': ['--stylo'], 18:24:27 INFO - 'tests': ['tsvgx', 18:24:27 INFO - 'tsvgr_opacity', 18:24:27 INFO - 'tart', 18:24:27 INFO - 'tscrollx', 18:24:27 INFO - 'tsvg_static']}, 18:24:27 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:24:27 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:24:27 INFO - 'talos_options': ['--stylo'], 18:24:27 INFO - 'tests': ['tp5o']}, 18:24:27 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:24:27 INFO - 'talos_options': ['--mitmproxy', 18:24:27 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:24:27 INFO - '--firstNonBlankPaint'], 18:24:27 INFO - 'tests': ['tp6_google', 18:24:27 INFO - 'tp6_youtube', 18:24:27 INFO - 'tp6_amazon', 18:24:27 INFO - 'tp6_facebook']}, 18:24:27 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:24:27 INFO - 'talos_options': ['--stylo', 18:24:27 INFO - '--mitmproxy', 18:24:27 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:24:27 INFO - '--firstNonBlankPaint'], 18:24:27 INFO - 'tests': ['tp6_google', 18:24:27 INFO - 'tp6_youtube', 18:24:27 INFO - 'tp6_amazon', 18:24:27 INFO - 'tp6_facebook']}, 18:24:27 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:24:27 INFO - 'talos_options': ['--stylo', 18:24:27 INFO - '--stylo-threads=1', 18:24:27 INFO - '--mitmproxy', 18:24:27 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:24:27 INFO - '--firstNonBlankPaint'], 18:24:27 INFO - 'tests': ['tp6_google', 18:24:27 INFO - 'tp6_youtube', 18:24:27 INFO - 'tp6_amazon', 18:24:27 INFO - 'tp6_facebook']}, 18:24:27 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:24:27 INFO - 'talos_options': ['--xperf_path', 18:24:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:24:27 INFO - 'tests': ['tp5n']}, 18:24:27 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:24:27 INFO - 'talos_options': ['--stylo', 18:24:27 INFO - '--xperf_path', 18:24:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:24:27 INFO - 'tests': ['tp5n']}}} 18:24:27 INFO - Running post-action listener: _resource_record_post_action 18:24:27 INFO - [mozharness: 2017-08-29 01:24:27.362643Z] Finished populate-webroot step (success) 18:24:27 INFO - [mozharness: 2017-08-29 01:24:27.362732Z] Running create-virtualenv step. 18:24:27 INFO - Running pre-action listener: _resource_record_pre_action 18:24:27 INFO - Running main action method: create_virtualenv 18:24:27 INFO - Creating virtualenv /builds/slave/test/build/venv 18:24:27 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 18:24:27 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 18:24:27 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 18:24:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:24:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:24:27 INFO - Installing distribute.............................................................................................................................................................................................done. 18:24:28 INFO - Installing pip...............done. 18:24:28 INFO - Return code: 0 18:24:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:24:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:24:28 INFO - Reading from file tmpfile_stdout 18:24:28 INFO - Output received: 18:24:28 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 18:24:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:24: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')]} 18:24:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:24: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')]} 18:24:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:24: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14941f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x165fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1802f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1800130>, '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 0x1801030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18014c0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1503964946.701655-1770442037', '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 18:24: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', 'pip>=1.5'] in /builds/slave/test/build 18:24: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 pip>=1.5 18:24:28 INFO - Using env: {'DISPLAY': ':0', 18:24:28 INFO - 'HOME': '/home/cltbld', 18:24:28 INFO - 'LANG': 'en_US.UTF-8', 18:24:28 INFO - 'LANGUAGE': 'en_US:en', 18:24:28 INFO - 'LOGNAME': 'cltbld', 18:24:28 INFO - 'MAIL': '/var/mail/cltbld', 18:24:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:28 INFO - 'MOZ_NO_REMOTE': '1', 18:24:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:24:28 INFO - 'NO_EM_RESTART': '1', 18:24:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:24:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:28 INFO - 'PWD': '/builds/slave/test', 18:24:28 INFO - 'SHELL': '/bin/bash', 18:24:28 INFO - 'SHLVL': '1', 18:24:28 INFO - 'TERM': 'linux', 18:24:28 INFO - 'TMOUT': '86400', 18:24:28 INFO - 'USER': 'cltbld', 18:24:28 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1503964946.701655-1770442037', 18:24:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:28 INFO - '_': '/tools/buildbot/bin/python'} 18:24:32 INFO - Ignoring indexes: http://pypi.python.org/simple/ 18:24:32 INFO - Downloading/unpacking pip>=1.5 18:24:32 INFO - Running setup.py egg_info for package pip 18:24:32 INFO - warning: no files found matching 'pip/cacert.pem' 18:24:32 INFO - warning: no files found matching '*.html' under directory 'docs' 18:24:32 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 18:24:32 INFO - no previously-included directories found matching 'docs/_build/_sources' 18:24:32 INFO - Installing collected packages: pip 18:24:32 INFO - Found existing installation: pip 1.1 18:24:32 INFO - Uninstalling pip: 18:24:32 INFO - Successfully uninstalled pip 18:24:32 INFO - Running setup.py install for pip 18:24:32 INFO - warning: no files found matching 'pip/cacert.pem' 18:24:32 INFO - warning: no files found matching '*.html' under directory 'docs' 18:24:32 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 18:24:32 INFO - no previously-included directories found matching 'docs/_build/_sources' 18:24:32 INFO - Installing pip script to /builds/slave/test/build/venv/bin 18:24:32 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 18:24:32 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 18:24:32 INFO - Successfully installed pip 18:24:32 INFO - Cleaning up... 18:24:32 INFO - Return code: 0 18:24:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:24:32 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')]} 18:24:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:24:32 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')]} 18:24:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:24:32 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 0x14941f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x165fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1802f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1800130>, '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 0x1801030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18014c0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1503964946.701655-1770442037', '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 18:24:32 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 18:24:32 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 18:24:32 INFO - Using env: {'DISPLAY': ':0', 18:24:32 INFO - 'HOME': '/home/cltbld', 18:24:32 INFO - 'LANG': 'en_US.UTF-8', 18:24:32 INFO - 'LANGUAGE': 'en_US:en', 18:24:32 INFO - 'LOGNAME': 'cltbld', 18:24:32 INFO - 'MAIL': '/var/mail/cltbld', 18:24:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:32 INFO - 'MOZ_NO_REMOTE': '1', 18:24:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:24:32 INFO - 'NO_EM_RESTART': '1', 18:24:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:24:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:32 INFO - 'PWD': '/builds/slave/test', 18:24:32 INFO - 'SHELL': '/bin/bash', 18:24:32 INFO - 'SHLVL': '1', 18:24:32 INFO - 'TERM': 'linux', 18:24:32 INFO - 'TMOUT': '86400', 18:24:32 INFO - 'USER': 'cltbld', 18:24:32 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1503964946.701655-1770442037', 18:24:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:32 INFO - '_': '/tools/buildbot/bin/python'} 18:24:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:24:32 INFO - Downloading/unpacking psutil>=3.1.1 18:24:32 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 18:24:32 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 18:24:32 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 18:24:32 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 18:24:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:24:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:24:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:24:33 INFO - Installing collected packages: psutil 18:24:33 INFO - Running setup.py install for psutil 18:24:34 INFO - building 'psutil._psutil_linux' extension 18:24:34 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 18:24:34 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 18:24:34 INFO - building 'psutil._psutil_posix' extension 18:24:34 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 18:24:34 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 18:24:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:24:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:24:34 INFO - Successfully installed psutil 18:24:34 INFO - Cleaning up... 18:24:34 INFO - Return code: 0 18:24:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:24:34 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')]} 18:24:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:24:34 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')]} 18:24:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:24:34 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 0x14941f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x165fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1802f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1800130>, '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 0x1801030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18014c0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1503964946.701655-1770442037', '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 18:24:34 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 18:24:34 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 18:24:34 INFO - Using env: {'DISPLAY': ':0', 18:24:34 INFO - 'HOME': '/home/cltbld', 18:24:34 INFO - 'LANG': 'en_US.UTF-8', 18:24:34 INFO - 'LANGUAGE': 'en_US:en', 18:24:34 INFO - 'LOGNAME': 'cltbld', 18:24:34 INFO - 'MAIL': '/var/mail/cltbld', 18:24:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:34 INFO - 'MOZ_NO_REMOTE': '1', 18:24:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:24:34 INFO - 'NO_EM_RESTART': '1', 18:24:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:24:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:34 INFO - 'PWD': '/builds/slave/test', 18:24:34 INFO - 'SHELL': '/bin/bash', 18:24:34 INFO - 'SHLVL': '1', 18:24:34 INFO - 'TERM': 'linux', 18:24:34 INFO - 'TMOUT': '86400', 18:24:34 INFO - 'USER': 'cltbld', 18:24:34 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1503964946.701655-1770442037', 18:24:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:34 INFO - '_': '/tools/buildbot/bin/python'} 18:24:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:24:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:24:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:24:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:24:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:24:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:24:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:24:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:24:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:24:35 INFO - Installing collected packages: mozsystemmonitor 18:24:35 INFO - Running setup.py install for mozsystemmonitor 18:24:35 INFO - Successfully installed mozsystemmonitor 18:24:35 INFO - Cleaning up... 18:24:35 INFO - Return code: 0 18:24:35 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:24:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:24:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:24:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:24:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:24:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x14941f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x165fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1802f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1800130>, '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 0x1801030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18014c0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1503964946.701655-1770442037', '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 18:24:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:24:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:24:35 INFO - Using env: {'DISPLAY': ':0', 18:24:35 INFO - 'HOME': '/home/cltbld', 18:24:35 INFO - 'LANG': 'en_US.UTF-8', 18:24:35 INFO - 'LANGUAGE': 'en_US:en', 18:24:35 INFO - 'LOGNAME': 'cltbld', 18:24:35 INFO - 'MAIL': '/var/mail/cltbld', 18:24:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:35 INFO - 'MOZ_NO_REMOTE': '1', 18:24:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:24:35 INFO - 'NO_EM_RESTART': '1', 18:24:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:24:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:35 INFO - 'PWD': '/builds/slave/test', 18:24:35 INFO - 'SHELL': '/bin/bash', 18:24:35 INFO - 'SHLVL': '1', 18:24:35 INFO - 'TERM': 'linux', 18:24:35 INFO - 'TMOUT': '86400', 18:24:35 INFO - 'USER': 'cltbld', 18:24:35 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1503964946.701655-1770442037', 18:24:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:35 INFO - '_': '/tools/buildbot/bin/python'} 18:24:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:24:36 INFO - Downloading/unpacking jsonschema==2.5.1 18:24:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:24:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:24:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:24:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:24:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:24:37 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:24:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:24:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:24:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:24:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:24:37 INFO - Downloading functools32-3.2.3-2.tar.gz 18:24:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:24:37 INFO - warning: no files found matching '*.txt' 18:24:37 INFO - no previously-included directories found matching 'build' 18:24:37 INFO - no previously-included directories found matching 'dist' 18:24:37 INFO - no previously-included directories found matching '.git*' 18:24:37 INFO - Installing collected packages: jsonschema, functools32 18:24:37 INFO - Running setup.py install for functools32 18:24:37 INFO - warning: no files found matching '*.txt' 18:24:37 INFO - no previously-included directories found matching 'build' 18:24:37 INFO - no previously-included directories found matching 'dist' 18:24:37 INFO - no previously-included directories found matching '.git*' 18:24:37 INFO - Successfully installed jsonschema functools32 18:24:37 INFO - Cleaning up... 18:24:37 INFO - Return code: 0 18:24:37 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:24:37 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')]} 18:24:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:24:37 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')]} 18:24:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:24:37 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 0x14941f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x165fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1802f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1800130>, '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 0x1801030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18014c0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1503964946.701655-1770442037', '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 18:24:37 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 18:24:37 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 18:24:37 INFO - Using env: {'DISPLAY': ':0', 18:24:37 INFO - 'HOME': '/home/cltbld', 18:24:37 INFO - 'LANG': 'en_US.UTF-8', 18:24:37 INFO - 'LANGUAGE': 'en_US:en', 18:24:37 INFO - 'LOGNAME': 'cltbld', 18:24:37 INFO - 'MAIL': '/var/mail/cltbld', 18:24:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:37 INFO - 'MOZ_NO_REMOTE': '1', 18:24:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:24:37 INFO - 'NO_EM_RESTART': '1', 18:24:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:24:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:37 INFO - 'PWD': '/builds/slave/test', 18:24:37 INFO - 'SHELL': '/bin/bash', 18:24:37 INFO - 'SHLVL': '1', 18:24:37 INFO - 'TERM': 'linux', 18:24:37 INFO - 'TMOUT': '86400', 18:24:37 INFO - 'USER': 'cltbld', 18:24:37 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1503964946.701655-1770442037', 18:24:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:37 INFO - '_': '/tools/buildbot/bin/python'} 18:24:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:24:37 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:24:37 INFO - Cleaning up... 18:24:37 INFO - Return code: 0 18:24:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:24:37 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')]} 18:24:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:24:37 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')]} 18:24:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:24:37 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 0x14941f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x165fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1802f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1800130>, '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 0x1801030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18014c0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1503964946.701655-1770442037', '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 18:24:37 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 18:24:37 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 18:24:37 INFO - Using env: {'DISPLAY': ':0', 18:24:37 INFO - 'HOME': '/home/cltbld', 18:24:37 INFO - 'LANG': 'en_US.UTF-8', 18:24:37 INFO - 'LANGUAGE': 'en_US:en', 18:24:37 INFO - 'LOGNAME': 'cltbld', 18:24:37 INFO - 'MAIL': '/var/mail/cltbld', 18:24:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:37 INFO - 'MOZ_NO_REMOTE': '1', 18:24:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:24:37 INFO - 'NO_EM_RESTART': '1', 18:24:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:24:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:37 INFO - 'PWD': '/builds/slave/test', 18:24:37 INFO - 'SHELL': '/bin/bash', 18:24:37 INFO - 'SHLVL': '1', 18:24:37 INFO - 'TERM': 'linux', 18:24:37 INFO - 'TMOUT': '86400', 18:24:37 INFO - 'USER': 'cltbld', 18:24:37 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1503964946.701655-1770442037', 18:24:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:37 INFO - '_': '/tools/buildbot/bin/python'} 18:24:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:24:38 INFO - Downloading/unpacking blobuploader==1.2.4 18:24:38 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 18:24:38 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 18:24:38 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 18:24:38 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 18:24:39 INFO - Downloading blobuploader-1.2.4.tar.gz 18:24:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:24:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:24:39 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 18:24:39 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 18:24:39 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 18:24:39 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 18:24:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:24:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:24:39 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 18:24:39 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 18:24:39 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 18:24:39 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 18:24:39 INFO - Downloading docopt-0.6.1.tar.gz 18:24:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:24:39 INFO - Installing collected packages: blobuploader, requests, docopt 18:24:39 INFO - Running setup.py install for blobuploader 18:24:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:24:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:24:40 INFO - Running setup.py install for requests 18:24:40 INFO - Running setup.py install for docopt 18:24:40 INFO - Successfully installed blobuploader requests docopt 18:24:40 INFO - Cleaning up... 18:24:40 INFO - Return code: 0 18:24:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:24:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:24:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:24:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:24:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:24:40 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 0x14941f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x165fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1802f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1800130>, '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 0x1801030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18014c0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1503964946.701655-1770442037', '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 18:24:40 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 18:24:40 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 18:24:40 INFO - Using env: {'DISPLAY': ':0', 18:24:40 INFO - 'HOME': '/home/cltbld', 18:24:40 INFO - 'LANG': 'en_US.UTF-8', 18:24:40 INFO - 'LANGUAGE': 'en_US:en', 18:24:40 INFO - 'LOGNAME': 'cltbld', 18:24:40 INFO - 'MAIL': '/var/mail/cltbld', 18:24:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:40 INFO - 'MOZ_NO_REMOTE': '1', 18:24:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:24:40 INFO - 'NO_EM_RESTART': '1', 18:24:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:24:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:40 INFO - 'PWD': '/builds/slave/test', 18:24:40 INFO - 'SHELL': '/bin/bash', 18:24:40 INFO - 'SHLVL': '1', 18:24:40 INFO - 'TERM': 'linux', 18:24:40 INFO - 'TMOUT': '86400', 18:24:40 INFO - 'USER': 'cltbld', 18:24:40 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1503964946.701655-1770442037', 18:24:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:40 INFO - '_': '/tools/buildbot/bin/python'} 18:24:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:24:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:24:40 INFO - Running setup.py (path:/tmp/pip-1oNpx4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:24:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:24:40 INFO - Running setup.py (path:/tmp/pip-cR2XSj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:24:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:24:40 INFO - Running setup.py (path:/tmp/pip-FbmYKH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:24:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:24:40 INFO - Running setup.py (path:/tmp/pip-sR9xC5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:24:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:24:40 INFO - Running setup.py (path:/tmp/pip-EgR4Ng-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:24:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:24:41 INFO - Running setup.py (path:/tmp/pip-0jJNIl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:24:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:24:41 INFO - Running setup.py (path:/tmp/pip-E1tUti-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:24:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:24:41 INFO - Running setup.py (path:/tmp/pip-aqqhqY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:24:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:24:41 INFO - Running setup.py (path:/tmp/pip-5o80Ds-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:24:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:24:41 INFO - Running setup.py (path:/tmp/pip-N6Jhfs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:24:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:24:41 INFO - Running setup.py (path:/tmp/pip-__6Oj8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:24:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:24:41 INFO - Running setup.py (path:/tmp/pip-uhjkos-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:24:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:24:41 INFO - Running setup.py (path:/tmp/pip-CbEWCh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:24:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:24:41 INFO - Running setup.py (path:/tmp/pip-XHlusC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:24:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:24:41 INFO - Running setup.py (path:/tmp/pip-40ziZw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:24:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:24:41 INFO - Running setup.py (path:/tmp/pip-UTE84G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:24:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:24:41 INFO - Running setup.py (path:/tmp/pip-pxTKms-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:24:41 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:24:41 INFO - Running setup.py install for manifestparser 18:24:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:24:42 INFO - Running setup.py install for mozcrash 18:24:42 INFO - Running setup.py install for mozdebug 18:24:42 INFO - Running setup.py install for mozdevice 18:24:42 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:24:42 INFO - Running setup.py install for mozfile 18:24:42 INFO - Running setup.py install for mozhttpd 18:24:42 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:24:42 INFO - Running setup.py install for mozinfo 18:24:42 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:24:42 INFO - Running setup.py install for mozInstall 18:24:42 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:24:42 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:24:42 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:24:42 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:24:42 INFO - Running setup.py install for mozleak 18:24:42 INFO - Running setup.py install for mozlog 18:24:42 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:24:42 INFO - Running setup.py install for moznetwork 18:24:43 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:24:43 INFO - Running setup.py install for mozprocess 18:24:43 INFO - Running setup.py install for mozprofile 18:24:43 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:24:43 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:24:43 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:24:43 INFO - Running setup.py install for mozrunner 18:24:43 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:24:43 INFO - Running setup.py install for mozscreenshot 18:24:43 INFO - Running setup.py install for moztest 18:24:43 INFO - Running setup.py install for mozversion 18:24:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:24:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:24:43 INFO - Cleaning up... 18:24:43 INFO - Return code: 0 18:24:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:24:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:24:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:24:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:24:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:24:43 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 0x14941f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x165fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1802f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1800130>, '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 0x1801030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18014c0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1503964946.701655-1770442037', '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 18:24:43 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 18:24:43 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 18:24:43 INFO - Using env: {'DISPLAY': ':0', 18:24:43 INFO - 'HOME': '/home/cltbld', 18:24:43 INFO - 'LANG': 'en_US.UTF-8', 18:24:43 INFO - 'LANGUAGE': 'en_US:en', 18:24:43 INFO - 'LOGNAME': 'cltbld', 18:24:43 INFO - 'MAIL': '/var/mail/cltbld', 18:24:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:43 INFO - 'MOZ_NO_REMOTE': '1', 18:24:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:24:43 INFO - 'NO_EM_RESTART': '1', 18:24:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:24:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:43 INFO - 'PWD': '/builds/slave/test', 18:24:43 INFO - 'SHELL': '/bin/bash', 18:24:43 INFO - 'SHLVL': '1', 18:24:43 INFO - 'TERM': 'linux', 18:24:43 INFO - 'TMOUT': '86400', 18:24:43 INFO - 'USER': 'cltbld', 18:24:43 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1503964946.701655-1770442037', 18:24:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:43 INFO - '_': '/tools/buildbot/bin/python'} 18:24:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:24:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:24:43 INFO - Running setup.py (path:/tmp/pip-yGxBW6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:24:43 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)) 18:24:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:24:43 INFO - Running setup.py (path:/tmp/pip-kBWVTd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:24:44 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)) 18:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:24:44 INFO - Running setup.py (path:/tmp/pip-jJEVg0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:24:44 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)) 18:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:24:44 INFO - Running setup.py (path:/tmp/pip-tqBMB9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:24:44 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)) 18:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:24:44 INFO - Running setup.py (path:/tmp/pip-Bt1PZs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:24:44 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)) 18:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:24:44 INFO - Running setup.py (path:/tmp/pip-ghkXX_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:24:44 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)) 18:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:24:44 INFO - Running setup.py (path:/tmp/pip-EGFqRj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:24:44 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)) 18:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:24:44 INFO - Running setup.py (path:/tmp/pip-D5jyvd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:24:44 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)) 18:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:24:44 INFO - Running setup.py (path:/tmp/pip-bcc6Hm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:24:44 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)) 18:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:24:44 INFO - Running setup.py (path:/tmp/pip-M0OUGR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:24:44 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)) 18:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:24:44 INFO - Running setup.py (path:/tmp/pip-isTmOz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:24:44 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)) 18:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:24:44 INFO - Running setup.py (path:/tmp/pip-bGbxcG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:24:44 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)) 18:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:24:44 INFO - Running setup.py (path:/tmp/pip-rBDFoh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:24:44 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)) 18:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:24:44 INFO - Running setup.py (path:/tmp/pip-Xne2gD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:24:44 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)) 18:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:24:44 INFO - Running setup.py (path:/tmp/pip-ZNz4tm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:24:44 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)) 18:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:24:44 INFO - Running setup.py (path:/tmp/pip-ZCfkss-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:24:45 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)) 18:24:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:24:45 INFO - Running setup.py (path:/tmp/pip-gFIQTv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:24:45 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)) 18:24:45 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)) 18:24:45 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)) 18:24:45 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)) 18:24:45 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)) 18:24:45 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)) 18:24:45 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)) 18:24:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:24:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:24:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:24:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:24:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:24:46 INFO - Downloading blessings-1.6.tar.gz 18:24:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:24:46 INFO - Installing collected packages: blessings 18:24:46 INFO - Running setup.py install for blessings 18:24:46 INFO - Successfully installed blessings 18:24:46 INFO - Cleaning up... 18:24:46 INFO - Return code: 0 18:24:46 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:24:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:24:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:24:46 INFO - Reading from file tmpfile_stdout 18:24:46 INFO - Current package versions: 18:24:46 INFO - argparse == 1.2.1 18:24:46 INFO - blessings == 1.6 18:24:46 INFO - blobuploader == 1.2.4 18:24:46 INFO - docopt == 0.6.1 18:24:46 INFO - functools32 == 3.2.3-2 18:24:46 INFO - jsonschema == 2.5.1 18:24:46 INFO - manifestparser == 1.1 18:24:46 INFO - mozInstall == 1.13 18:24:46 INFO - mozcrash == 1.0 18:24:46 INFO - mozdebug == 0.1 18:24:46 INFO - mozdevice == 0.51 18:24:46 INFO - mozfile == 1.2 18:24:46 INFO - mozhttpd == 0.7 18:24:46 INFO - mozinfo == 0.10 18:24:46 INFO - mozleak == 0.1 18:24:46 INFO - mozlog == 3.5 18:24:46 INFO - moznetwork == 0.27 18:24:46 INFO - mozprocess == 0.25 18:24:46 INFO - mozprofile == 0.28 18:24:46 INFO - mozrunner == 6.13 18:24:46 INFO - mozscreenshot == 0.1 18:24:46 INFO - mozsystemmonitor == 0.3 18:24:46 INFO - moztest == 0.8 18:24:46 INFO - mozversion == 1.4 18:24:46 INFO - psutil == 3.1.1 18:24:46 INFO - requests == 1.2.3 18:24:46 INFO - wsgiref == 0.1.2 18:24:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:24: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')]} 18:24:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:24: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')]} 18:24:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:24:46 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 0x14941f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x165fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1802f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1800130>, '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 0x1801030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18014c0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1503964946.701655-1770442037', '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 18:24:46 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 18:24:46 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 18:24:46 INFO - Using env: {'DISPLAY': ':0', 18:24:46 INFO - 'HOME': '/home/cltbld', 18:24:46 INFO - 'LANG': 'en_US.UTF-8', 18:24:46 INFO - 'LANGUAGE': 'en_US:en', 18:24:46 INFO - 'LOGNAME': 'cltbld', 18:24:46 INFO - 'MAIL': '/var/mail/cltbld', 18:24:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:46 INFO - 'MOZ_NO_REMOTE': '1', 18:24:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:24:46 INFO - 'NO_EM_RESTART': '1', 18:24:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:24:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:46 INFO - 'PWD': '/builds/slave/test', 18:24:46 INFO - 'SHELL': '/bin/bash', 18:24:46 INFO - 'SHLVL': '1', 18:24:46 INFO - 'TERM': 'linux', 18:24:46 INFO - 'TMOUT': '86400', 18:24:46 INFO - 'USER': 'cltbld', 18:24:46 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1503964946.701655-1770442037', 18:24:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:46 INFO - '_': '/tools/buildbot/bin/python'} 18:24:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:24:46 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)) 18:24:46 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)) 18:24:46 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)) 18:24:46 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)) 18:24:46 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)) 18:24:46 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)) 18:24:46 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)) 18:24:46 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)) 18:24:46 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)) 18:24:46 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:24:46 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 18:24:46 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 18:24:46 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 18:24:46 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 18:24:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 18:24:48 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)) 18:24:48 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)) 18:24:48 INFO - Installing collected packages: simplejson 18:24:48 INFO - Running setup.py install for simplejson 18:24:48 INFO - building 'simplejson._speedups' extension 18:24:48 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 18:24:48 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 18:24:49 INFO - Successfully installed simplejson 18:24:49 INFO - Cleaning up... 18:24:49 INFO - Return code: 0 18:24:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:24:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:24:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:24:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:24:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:24:49 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 0x14941f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x165fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1802f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1800130>, '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 0x1801030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18014c0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1503964946.701655-1770442037', '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 18:24:49 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 18:24:49 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 18:24:49 INFO - Using env: {'DISPLAY': ':0', 18:24:49 INFO - 'HOME': '/home/cltbld', 18:24:49 INFO - 'LANG': 'en_US.UTF-8', 18:24:49 INFO - 'LANGUAGE': 'en_US:en', 18:24:49 INFO - 'LOGNAME': 'cltbld', 18:24:49 INFO - 'MAIL': '/var/mail/cltbld', 18:24:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:49 INFO - 'MOZ_NO_REMOTE': '1', 18:24:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:24:49 INFO - 'NO_EM_RESTART': '1', 18:24:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:24:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:49 INFO - 'PWD': '/builds/slave/test', 18:24:49 INFO - 'SHELL': '/bin/bash', 18:24:49 INFO - 'SHLVL': '1', 18:24:49 INFO - 'TERM': 'linux', 18:24:49 INFO - 'TMOUT': '86400', 18:24:49 INFO - 'USER': 'cltbld', 18:24:49 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1503964946.701655-1770442037', 18:24:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:49 INFO - '_': '/tools/buildbot/bin/python'} 18:24:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:24:49 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:24:49 INFO - Cleaning up... 18:24:49 INFO - Return code: 0 18:24:49 INFO - Running post-action listener: _resource_record_post_action 18:24:49 INFO - Running post-action listener: _start_resource_monitoring 18:24:49 INFO - Starting resource monitoring. 18:24:49 INFO - [mozharness: 2017-08-29 01:24:49.397006Z] Finished create-virtualenv step (success) 18:24:49 INFO - [mozharness: 2017-08-29 01:24:49.397524Z] Running install step. 18:24:49 INFO - Running pre-action listener: _resource_record_pre_action 18:24:49 INFO - Running main action method: install 18:24:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:24:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:24:49 INFO - Reading from file tmpfile_stdout 18:24:49 INFO - Detecting whether we're running mozinstall >=1.0... 18:24:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:24:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:24:49 INFO - Reading from file tmpfile_stdout 18:24:49 INFO - Output received: 18:24:49 INFO - Usage: mozinstall [options] installer 18:24:49 INFO - Options: 18:24:49 INFO - -h, --help show this help message and exit 18:24:49 INFO - -d DEST, --destination=DEST 18:24:49 INFO - Directory to install application into. [default: 18:24:49 INFO - "/builds/slave/test"] 18:24:49 INFO - --app=APP Application being installed. [default: firefox] 18:24:49 INFO - mkdir: /builds/slave/test/build/application 18:24:49 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'] 18:24:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 18:25:07 INFO - Reading from file tmpfile_stdout 18:25:07 INFO - Output received: 18:25:07 INFO - /builds/slave/test/build/application/firefox/firefox 18:25:07 INFO - Running post-action listener: _resource_record_post_action 18:25:07 INFO - [mozharness: 2017-08-29 01:25:07.454861Z] Finished install step (success) 18:25:07 INFO - [mozharness: 2017-08-29 01:25:07.455015Z] Running setup-mitmproxy step. 18:25:07 INFO - Running pre-action listener: _resource_record_pre_action 18:25:07 INFO - Running main action method: setup_mitmproxy 18:25:07 INFO - Skipping: mitmproxy is not required 18:25:07 INFO - Running post-action listener: _resource_record_post_action 18:25:07 INFO - [mozharness: 2017-08-29 01:25:07.455547Z] Finished setup-mitmproxy step (success) 18:25:07 INFO - [mozharness: 2017-08-29 01:25:07.455650Z] Running run-tests step. 18:25:07 INFO - Running pre-action listener: _resource_record_pre_action 18:25:07 INFO - Running pre-action listener: _set_gcov_prefix 18:25:07 INFO - Running main action method: run_tests 18:25:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:25:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:25:07 INFO - Python 2.7.3 18:25:07 INFO - Return code: 0 18:25:07 INFO - grabbing minidump binary from tooltool 18:25:07 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')]} 18:25:07 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 0x1800130>, '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 0x1801030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18014c0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:25:07 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 18:25:07 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 18:25:07 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 18:25:07 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:25:07 INFO - Return code: 0 18:25:07 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:25:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:25:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:25:07 INFO - ENV: RUST_BACKTRACE is now full 18:25:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:25:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:25:07 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/A3hmUCGWRMeTZ6zQMbT4dg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 18:25:07 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/A3hmUCGWRMeTZ6zQMbT4dg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-018 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 18:25:07 INFO - Using env: {'DISPLAY': ':0', 18:25:07 INFO - 'HOME': '/home/cltbld', 18:25:07 INFO - 'LANG': 'en_US.UTF-8', 18:25:07 INFO - 'LANGUAGE': 'en_US:en', 18:25:07 INFO - 'LOGNAME': 'cltbld', 18:25:07 INFO - 'MAIL': '/var/mail/cltbld', 18:25:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:25:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:25:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:25:07 INFO - 'MOZ_NO_REMOTE': '1', 18:25:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:25:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:25:07 INFO - 'NO_EM_RESTART': '1', 18:25:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:25:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:25:07 INFO - 'PWD': '/builds/slave/test', 18:25:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:25:07 INFO - 'RUST_BACKTRACE': 'full', 18:25:07 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:25:07 INFO - 'SHELL': '/bin/bash', 18:25:07 INFO - 'SHLVL': '1', 18:25:07 INFO - 'STYLO_FORCE_ENABLED': '1', 18:25:07 INFO - 'TERM': 'linux', 18:25:07 INFO - 'TMOUT': '86400', 18:25:07 INFO - 'USER': 'cltbld', 18:25:07 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1503964946.701655-1770442037', 18:25:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:25:07 INFO - '_': '/tools/buildbot/bin/python'} 18:25:07 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/A3hmUCGWRMeTZ6zQMbT4dg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 18:25:07 INFO - mozversion application_buildid: 20170829003906 18:25:07 INFO - mozversion application_changeset: 1b4c59eef820b46eb0037aca68f83a15088db45f 18:25:07 INFO - mozversion application_display_name: Nightly 18:25:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:25:07 INFO - mozversion application_name: Firefox 18:25:07 INFO - mozversion application_remotingname: firefox 18:25:07 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:25:07 INFO - mozversion application_vendor: Mozilla 18:25:07 INFO - mozversion application_version: 57.0a1 18:25:07 INFO - mozversion platform_buildid: 20170829003906 18:25:07 INFO - mozversion platform_changeset: 1b4c59eef820b46eb0037aca68f83a15088db45f 18:25:07 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:25:07 INFO - mozversion platform_version: 57.0a1 18:25:07 INFO - using testdate: 1503969907 18:25:07 INFO - actual date: 1503969907 18:25:07 INFO - starting webserver on 'localhost:53378' 18:25:07 INFO - SUITE-START | Running 2 tests 18:25:07 INFO - TEST-START | dromaeo_css 18:25:07 INFO - Initialising browser for dromaeo_css test... 18:25:07 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:53378/getInfo.html -profile /tmp/tmpu8bUt5/profile 18:25:07 INFO - TEST-INFO | started process 7116 (/builds/slave/test/build/application/firefox/firefox http://localhost:53378/getInfo.html) 18:25:13 INFO - TEST-INFO | 7116: exit 0 18:25:13 INFO - Browser initialized. 18:25:13 INFO - Running cycle 1/1 for dromaeo_css test... 18:25:13 INFO - Using env: {'DISPLAY': ':0', 18:25:13 INFO - 'HOME': '/home/cltbld', 18:25:13 INFO - 'JSGC_DISABLE_POISONING': '1', 18:25:13 INFO - 'LANG': 'en_US.UTF-8', 18:25:13 INFO - 'LANGUAGE': 'en_US:en', 18:25:13 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 18:25:13 INFO - 'LOGNAME': 'cltbld', 18:25:13 INFO - 'MAIL': '/var/mail/cltbld', 18:25:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:25:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:25:13 INFO - 'MOZ_CRASHREPORTER': '1', 18:25:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:25:13 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 18:25:13 INFO - 'MOZ_NO_REMOTE': '1', 18:25:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:25:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:25:13 INFO - 'NO_EM_RESTART': '1', 18:25:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:25:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:25:13 INFO - 'PWD': '/builds/slave/test', 18:25:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:25:13 INFO - 'RUST_BACKTRACE': 'full', 18:25:13 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:25:13 INFO - 'SHELL': '/bin/bash', 18:25:13 INFO - 'SHLVL': '1', 18:25:13 INFO - 'STYLO_FORCE_ENABLED': '1', 18:25:13 INFO - 'TERM': 'linux', 18:25:13 INFO - 'TMOUT': '86400', 18:25:13 INFO - 'USER': 'cltbld', 18:25:13 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1503964946.701655-1770442037', 18:25:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:25:13 INFO - '_': '/tools/buildbot/bin/python'} 18:25:13 INFO - TEST-INFO | started process 7301 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpu8bUt5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:25:14 INFO - PID 7301 | 18:25:14 INFO - PID 7301 | (/builds/slave/test/build/application/firefox/firefox:7350): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:25:14 INFO - PID 7301 | 18:25:24 INFO - PID 7301 | RSS: Main: 173199360 18:25:24 INFO - PID 7301 | 18:28:23 INFO - PID 7301 | Cycle 1(1): loaded http://localhost:53378/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53378/tests/dromaeo/cssquery-ext.html) 18:28:24 INFO - PID 7301 | RSS: Main: 177266688 18:28:24 INFO - PID 7301 | 18:31:26 INFO - PID 7301 | Cycle 1(1): loaded http://localhost:53378/tests/dromaeo/cssquery-ext.html (next: http://localhost:53378/tests/dromaeo/cssquery-jquery.html) 18:31:27 INFO - PID 7301 | RSS: Main: 171401216 18:31:27 INFO - PID 7301 | 18:34:28 INFO - PID 7301 | Cycle 1(1): loaded http://localhost:53378/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53378/tests/dromaeo/cssquery-mootools.html) 18:34:29 INFO - PID 7301 | RSS: Main: 173453312 18:34:29 INFO - PID 7301 | 18:37:31 INFO - PID 7301 | Cycle 1(1): loaded http://localhost:53378/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53378/tests/dromaeo/cssquery-prototype.html) 18:37:31 INFO - PID 7301 | RSS: Main: 174407680 18:37:31 INFO - PID 7301 | 18:40:35 INFO - PID 7301 | Cycle 1(1): loaded http://localhost:53378/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53378/tests/dromaeo/cssquery-yui.html) 18:40:36 INFO - PID 7301 | RSS: Main: 177930240 18:40:36 INFO - PID 7301 | 18:43:37 INFO - PID 7301 | Cycle 1(1): loaded http://localhost:53378/tests/dromaeo/cssquery-yui.html (next: http://localhost:53378/tests/dromaeo/cssquery-dojo.html) 18:43:37 INFO - PID 7301 | RSS: Main: 178688000 18:43:37 INFO - PID 7301 | 18:43:37 INFO - PID 7301 | __start_tp_report 18:43:37 INFO - PID 7301 | _x_x_mozilla_page_load 18:43:37 INFO - PID 7301 | _x_x_mozilla_page_load_details 18:43:37 INFO - PID 7301 | |i|pagename|runs| 18:43:37 INFO - PID 7301 | |0;dojo.html;3293.7529685273603;3296.110050286423;3336.432806422908;3347.7991320520755;3359.2105855124055;6143.969280153593;6230.439260466557;6242.875142497153;6280.403361680645;6326.968365158168;6123.510119190465;6191.628502289855;6203.937960620392;6206.131141640174;6216.129741836146;5696.572757043228;5706.3722990471015;5724.54203663708;5737.885242295151;5745.425457454242;5746.051901436258;5754.367019627858;5767.653940763567;5775.884482310351;5829.708514574276;5779.682117483537;5800.187973683687;5823.097419899922;5847.941520584804;5848.385919478457;5680.091185410356;5762.308522977249;5819.73811178498;5823.213866128103;5824.854378640525;5543.25166102575;5564.1931919871795;5663.433656634304;5670.177824215466;5733.512651424634;6181.258249010102;6182.103594978747;6182.598131121463;6240.812775616693;6254.0618907163835;6639.435647969928;6681;6744.966275168593;6776.322367763184;6799.048133261347;6510.088587597693;6672.099266599044;6698.363655452724;6726.260111387744;6833.282505336968;6292.590981586182;6306.968465157691;6320.241571011462;6327.588706734094;6327.715252813635;6882.174139103339;6882.8623427531165;6962.582245065312;6966.512344135963;6969.895451568231;6577.736890524421;6582.901256481156;6618.7021584028835;6674.299198584079;6713.462922966151;6599.637019963852;6671.266160722316;6698.832529186712;6753.3921947025;6754.290799466084;6616.900746488806;6695.732170713117;6711.563748356341;6715.7985260442265;6720.630365329856;6660.300668429842;6664.8000559983275;6675.7997260082275;6756.121704178426;6757.729690812411;4882.487338829372;4909.754512274372;4954.083494553447;5007.273945277987;5015.84952451427;4335.891602709894;4414.668899832525;4454.309582014793;4454.443194600675;4479.664025198123;5738.655680659173;5767.913481297784;5768.020694959063;5854;5860.09168578871;6520.836979075466;6562.212534495891;6584.242812076577;6604.966975165094;6614.371634694697;5752.597318187771;5761;5784.045632470679;5793.594448388572;5798.623089499169;5689.175069614883;5703.287089113861;5707.71461426927;5713.342965559014;5764.020116580192;6509.121268628674;6537.117489139;6617.2059352877;6618.040384144272;6624.8343791405605;6619.50353723463;6646.567973081735;6663.933360666429;6735.797926062226;6755.121834161528;6407.647579382992;6458.451031662174;6483.967580162069;6494.740210391531;6517.967410162919;6553.770618028346;6562.114114594468;6585.604863708193;6631.3700198481065;6640.900386494303;6787.423069039187;6835.14560679915;6845.657717114224;6848.178218613798;6855.314468553304;6505.934940650723;6508.642024688687;6588.8023359299295;6601.768938087144;6603.372679595431;6457.354264573693;6475.546711730322;6512.837179070561;6550.017497375432;6567.376099270562;6426.678666066771;6449.097126402214;6453.967730161507;6469.644169570719;6484.059811327425;6535.379138981789;6558.639274839738;6593.142891424084;6600.438962688226;6619.801405957829;6199.039148931923;6291.087792270187;6301.558890877594;6315.621062736251;6396.552241343062 18:43:37 INFO - PID 7301 | |1;ext.html;845.8562044452442;869.9086595907429;894.2130924786192;895.4135041547786;1132.5526417065264;37955;41435.79282103585;41502.37746433806;41519.16961660765;41531;41590.58409415905;41832.07785781053;41924.79037604816;41952.370714439305;42025.57974420255;7645.694172233104;7775.88336174955;7803.648835802374;7809.8828517572;7813.296803287703;7716.884246736304;7823.335016523574;7833.6083195840265;7833.8824917626;7836.294733474015;7350.558966462003;7819.648115834772;7832;7946.046474423078;7959;7857.842843143134;7893.921060789376;7903.36773058154;7911;7929.682812687485;7671.194524574895;7742.8838567421535;7749.070111586646;7792.766217013499;7797;37581.43627845585;38039.61960380388;38137.42793858094;38141.61858381408;38154.80922595397;3488.0930957950864;3543.5747710274672;3545.2200515886466;3546.3616549021167;3547.592026916912;31913.680863191305;31946.520802187984;31960;31961.680383196337;32038.199045023826;5081.196175357769;5169.948300517022;5186.118359878792;5186.5332120109;5210.270562121306;171327;172010;173425;173828;174336;46976;47029;47090.76454617705;47095.29357059647;47135.05729885383;48566.5143348569;48568;48576.5142348579;48604.27093593599;48630.513694862595;15891.443799466962;16237.756433653505;16285.837141628668;16295.83704162967;16310.184490775413;13442.731145377038;13454.192748435125;13454.461821527226;13459;13480.5281815136;13944;14161.29193540319;14168;14222.928885355506;14332.211728354832;4923.040007198633;5974.2233509643465;5995.550333724901;6066.817995460055;6091.299500557404;998.2513115163628;1003.9648612298535;1005.2058873490007;1020.6784862768353;1027.3322340478749;22546.985385657685;22687.886560567422;22855.20006799754;22865.88567057154;22913.427164321027;1107.7507560798917;1115.0577761791274;1118.4016551145098;1125.2067292558727;1127.1264769803468;12097.455614497369;12112;12195.695107622203;12255;12289.324087175113;223434;225244;225402;225429;225917;48972;49286.75356623194;49479.25781113286;49808.750956244985;49970;1411.8094057302337;1460.664047269134;1465.4284828916732;1470.9264536773333;1479.7040591881666;21967.890160549734;22004.88997555002;22278.88860555687;22305;22667.20664776725;32458.513122302713;33135;35779.46330804988;35828;35878.461823073194;183566;183984;184253;184288;184346;33170.50244246387;33226.50160247646;33431.33137337289;33480.66519334873;33502.32995340031;37358.17222496772;41568.168636627706;42257;42298.78850605727;42323;31115.41012051069;31139.221519462193;31144.20718942477;31289.217769555944;31633.20916977094;30901;31472.84263578759;31577.526337104504;31654.366912661157;31725.841370792998;34848.65151348454;34878;34890.47664284987;35038.12404689801;35221.471677925365;30091.849540752155;30645.540316894818;30733;30806.845965770026;31169.8441507791;22711.07250890983;23020.194293200326;23297.767022330238;23529.058837646302;23559.528809424057 18:43:37 INFO - PID 7301 | |2;jquery.html;547.9095949168386;548.956083513319;562.7130163616554;565.9094544872819;569.8034178208519;21266.36200913973;21798.782012179872;22155.224567140132;22160.113595456183;22212.666809997823;24546;24926;25026.624600631007;25093.37266568332;25135.120270790525;6648.800535983916;6813.829654258659;6821.317868213164;6822.113125293705;6826.2149852766825;6045.425684559959;6368.649724265164;6613.537052406334;6638.767643132492;6653.8336541586605;6617.702203400859;6621.370969757865;6623.933760662404;6628.072069910217;6629.071929929813;6585.407313341774;6612.6693665317025;6614.173228346457;6619.834504137411;6625.271220165778;6025.367336429656;6064.332923378425;6078.209832721762;6100.267967843843;6138.631682099043;27073;27746.167614971582;28556.286092847637;28603.427931441256;28614.570781438295;1999.8100180482977;2030.0661695620033;2032.095717405755;2046.6520691482483;2055.5683306505653;18237;18700.90649546757;18716.251349945986;18718.71921921172;18744.62510749791;2451.644511545816;2465.1988103866315;2514.748525147489;2520.7227205007434;2532.734062923385;129096;129309;132198;132735;133049;14219.928900355431;14300.427982880568;14348.85651143496;14713.321669582605;14864.405423783144;21965.450863728216;22504.662430063563;22567.435814104778;22733.545329093326;22745.09019639229;10103.292769506064;10151.441670708035;10215.897841021495;10277.126444252173;10367.32612380193;9372.297077719199;9424.104710052534;9489.098535639103;9519.52402379892;9556.283278754121;8955.776105597412;9165.450072995642;9175.862362064574;9184.540772961325;9303.674371396968;2570.8971641134517;2626.2383908666275;2661.65398498194;2687.3953360494124;2712.32191952012;943.4527973775193;958.2286259560981;967.7677357434305;971.582219645545;979.6571200080058;19290;19434.551640436897;19669.50826229327;19886.204551817766;19904.50238744014;1027.2295778166376;1032.7108409645311;1047.7537778622018;1047.8323468245044;1050.5745173204864;10951.34291942486;10976.615818446317;11043.503042363112;11062.446877656084;11168.776624467466;451670;457061;457716;463039;465293;105079;107071;107082.46458767656;107785.46107269413;108580.45709771401;1182.1252273317682;1225.5036710132233;1245.0910835090435;1246.2148846227;1249.5064449542563;20851;21078.156873724878;21151.153953841673;21189;21272.787272127698;56141;56998.7150064247;57279.28162155142;57338.85322293447;58233;65702.67148664226;66289.33710662831;66514.66742666256;66637;67004.6649766748;14223.217723025333;14525.636859078608;14531.34608942579;14616.488422905148;14626.561203163923;22875.656865147368;23133.768662313836;23169.652455212847;23271.301860944197;23434.414139646644;17273.740893886352;18256.36102736398;18585.070746462352;19149.90425047866;19333.51666208356;16672.916635416746;17029.148542573068;17077.573060673087;17553.385631502835;17648.470545883643;17228;17237.913810430866;17241.310347585957;17861.642767144844;19761.703574446106;14159.787603185754;14424.927875360556;14460.06009609372;14467.78298325547;14481.203533805741;17067;17278.91360543189;18130.45608631743;18198.909005454887;18226.72659910076 18:43:37 INFO - PID 7301 | |3;mootools.html;1287.1633438265128;1342.946282148714;1348.6763176837562;1353.7089525751967;1365.7063731297771;14193;14743.336549855256;14809.481668141618;14820.703585928275;14832.703345933074;14020.298985050758;14528.709425811478;15413.306401211945;15414.306356213969;15428.15145167018;2627.829191102577;2709.7832173426077;2715.022591866926;2716.1579910227892;2721.632579601758;2622.4755048990182;2653.2968763277745;2674.9598756018677;2677.116551537988;2781.1795520321552;2720.6735191776916;2750.2986738381683;2754.118682021754;2755.641766570343;2757.6139340492346;2687.2878687147822;2689.3007817967273;2698.3254186453387;2700.8244464109866;2707.160780158157;2587.1462417402213;2588.2105957682897;2601.492708921761;2602.778763805079;2604.127617248224;13911.582652520441;14170.929145354206;14204.786928196085;14225.928870355685;14242.359093840802;7595.354394876442;7836.882446763303;7842.254985776342;7852.960735196345;7857.646405911691;5092.9236061459105;5206.297149884793;5366.114591092504;5436.646617969819;5459.945400545984;1926.5376309685576;1928.161249856303;1929.170456703617;1956.2664000999625;1970.2611520679745;25486.10798622039;25643.615345769642;25668.358291042874;25680.357991050187;25716.228513144637;6289.811305660837;6436.903446448307;6573.57271777333;6642.66786660665;6720.865582688417;16625.833741662427;16628.750568741478;16638.25127869249;16648.667026659397;16652.084135372428;7358.190599034102;7458.589777562286;7474.887876681854;7475.925240747523;7481.8877716834295;6775.119234499483;6889.138857642794;6897.965510172417;6926.584404935619;6965.79102626922;4803.3995750531185;4843.4430040545085;4939.876503087452;4942.654014219042;4972.005598880239;1235.4810979388678;1261.0416083776397;1314.8685131486966;1316.3089378076395;1366.5831921263912;891.8528442806994;892.5091199840062;913.7761248494162;916.7524768312518;921.5392303848076;7264.128304603481;7375.37309328713;7403;7461.365783908429;7541.283578060075;982.8869679986893;987.5457289646843;988.9851652225223;993.7366597851575;996.8604395384652;4427.380166776654;4497.347884556721;4566.748828814447;4570.085982803386;4601.838935637236;20287.797122028885;20857.791422085887;20948.47628809292;20975.685364719542;21001.369958901258;19856.60286794256;20176.798232017787;20748.170073197205;20884;20920.163193472396;11117.83323250168;11143.944280278547;11351.262167959056;11360.375179365214;11388.430578471043;8304.252617264476;8535;8535.615897284513;8576.742697719079;8618.181272779077;17703.93776373422;18137;18326.17532211027;18547.907260463613;18621.162047707614;17650.293988240326;18486.81513184905;18516.81483185151;18618.255269789057;18658.813411865707;11067.667969960914;11335.886641133484;11526.654200374001;11553.595624153451;11604.245724027911;13618.86381136176;13644.590662280147;13850.930745346208;13866.930665346608;13885.791713124108;10987.890121099006;11040.227184097035;11355.886441135483;11504.539818407506;11617.361045142598;10731.356118632906;10896.61861834832;11031.613893513688;11197.832032519356;11388.886111138783;9612.567434465325;9809.950950245204;9827.11555959967;9829.557669905014;9834.409935403899;7064;7066.152061752623;7091;7097.503174777716;7130.251323611154;7345;7414.62926853666;7462.328390444886;7503.437242206747;7533.3973282138395 18:43:37 INFO - PID 7301 | |4;prototype.html;494.0252295260779;497.37306420142625;515.6570880364558;516.8087807511221;526.9104252277115;6277.340879207686;6355.777547785829;6394.332137539593;6405;6447.936660268716;5981.401859814027;6116.694165291739;6178.536609754264;6211.906821397672;6232.532560058003;4922.433920099181;4970.478099799523;4988.825391111312;4999.425066117388;5006.349174607296;4756.90486190276;4901.362822833027;4977.253411988195;4982.202847544394;5032.345795046639;4927.236278376858;4957.529034741694;4978.228374601925;5002.974985125069;5050.520200580939;4890.258951901798;4925.26121081837;4929.457759646436;4982.334451068679;5016.799328026875;4441.733495990251;4473.217186992263;4505.662075344362;4507.332287053779;4521.796519156645;6162.198914141133;6184.628922264678;6221.471174950135;6234;6289.654069026202;5691.857703557403;5703.914441283343;5706.343770466408;5716.256886604757;5770.758492508004;6437.195350581177;6447.323031081716;6518.413342799124;6548.574342667759;6566.967165164191;4782.30656554801;4795.4964728703335;4841.322214889883;4887.3890763654545;4904.411470623548;6869.0806407276705;6882.793516194522;6960.269171736945;6967.651617419108;6975.895361569581;4926;4929.852104436873;5008.5993120550265;5013.498650134958;5033.697978121324;5061.569766569883;5089.185730283137;5109.156989096758;5109.463506331782;5125.205593133071;5647.830565083054;5673.858153546194;5688.132070192309;5757.453041961007;5788.565857560699;6480;6519.380658837403;6596.934030659632;6610.0745895574655;6629.734810607618;1615.273127092813;1676.8910020848605;1686;1706.2663054886518;1730.6711724772254;1161.872194058653;1192.791261529229;1207.335965219126;1215.9574414895437;1220.6521141474636;5654.886902261932;5658;5701.857453563694;5756.913646295309;5801.129830525455;6508.739650414025;6604.669766511655;6638.4357329626555;6781.932180678229;6783.830404239933;5703.315602127688;5811.774465171786;5831.446012628793;5851.122331650201;5851.970740146272;3541.982290088533;3568.946465803015;3574.463830425405;3600.891973240807;3601.441776524643;6540.574862634009;6577.704003319862;6580.967095164494;6619.669016549154;6652;6869.278725733826;6879.081456758342;6941.548799328028;6967.372936435745;6995.755148569776;6513.967430162914;6520.967395162994;6591.6374599397495;6604.702788374439;6692.89567221403;6652.434543063894;6670.499712521678;6679.8664026718225;6795.38841504267;6825.419839313714;6692.297308782702;6724.250765595346;6793.93206067933;6818.761343352959;6827.931720682928;6553.803385898431;6611.471082313499;6612.867742645024;6650.401463868275;6661.900071499028;4941.2835138905375;4978.825741099189;5009.724465154306;5035.647504674637;5041.7227052511;6464.967675161594;6768.932310677027;6793.622232218881;6857.622830744357;6905.723771049102;6028;6450.129232553733;6455.48356131499;6494.772682956074;6531.804045878631;6452.774152904626;6471.7734879279;6497.675116244263;6512.5766825156215;6608.074869518367;6369.33122022181;6393.456556192589;6401.8719625608155;6462.579932304385;6473.417392434703;6512.967435162794;6595.835104122435;6628.668566571749;6631.900521492085;6684.832879177865;6268.746250749777;6375.266844312963;6405.423511883952;6434.420902118832;6486.610803351814 18:43:37 INFO - PID 7301 | |5;yui.html;844.8394804987056;853.6158728572143;869.469623529647;895.5208963204686;896.018859349013;19069.332573359934;19231.423057308286;19606.215751369946;19755.802441975593;20415.183392664312;20511.897440512814;21144.89427552864;21210.57578848422;21316.786832131675;21363.359099227007;887.6626881784913;910.0535443139128;912.9908700912972;913.6025828764475;914.5518695839024;908.04655112132;955.3837774635356;960.1166926427703;960.1838437328284;960.6541645007792;982.8673129127584;987.6641941739807;988.2785566536397;989.4706332112321;989.6239429016999;969.4425705219492;980.4019548075668;981.1660088924393;986.5067466266867;986.551119240751;722.324626474243;727.3998950865504;730.5470608222936;731.334485618085;731.5281643340069;17322.82677173225;18299.63400731978;18869.811301886944;18964.715529267072;19065.237390504364;21085.578288434146;21210.151593936225;21367.893160534255;21727.45681357963;21735.347939561838;16554.503364899072;16838.410655627118;16896.746548801777;17001.404950826785;17007.744883826752;3702.907427314312;3715.6655900969045;3716.2195938852874;3735.34631439497;3736.0473079364588;122401.99999999911;123083.38458307741;126298.36850815779;126567;129775;16906;17199.312027518758;17667.55831104207;17678.734818977726;17736.201870915582;30005.549916751264;30311;30395;30467.542986855216;30712.846435768126;7480.625968701544;7891.6054197289905;8467.49195048299;8469.74590762278;8881.600327985256;4265.76538290397;4373.059792144704;4374.300111982131;4384.780760961939;4396.494403143615;5164.302819119371;5233.2935053768015;5271.630985830955;5310.946890531122;5322.760475778599;1027.6249169053392;1041.1254546181092;1055.5619417941468;1058.031900810765;1058.1481907064888;646.8221239159269;655.367570294668;659.5185514574388;672.6468603982948;672.909157263773;11729.35488548121;12070;12350;12956.546520871725;13372.331383430985;664.6444152378455;666.3170250493187;670.527278268829;672.3276723276723;684.3532861445985;5739.569532285095;5799.898019376305;5814.418558144236;5898.439648233411;6016.217891674054;105795;107108;107621.46189269003;109341.45329273462;111976;95834.52082739682;96767.51616242014;97629;99520.50239748755;99589;84679;84955.57522212472;85062.57468712617;86605.56697216474;86824;10605.151587873106;10657.25399222047;10755.946220268848;10850.403227822546;11077;39201.2159756809;39689.801550993216;40100.598994009684;40806;40845.38731919083;78831.60584197042;81895.590522047;84545.57727211571;84566;91031;18375.54061148482;19356.8064319355;19487.220511179396;19517;19596.412107636792;20814.79185208189;21112.366629001153;21121.366359009255;21333.253336133163;21621.24325648595;17886.105694715472;18214.72677909806;18345.449636510926;18728.531786704898;19463.70804437963;19351.90324048427;19392.8060719391;19397.612047758685;19572.412827615193;19934.50163745918;17751.201195946473;18081.276748929897;18143.364982225557;18255;18491.907540462213;12358.8563000169;12435.626931192079;12675;12836.679083022998;13013.47946082184;13253.138545994865;13340.26628535401;13370.598882033555;13383.53157639478;13455.192688438725 18:43:37 INFO - PID 7301 | __end_tp_report 18:43:37 INFO - PID 7301 | __start_cc_report 18:43:37 INFO - PID 7301 | _x_x_mozilla_cycle_collect,362 18:43:37 INFO - PID 7301 | __end_cc_report 18:43:37 INFO - PID 7301 | __startTimestamp1503971017979__endTimestamp 18:43:37 INFO - PID 7301 | 18:43:37 INFO - PID 7301 | ------- Summary: start ------- 18:43:37 INFO - PID 7301 | Number of tests: 6 18:43:37 INFO - PID 7301 | 18:43:37 INFO - PID 7301 | [#0] dojo.html Cycles:165 Average:6149.10 Median:6449.10 stddev:744.06 (11.5%) stddev-sans-first:711.83 18:43:37 INFO - PID 7301 | Values: 3293.8 3296.1 3336.4 3347.8 3359.2 6144.0 6230.4 6242.9 6280.4 6327.0 6123.5 6191.6 6203.9 6206.1 6216.1 5696.6 5706.4 5724.5 5737.9 5745.4 5746.1 5754.4 5767.7 5775.9 5829.7 5779.7 5800.2 5823.1 5847.9 5848.4 5680.1 5762.3 5819.7 5823.2 5824.9 5543.3 5564.2 5663.4 5670.2 5733.5 6181.3 6182.1 6182.6 6240.8 6254.1 6639.4 6681.0 6745.0 6776.3 6799.0 6510.1 6672.1 6698.4 6726.3 6833.3 6292.6 6307.0 6320.2 6327.6 6327.7 6882.2 6882.9 6962.6 6966.5 6969.9 6577.7 6582.9 6618.7 6674.3 6713.5 6599.6 6671.3 6698.8 6753.4 6754.3 6616.9 6695.7 6711.6 6715.8 6720.6 6660.3 6664.8 6675.8 6756.1 6757.7 4882.5 4909.8 4954.1 5007.3 5015.8 4335.9 4414.7 4454.3 4454.4 4479.7 5738.7 5767.9 5768.0 5854.0 5860.1 6520.8 6562.2 6584.2 6605.0 6614.4 5752.6 5761.0 5784.0 5793.6 5798.6 5689.2 5703.3 5707.7 5713.3 5764.0 6509.1 6537.1 6617.2 6618.0 6624.8 6619.5 6646.6 6663.9 6735.8 6755.1 6407.6 6458.5 6484.0 6494.7 6518.0 6553.8 6562.1 6585.6 6631.4 6640.9 6787.4 6835.1 6845.7 6848.2 6855.3 6505.9 6508.6 6588.8 6601.8 6603.4 6457.4 6475.5 6512.8 6550.0 6567.4 6426.7 6449.1 6454.0 6469.6 6484.1 6535.4 6558.6 6593.1 6600.4 6619.8 6199.0 6291.1 6301.6 6315.6 6396.6 18:43:37 INFO - PID 7301 | 18:43:37 INFO - PID 7301 | [#1] ext.html Cycles:180 Average:36104.86 Median:33491.50 stddev:50509.80 (150.8%) stddev-sans-first:50582.10 18:43:38 INFO - PID 7301 | Values: 845.9 869.9 894.2 895.4 1132.6 37955.0 41435.8 41502.4 41519.2 41531.0 41590.6 41832.1 41924.8 41952.4 42025.6 7645.7 7775.9 7803.6 7809.9 7813.3 7716.9 7823.3 7833.6 7833.9 7836.3 7350.6 7819.6 7832.0 7946.0 7959.0 7857.8 7893.9 7903.4 7911.0 7929.7 7671.2 7742.9 7749.1 7792.8 7797.0 37581.4 38039.6 38137.4 38141.6 38154.8 3488.1 3543.6 3545.2 3546.4 3547.6 31913.7 31946.5 31960.0 31961.7 32038.2 5081.2 5169.9 5186.1 5186.5 5210.3 171327.0 172010.0 173425.0 173828.0 174336.0 46976.0 47029.0 47090.8 47095.3 47135.1 48566.5 48568.0 48576.5 48604.3 48630.5 15891.4 16237.8 16285.8 16295.8 16310.2 13442.7 13454.2 13454.5 13459.0 13480.5 13944.0 14161.3 14168.0 14222.9 14332.2 4923.0 5974.2 5995.6 6066.8 6091.3 998.3 1004.0 1005.2 1020.7 1027.3 22547.0 22687.9 22855.2 22865.9 22913.4 1107.8 1115.1 1118.4 1125.2 1127.1 12097.5 12112.0 12195.7 12255.0 12289.3 223434.0 225244.0 225402.0 225429.0 225917.0 48972.0 49286.8 49479.3 49808.8 49970.0 1411.8 1460.7 1465.4 1470.9 1479.7 21967.9 22004.9 22278.9 22305.0 22667.2 32458.5 33135.0 35779.5 35828.0 35878.5 183566.0 183984.0 184253.0 184288.0 184346.0 33170.5 33226.5 33431.3 33480.7 33502.3 37358.2 41568.2 42257.0 42298.8 42323.0 31115.4 31139.2 31144.2 31289.2 31633.2 30901.0 31472.8 31577.5 31654.4 31725.8 34848.7 34878.0 34890.5 35038.1 35221.5 30091.8 30645.5 30733.0 30806.8 31169.8 22711.1 23020.2 23297.8 23529.1 23559.5 18:43:38 INFO - PID 7301 | 18:43:38 INFO - PID 7301 | [#2] jquery.html Cycles:180 Average:33311.22 Median:22358.66 stddev:77273.27 (345.6%) stddev-sans-first:77450.89 18:43:38 INFO - PID 7301 | Values: 547.9 549.0 562.7 565.9 569.8 21266.4 21798.8 22155.2 22160.1 22212.7 24546.0 24926.0 25026.6 25093.4 25135.1 6648.8 6813.8 6821.3 6822.1 6826.2 6045.4 6368.6 6613.5 6638.8 6653.8 6617.7 6621.4 6623.9 6628.1 6629.1 6585.4 6612.7 6614.2 6619.8 6625.3 6025.4 6064.3 6078.2 6100.3 6138.6 27073.0 27746.2 28556.3 28603.4 28614.6 1999.8 2030.1 2032.1 2046.7 2055.6 18237.0 18700.9 18716.3 18718.7 18744.6 2451.6 2465.2 2514.7 2520.7 2532.7 129096.0 129309.0 132198.0 132735.0 133049.0 14219.9 14300.4 14348.9 14713.3 14864.4 21965.5 22504.7 22567.4 22733.5 22745.1 10103.3 10151.4 10215.9 10277.1 10367.3 9372.3 9424.1 9489.1 9519.5 9556.3 8955.8 9165.5 9175.9 9184.5 9303.7 2570.9 2626.2 2661.7 2687.4 2712.3 943.5 958.2 967.8 971.6 979.7 19290.0 19434.6 19669.5 19886.2 19904.5 1027.2 1032.7 1047.8 1047.8 1050.6 10951.3 10976.6 11043.5 11062.4 11168.8 451670.0 457061.0 457716.0 463039.0 465293.0 105079.0 107071.0 107082.5 107785.5 108580.5 1182.1 1225.5 1245.1 1246.2 1249.5 20851.0 21078.2 21151.2 21189.0 21272.8 56141.0 56998.7 57279.3 57338.9 58233.0 65702.7 66289.3 66514.7 66637.0 67004.7 14223.2 14525.6 14531.3 14616.5 14626.6 22875.7 23133.8 23169.7 23271.3 23434.4 17273.7 18256.4 18585.1 19149.9 19333.5 16672.9 17029.1 17077.6 17553.4 17648.5 17228.0 17237.9 17241.3 17861.6 19761.7 14159.8 14424.9 14460.1 14467.8 14481.2 17067.0 17278.9 18130.5 18198.9 18226.7 18:43:38 INFO - PID 7301 | 18:43:38 INFO - PID 7301 | [#3] mootools.html Cycles:180 Average:9067.50 Median:2625.15 stddev:6453.88 (245.8%) stddev-sans-first:6445.51 18:43:38 INFO - PID 7301 | Values: 1287.2 1342.9 1348.7 1353.7 1365.7 14193.0 14743.3 14809.5 14820.7 14832.7 14020.3 14528.7 15413.3 15414.3 15428.2 2627.8 2709.8 2715.0 2716.2 2721.6 2622.5 2653.3 2675.0 2677.1 2781.2 2720.7 2750.3 2754.1 2755.6 2757.6 2687.3 2689.3 2698.3 2700.8 2707.2 2587.1 2588.2 2601.5 2602.8 2604.1 13911.6 14170.9 14204.8 14225.9 14242.4 7595.4 7836.9 7842.3 7853.0 7857.6 5092.9 5206.3 5366.1 5436.6 5459.9 1926.5 1928.2 1929.2 1956.3 1970.3 25486.1 25643.6 25668.4 25680.4 25716.2 6289.8 6436.9 6573.6 6642.7 6720.9 16625.8 16628.8 16638.3 16648.7 16652.1 7358.2 7458.6 7474.9 7475.9 7481.9 6775.1 6889.1 6898.0 6926.6 6965.8 4803.4 4843.4 4939.9 4942.7 4972.0 1235.5 1261.0 1314.9 1316.3 1366.6 891.9 892.5 913.8 916.8 921.5 7264.1 7375.4 7403.0 7461.4 7541.3 982.9 987.5 989.0 993.7 996.9 4427.4 4497.3 4566.7 4570.1 4601.8 20287.8 20857.8 20948.5 20975.7 21001.4 19856.6 20176.8 20748.2 20884.0 20920.2 11117.8 11143.9 11351.3 11360.4 11388.4 8304.3 8535.0 8535.6 8576.7 8618.2 17703.9 18137.0 18326.2 18547.9 18621.2 17650.3 18486.8 18516.8 18618.3 18658.8 11067.7 11335.9 11526.7 11553.6 11604.2 13618.9 13644.6 13850.9 13866.9 13885.8 10987.9 11040.2 11355.9 11504.5 11617.4 10731.4 10896.6 11031.6 11197.8 11388.9 9612.6 9810.0 9827.1 9829.6 9834.4 7064.0 7066.2 7091.0 7097.5 7130.3 7345.0 7414.6 7462.3 7503.4 7533.4 18:43:38 INFO - PID 7301 | 18:43:38 INFO - PID 7301 | [#4] prototype.html Cycles:180 Average:5524.73 Median:6198.27 stddev:1564.55 (25.2%) stddev-sans-first:1522.70 18:43:38 INFO - PID 7301 | Values: 494.0 497.4 515.7 516.8 526.9 6277.3 6355.8 6394.3 6405.0 6447.9 5981.4 6116.7 6178.5 6211.9 6232.5 4922.4 4970.5 4988.8 4999.4 5006.3 4756.9 4901.4 4977.3 4982.2 5032.3 4927.2 4957.5 4978.2 5003.0 5050.5 4890.3 4925.3 4929.5 4982.3 5016.8 4441.7 4473.2 4505.7 4507.3 4521.8 6162.2 6184.6 6221.5 6234.0 6289.7 5691.9 5703.9 5706.3 5716.3 5770.8 6437.2 6447.3 6518.4 6548.6 6567.0 4782.3 4795.5 4841.3 4887.4 4904.4 6869.1 6882.8 6960.3 6967.7 6975.9 4926.0 4929.9 5008.6 5013.5 5033.7 5061.6 5089.2 5109.2 5109.5 5125.2 5647.8 5673.9 5688.1 5757.5 5788.6 6480.0 6519.4 6596.9 6610.1 6629.7 1615.3 1676.9 1686.0 1706.3 1730.7 1161.9 1192.8 1207.3 1216.0 1220.7 5654.9 5658.0 5701.9 5756.9 5801.1 6508.7 6604.7 6638.4 6781.9 6783.8 5703.3 5811.8 5831.4 5851.1 5852.0 3542.0 3568.9 3574.5 3600.9 3601.4 6540.6 6577.7 6581.0 6619.7 6652.0 6869.3 6879.1 6941.5 6967.4 6995.8 6514.0 6521.0 6591.6 6604.7 6692.9 6652.4 6670.5 6679.9 6795.4 6825.4 6692.3 6724.3 6793.9 6818.8 6827.9 6553.8 6611.5 6612.9 6650.4 6661.9 4941.3 4978.8 5009.7 5035.6 5041.7 6465.0 6768.9 6793.6 6857.6 6905.7 6028.0 6450.1 6455.5 6494.8 6531.8 6452.8 6471.8 6497.7 6512.6 6608.1 6369.3 6393.5 6401.9 6462.6 6473.4 6513.0 6595.8 6628.7 6631.9 6684.8 6268.7 6375.3 6405.4 6434.4 6486.6 18:43:38 INFO - PID 7301 | 18:43:38 INFO - PID 7301 | [#5] yui.html Cycles:180 Average:24120.50 Median:30158.27 stddev:32587.79 (108.1%) stddev-sans-first:32632.34 18:43:38 INFO - PID 7301 | Values: 844.8 853.6 869.5 895.5 896.0 19069.3 19231.4 19606.2 19755.8 20415.2 20511.9 21144.9 21210.6 21316.8 21363.4 887.7 910.1 913.0 913.6 914.6 908.0 955.4 960.1 960.2 960.7 982.9 987.7 988.3 989.5 989.6 969.4 980.4 981.2 986.5 986.6 722.3 727.4 730.5 731.3 731.5 17322.8 18299.6 18869.8 18964.7 19065.2 21085.6 21210.2 21367.9 21727.5 21735.3 16554.5 16838.4 16896.7 17001.4 17007.7 3702.9 3715.7 3716.2 3735.3 3736.0 122402.0 123083.4 126298.4 126567.0 129775.0 16906.0 17199.3 17667.6 17678.7 17736.2 30005.5 30311.0 30395.0 30467.5 30712.8 7480.6 7891.6 8467.5 8469.7 8881.6 4265.8 4373.1 4374.3 4384.8 4396.5 5164.3 5233.3 5271.6 5310.9 5322.8 1027.6 1041.1 1055.6 1058.0 1058.1 646.8 655.4 659.5 672.6 672.9 11729.4 12070.0 12350.0 12956.5 13372.3 664.6 666.3 670.5 672.3 684.4 5739.6 5799.9 5814.4 5898.4 6016.2 105795.0 107108.0 107621.5 109341.5 111976.0 95834.5 96767.5 97629.0 99520.5 99589.0 84679.0 84955.6 85062.6 86605.6 86824.0 10605.2 10657.3 10755.9 10850.4 11077.0 39201.2 39689.8 40100.6 40806.0 40845.4 78831.6 81895.6 84545.6 84566.0 91031.0 18375.5 19356.8 19487.2 19517.0 19596.4 20814.8 21112.4 21121.4 21333.3 21621.2 17886.1 18214.7 18345.4 18728.5 19463.7 19351.9 19392.8 19397.6 19572.4 19934.5 17751.2 18081.3 18143.4 18255.0 18491.9 12358.9 12435.6 12675.0 12836.7 13013.5 13253.1 13340.3 13370.6 13383.5 13455.2 18:43:38 INFO - PID 7301 | -------- Summary: end -------- 18:43:38 INFO - PID 7301 | 18:43:38 INFO - TEST-INFO | 7301: exit 0 18:43:38 INFO - TEST-OK | dromaeo_css | took 1110942ms 18:43:38 INFO - TEST-START | kraken 18:43:38 INFO - Initialising browser for kraken test... 18:43:38 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:53378/getInfo.html -profile /tmp/tmpUAOVlH/profile 18:43:38 INFO - TEST-INFO | started process 7675 (/builds/slave/test/build/application/firefox/firefox http://localhost:53378/getInfo.html) 18:43:44 INFO - TEST-INFO | 7675: exit 0 18:43:44 INFO - Browser initialized. 18:43:44 INFO - Running cycle 1/1 for kraken test... 18:43:44 INFO - Using env: {'DISPLAY': ':0', 18:43:44 INFO - 'HOME': '/home/cltbld', 18:43:44 INFO - 'JSGC_DISABLE_POISONING': '1', 18:43:44 INFO - 'LANG': 'en_US.UTF-8', 18:43:44 INFO - 'LANGUAGE': 'en_US:en', 18:43:44 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 18:43:44 INFO - 'LOGNAME': 'cltbld', 18:43:44 INFO - 'MAIL': '/var/mail/cltbld', 18:43:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:43:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:43:44 INFO - 'MOZ_CRASHREPORTER': '1', 18:43:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:43:44 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 18:43:44 INFO - 'MOZ_NO_REMOTE': '1', 18:43:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:43:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:43:44 INFO - 'NO_EM_RESTART': '1', 18:43:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:43:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:43:44 INFO - 'PWD': '/builds/slave/test', 18:43:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:43:44 INFO - 'RUST_BACKTRACE': 'full', 18:43:44 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:43:44 INFO - 'SHELL': '/bin/bash', 18:43:44 INFO - 'SHLVL': '1', 18:43:44 INFO - 'STYLO_FORCE_ENABLED': '1', 18:43:44 INFO - 'TERM': 'linux', 18:43:44 INFO - 'TMOUT': '86400', 18:43:44 INFO - 'USER': 'cltbld', 18:43:44 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1503964946.701655-1770442037', 18:43:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:43:44 INFO - '_': '/tools/buildbot/bin/python'} 18:43:44 INFO - TEST-INFO | started process 7860 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpUAOVlH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:43:45 INFO - PID 7860 | 18:43:45 INFO - PID 7860 | (/builds/slave/test/build/application/firefox/firefox:7909): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:43:45 INFO - PID 7860 | 18:43:55 INFO - PID 7860 | RSS: Main: 170176512 18:43:55 INFO - PID 7860 | 18:43:58 INFO - PID 7860 | Cycle 1(1): loaded http://localhost:53378/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53378/tests/kraken/driver.html?testName=audio-beat-detection) 18:43:59 INFO - PID 7860 | RSS: Main: 174944256 18:43:59 INFO - PID 7860 | 18:44:02 INFO - PID 7860 | Cycle 1(1): loaded http://localhost:53378/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53378/tests/kraken/driver.html?testName=audio-dft) 18:44:02 INFO - PID 7860 | RSS: Main: 175443968 18:44:02 INFO - PID 7860 | 18:44:05 INFO - PID 7860 | Cycle 1(1): loaded http://localhost:53378/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53378/tests/kraken/driver.html?testName=audio-fft) 18:44:06 INFO - PID 7860 | RSS: Main: 169816064 18:44:06 INFO - PID 7860 | 18:44:08 INFO - PID 7860 | Cycle 1(1): loaded http://localhost:53378/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53378/tests/kraken/driver.html?testName=audio-oscillator) 18:44:09 INFO - PID 7860 | RSS: Main: 170422272 18:44:09 INFO - PID 7860 | 18:44:11 INFO - PID 7860 | Cycle 1(1): loaded http://localhost:53378/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53378/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:44:11 INFO - PID 7860 | RSS: Main: 171081728 18:44:11 INFO - PID 7860 | 18:44:14 INFO - PID 7860 | Cycle 1(1): loaded http://localhost:53378/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53378/tests/kraken/driver.html?testName=imaging-darkroom) 18:44:15 INFO - PID 7860 | RSS: Main: 173584384 18:44:15 INFO - PID 7860 | 18:44:19 INFO - PID 7860 | Cycle 1(1): loaded http://localhost:53378/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53378/tests/kraken/driver.html?testName=imaging-desaturate) 18:44:19 INFO - PID 7860 | RSS: Main: 173408256 18:44:19 INFO - PID 7860 | 18:44:22 INFO - PID 7860 | Cycle 1(1): loaded http://localhost:53378/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53378/tests/kraken/driver.html?testName=json-parse-financial) 18:44:23 INFO - PID 7860 | RSS: Main: 173330432 18:44:23 INFO - PID 7860 | 18:44:24 INFO - PID 7860 | Cycle 1(1): loaded http://localhost:53378/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53378/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:44:25 INFO - PID 7860 | RSS: Main: 173432832 18:44:25 INFO - PID 7860 | 18:44:27 INFO - PID 7860 | Cycle 1(1): loaded http://localhost:53378/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53378/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:44:27 INFO - PID 7860 | RSS: Main: 173817856 18:44:27 INFO - PID 7860 | 18:44:29 INFO - PID 7860 | Cycle 1(1): loaded http://localhost:53378/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53378/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:44:30 INFO - PID 7860 | RSS: Main: 173805568 18:44:30 INFO - PID 7860 | 18:44:32 INFO - PID 7860 | Cycle 1(1): loaded http://localhost:53378/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53378/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:44:33 INFO - PID 7860 | RSS: Main: 174141440 18:44:33 INFO - PID 7860 | 18:44:36 INFO - PID 7860 | Cycle 1(1): loaded http://localhost:53378/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53378/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:44:37 INFO - PID 7860 | RSS: Main: 174452736 18:44:37 INFO - PID 7860 | 18:44:39 INFO - PID 7860 | Cycle 1(1): loaded http://localhost:53378/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53378/tests/kraken/driver.html?testName=ai-astar) 18:44:39 INFO - PID 7860 | RSS: Main: 175935488 18:44:39 INFO - PID 7860 | 18:44:39 INFO - PID 7860 | __start_tp_report 18:44:39 INFO - PID 7860 | _x_x_mozilla_page_load 18:44:39 INFO - PID 7860 | _x_x_mozilla_page_load_details 18:44:39 INFO - PID 7860 | |i|pagename|runs| 18:44:39 INFO - PID 7860 | |0;ai-astar;103;106;94;107;108;111;109;107;108;108 18:44:39 INFO - PID 7860 | |1;audio-beat-detection;152;116;142;173;124;147;146;115;168;120 18:44:39 INFO - PID 7860 | |2;audio-dft;161;162;163;161;162;161;165;166;161;163 18:44:39 INFO - PID 7860 | |3;audio-fft;75;74;74;164;75;74;75;73;151;79 18:44:39 INFO - PID 7860 | |4;audio-oscillator;78;77;127;82;79;78;132;78;80;124 18:44:39 INFO - PID 7860 | |5;imaging-gaussian-blur;113;113;114;114;114;115;114;114;114;113 18:44:39 INFO - PID 7860 | |6;imaging-darkroom;148;148;147;153;147;148;147;147;147;148 18:44:39 INFO - PID 7860 | |7;imaging-desaturate;94;94;99;94;94;93;94;94;94;95 18:44:39 INFO - PID 7860 | |8;json-parse-financial;51;51;52;51;51;52;52;51;52;51 18:44:39 INFO - PID 7860 | |9;json-stringify-tinderbox;38;38;37;38;37;37;38;38;38;38 18:44:39 INFO - PID 7860 | |10;stanford-crypto-aes;75;76;75;73;74;76;78;74;75;73 18:44:39 INFO - PID 7860 | |11;stanford-crypto-ccm;133;115;117;122;119;124;123;114;112;158 18:44:39 INFO - PID 7860 | |12;stanford-crypto-pbkdf2;171;171;168;181;172;193;174;180;168;168 18:44:39 INFO - PID 7860 | |13;stanford-crypto-sha256-iterative;64;63;68;64;64;65;66;62;63;64 18:44:39 INFO - PID 7860 | __end_tp_report 18:44:39 INFO - PID 7860 | __start_cc_report 18:44:39 INFO - PID 7860 | _x_x_mozilla_cycle_collect,935 18:44:39 INFO - PID 7860 | __end_cc_report 18:44:39 INFO - PID 7860 | __startTimestamp1503971079371__endTimestamp 18:44:39 INFO - PID 7860 | 18:44:39 INFO - PID 7860 | ------- Summary: start ------- 18:44:39 INFO - PID 7860 | Number of tests: 14 18:44:39 INFO - PID 7860 | 18:44:39 INFO - PID 7860 | [#0] ai-astar Cycles:10 Average:106.10 Median:108.00 stddev:4.72 (4.4%) stddev-sans-first:4.88 18:44:39 INFO - PID 7860 | Values: 103.0 106.0 94.0 107.0 108.0 111.0 109.0 107.0 108.0 108.0 18:44:39 INFO - PID 7860 | 18:44:39 INFO - PID 7860 | [#1] audio-beat-detection Cycles:10 Average:140.30 Median:146.50 stddev:20.98 (14.3%) stddev-sans-first:21.82 18:44:39 INFO - PID 7860 | Values: 152.0 116.0 142.0 173.0 124.0 147.0 146.0 115.0 168.0 120.0 18:44:39 INFO - PID 7860 | 18:44:39 INFO - PID 7860 | [#2] audio-dft Cycles:10 Average:162.50 Median:162.50 stddev:1.78 (1.1%) stddev-sans-first:1.80 18:44:39 INFO - PID 7860 | Values: 161.0 162.0 163.0 161.0 162.0 161.0 165.0 166.0 161.0 163.0 18:44:39 INFO - PID 7860 | 18:44:39 INFO - PID 7860 | [#3] audio-fft Cycles:10 Average:91.40 Median:74.50 stddev:35.01 (47.0%) stddev-sans-first:36.63 18:44:39 INFO - PID 7860 | Values: 75.0 74.0 74.0 164.0 75.0 74.0 75.0 73.0 151.0 79.0 18:44:39 INFO - PID 7860 | 18:44:39 INFO - PID 7860 | [#4] audio-oscillator Cycles:10 Average:93.50 Median:78.00 stddev:23.69 (30.4%) stddev-sans-first:24.46 18:44:39 INFO - PID 7860 | Values: 78.0 77.0 127.0 82.0 79.0 78.0 132.0 78.0 80.0 124.0 18:44:39 INFO - PID 7860 | 18:44:39 INFO - PID 7860 | [#5] imaging-gaussian-blur Cycles:10 Average:113.80 Median:114.00 stddev:0.63 (0.6%) stddev-sans-first:0.60 18:44:39 INFO - PID 7860 | Values: 113.0 113.0 114.0 114.0 114.0 115.0 114.0 114.0 114.0 113.0 18:44:39 INFO - PID 7860 | 18:44:39 INFO - PID 7860 | [#6] imaging-darkroom Cycles:10 Average:148.00 Median:148.00 stddev:1.83 (1.2%) stddev-sans-first:1.94 18:44:39 INFO - PID 7860 | Values: 148.0 148.0 147.0 153.0 147.0 148.0 147.0 147.0 147.0 148.0 18:44:39 INFO - PID 7860 | 18:44:39 INFO - PID 7860 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:94.00 stddev:1.65 (1.8%) stddev-sans-first:1.74 18:44:39 INFO - PID 7860 | Values: 94.0 94.0 99.0 94.0 94.0 93.0 94.0 94.0 94.0 95.0 18:44:39 INFO - PID 7860 | 18:44:39 INFO - PID 7860 | [#8] json-parse-financial Cycles:10 Average:51.40 Median:51.50 stddev:0.52 (1.0%) stddev-sans-first:0.53 18:44:39 INFO - PID 7860 | Values: 51.0 51.0 52.0 51.0 51.0 52.0 52.0 51.0 52.0 51.0 18:44:39 INFO - PID 7860 | 18:44:39 INFO - PID 7860 | [#9] json-stringify-tinderbox Cycles:10 Average:37.70 Median:38.00 stddev:0.48 (1.3%) stddev-sans-first:0.50 18:44:39 INFO - PID 7860 | Values: 38.0 38.0 37.0 38.0 37.0 37.0 38.0 38.0 38.0 38.0 18:44:39 INFO - PID 7860 | 18:44:39 INFO - PID 7860 | [#10] stanford-crypto-aes Cycles:10 Average:74.90 Median:75.00 stddev:1.52 (2.0%) stddev-sans-first:1.62 18:44:39 INFO - PID 7860 | Values: 75.0 76.0 75.0 73.0 74.0 76.0 78.0 74.0 75.0 73.0 18:44:39 INFO - PID 7860 | 18:44:39 INFO - PID 7860 | [#11] stanford-crypto-ccm Cycles:10 Average:123.70 Median:122.50 stddev:13.50 (11.0%) stddev-sans-first:13.89 18:44:39 INFO - PID 7860 | Values: 133.0 115.0 117.0 122.0 119.0 124.0 123.0 114.0 112.0 158.0 18:44:39 INFO - PID 7860 | 18:44:39 INFO - PID 7860 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.60 Median:173.00 stddev:7.97 (4.6%) stddev-sans-first:8.35 18:44:39 INFO - PID 7860 | Values: 171.0 171.0 168.0 181.0 172.0 193.0 174.0 180.0 168.0 168.0 18:44:39 INFO - PID 7860 | 18:44:39 INFO - PID 7860 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.30 Median:64.00 stddev:1.70 (2.7%) stddev-sans-first:1.80 18:44:39 INFO - PID 7860 | Values: 64.0 63.0 68.0 64.0 64.0 65.0 66.0 62.0 63.0 64.0 18:44:39 INFO - PID 7860 | -------- Summary: end -------- 18:44:39 INFO - PID 7860 | 18:44:39 INFO - PID 7860 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 18:44:39 INFO - TEST-INFO | 7860: exit 0 18:44:40 INFO - TEST-OK | kraken | took 61291ms 18:44:40 INFO - SUITE-END | took 1172s 18:44:40 INFO - Completed test suite (00:19:32) 18:44:40 INFO - javascript benchmark 18:44:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8463.325684526719, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3293.7529685273603, 3296.110050286423, 3336.432806422908, 3347.7991320520755, 3359.2105855124055, 6143.969280153593, 6230.439260466557, 6242.875142497153, 6280.403361680645, 6326.968365158168, 6123.510119190465, 6191.628502289855, 6203.937960620392, 6206.131141640174, 6216.129741836146, 5696.572757043228, 5706.3722990471015, 5724.54203663708, 5737.885242295151, 5745.425457454242, 5746.051901436258, 5754.367019627858, 5767.653940763567, 5775.884482310351, 5829.708514574276, 5779.682117483537, 5800.187973683687, 5823.097419899922, 5847.941520584804, 5848.385919478457, 5680.091185410356, 5762.308522977249, 5819.73811178498, 5823.213866128103, 5824.854378640525, 5543.25166102575, 5564.1931919871795, 5663.433656634304, 5670.177824215466, 5733.512651424634, 6181.258249010102, 6182.103594978747, 6182.598131121463, 6240.812775616693, 6254.0618907163835, 6639.435647969928, 6681.0, 6744.966275168593, 6776.322367763184, 6799.048133261347, 6510.088587597693, 6672.099266599044, 6698.363655452724, 6726.260111387744, 6833.282505336968, 6292.590981586182, 6306.968465157691, 6320.241571011462, 6327.588706734094, 6327.715252813635, 6882.174139103339, 6882.8623427531165, 6962.582245065312, 6966.512344135963, 6969.895451568231, 6577.736890524421, 6582.901256481156, 6618.7021584028835, 6674.299198584079, 6713.462922966151, 6599.637019963852, 6671.266160722316, 6698.832529186712, 6753.3921947025, 6754.290799466084, 6616.900746488806, 6695.732170713117, 6711.563748356341, 6715.7985260442265, 6720.630365329856, 6660.300668429842, 6664.8000559983275, 6675.7997260082275, 6756.121704178426, 6757.729690812411, 4882.487338829372, 4909.754512274372, 4954.083494553447, 5007.273945277987, 5015.84952451427, 4335.891602709894, 4414.668899832525, 4454.309582014793, 4454.443194600675, 4479.664025198123, 5738.655680659173, 5767.913481297784, 5768.020694959063, 5854.0, 5860.09168578871, 6520.836979075466, 6562.212534495891, 6584.242812076577, 6604.966975165094, 6614.371634694697, 5752.597318187771, 5761.0, 5784.045632470679, 5793.594448388572, 5798.623089499169, 5689.175069614883, 5703.287089113861, 5707.71461426927, 5713.342965559014, 5764.020116580192, 6509.121268628674, 6537.117489139, 6617.2059352877, 6618.040384144272, 6624.8343791405605, 6619.50353723463, 6646.567973081735, 6663.933360666429, 6735.797926062226, 6755.121834161528, 6407.647579382992, 6458.451031662174, 6483.967580162069, 6494.740210391531, 6517.967410162919, 6553.770618028346, 6562.114114594468, 6585.604863708193, 6631.3700198481065, 6640.900386494303, 6787.423069039187, 6835.14560679915, 6845.657717114224, 6848.178218613798, 6855.314468553304, 6505.934940650723, 6508.642024688687, 6588.8023359299295, 6601.768938087144, 6603.372679595431, 6457.354264573693, 6475.546711730322, 6512.837179070561, 6550.017497375432, 6567.376099270562, 6426.678666066771, 6449.097126402214, 6453.967730161507, 6469.644169570719, 6484.059811327425, 6535.379138981789, 6558.639274839738, 6593.142891424084, 6600.438962688226, 6619.801405957829, 6199.039148931923, 6291.087792270187, 6301.558890877594, 6315.621062736251, 6396.552241343062], "value": 6093.66654073998, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [845.8562044452442, 869.9086595907429, 894.2130924786192, 895.4135041547786, 1132.5526417065264, 37955.0, 41435.79282103585, 41502.37746433806, 41519.16961660765, 41531.0, 41590.58409415905, 41832.07785781053, 41924.79037604816, 41952.370714439305, 42025.57974420255, 7645.694172233104, 7775.88336174955, 7803.648835802374, 7809.8828517572, 7813.296803287703, 7716.884246736304, 7823.335016523574, 7833.6083195840265, 7833.8824917626, 7836.294733474015, 7350.558966462003, 7819.648115834772, 7832.0, 7946.046474423078, 7959.0, 7857.842843143134, 7893.921060789376, 7903.36773058154, 7911.0, 7929.682812687485, 7671.194524574895, 7742.8838567421535, 7749.070111586646, 7792.766217013499, 7797.0, 37581.43627845585, 38039.61960380388, 38137.42793858094, 38141.61858381408, 38154.80922595397, 3488.0930957950864, 3543.5747710274672, 3545.2200515886466, 3546.3616549021167, 3547.592026916912, 31913.680863191305, 31946.520802187984, 31960.0, 31961.680383196337, 32038.199045023826, 5081.196175357769, 5169.948300517022, 5186.118359878792, 5186.5332120109, 5210.270562121306, 171327.0, 172010.0, 173425.0, 173828.0, 174336.0, 46976.0, 47029.0, 47090.76454617705, 47095.29357059647, 47135.05729885383, 48566.5143348569, 48568.0, 48576.5142348579, 48604.27093593599, 48630.513694862595, 15891.443799466962, 16237.756433653505, 16285.837141628668, 16295.83704162967, 16310.184490775413, 13442.731145377038, 13454.192748435125, 13454.461821527226, 13459.0, 13480.5281815136, 13944.0, 14161.29193540319, 14168.0, 14222.928885355506, 14332.211728354832, 4923.040007198633, 5974.2233509643465, 5995.550333724901, 6066.817995460055, 6091.299500557404, 998.2513115163628, 1003.9648612298535, 1005.2058873490007, 1020.6784862768353, 1027.3322340478749, 22546.985385657685, 22687.886560567422, 22855.20006799754, 22865.88567057154, 22913.427164321027, 1107.7507560798917, 1115.0577761791274, 1118.4016551145098, 1125.2067292558727, 1127.1264769803468, 12097.455614497369, 12112.0, 12195.695107622203, 12255.0, 12289.324087175113, 223434.0, 225244.0, 225402.0, 225429.0, 225917.0, 48972.0, 49286.75356623194, 49479.25781113286, 49808.750956244985, 49970.0, 1411.8094057302337, 1460.664047269134, 1465.4284828916732, 1470.9264536773333, 1479.7040591881666, 21967.890160549734, 22004.88997555002, 22278.88860555687, 22305.0, 22667.20664776725, 32458.513122302713, 33135.0, 35779.46330804988, 35828.0, 35878.461823073194, 183566.0, 183984.0, 184253.0, 184288.0, 184346.0, 33170.50244246387, 33226.50160247646, 33431.33137337289, 33480.66519334873, 33502.32995340031, 37358.17222496772, 41568.168636627706, 42257.0, 42298.78850605727, 42323.0, 31115.41012051069, 31139.221519462193, 31144.20718942477, 31289.217769555944, 31633.20916977094, 30901.0, 31472.84263578759, 31577.526337104504, 31654.366912661157, 31725.841370792998, 34848.65151348454, 34878.0, 34890.47664284987, 35038.12404689801, 35221.471677925365, 30091.849540752155, 30645.540316894818, 30733.0, 30806.845965770026, 31169.8441507791, 22711.07250890983, 23020.194293200326, 23297.767022330238, 23529.058837646302, 23559.528809424057], "value": 16945.643817384007, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [547.9095949168386, 548.956083513319, 562.7130163616554, 565.9094544872819, 569.8034178208519, 21266.36200913973, 21798.782012179872, 22155.224567140132, 22160.113595456183, 22212.666809997823, 24546.0, 24926.0, 25026.624600631007, 25093.37266568332, 25135.120270790525, 6648.800535983916, 6813.829654258659, 6821.317868213164, 6822.113125293705, 6826.2149852766825, 6045.425684559959, 6368.649724265164, 6613.537052406334, 6638.767643132492, 6653.8336541586605, 6617.702203400859, 6621.370969757865, 6623.933760662404, 6628.072069910217, 6629.071929929813, 6585.407313341774, 6612.6693665317025, 6614.173228346457, 6619.834504137411, 6625.271220165778, 6025.367336429656, 6064.332923378425, 6078.209832721762, 6100.267967843843, 6138.631682099043, 27073.0, 27746.167614971582, 28556.286092847637, 28603.427931441256, 28614.570781438295, 1999.8100180482977, 2030.0661695620033, 2032.095717405755, 2046.6520691482483, 2055.5683306505653, 18237.0, 18700.90649546757, 18716.251349945986, 18718.71921921172, 18744.62510749791, 2451.644511545816, 2465.1988103866315, 2514.748525147489, 2520.7227205007434, 2532.734062923385, 129096.0, 129309.0, 132198.0, 132735.0, 133049.0, 14219.928900355431, 14300.427982880568, 14348.85651143496, 14713.321669582605, 14864.405423783144, 21965.450863728216, 22504.662430063563, 22567.435814104778, 22733.545329093326, 22745.09019639229, 10103.292769506064, 10151.441670708035, 10215.897841021495, 10277.126444252173, 10367.32612380193, 9372.297077719199, 9424.104710052534, 9489.098535639103, 9519.52402379892, 9556.283278754121, 8955.776105597412, 9165.450072995642, 9175.862362064574, 9184.540772961325, 9303.674371396968, 2570.8971641134517, 2626.2383908666275, 2661.65398498194, 2687.3953360494124, 2712.32191952012, 943.4527973775193, 958.2286259560981, 967.7677357434305, 971.582219645545, 979.6571200080058, 19290.0, 19434.551640436897, 19669.50826229327, 19886.204551817766, 19904.50238744014, 1027.2295778166376, 1032.7108409645311, 1047.7537778622018, 1047.8323468245044, 1050.5745173204864, 10951.34291942486, 10976.615818446317, 11043.503042363112, 11062.446877656084, 11168.776624467466, 451670.0, 457061.0, 457716.0, 463039.0, 465293.0, 105079.0, 107071.0, 107082.46458767656, 107785.46107269413, 108580.45709771401, 1182.1252273317682, 1225.5036710132233, 1245.0910835090435, 1246.2148846227, 1249.5064449542563, 20851.0, 21078.156873724878, 21151.153953841673, 21189.0, 21272.787272127698, 56141.0, 56998.7150064247, 57279.28162155142, 57338.85322293447, 58233.0, 65702.67148664226, 66289.33710662831, 66514.66742666256, 66637.0, 67004.6649766748, 14223.217723025333, 14525.636859078608, 14531.34608942579, 14616.488422905148, 14626.561203163923, 22875.656865147368, 23133.768662313836, 23169.652455212847, 23271.301860944197, 23434.414139646644, 17273.740893886352, 18256.36102736398, 18585.070746462352, 19149.90425047866, 19333.51666208356, 16672.916635416746, 17029.148542573068, 17077.573060673087, 17553.385631502835, 17648.470545883643, 17228.0, 17237.913810430866, 17241.310347585957, 17861.642767144844, 19761.703574446106, 14159.787603185754, 14424.927875360556, 14460.06009609372, 14467.78298325547, 14481.203533805741, 17067.0, 17278.91360543189, 18130.45608631743, 18198.909005454887, 18226.72659910076], "value": 11881.697555607392, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1287.1633438265128, 1342.946282148714, 1348.6763176837562, 1353.7089525751967, 1365.7063731297771, 14193.0, 14743.336549855256, 14809.481668141618, 14820.703585928275, 14832.703345933074, 14020.298985050758, 14528.709425811478, 15413.306401211945, 15414.306356213969, 15428.15145167018, 2627.829191102577, 2709.7832173426077, 2715.022591866926, 2716.1579910227892, 2721.632579601758, 2622.4755048990182, 2653.2968763277745, 2674.9598756018677, 2677.116551537988, 2781.1795520321552, 2720.6735191776916, 2750.2986738381683, 2754.118682021754, 2755.641766570343, 2757.6139340492346, 2687.2878687147822, 2689.3007817967273, 2698.3254186453387, 2700.8244464109866, 2707.160780158157, 2587.1462417402213, 2588.2105957682897, 2601.492708921761, 2602.778763805079, 2604.127617248224, 13911.582652520441, 14170.929145354206, 14204.786928196085, 14225.928870355685, 14242.359093840802, 7595.354394876442, 7836.882446763303, 7842.254985776342, 7852.960735196345, 7857.646405911691, 5092.9236061459105, 5206.297149884793, 5366.114591092504, 5436.646617969819, 5459.945400545984, 1926.5376309685576, 1928.161249856303, 1929.170456703617, 1956.2664000999625, 1970.2611520679745, 25486.10798622039, 25643.615345769642, 25668.358291042874, 25680.357991050187, 25716.228513144637, 6289.811305660837, 6436.903446448307, 6573.57271777333, 6642.66786660665, 6720.865582688417, 16625.833741662427, 16628.750568741478, 16638.25127869249, 16648.667026659397, 16652.084135372428, 7358.190599034102, 7458.589777562286, 7474.887876681854, 7475.925240747523, 7481.8877716834295, 6775.119234499483, 6889.138857642794, 6897.965510172417, 6926.584404935619, 6965.79102626922, 4803.3995750531185, 4843.4430040545085, 4939.876503087452, 4942.654014219042, 4972.005598880239, 1235.4810979388678, 1261.0416083776397, 1314.8685131486966, 1316.3089378076395, 1366.5831921263912, 891.8528442806994, 892.5091199840062, 913.7761248494162, 916.7524768312518, 921.5392303848076, 7264.128304603481, 7375.37309328713, 7403.0, 7461.365783908429, 7541.283578060075, 982.8869679986893, 987.5457289646843, 988.9851652225223, 993.7366597851575, 996.8604395384652, 4427.380166776654, 4497.347884556721, 4566.748828814447, 4570.085982803386, 4601.838935637236, 20287.797122028885, 20857.791422085887, 20948.47628809292, 20975.685364719542, 21001.369958901258, 19856.60286794256, 20176.798232017787, 20748.170073197205, 20884.0, 20920.163193472396, 11117.83323250168, 11143.944280278547, 11351.262167959056, 11360.375179365214, 11388.430578471043, 8304.252617264476, 8535.0, 8535.615897284513, 8576.742697719079, 8618.181272779077, 17703.93776373422, 18137.0, 18326.17532211027, 18547.907260463613, 18621.162047707614, 17650.293988240326, 18486.81513184905, 18516.81483185151, 18618.255269789057, 18658.813411865707, 11067.667969960914, 11335.886641133484, 11526.654200374001, 11553.595624153451, 11604.245724027911, 13618.86381136176, 13644.590662280147, 13850.930745346208, 13866.930665346608, 13885.791713124108, 10987.890121099006, 11040.227184097035, 11355.886441135483, 11504.539818407506, 11617.361045142598, 10731.356118632906, 10896.61861834832, 11031.613893513688, 11197.832032519356, 11388.886111138783, 9612.567434465325, 9809.950950245204, 9827.11555959967, 9829.557669905014, 9834.409935403899, 7064.0, 7066.152061752623, 7091.0, 7097.503174777716, 7130.251323611154, 7345.0, 7414.62926853666, 7462.328390444886, 7503.437242206747, 7533.3973282138395], "value": 6495.504903102056, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [494.0252295260779, 497.37306420142625, 515.6570880364558, 516.8087807511221, 526.9104252277115, 6277.340879207686, 6355.777547785829, 6394.332137539593, 6405.0, 6447.936660268716, 5981.401859814027, 6116.694165291739, 6178.536609754264, 6211.906821397672, 6232.532560058003, 4922.433920099181, 4970.478099799523, 4988.825391111312, 4999.425066117388, 5006.349174607296, 4756.90486190276, 4901.362822833027, 4977.253411988195, 4982.202847544394, 5032.345795046639, 4927.236278376858, 4957.529034741694, 4978.228374601925, 5002.974985125069, 5050.520200580939, 4890.258951901798, 4925.26121081837, 4929.457759646436, 4982.334451068679, 5016.799328026875, 4441.733495990251, 4473.217186992263, 4505.662075344362, 4507.332287053779, 4521.796519156645, 6162.198914141133, 6184.628922264678, 6221.471174950135, 6234.0, 6289.654069026202, 5691.857703557403, 5703.914441283343, 5706.343770466408, 5716.256886604757, 5770.758492508004, 6437.195350581177, 6447.323031081716, 6518.413342799124, 6548.574342667759, 6566.967165164191, 4782.30656554801, 4795.4964728703335, 4841.322214889883, 4887.3890763654545, 4904.411470623548, 6869.0806407276705, 6882.793516194522, 6960.269171736945, 6967.651617419108, 6975.895361569581, 4926.0, 4929.852104436873, 5008.5993120550265, 5013.498650134958, 5033.697978121324, 5061.569766569883, 5089.185730283137, 5109.156989096758, 5109.463506331782, 5125.205593133071, 5647.830565083054, 5673.858153546194, 5688.132070192309, 5757.453041961007, 5788.565857560699, 6480.0, 6519.380658837403, 6596.934030659632, 6610.0745895574655, 6629.734810607618, 1615.273127092813, 1676.8910020848605, 1686.0, 1706.2663054886518, 1730.6711724772254, 1161.872194058653, 1192.791261529229, 1207.335965219126, 1215.9574414895437, 1220.6521141474636, 5654.886902261932, 5658.0, 5701.857453563694, 5756.913646295309, 5801.129830525455, 6508.739650414025, 6604.669766511655, 6638.4357329626555, 6781.932180678229, 6783.830404239933, 5703.315602127688, 5811.774465171786, 5831.446012628793, 5851.122331650201, 5851.970740146272, 3541.982290088533, 3568.946465803015, 3574.463830425405, 3600.891973240807, 3601.441776524643, 6540.574862634009, 6577.704003319862, 6580.967095164494, 6619.669016549154, 6652.0, 6869.278725733826, 6879.081456758342, 6941.548799328028, 6967.372936435745, 6995.755148569776, 6513.967430162914, 6520.967395162994, 6591.6374599397495, 6604.702788374439, 6692.89567221403, 6652.434543063894, 6670.499712521678, 6679.8664026718225, 6795.38841504267, 6825.419839313714, 6692.297308782702, 6724.250765595346, 6793.93206067933, 6818.761343352959, 6827.931720682928, 6553.803385898431, 6611.471082313499, 6612.867742645024, 6650.401463868275, 6661.900071499028, 4941.2835138905375, 4978.825741099189, 5009.724465154306, 5035.647504674637, 5041.7227052511, 6464.967675161594, 6768.932310677027, 6793.622232218881, 6857.622830744357, 6905.723771049102, 6028.0, 6450.129232553733, 6455.48356131499, 6494.772682956074, 6531.804045878631, 6452.774152904626, 6471.7734879279, 6497.675116244263, 6512.5766825156215, 6608.074869518367, 6369.33122022181, 6393.456556192589, 6401.8719625608155, 6462.579932304385, 6473.417392434703, 6512.967435162794, 6595.835104122435, 6628.668566571749, 6631.900521492085, 6684.832879177865, 6268.746250749777, 6375.266844312963, 6405.423511883952, 6434.420902118832, 6486.610803351814], "value": 5059.359201461397, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [844.8394804987056, 853.6158728572143, 869.469623529647, 895.5208963204686, 896.018859349013, 19069.332573359934, 19231.423057308286, 19606.215751369946, 19755.802441975593, 20415.183392664312, 20511.897440512814, 21144.89427552864, 21210.57578848422, 21316.786832131675, 21363.359099227007, 887.6626881784913, 910.0535443139128, 912.9908700912972, 913.6025828764475, 914.5518695839024, 908.04655112132, 955.3837774635356, 960.1166926427703, 960.1838437328284, 960.6541645007792, 982.8673129127584, 987.6641941739807, 988.2785566536397, 989.4706332112321, 989.6239429016999, 969.4425705219492, 980.4019548075668, 981.1660088924393, 986.5067466266867, 986.551119240751, 722.324626474243, 727.3998950865504, 730.5470608222936, 731.334485618085, 731.5281643340069, 17322.82677173225, 18299.63400731978, 18869.811301886944, 18964.715529267072, 19065.237390504364, 21085.578288434146, 21210.151593936225, 21367.893160534255, 21727.45681357963, 21735.347939561838, 16554.503364899072, 16838.410655627118, 16896.746548801777, 17001.404950826785, 17007.744883826752, 3702.907427314312, 3715.6655900969045, 3716.2195938852874, 3735.34631439497, 3736.0473079364588, 122401.99999999911, 123083.38458307741, 126298.36850815779, 126567.0, 129775.0, 16906.0, 17199.312027518758, 17667.55831104207, 17678.734818977726, 17736.201870915582, 30005.549916751264, 30311.0, 30395.0, 30467.542986855216, 30712.846435768126, 7480.625968701544, 7891.6054197289905, 8467.49195048299, 8469.74590762278, 8881.600327985256, 4265.76538290397, 4373.059792144704, 4374.300111982131, 4384.780760961939, 4396.494403143615, 5164.302819119371, 5233.2935053768015, 5271.630985830955, 5310.946890531122, 5322.760475778599, 1027.6249169053392, 1041.1254546181092, 1055.5619417941468, 1058.031900810765, 1058.1481907064888, 646.8221239159269, 655.367570294668, 659.5185514574388, 672.6468603982948, 672.909157263773, 11729.35488548121, 12070.0, 12350.0, 12956.546520871725, 13372.331383430985, 664.6444152378455, 666.3170250493187, 670.527278268829, 672.3276723276723, 684.3532861445985, 5739.569532285095, 5799.898019376305, 5814.418558144236, 5898.439648233411, 6016.217891674054, 105795.0, 107108.0, 107621.46189269003, 109341.45329273462, 111976.0, 95834.52082739682, 96767.51616242014, 97629.0, 99520.50239748755, 99589.0, 84679.0, 84955.57522212472, 85062.57468712617, 86605.56697216474, 86824.0, 10605.151587873106, 10657.25399222047, 10755.946220268848, 10850.403227822546, 11077.0, 39201.2159756809, 39689.801550993216, 40100.598994009684, 40806.0, 40845.38731919083, 78831.60584197042, 81895.590522047, 84545.57727211571, 84566.0, 91031.0, 18375.54061148482, 19356.8064319355, 19487.220511179396, 19517.0, 19596.412107636792, 20814.79185208189, 21112.366629001153, 21121.366359009255, 21333.253336133163, 21621.24325648595, 17886.105694715472, 18214.72677909806, 18345.449636510926, 18728.531786704898, 19463.70804437963, 19351.90324048427, 19392.8060719391, 19397.612047758685, 19572.412827615193, 19934.50163745918, 17751.201195946473, 18081.276748929897, 18143.364982225557, 18255.0, 18491.907540462213, 12358.8563000169, 12435.626931192079, 12675.0, 12836.679083022998, 13013.47946082184, 13253.138545994865, 13340.26628535401, 13370.598882033555, 13383.53157639478, 13455.192688438725], "value": 9113.742058273254, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1476.6999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 106.0, 94.0, 107.0, 108.0, 111.0, 109.0, 107.0, 108.0, 108.0], "value": 106.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 116.0, 142.0, 173.0, 124.0, 147.0, 146.0, 115.0, 168.0, 120.0], "value": 140.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 163.0, 161.0, 162.0, 161.0, 165.0, 166.0, 161.0, 163.0], "value": 162.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 74.0, 164.0, 75.0, 74.0, 75.0, 73.0, 151.0, 79.0], "value": 91.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 77.0, 127.0, 82.0, 79.0, 78.0, 132.0, 78.0, 80.0, 124.0], "value": 93.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 114.0, 114.0, 114.0, 115.0, 114.0, 114.0, 114.0, 113.0], "value": 113.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 147.0, 153.0, 147.0, 148.0, 147.0, 147.0, 147.0, 148.0], "value": 148.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 99.0, 94.0, 94.0, 93.0, 94.0, 94.0, 94.0, 95.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 51.0, 52.0, 51.0, 51.0, 52.0, 52.0, 51.0, 52.0, 51.0], "value": 51.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 38.0, 37.0, 38.0, 37.0, 37.0, 38.0, 38.0, 38.0, 38.0], "value": 37.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 73.0, 74.0, 76.0, 78.0, 74.0, 75.0, 73.0], "value": 74.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 115.0, 117.0, 122.0, 119.0, 124.0, 123.0, 114.0, 112.0, 158.0], "value": 123.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 171.0, 168.0, 181.0, 172.0, 193.0, 174.0, 180.0, 168.0, 168.0], "value": 174.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 68.0, 64.0, 64.0, 65.0, 66.0, 62.0, 63.0, 64.0], "value": 64.3, "unit": "score"}]}]} 18:44:40 INFO - Return code: 0 18:44:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:44:40 INFO - # TBPL SUCCESS # 18:44:40 INFO - Running post-action listener: _package_coverage_data 18:44:40 INFO - Running post-action listener: _resource_record_post_action 18:44:40 INFO - [mozharness: 2017-08-29 01:44:40.672977Z] Finished run-tests step (success) 18:44:40 INFO - Running post-run listener: _resource_record_post_run 18:44:40 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 499712; Write bytes: 338276352; Read time: 76; Write time: 265776 18:44:40 INFO - TinderboxPrint: CPU usage
13.1% 18:44:40 INFO - TinderboxPrint: I/O read bytes / time
499,712 / 76 18:44:40 INFO - TinderboxPrint: I/O write bytes / time
338,276,352 / 265,776 18:44:40 INFO - TinderboxPrint: CPU idle
8,253.3 (86.9%) 18:44:40 INFO - TinderboxPrint: CPU user
1,193.4 (12.6%) 18:44:40 INFO - TinderboxPrint: Swap in / out
0 / 0 18:44:40 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 14405632; Read time: 0; Write time: 1288 18:44:40 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 18:44:40 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 495616; Write bytes: 323870720; Read time: 64; Write time: 264488 18:44:41 INFO - Running post-run listener: _upload_blobber_files 18:44:41 INFO - Blob upload gear active. 18:44:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:44:41 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 18:44:41 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'] 18:44:41 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 18:44:41 INFO - (blobuploader) - INFO - Open directory for files ... 18:44:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:44:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:44:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:44:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:44:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:44:42 INFO - (blobuploader) - INFO - Done attempting. 18:44:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:44:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:44:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:44:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:44:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:44:43 INFO - (blobuploader) - INFO - Done attempting. 18:44:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:44:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:44:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:44:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:44:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:44:47 INFO - (blobuploader) - INFO - Done attempting. 18:44:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:44:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:44:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:44:48 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:44:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:44:48 INFO - (blobuploader) - INFO - Done attempting. 18:44:48 INFO - (blobuploader) - INFO - Iteration through files over. 18:44:48 INFO - Return code: 0 18:44:48 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:44:48 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:44:48 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/47674a39af47bbc604a4ba3b13a1202b8d2e842a4ab179aaba7bd0caec97ca40aacf853bca18263f5aa666462694dbf8dc09c01e5e12decf7d12be6a9d7e4e61", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5fca8e08d7b91c44365da41551748e602e2121171f5fd04694798fe03eddf20e343e841a604fbae03e29349a61388db30692748c5ac6e638546b51d804fc1b55", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/54e3f8e4706c1c99314ff68b1f08d813ecb60aecee82d36370d9df20efdb46b94d8109afb2afb5d556d753b833042e159077946bde427206b86a44d57ff6a0e9", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9d4de355384b55d28522922cfe4833ff326546068c5d8cb60007e0451b43df581ebf843c7c62a75d06c2f184738170679ba3a27d2c1efa53e5ac81a48f742a4e"} 18:44:48 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:44:48 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:44:48 INFO - Contents: 18:44:48 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/47674a39af47bbc604a4ba3b13a1202b8d2e842a4ab179aaba7bd0caec97ca40aacf853bca18263f5aa666462694dbf8dc09c01e5e12decf7d12be6a9d7e4e61", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5fca8e08d7b91c44365da41551748e602e2121171f5fd04694798fe03eddf20e343e841a604fbae03e29349a61388db30692748c5ac6e638546b51d804fc1b55", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/54e3f8e4706c1c99314ff68b1f08d813ecb60aecee82d36370d9df20efdb46b94d8109afb2afb5d556d753b833042e159077946bde427206b86a44d57ff6a0e9", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9d4de355384b55d28522922cfe4833ff326546068c5d8cb60007e0451b43df581ebf843c7c62a75d06c2f184738170679ba3a27d2c1efa53e5ac81a48f742a4e"} 18:44:48 INFO - Running post-run listener: copy_logs_to_upload_dir 18:44:48 INFO - Copying logs to upload dir... 18:44:48 INFO - mkdir: /builds/slave/test/build/upload/logs 18:44:48 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.626106 ========= master_lag: -0.10 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2017-08-28 18:44:48.400281) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-28 18:44:48.407882) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=62c568580acbfdaac3eec7be000001dc-1503964946.701655-1770442037 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/47674a39af47bbc604a4ba3b13a1202b8d2e842a4ab179aaba7bd0caec97ca40aacf853bca18263f5aa666462694dbf8dc09c01e5e12decf7d12be6a9d7e4e61", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5fca8e08d7b91c44365da41551748e602e2121171f5fd04694798fe03eddf20e343e841a604fbae03e29349a61388db30692748c5ac6e638546b51d804fc1b55", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/54e3f8e4706c1c99314ff68b1f08d813ecb60aecee82d36370d9df20efdb46b94d8109afb2afb5d556d753b833042e159077946bde427206b86a44d57ff6a0e9", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9d4de355384b55d28522922cfe4833ff326546068c5d8cb60007e0451b43df581ebf843c7c62a75d06c2f184738170679ba3a27d2c1efa53e5ac81a48f742a4e"} build_url:https://queue.taskcluster.net/v1/task/A3hmUCGWRMeTZ6zQMbT4dg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011411 build_url: 'https://queue.taskcluster.net/v1/task/A3hmUCGWRMeTZ6zQMbT4dg/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/47674a39af47bbc604a4ba3b13a1202b8d2e842a4ab179aaba7bd0caec97ca40aacf853bca18263f5aa666462694dbf8dc09c01e5e12decf7d12be6a9d7e4e61", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5fca8e08d7b91c44365da41551748e602e2121171f5fd04694798fe03eddf20e343e841a604fbae03e29349a61388db30692748c5ac6e638546b51d804fc1b55", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/54e3f8e4706c1c99314ff68b1f08d813ecb60aecee82d36370d9df20efdb46b94d8109afb2afb5d556d753b833042e159077946bde427206b86a44d57ff6a0e9", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9d4de355384b55d28522922cfe4833ff326546068c5d8cb60007e0451b43df581ebf843c7c62a75d06c2f184738170679ba3a27d2c1efa53e5ac81a48f742a4e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-28 18:44:48.440812) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-28 18:44:48.441511) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=62c568580acbfdaac3eec7be000001dc-1503964946.701655-1770442037 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004493 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-28 18:44:48.524753) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-28 18:44:48.525167) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-28 18:44:48.525574) ========= ========= Total master_lag: 0.07 =========