builder: mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s slave: talos-linux64-ix-001 starttime: 1503882916.09 results: success (0) revision: 7aec8d3eaa72838f1d83767f46dcfcae8de510ff ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-27 18:15:16.095073) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-27 18:15:16.095522) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-27 18:15:16.095795) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-27 18:15:16.144623) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-27 18:15:16.144894) ========= 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=8c32c1c60be582960b75045f000001c1-1503873263.99089-675433845 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-27 18:15:16-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 21.8M=0.001s 2017-08-27 18:15:16 (21.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.118762 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-27 18:15:16.291951) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-27 18:15:16.292255) ========= 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=8c32c1c60be582960b75045f000001c1-1503873263.99089-675433845 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.024105 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-27 18:15:16.349865) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 13 secs) (at 2017-08-27 18:15:16.350163) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 7aec8d3eaa72838f1d83767f46dcfcae8de510ff --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 7aec8d3eaa72838f1d83767f46dcfcae8de510ff --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=8c32c1c60be582960b75045f000001c1-1503873263.99089-675433845 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-27 18:15:16,582 truncating revision to first 12 chars 2017-08-27 18:15:16,583 Setting DEBUG logging. 2017-08-27 18:15:16,583 attempt 1/10 2017-08-27 18:15:16,583 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/7aec8d3eaa72?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-27 18:15:17,095 attempt 1/10 2017-08-27 18:15:17,810 current task status: no status available at this point. state: STARTED 2017-08-27 18:15:17,811 sleeping for 11.00s (attempt 1/10) 2017-08-27 18:15:28,822 attempt 2/10 2017-08-27 18:15:29,134 current task status: Task completed! Check 's3_urls' for upload locations. state: SUCCESS 2017-08-27 18:15:29,363 unpacking tar archive at: mozilla-central-7aec8d3eaa72/testing/mozharness/ program finished with exit code 0 elapsedTime=13.187925 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 13 secs) (at 2017-08-27 18:15:29.566419) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-27 18:15:29.566726) ========= script_repo_revision: 7aec8d3eaa72838f1d83767f46dcfcae8de510ff ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-27 18:15:29.567137) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-27 18:15:29.570460) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-27 18:15:29.585723) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 54 secs) (at 2017-08-27 18:15:29.586027) ========= /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=8c32c1c60be582960b75045f000001c1-1503873263.99089-675433845 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:15:29 INFO - MultiFileLogger online at 20170827 18:15:29 in /builds/slave/test 18:15:29 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:15:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:15:29 INFO - {'append_to_log': False, 18:15:29 INFO - 'base_work_dir': '/builds/slave/test', 18:15:29 INFO - 'blob_upload_branch': 'Firefox', 18:15:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:15:29 INFO - 'branch': 'Firefox', 18:15:29 INFO - 'buildbot_json_path': 'buildprops.json', 18:15:29 INFO - 'code_coverage': False, 18:15:29 INFO - 'config_files': ('talos/linux_config.py',), 18:15:29 INFO - 'default_actions': ('clobber', 18:15:29 INFO - 'read-buildbot-config', 18:15:29 INFO - 'download-and-extract', 18:15:29 INFO - 'populate-webroot', 18:15:29 INFO - 'create-virtualenv', 18:15:29 INFO - 'install', 18:15:29 INFO - 'setup-mitmproxy', 18:15:29 INFO - 'run-tests'), 18:15:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:15:29 INFO - 'disable_ccov_upload': False, 18:15:29 INFO - 'download_minidump_stackwalk': True, 18:15:29 INFO - 'download_symbols': 'ondemand', 18:15:29 INFO - 'enable_stylo': False, 18:15:29 INFO - 'enable_webrender': False, 18:15:29 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 18:15:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:15:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:15:29 INFO - 'gecko_profile': False, 18:15:29 INFO - 'gecko_profile_interval': 0, 18:15:29 INFO - 'installer_path': 'installer.exe', 18:15:29 INFO - 'log_level': 'info', 18:15:29 INFO - 'log_name': 'talos', 18:15:29 INFO - 'log_to_console': True, 18:15:29 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:15:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:15:29 INFO - 'opt_config_files': (), 18:15:29 INFO - 'pip_index': False, 18:15:29 INFO - 'suite': 'dromaeojs-e10s', 18:15:29 INFO - 'system_bits': '32', 18:15:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:15:29 INFO - 'title': 'talos-linux64-ix-001', 18:15:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:15:29 INFO - 'use_talos_json': True, 18:15:29 INFO - 'verify': 'False', 18:15:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:15:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:15:29 INFO - 'work_dir': 'build'} 18:15:29 INFO - [mozharness: 2017-08-28 01:15:29.952069Z] Running clobber step. 18:15:29 INFO - Running pre-action listener: _resource_record_pre_action 18:15:29 INFO - Running main action method: clobber 18:15:29 INFO - rmtree: /builds/slave/test/build 18:15:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:15:30 INFO - Running post-action listener: _resource_record_post_action 18:15:30 INFO - [mozharness: 2017-08-28 01:15:30.971972Z] Finished clobber step (success) 18:15:30 INFO - [mozharness: 2017-08-28 01:15:30.972071Z] Running read-buildbot-config step. 18:15:30 INFO - Running pre-action listener: _resource_record_pre_action 18:15:30 INFO - Running main action method: read_buildbot_config 18:15:30 INFO - Using buildbot properties: 18:15:30 INFO - { 18:15:30 INFO - "project": "", 18:15:30 INFO - "product": "firefox", 18:15:30 INFO - "who": "philringnalda@gmail.com", 18:15:30 INFO - "installer_path": "public/build/target.tar.bz2", 18:15:30 INFO - "builddir": "mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s", 18:15:30 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 18:15:30 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo mozilla-central talos dromaeojs-e10s", 18:15:30 INFO - "stage_platform": "linux64-stylo", 18:15:30 INFO - "basedir": "/builds/slave/test", 18:15:30 INFO - "buildnumber": 59, 18:15:30 INFO - "platform": "ubuntu64_hw_stylo", 18:15:30 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 18:15:30 INFO - "slavebuilddir": "test", 18:15:30 INFO - "taskId": "I7X-if90SmKDrGSq0C_fCA", 18:15:30 INFO - "branch": "mozilla-central", 18:15:30 INFO - "script_repo_revision": "production", 18:15:30 INFO - "revision": "7aec8d3eaa72838f1d83767f46dcfcae8de510ff", 18:15:30 INFO - "slavename": "talos-linux64-ix-001", 18:15:30 INFO - "repo_path": "mozilla-central" 18:15:30 INFO - } 18:15:30 INFO - Finding installer, test and symbols from parent task. 18:15:30 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/I7X-if90SmKDrGSq0C_fCA'}, attempt #1 18:15:31 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/I7X-if90SmKDrGSq0C_fCA'}, attempt #1 18:15:31 INFO - Task dependencies: fJ4-8OdcRqSrwqD2wKF_lQ 18:15:31 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fJ4-8OdcRqSrwqD2wKF_lQ'}, attempt #1 18:15:32 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/fJ4-8OdcRqSrwqD2wKF_lQ/artifacts/public/build/target.tar.bz2 18:15:32 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/fJ4-8OdcRqSrwqD2wKF_lQ/artifacts/public/build/target.test_packages.json 18:15:32 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/fJ4-8OdcRqSrwqD2wKF_lQ/artifacts/public/build/target.crashreporter-symbols.zip 18:15:32 INFO - Running post-action listener: _resource_record_post_action 18:15:32 INFO - [mozharness: 2017-08-28 01:15:32.318580Z] Finished read-buildbot-config step (success) 18:15:32 INFO - [mozharness: 2017-08-28 01:15:32.318796Z] Running download-and-extract step. 18:15:32 INFO - Running pre-action listener: _resource_record_pre_action 18:15:32 INFO - Running main action method: download_and_extract 18:15:32 INFO - mkdir: /builds/slave/test/build/tests 18:15: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:15:32 INFO - trying https://queue.taskcluster.net/v1/task/fJ4-8OdcRqSrwqD2wKF_lQ/artifacts/public/build/target.test_packages.json 18:15:32 INFO - Downloading https://queue.taskcluster.net/v1/task/fJ4-8OdcRqSrwqD2wKF_lQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 18:15:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fJ4-8OdcRqSrwqD2wKF_lQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 18:15:32 INFO - Downloaded 989 bytes. 18:15:32 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 18:15:32 INFO - Using the following test package requirements: 18:15:32 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 18:15:32 INFO - u'common': [u'target.common.tests.zip'], 18:15:32 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 18:15:32 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 18:15:32 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 18:15:32 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 18:15:32 INFO - u'mozbase': [u'target.common.tests.zip'], 18:15:32 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 18:15:32 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 18:15:32 INFO - u'web-platform': [u'target.common.tests.zip', 18:15:32 INFO - u'target.web-platform.tests.tar.gz'], 18:15:32 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 18:15:32 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 18:15:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fJ4-8OdcRqSrwqD2wKF_lQ/artifacts/public/build/target.common.tests.zip 18:15:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fJ4-8OdcRqSrwqD2wKF_lQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:15:32 INFO - Fetch https://queue.taskcluster.net/v1/task/fJ4-8OdcRqSrwqD2wKF_lQ/artifacts/public/build/target.common.tests.zip into memory 18:15:33 INFO - Content-Length response header: 54977876 18:15:33 INFO - Bytes received: 54977876 18:15:45 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 18:15:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fJ4-8OdcRqSrwqD2wKF_lQ/artifacts/public/build/target.common.tests.zip 18:15:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fJ4-8OdcRqSrwqD2wKF_lQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:15:45 INFO - Fetch https://queue.taskcluster.net/v1/task/fJ4-8OdcRqSrwqD2wKF_lQ/artifacts/public/build/target.common.tests.zip into memory 18:15:46 INFO - Content-Length response header: 54977876 18:15:46 INFO - Bytes received: 54977876 18:15:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fJ4-8OdcRqSrwqD2wKF_lQ/artifacts/public/build/target.talos.tests.zip 18:15:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fJ4-8OdcRqSrwqD2wKF_lQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 18:15:58 INFO - Fetch https://queue.taskcluster.net/v1/task/fJ4-8OdcRqSrwqD2wKF_lQ/artifacts/public/build/target.talos.tests.zip into memory 18:15:59 INFO - Content-Length response header: 14496611 18:15:59 INFO - Bytes received: 14496611 18:16:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:00 INFO - trying https://queue.taskcluster.net/v1/task/fJ4-8OdcRqSrwqD2wKF_lQ/artifacts/public/build/target.tar.bz2 18:16:00 INFO - Downloading https://queue.taskcluster.net/v1/task/fJ4-8OdcRqSrwqD2wKF_lQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 18:16:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fJ4-8OdcRqSrwqD2wKF_lQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 18:16:32 WARNING - Socket error when accessing https://queue.taskcluster.net/v1/task/fJ4-8OdcRqSrwqD2wKF_lQ/artifacts/public/build/target.tar.bz2: The read operation timed out 18:16:32 INFO - retry: attempt #1 caught SSLError exception: The read operation timed out 18:16:32 INFO - retry: Failed, sleeping 30 seconds before retrying 18:17:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fJ4-8OdcRqSrwqD2wKF_lQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #2 18:17:03 INFO - Downloaded 61264127 bytes. 18:17:03 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fJ4-8OdcRqSrwqD2wKF_lQ/artifacts/public/build/target.tar.bz2 18:17:03 INFO - mkdir: /builds/slave/test/properties 18:17:03 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:17:03 INFO - Writing to file /builds/slave/test/properties/build_url 18:17:03 INFO - Contents: 18:17:03 INFO - build_url:https://queue.taskcluster.net/v1/task/fJ4-8OdcRqSrwqD2wKF_lQ/artifacts/public/build/target.tar.bz2 18:17:03 INFO - Running post-action listener: _resource_record_post_action 18:17:03 INFO - Running post-action listener: find_tests_for_verification 18:17:03 INFO - Running post-action listener: set_extra_try_arguments 18:17:03 INFO - [mozharness: 2017-08-28 01:17:03.269011Z] Finished download-and-extract step (success) 18:17:03 INFO - [mozharness: 2017-08-28 01:17:03.269107Z] Running populate-webroot step. 18:17:03 INFO - Running pre-action listener: _resource_record_pre_action 18:17:03 INFO - Running main action method: populate_webroot 18:17:03 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:17:03 INFO - 'chromez-stylo-e10s': {'talos_options': ['--stylo'], 18:17:03 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:17:03 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:17:03 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--stylo'], 18:17:03 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:17:03 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:17:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:17:03 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:17:03 INFO - 'talos_options': ['--stylo'], 18:17:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:17:03 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:17:03 INFO - 'tests': ['damp', 'tps']}, 18:17:03 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:17:03 INFO - 'talos_options': ['--stylo'], 18:17:03 INFO - 'tests': ['damp', 'tps']}, 18:17:03 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:17:03 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:17:03 INFO - 'g4-stylo-e10s': {'talos_options': ['--stylo'], 18:17:03 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 18:17:03 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 18:17:03 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:17:03 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:17:03 INFO - 'talos_options': ['--stylo'], 18:17:03 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:17:03 INFO - 'other-e10s': {'tests': ['a11yr', 18:17:03 INFO - 'ts_paint', 18:17:03 INFO - 'tpaint', 18:17:03 INFO - 'sessionrestore', 18:17:03 INFO - 'sessionrestore_many_windows', 18:17:03 INFO - 'sessionrestore_no_auto_restore', 18:17:03 INFO - 'tabpaint']}, 18:17:03 INFO - 'other-stylo-e10s': {'talos_options': ['--stylo'], 18:17:03 INFO - 'tests': ['a11yr', 18:17:03 INFO - 'ts_paint', 18:17:03 INFO - 'tpaint', 18:17:03 INFO - 'sessionrestore', 18:17:03 INFO - 'sessionrestore_many_windows', 18:17:03 INFO - 'sessionrestore_no_auto_restore', 18:17:03 INFO - 'tabpaint']}, 18:17:03 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 18:17:03 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 18:17:03 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--stylo'], 18:17:03 INFO - 'tests': ['bloom_basic_singleton']}, 18:17:03 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--stylo'], 18:17:03 INFO - 'tests': ['bloom_basic']}, 18:17:03 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:17:03 INFO - 'tsvgr_opacity', 18:17:03 INFO - 'tart', 18:17:03 INFO - 'tscrollx', 18:17:03 INFO - 'tsvg_static']}, 18:17:03 INFO - 'svgr-stylo-e10s': {'talos_options': ['--stylo'], 18:17:03 INFO - 'tests': ['tsvgx', 18:17:03 INFO - 'tsvgr_opacity', 18:17:03 INFO - 'tart', 18:17:03 INFO - 'tscrollx', 18:17:03 INFO - 'tsvg_static']}, 18:17:03 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:17:03 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:17:03 INFO - 'talos_options': ['--stylo'], 18:17:03 INFO - 'tests': ['tp5o']}, 18:17:03 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:17:03 INFO - 'talos_options': ['--mitmproxy', 18:17:03 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:17:03 INFO - '--firstNonBlankPaint'], 18:17:03 INFO - 'tests': ['tp6_google', 18:17:03 INFO - 'tp6_youtube', 18:17:03 INFO - 'tp6_amazon', 18:17:03 INFO - 'tp6_facebook']}, 18:17:03 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:17:03 INFO - 'talos_options': ['--stylo', 18:17:03 INFO - '--mitmproxy', 18:17:03 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:17:03 INFO - '--firstNonBlankPaint'], 18:17:03 INFO - 'tests': ['tp6_google', 18:17:03 INFO - 'tp6_youtube', 18:17:03 INFO - 'tp6_amazon', 18:17:03 INFO - 'tp6_facebook']}, 18:17:03 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:17:03 INFO - 'talos_options': ['--stylo', 18:17:03 INFO - '--stylo-threads=1', 18:17:03 INFO - '--mitmproxy', 18:17:03 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:17:03 INFO - '--firstNonBlankPaint'], 18:17:03 INFO - 'tests': ['tp6_google', 18:17:03 INFO - 'tp6_youtube', 18:17:03 INFO - 'tp6_amazon', 18:17:03 INFO - 'tp6_facebook']}, 18:17:03 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:17:03 INFO - 'talos_options': ['--xperf_path', 18:17:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:17:03 INFO - 'tests': ['tp5n']}, 18:17:03 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:17:03 INFO - 'talos_options': ['--stylo', 18:17:03 INFO - '--xperf_path', 18:17:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:17:03 INFO - 'tests': ['tp5n']}}} 18:17:03 INFO - Running post-action listener: _resource_record_post_action 18:17:03 INFO - [mozharness: 2017-08-28 01:17:03.283319Z] Finished populate-webroot step (success) 18:17:03 INFO - [mozharness: 2017-08-28 01:17:03.283407Z] Running create-virtualenv step. 18:17:03 INFO - Running pre-action listener: _resource_record_pre_action 18:17:03 INFO - Running main action method: create_virtualenv 18:17:03 INFO - Creating virtualenv /builds/slave/test/build/venv 18:17:03 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 18:17:03 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 18:17:03 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 18:17:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:17:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:17:03 INFO - Installing distribute.............................................................................................................................................................................................done. 18:17:04 INFO - Installing pip...............done. 18:17:04 INFO - Return code: 0 18:17:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:17:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:17:04 INFO - Reading from file tmpfile_stdout 18:17:04 INFO - Output received: 18:17:04 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:17:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:17:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:17:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:17:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:17:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:17:04 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 0x203e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2209a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23acf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23aa130>, '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 0x23ab030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23ab4c0>, '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': '8c32c1c60be582960b75045f000001c1-1503873263.99089-675433845', '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:17:04 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:17:04 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:17:04 INFO - Using env: {'DISPLAY': ':0', 18:17:04 INFO - 'HOME': '/home/cltbld', 18:17:04 INFO - 'LANG': 'en_US.UTF-8', 18:17:04 INFO - 'LANGUAGE': 'en_US:en', 18:17:04 INFO - 'LOGNAME': 'cltbld', 18:17:04 INFO - 'MAIL': '/var/mail/cltbld', 18:17:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:17:04 INFO - 'MOZ_NO_REMOTE': '1', 18:17:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:17:04 INFO - 'NO_EM_RESTART': '1', 18:17:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:17:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:17:04 INFO - 'PWD': '/builds/slave/test', 18:17:04 INFO - 'SHELL': '/bin/bash', 18:17:04 INFO - 'SHLVL': '1', 18:17:04 INFO - 'TERM': 'linux', 18:17:04 INFO - 'TMOUT': '86400', 18:17:04 INFO - 'USER': 'cltbld', 18:17:04 INFO - 'XDG_SESSION_COOKIE': '8c32c1c60be582960b75045f000001c1-1503873263.99089-675433845', 18:17:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:17:04 INFO - '_': '/tools/buildbot/bin/python'} 18:17:05 INFO - Ignoring indexes: http://pypi.python.org/simple/ 18:17:05 INFO - Downloading/unpacking pip>=1.5 18:17:05 INFO - Running setup.py egg_info for package pip 18:17:05 INFO - warning: no files found matching 'pip/cacert.pem' 18:17:05 INFO - warning: no files found matching '*.html' under directory 'docs' 18:17:05 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 18:17:05 INFO - no previously-included directories found matching 'docs/_build/_sources' 18:17:05 INFO - Installing collected packages: pip 18:17:05 INFO - Found existing installation: pip 1.1 18:17:05 INFO - Uninstalling pip: 18:17:05 INFO - Successfully uninstalled pip 18:17:05 INFO - Running setup.py install for pip 18:17:05 INFO - warning: no files found matching 'pip/cacert.pem' 18:17:05 INFO - warning: no files found matching '*.html' under directory 'docs' 18:17:05 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 18:17:05 INFO - no previously-included directories found matching 'docs/_build/_sources' 18:17:05 INFO - Installing pip script to /builds/slave/test/build/venv/bin 18:17:05 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 18:17:05 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 18:17:05 INFO - Successfully installed pip 18:17:05 INFO - Cleaning up... 18:17:05 INFO - Return code: 0 18:17:05 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:17:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:17:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:17:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:17:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:17:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x203e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2209a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23acf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23aa130>, '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 0x23ab030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23ab4c0>, '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': '8c32c1c60be582960b75045f000001c1-1503873263.99089-675433845', '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:17:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:17:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:17:05 INFO - Using env: {'DISPLAY': ':0', 18:17:05 INFO - 'HOME': '/home/cltbld', 18:17:05 INFO - 'LANG': 'en_US.UTF-8', 18:17:05 INFO - 'LANGUAGE': 'en_US:en', 18:17:05 INFO - 'LOGNAME': 'cltbld', 18:17:05 INFO - 'MAIL': '/var/mail/cltbld', 18:17:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:17:05 INFO - 'MOZ_NO_REMOTE': '1', 18:17:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:17:05 INFO - 'NO_EM_RESTART': '1', 18:17:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:17:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:17:05 INFO - 'PWD': '/builds/slave/test', 18:17:05 INFO - 'SHELL': '/bin/bash', 18:17:05 INFO - 'SHLVL': '1', 18:17:05 INFO - 'TERM': 'linux', 18:17:05 INFO - 'TMOUT': '86400', 18:17:05 INFO - 'USER': 'cltbld', 18:17:05 INFO - 'XDG_SESSION_COOKIE': '8c32c1c60be582960b75045f000001c1-1503873263.99089-675433845', 18:17:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:17:05 INFO - '_': '/tools/buildbot/bin/python'} 18:17:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:17:05 INFO - Downloading/unpacking psutil>=3.1.1 18:17:05 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:17:05 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:17:05 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:17:05 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:17:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:17:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:17:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:17:07 INFO - Installing collected packages: psutil 18:17:07 INFO - Running setup.py install for psutil 18:17:07 INFO - building 'psutil._psutil_linux' extension 18:17:07 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:17:07 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:17:07 INFO - building 'psutil._psutil_posix' extension 18:17:07 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:17:07 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:17:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:17:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:17:07 INFO - Successfully installed psutil 18:17:07 INFO - Cleaning up... 18:17:07 INFO - Return code: 0 18:17:07 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:17: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:17:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:17: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:17:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:17:07 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 0x203e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2209a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23acf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23aa130>, '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 0x23ab030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23ab4c0>, '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': '8c32c1c60be582960b75045f000001c1-1503873263.99089-675433845', '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:17:07 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:17:07 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:17:07 INFO - Using env: {'DISPLAY': ':0', 18:17:07 INFO - 'HOME': '/home/cltbld', 18:17:07 INFO - 'LANG': 'en_US.UTF-8', 18:17:07 INFO - 'LANGUAGE': 'en_US:en', 18:17:07 INFO - 'LOGNAME': 'cltbld', 18:17:07 INFO - 'MAIL': '/var/mail/cltbld', 18:17:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:17:07 INFO - 'MOZ_NO_REMOTE': '1', 18:17:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:17:07 INFO - 'NO_EM_RESTART': '1', 18:17:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:17:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:17:07 INFO - 'PWD': '/builds/slave/test', 18:17:07 INFO - 'SHELL': '/bin/bash', 18:17:07 INFO - 'SHLVL': '1', 18:17:07 INFO - 'TERM': 'linux', 18:17:07 INFO - 'TMOUT': '86400', 18:17:07 INFO - 'USER': 'cltbld', 18:17:07 INFO - 'XDG_SESSION_COOKIE': '8c32c1c60be582960b75045f000001c1-1503873263.99089-675433845', 18:17:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:17:07 INFO - '_': '/tools/buildbot/bin/python'} 18:17:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:17:07 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:17:07 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:17:07 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:17:07 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:17:07 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:17:08 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:17:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:17:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:17:08 INFO - Installing collected packages: mozsystemmonitor 18:17:08 INFO - Running setup.py install for mozsystemmonitor 18:17:08 INFO - Successfully installed mozsystemmonitor 18:17:08 INFO - Cleaning up... 18:17:09 INFO - Return code: 0 18:17:09 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:17:09 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:17:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:17:09 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:17:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:17:09 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 0x203e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2209a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23acf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23aa130>, '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 0x23ab030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23ab4c0>, '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': '8c32c1c60be582960b75045f000001c1-1503873263.99089-675433845', '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:17:09 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:17:09 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:17:09 INFO - Using env: {'DISPLAY': ':0', 18:17:09 INFO - 'HOME': '/home/cltbld', 18:17:09 INFO - 'LANG': 'en_US.UTF-8', 18:17:09 INFO - 'LANGUAGE': 'en_US:en', 18:17:09 INFO - 'LOGNAME': 'cltbld', 18:17:09 INFO - 'MAIL': '/var/mail/cltbld', 18:17:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:17:09 INFO - 'MOZ_NO_REMOTE': '1', 18:17:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:17:09 INFO - 'NO_EM_RESTART': '1', 18:17:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:17:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:17:09 INFO - 'PWD': '/builds/slave/test', 18:17:09 INFO - 'SHELL': '/bin/bash', 18:17:09 INFO - 'SHLVL': '1', 18:17:09 INFO - 'TERM': 'linux', 18:17:09 INFO - 'TMOUT': '86400', 18:17:09 INFO - 'USER': 'cltbld', 18:17:09 INFO - 'XDG_SESSION_COOKIE': '8c32c1c60be582960b75045f000001c1-1503873263.99089-675433845', 18:17:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:17:09 INFO - '_': '/tools/buildbot/bin/python'} 18:17:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:17:09 INFO - Downloading/unpacking jsonschema==2.5.1 18:17:09 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:17:09 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:17:09 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:17:09 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:17:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:17:10 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:17:10 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:17:10 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:17:10 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:17:10 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:17:10 INFO - Downloading functools32-3.2.3-2.tar.gz 18:17:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:17:10 INFO - warning: no files found matching '*.txt' 18:17:10 INFO - no previously-included directories found matching 'build' 18:17:10 INFO - no previously-included directories found matching 'dist' 18:17:10 INFO - no previously-included directories found matching '.git*' 18:17:10 INFO - Installing collected packages: jsonschema, functools32 18:17:10 INFO - Running setup.py install for functools32 18:17:10 INFO - warning: no files found matching '*.txt' 18:17:10 INFO - no previously-included directories found matching 'build' 18:17:10 INFO - no previously-included directories found matching 'dist' 18:17:10 INFO - no previously-included directories found matching '.git*' 18:17:10 INFO - Successfully installed jsonschema functools32 18:17:10 INFO - Cleaning up... 18:17:10 INFO - Return code: 0 18:17:10 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:17:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:17:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:17:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:17:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:17:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x203e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2209a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23acf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23aa130>, '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 0x23ab030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23ab4c0>, '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': '8c32c1c60be582960b75045f000001c1-1503873263.99089-675433845', '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:17:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:17:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 18:17:10 INFO - Using env: {'DISPLAY': ':0', 18:17:10 INFO - 'HOME': '/home/cltbld', 18:17:10 INFO - 'LANG': 'en_US.UTF-8', 18:17:10 INFO - 'LANGUAGE': 'en_US:en', 18:17:10 INFO - 'LOGNAME': 'cltbld', 18:17:10 INFO - 'MAIL': '/var/mail/cltbld', 18:17:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:17:10 INFO - 'MOZ_NO_REMOTE': '1', 18:17:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:17:10 INFO - 'NO_EM_RESTART': '1', 18:17:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:17:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:17:10 INFO - 'PWD': '/builds/slave/test', 18:17:10 INFO - 'SHELL': '/bin/bash', 18:17:10 INFO - 'SHLVL': '1', 18:17:10 INFO - 'TERM': 'linux', 18:17:10 INFO - 'TMOUT': '86400', 18:17:10 INFO - 'USER': 'cltbld', 18:17:10 INFO - 'XDG_SESSION_COOKIE': '8c32c1c60be582960b75045f000001c1-1503873263.99089-675433845', 18:17:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:17:10 INFO - '_': '/tools/buildbot/bin/python'} 18:17:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:17:10 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:17:10 INFO - Cleaning up... 18:17:10 INFO - Return code: 0 18:17:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:17:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:17:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:17:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:17:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:17:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x203e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2209a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23acf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23aa130>, '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 0x23ab030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23ab4c0>, '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': '8c32c1c60be582960b75045f000001c1-1503873263.99089-675433845', '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:17:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:17:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:17:10 INFO - Using env: {'DISPLAY': ':0', 18:17:10 INFO - 'HOME': '/home/cltbld', 18:17:10 INFO - 'LANG': 'en_US.UTF-8', 18:17:10 INFO - 'LANGUAGE': 'en_US:en', 18:17:10 INFO - 'LOGNAME': 'cltbld', 18:17:10 INFO - 'MAIL': '/var/mail/cltbld', 18:17:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:17:10 INFO - 'MOZ_NO_REMOTE': '1', 18:17:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:17:10 INFO - 'NO_EM_RESTART': '1', 18:17:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:17:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:17:10 INFO - 'PWD': '/builds/slave/test', 18:17:10 INFO - 'SHELL': '/bin/bash', 18:17:10 INFO - 'SHLVL': '1', 18:17:10 INFO - 'TERM': 'linux', 18:17:10 INFO - 'TMOUT': '86400', 18:17:10 INFO - 'USER': 'cltbld', 18:17:10 INFO - 'XDG_SESSION_COOKIE': '8c32c1c60be582960b75045f000001c1-1503873263.99089-675433845', 18:17:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:17:10 INFO - '_': '/tools/buildbot/bin/python'} 18:17:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:17:11 INFO - Downloading/unpacking blobuploader==1.2.4 18:17:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:17:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:17:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:17:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:17:12 INFO - Downloading blobuploader-1.2.4.tar.gz 18:17:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:17:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:17:12 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:17:12 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:17:12 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:17:12 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:17:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:17:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:17:12 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:17:12 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:17:12 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:17:12 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:17:12 INFO - Downloading docopt-0.6.1.tar.gz 18:17:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:17:12 INFO - Installing collected packages: blobuploader, requests, docopt 18:17:12 INFO - Running setup.py install for blobuploader 18:17:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:17:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:17:12 INFO - Running setup.py install for requests 18:17:13 INFO - Running setup.py install for docopt 18:17:13 INFO - Successfully installed blobuploader requests docopt 18:17:13 INFO - Cleaning up... 18:17:13 INFO - Return code: 0 18:17:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:17:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:17:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:17:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:17:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:17:13 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 0x203e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2209a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23acf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23aa130>, '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 0x23ab030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23ab4c0>, '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': '8c32c1c60be582960b75045f000001c1-1503873263.99089-675433845', '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:17:13 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:17:13 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:17:13 INFO - Using env: {'DISPLAY': ':0', 18:17:13 INFO - 'HOME': '/home/cltbld', 18:17:13 INFO - 'LANG': 'en_US.UTF-8', 18:17:13 INFO - 'LANGUAGE': 'en_US:en', 18:17:13 INFO - 'LOGNAME': 'cltbld', 18:17:13 INFO - 'MAIL': '/var/mail/cltbld', 18:17:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:17:13 INFO - 'MOZ_NO_REMOTE': '1', 18:17:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:17:13 INFO - 'NO_EM_RESTART': '1', 18:17:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:17:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:17:13 INFO - 'PWD': '/builds/slave/test', 18:17:13 INFO - 'SHELL': '/bin/bash', 18:17:13 INFO - 'SHLVL': '1', 18:17:13 INFO - 'TERM': 'linux', 18:17:13 INFO - 'TMOUT': '86400', 18:17:13 INFO - 'USER': 'cltbld', 18:17:13 INFO - 'XDG_SESSION_COOKIE': '8c32c1c60be582960b75045f000001c1-1503873263.99089-675433845', 18:17:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:17:13 INFO - '_': '/tools/buildbot/bin/python'} 18:17:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:17:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:17:13 INFO - Running setup.py (path:/tmp/pip-hfkXhh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:17:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:17:13 INFO - Running setup.py (path:/tmp/pip-2zqJt0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:17:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:17:13 INFO - Running setup.py (path:/tmp/pip-6hyr0_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:17:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:17:13 INFO - Running setup.py (path:/tmp/pip-YBcKm5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:17:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:17:13 INFO - Running setup.py (path:/tmp/pip-46XoV1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:17:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:17:13 INFO - Running setup.py (path:/tmp/pip-hnAPxh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:17:14 INFO - Running setup.py (path:/tmp/pip-n3Jj9t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:17:14 INFO - Running setup.py (path:/tmp/pip-nTvX6U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:17:14 INFO - Running setup.py (path:/tmp/pip-flneYI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:17:14 INFO - Running setup.py (path:/tmp/pip-gjIWI0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:17:14 INFO - Running setup.py (path:/tmp/pip-QvQara-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:17:14 INFO - Running setup.py (path:/tmp/pip-g1Jd8z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:17:14 INFO - Running setup.py (path:/tmp/pip-br9isv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:17:14 INFO - Running setup.py (path:/tmp/pip-YaGsR7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:17:14 INFO - Running setup.py (path:/tmp/pip-cilJbV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:17:14 INFO - Running setup.py (path:/tmp/pip-kWmvAM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:17:14 INFO - Running setup.py (path:/tmp/pip-S3eFqk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:17:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:17:14 INFO - Running setup.py install for manifestparser 18:17:14 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:17:14 INFO - Running setup.py install for mozcrash 18:17:15 INFO - Running setup.py install for mozdebug 18:17:15 INFO - Running setup.py install for mozdevice 18:17:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:17:15 INFO - Running setup.py install for mozfile 18:17:15 INFO - Running setup.py install for mozhttpd 18:17:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:17:15 INFO - Running setup.py install for mozinfo 18:17:15 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:17:15 INFO - Running setup.py install for mozInstall 18:17:15 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:17:15 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:17:15 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:17:15 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:17:15 INFO - Running setup.py install for mozleak 18:17:15 INFO - Running setup.py install for mozlog 18:17:15 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:17:15 INFO - Running setup.py install for moznetwork 18:17:15 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:17:15 INFO - Running setup.py install for mozprocess 18:17:16 INFO - Running setup.py install for mozprofile 18:17:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:17:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:17:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:17:16 INFO - Running setup.py install for mozrunner 18:17:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:17:16 INFO - Running setup.py install for mozscreenshot 18:17:16 INFO - Running setup.py install for moztest 18:17:16 INFO - Running setup.py install for mozversion 18:17:16 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:17:16 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:17:16 INFO - Cleaning up... 18:17:16 INFO - Return code: 0 18:17:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:17:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:17:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:17:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:17:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:17:16 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 0x203e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2209a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23acf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23aa130>, '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 0x23ab030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23ab4c0>, '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': '8c32c1c60be582960b75045f000001c1-1503873263.99089-675433845', '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:17:16 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:17:16 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:17:16 INFO - Using env: {'DISPLAY': ':0', 18:17:16 INFO - 'HOME': '/home/cltbld', 18:17:16 INFO - 'LANG': 'en_US.UTF-8', 18:17:16 INFO - 'LANGUAGE': 'en_US:en', 18:17:16 INFO - 'LOGNAME': 'cltbld', 18:17:16 INFO - 'MAIL': '/var/mail/cltbld', 18:17:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:17:16 INFO - 'MOZ_NO_REMOTE': '1', 18:17:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:17:16 INFO - 'NO_EM_RESTART': '1', 18:17:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:17:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:17:16 INFO - 'PWD': '/builds/slave/test', 18:17:16 INFO - 'SHELL': '/bin/bash', 18:17:16 INFO - 'SHLVL': '1', 18:17:16 INFO - 'TERM': 'linux', 18:17:16 INFO - 'TMOUT': '86400', 18:17:16 INFO - 'USER': 'cltbld', 18:17:16 INFO - 'XDG_SESSION_COOKIE': '8c32c1c60be582960b75045f000001c1-1503873263.99089-675433845', 18:17:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:17:16 INFO - '_': '/tools/buildbot/bin/python'} 18:17:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:17:16 INFO - Running setup.py (path:/tmp/pip-OA9fqw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:17:16 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:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:17:16 INFO - Running setup.py (path:/tmp/pip-b0XuzK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:17:16 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:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:17:16 INFO - Running setup.py (path:/tmp/pip-K4nqN4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:17:17 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:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:17:17 INFO - Running setup.py (path:/tmp/pip-CIJm9s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:17:17 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:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:17:17 INFO - Running setup.py (path:/tmp/pip-yuHUyV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:17:17 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:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:17:17 INFO - Running setup.py (path:/tmp/pip-O1s9Ov-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:17:17 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:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:17:17 INFO - Running setup.py (path:/tmp/pip-DxYILf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:17:17 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:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:17:17 INFO - Running setup.py (path:/tmp/pip-8qeOO6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:17:17 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:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:17:17 INFO - Running setup.py (path:/tmp/pip-H3gzU8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:17:17 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:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:17:17 INFO - Running setup.py (path:/tmp/pip-tEzCia-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:17:17 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:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:17:17 INFO - Running setup.py (path:/tmp/pip-jyESD5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:17:17 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:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:17:17 INFO - Running setup.py (path:/tmp/pip-amqpqV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:17:17 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:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:17:17 INFO - Running setup.py (path:/tmp/pip-NNNyOB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:17:17 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:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:17:17 INFO - Running setup.py (path:/tmp/pip-Q85kAx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:17:17 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:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:17:17 INFO - Running setup.py (path:/tmp/pip-tnrAMz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:17:17 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:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:17:17 INFO - Running setup.py (path:/tmp/pip-j2n4mt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:17:17 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:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:17:17 INFO - Running setup.py (path:/tmp/pip-G0_b2A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:17:18 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:17:18 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:17:18 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:17:18 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:17:18 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:17:18 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:17:18 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:17:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:17:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:17:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:17:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:17:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:17:19 INFO - Downloading blessings-1.6.tar.gz 18:17:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:17:19 INFO - Installing collected packages: blessings 18:17:19 INFO - Running setup.py install for blessings 18:17:19 INFO - Successfully installed blessings 18:17:19 INFO - Cleaning up... 18:17:19 INFO - Return code: 0 18:17:19 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:17:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:17:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:17:19 INFO - Reading from file tmpfile_stdout 18:17:19 INFO - Current package versions: 18:17:19 INFO - argparse == 1.2.1 18:17:19 INFO - blessings == 1.6 18:17:19 INFO - blobuploader == 1.2.4 18:17:19 INFO - docopt == 0.6.1 18:17:19 INFO - functools32 == 3.2.3-2 18:17:19 INFO - jsonschema == 2.5.1 18:17:19 INFO - manifestparser == 1.1 18:17:19 INFO - mozInstall == 1.13 18:17:19 INFO - mozcrash == 1.0 18:17:19 INFO - mozdebug == 0.1 18:17:19 INFO - mozdevice == 0.51 18:17:19 INFO - mozfile == 1.2 18:17:19 INFO - mozhttpd == 0.7 18:17:19 INFO - mozinfo == 0.10 18:17:19 INFO - mozleak == 0.1 18:17:19 INFO - mozlog == 3.5 18:17:19 INFO - moznetwork == 0.27 18:17:19 INFO - mozprocess == 0.25 18:17:19 INFO - mozprofile == 0.28 18:17:19 INFO - mozrunner == 6.13 18:17:19 INFO - mozscreenshot == 0.1 18:17:19 INFO - mozsystemmonitor == 0.3 18:17:19 INFO - moztest == 0.8 18:17:19 INFO - mozversion == 1.4 18:17:19 INFO - psutil == 3.1.1 18:17:19 INFO - requests == 1.2.3 18:17:19 INFO - wsgiref == 0.1.2 18:17:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:17:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:17:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:17:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:17:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:17:19 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 0x203e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2209a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23acf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23aa130>, '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 0x23ab030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23ab4c0>, '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': '8c32c1c60be582960b75045f000001c1-1503873263.99089-675433845', '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:17:19 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:17:19 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:17:19 INFO - Using env: {'DISPLAY': ':0', 18:17:19 INFO - 'HOME': '/home/cltbld', 18:17:19 INFO - 'LANG': 'en_US.UTF-8', 18:17:19 INFO - 'LANGUAGE': 'en_US:en', 18:17:19 INFO - 'LOGNAME': 'cltbld', 18:17:19 INFO - 'MAIL': '/var/mail/cltbld', 18:17:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:17:19 INFO - 'MOZ_NO_REMOTE': '1', 18:17:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:17:19 INFO - 'NO_EM_RESTART': '1', 18:17:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:17:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:17:19 INFO - 'PWD': '/builds/slave/test', 18:17:19 INFO - 'SHELL': '/bin/bash', 18:17:19 INFO - 'SHLVL': '1', 18:17:19 INFO - 'TERM': 'linux', 18:17:19 INFO - 'TMOUT': '86400', 18:17:19 INFO - 'USER': 'cltbld', 18:17:19 INFO - 'XDG_SESSION_COOKIE': '8c32c1c60be582960b75045f000001c1-1503873263.99089-675433845', 18:17:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:17:19 INFO - '_': '/tools/buildbot/bin/python'} 18:17:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:17:19 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:17:19 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:17:19 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:17:19 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:17:19 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:17:19 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:17:19 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:17:19 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:17:19 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:17:19 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:17:19 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:17:19 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:17:19 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:17:19 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:17:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 18:17:20 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:17:20 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:17:20 INFO - Installing collected packages: simplejson 18:17:20 INFO - Running setup.py install for simplejson 18:17:21 INFO - building 'simplejson._speedups' extension 18:17:21 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:17:21 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:17:21 INFO - Successfully installed simplejson 18:17:21 INFO - Cleaning up... 18:17:22 INFO - Return code: 0 18:17:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:17:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:17:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:17:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:17:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:17:22 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 0x203e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2209a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23acf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23aa130>, '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 0x23ab030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23ab4c0>, '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': '8c32c1c60be582960b75045f000001c1-1503873263.99089-675433845', '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:17:22 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:17:22 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:17:22 INFO - Using env: {'DISPLAY': ':0', 18:17:22 INFO - 'HOME': '/home/cltbld', 18:17:22 INFO - 'LANG': 'en_US.UTF-8', 18:17:22 INFO - 'LANGUAGE': 'en_US:en', 18:17:22 INFO - 'LOGNAME': 'cltbld', 18:17:22 INFO - 'MAIL': '/var/mail/cltbld', 18:17:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:17:22 INFO - 'MOZ_NO_REMOTE': '1', 18:17:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:17:22 INFO - 'NO_EM_RESTART': '1', 18:17:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:17:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:17:22 INFO - 'PWD': '/builds/slave/test', 18:17:22 INFO - 'SHELL': '/bin/bash', 18:17:22 INFO - 'SHLVL': '1', 18:17:22 INFO - 'TERM': 'linux', 18:17:22 INFO - 'TMOUT': '86400', 18:17:22 INFO - 'USER': 'cltbld', 18:17:22 INFO - 'XDG_SESSION_COOKIE': '8c32c1c60be582960b75045f000001c1-1503873263.99089-675433845', 18:17:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:17:22 INFO - '_': '/tools/buildbot/bin/python'} 18:17:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:17:22 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:17:22 INFO - Cleaning up... 18:17:22 INFO - Return code: 0 18:17:22 INFO - Running post-action listener: _resource_record_post_action 18:17:22 INFO - Running post-action listener: _start_resource_monitoring 18:17:22 INFO - Starting resource monitoring. 18:17:22 INFO - [mozharness: 2017-08-28 01:17:22.287946Z] Finished create-virtualenv step (success) 18:17:22 INFO - [mozharness: 2017-08-28 01:17:22.288466Z] Running install step. 18:17:22 INFO - Running pre-action listener: _resource_record_pre_action 18:17:22 INFO - Running main action method: install 18:17:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:17:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:17:22 INFO - Reading from file tmpfile_stdout 18:17:22 INFO - Detecting whether we're running mozinstall >=1.0... 18:17:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:17:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:17:22 INFO - Reading from file tmpfile_stdout 18:17:22 INFO - Output received: 18:17:22 INFO - Usage: mozinstall [options] installer 18:17:22 INFO - Options: 18:17:22 INFO - -h, --help show this help message and exit 18:17:22 INFO - -d DEST, --destination=DEST 18:17:22 INFO - Directory to install application into. [default: 18:17:22 INFO - "/builds/slave/test"] 18:17:22 INFO - --app=APP Application being installed. [default: firefox] 18:17:22 INFO - mkdir: /builds/slave/test/build/application 18:17:22 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:17:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 18:17:40 INFO - Reading from file tmpfile_stdout 18:17:40 INFO - Output received: 18:17:40 INFO - /builds/slave/test/build/application/firefox/firefox 18:17:40 INFO - Running post-action listener: _resource_record_post_action 18:17:40 INFO - [mozharness: 2017-08-28 01:17:40.342664Z] Finished install step (success) 18:17:40 INFO - [mozharness: 2017-08-28 01:17:40.342948Z] Running setup-mitmproxy step. 18:17:40 INFO - Running pre-action listener: _resource_record_pre_action 18:17:40 INFO - Running main action method: setup_mitmproxy 18:17:40 INFO - Skipping: mitmproxy is not required 18:17:40 INFO - Running post-action listener: _resource_record_post_action 18:17:40 INFO - [mozharness: 2017-08-28 01:17:40.344057Z] Finished setup-mitmproxy step (success) 18:17:40 INFO - [mozharness: 2017-08-28 01:17:40.344274Z] Running run-tests step. 18:17:40 INFO - Running pre-action listener: _resource_record_pre_action 18:17:40 INFO - Running pre-action listener: _set_gcov_prefix 18:17:40 INFO - Running main action method: run_tests 18:17:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:17:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:17:40 INFO - Python 2.7.3 18:17:40 INFO - Return code: 0 18:17:40 INFO - grabbing minidump binary from tooltool 18:17: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:17:40 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 0x23aa130>, '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 0x23ab030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23ab4c0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:17:40 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:17:40 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:17:40 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:17:40 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:17:40 INFO - Return code: 0 18:17:40 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:17:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:17:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:17:40 INFO - ENV: RUST_BACKTRACE is now full 18:17:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:17:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:17:40 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/fJ4-8OdcRqSrwqD2wKF_lQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-001', '--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:17:40 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/fJ4-8OdcRqSrwqD2wKF_lQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-001 --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:17:40 INFO - Using env: {'DISPLAY': ':0', 18:17:40 INFO - 'HOME': '/home/cltbld', 18:17:40 INFO - 'LANG': 'en_US.UTF-8', 18:17:40 INFO - 'LANGUAGE': 'en_US:en', 18:17:40 INFO - 'LOGNAME': 'cltbld', 18:17:40 INFO - 'MAIL': '/var/mail/cltbld', 18:17:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:17:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:17:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:17:40 INFO - 'MOZ_NO_REMOTE': '1', 18:17:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:17:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:17:40 INFO - 'NO_EM_RESTART': '1', 18:17:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:17:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:17:40 INFO - 'PWD': '/builds/slave/test', 18:17:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:17:40 INFO - 'RUST_BACKTRACE': 'full', 18:17:40 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:17:40 INFO - 'SHELL': '/bin/bash', 18:17:40 INFO - 'SHLVL': '1', 18:17:40 INFO - 'STYLO_FORCE_ENABLED': '1', 18:17:40 INFO - 'TERM': 'linux', 18:17:40 INFO - 'TMOUT': '86400', 18:17:40 INFO - 'USER': 'cltbld', 18:17:40 INFO - 'XDG_SESSION_COOKIE': '8c32c1c60be582960b75045f000001c1-1503873263.99089-675433845', 18:17:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:17:40 INFO - '_': '/tools/buildbot/bin/python'} 18:17:40 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/fJ4-8OdcRqSrwqD2wKF_lQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-001', '--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:17:40 INFO - mozversion application_buildid: 20170828003112 18:17:40 INFO - mozversion application_changeset: 7aec8d3eaa72838f1d83767f46dcfcae8de510ff 18:17:40 INFO - mozversion application_display_name: Nightly 18:17:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:17:40 INFO - mozversion application_name: Firefox 18:17:40 INFO - mozversion application_remotingname: firefox 18:17:40 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:17:40 INFO - mozversion application_vendor: Mozilla 18:17:40 INFO - mozversion application_version: 57.0a1 18:17:40 INFO - mozversion platform_buildid: 20170828003112 18:17:40 INFO - mozversion platform_changeset: 7aec8d3eaa72838f1d83767f46dcfcae8de510ff 18:17:40 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:17:40 INFO - mozversion platform_version: 57.0a1 18:17:40 INFO - using testdate: 1503883060 18:17:40 INFO - actual date: 1503883060 18:17:40 INFO - starting webserver on 'localhost:44180' 18:17:40 INFO - SUITE-START | Running 2 tests 18:17:40 INFO - TEST-START | dromaeo_css 18:17:40 INFO - Initialising browser for dromaeo_css test... 18:17:40 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:44180/getInfo.html -profile /tmp/tmpRAupPt/profile 18:17:40 INFO - TEST-INFO | started process 16334 (/builds/slave/test/build/application/firefox/firefox http://localhost:44180/getInfo.html) 18:17:46 INFO - TEST-INFO | 16334: exit 0 18:17:46 INFO - Browser initialized. 18:17:46 INFO - Running cycle 1/1 for dromaeo_css test... 18:17:46 INFO - Using env: {'DISPLAY': ':0', 18:17:46 INFO - 'HOME': '/home/cltbld', 18:17:46 INFO - 'JSGC_DISABLE_POISONING': '1', 18:17:46 INFO - 'LANG': 'en_US.UTF-8', 18:17:46 INFO - 'LANGUAGE': 'en_US:en', 18:17:46 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 18:17:46 INFO - 'LOGNAME': 'cltbld', 18:17:46 INFO - 'MAIL': '/var/mail/cltbld', 18:17:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:17:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:17:46 INFO - 'MOZ_CRASHREPORTER': '1', 18:17:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:17:46 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 18:17:46 INFO - 'MOZ_NO_REMOTE': '1', 18:17:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:17:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:17:46 INFO - 'NO_EM_RESTART': '1', 18:17:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:17:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:17:46 INFO - 'PWD': '/builds/slave/test', 18:17:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:17:46 INFO - 'RUST_BACKTRACE': 'full', 18:17:46 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:17:46 INFO - 'SHELL': '/bin/bash', 18:17:46 INFO - 'SHLVL': '1', 18:17:46 INFO - 'STYLO_FORCE_ENABLED': '1', 18:17:46 INFO - 'TERM': 'linux', 18:17:46 INFO - 'TMOUT': '86400', 18:17:46 INFO - 'USER': 'cltbld', 18:17:46 INFO - 'XDG_SESSION_COOKIE': '8c32c1c60be582960b75045f000001c1-1503873263.99089-675433845', 18:17:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:17:46 INFO - '_': '/tools/buildbot/bin/python'} 18:17:46 INFO - TEST-INFO | started process 16522 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRAupPt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:17:47 INFO - PID 16522 | 18:17:47 INFO - PID 16522 | (/builds/slave/test/build/application/firefox/firefox:16571): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:17:47 INFO - PID 16522 | 18:17:48 INFO - PID 16522 | 18:17:48 INFO - PID 16522 | (/builds/slave/test/build/application/firefox/firefox:16632): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:17:48 INFO - PID 16522 | 18:17:48 INFO - PID 16522 | __metrics Screen width/height:1600/1200 18:17:48 INFO - PID 16522 | colorDepth:24 18:17:48 INFO - PID 16522 | Browser inner width/height: 1024/768 18:17:48 INFO - PID 16522 | __metrics 18:17:58 INFO - PID 16522 | RSS: Main: 174546944 18:17:58 INFO - PID 16522 | 18:20:56 INFO - PID 16522 | Cycle 1(1): loaded http://localhost:44180/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44180/tests/dromaeo/cssquery-ext.html) 18:20:58 INFO - PID 16522 | RSS: Main: 178196480 18:20:58 INFO - PID 16522 | 18:23:59 INFO - PID 16522 | Cycle 1(1): loaded http://localhost:44180/tests/dromaeo/cssquery-ext.html (next: http://localhost:44180/tests/dromaeo/cssquery-jquery.html) 18:24:00 INFO - PID 16522 | RSS: Main: 174477312 18:24:00 INFO - PID 16522 | 18:27:02 INFO - PID 16522 | Cycle 1(1): loaded http://localhost:44180/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44180/tests/dromaeo/cssquery-mootools.html) 18:27:02 INFO - PID 16522 | RSS: Main: 177061888 18:27:02 INFO - PID 16522 | 18:30:04 INFO - PID 16522 | Cycle 1(1): loaded http://localhost:44180/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44180/tests/dromaeo/cssquery-prototype.html) 18:30:05 INFO - PID 16522 | RSS: Main: 175288320 18:30:05 INFO - PID 16522 | 18:33:08 INFO - PID 16522 | Cycle 1(1): loaded http://localhost:44180/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44180/tests/dromaeo/cssquery-yui.html) 18:33:09 INFO - PID 16522 | RSS: Main: 179150848 18:33:09 INFO - PID 16522 | 18:36:10 INFO - PID 16522 | Cycle 1(1): loaded http://localhost:44180/tests/dromaeo/cssquery-yui.html (next: http://localhost:44180/tests/dromaeo/cssquery-dojo.html) 18:36:11 INFO - PID 16522 | RSS: Main: 177799168 18:36:11 INFO - PID 16522 | 18:36:11 INFO - PID 16522 | __start_tp_report 18:36:11 INFO - PID 16522 | _x_x_mozilla_page_load 18:36:11 INFO - PID 16522 | _x_x_mozilla_page_load_details 18:36:11 INFO - PID 16522 | |i|pagename|runs| 18:36:11 INFO - PID 16522 | |0;dojo.html;3269.986304245684;3274.345130973805;3297.472404415293;3302.6367099619024;3311.0894504011394;6365.077063825743;6373.585716928396;6410.358964103593;6411.615303081812;6457.160569126007;6278.686065696708;6386.137871387361;6399.616023038621;6401.231852177745;6423.614583125016;5823.912641310384;5848.795292164754;5850.853728656796;5865.794697185599;5883.646981181121;5847.239858818369;5856.121581762748;5937.584369094165;5950.494207992326;5977.671228082454;5875.941240587604;5967.16459695643;5967.910481342783;5985.970070149665;5991.221141251653;5689.061304884689;5780.335261444903;5804.332501762309;5884.823455296348;5915.615484993505;5604.467575580314;5792.333881603585;5843.824685259449;5913.083472061838;5956.612820166719;6358.7774427895265;6378.170837791104;6382.712777924958;6426.164598602152;6454.386833250881;6711.295313992009;6779.661016949133;6843.418309443703;6880.380765731058;6887.931120688779;6863.10779598654;6924.792256232321;6935.271796461399;6946.0622815920215;6957.7216911323485;6320.557560970734;6334.208223972004;6351.904721429136;6430.742770289136;6437.324080971477;6891.173059232924;7022.297770223039;7026.402755765817;7034.6834392452465;7121.28787121294;6671.566348187353;6673.265940746514;6674.098996635489;6675.198976122896;6693.163354580677;6696.698648560827;6757.966210168917;6769.153855768029;6907.896381554281;6920.8961865572055;6787.830304242433;6873;6877.931220687829;6878.8624227516175;6879.5184337095925;6797.116374871235;6807.727690892408;6816.113905192393;6893.20728116263;6921.411680007155;5325.813596524103;5381.67709937405;5382.973085134549;5383.111786555253;5439.728013599383;4702.694324868872;4735.95264047362;4751.54860288279;4786;4798.2082956311815;5952.821415357546;6007.189029481052;6011.308699499614;6018.668973206516;6020.09698545213;6898.379145876896;6907.309269073053;6960.443164546926;6962.7911162665205;7006.054182685374;5892.322382925932;5977.133315669291;5984.132300816357;6071.939280607226;6078.179445774764;5927;5940.851478713067;5942.64344139353;5969.970150149222;5978.282606087298;6598.60408375499;6601.33986601336;6610.504212184105;6665.166854143232;6704.765333213314;6735.360140786617;6853.691583878837;6868;6960.303969602999;6962.895556566757;6531.934680653133;6540.672966351759;6544.116544266463;6558.344165583403;6572.605643661395;6675.4325882300545;6684.866302674017;6793.184817821893;6803.795886123424;6806.965965170142;6877.243503214742;6894.965525172342;6992.8811390177325;6994.790156295319;6997.650117494003;6650.90023649636;6654.3012983637955;6673.9666301668185;6748.19021717397;6750.831229219309;6557.147570815763;6613.338666133348;6648;6705.932940670531;6707.295733947865;6622.039804228456;6645.235797883112;6655.833604159741;6668.233153187445;6669.799906002828;6699.899501507579;6750.32496750321;6804.489663275175;6829.6243706596615;6835;6385;6482.35176482348;6491.545591808528;6555.3772391622715;6556.0493728410065 18:36:11 INFO - PID 16522 | |1;ext.html;804.8591496488117;826.9586520673965;851.0510780479767;854.5983387807728;1137.0562433180462;30187.943421980235;30721.539176912338;40882.795586022105;41372;41399;41648;41968.58031419685;41990.790046049806;42009.789951050276;42016.94957626061;7880.4089693272945;8079.919200807975;8084.959575202116;8088.919110808904;8089.312408445289;7956.6817327306835;8068.838623227532;8071.798205044862;8072.798180045487;8074.798130046737;8048.919510804905;8103.473274237156;8109.229623185788;8115.513069215866;8124.5937703114905;8036.678532858678;8059.63731632075;8064.959675201645;8085.3127484163915;8089.352851771873;7904.762857114296;7959.761207163794;7976.122626511079;7977.760667179936;7985.520868747835;36942.445863312074;37295.25409491822;37313;37335.81332093322;37434.06414839624;3473.722102231841;3506.0358401439553;3506.912327191815;3508.9823951054163;3513.736469764752;30651.540226896614;30674.233144171576;30684.846575767202;30689.539656905163;30742.69257307443;4682.391289132425;4861.076395484882;4881.658283920127;4905.215165573527;4945.7774400152075;175833;176977.1151144249;177447.22552774436;177457;178036;46255.76872115618;46257.30614040792;46287;46324.305135423;46606;49531.25703114456;49547.752261238464;49651;49674.75162624236;49715;14092.506762263272;14330.211838348783;15863.286152123183;16026.19869006545;16028.75956860648;12935.935320323339;13207.933960330136;13210.933945330404;13211.405486752927;13234.536791212262;14086.859131408555;14127.434902603985;14162.150270983773;14172.64568385778;14237.074590151815;5505.917411238835;5540.56215265282;5678.517326027251;5683.403242659544;5742.684152371659;990.8761404824397;995.9103680668775;1003.3895597565802;1010.4644538394663;1017.7150397888455;22423.21518746836;22620.434489137904;22775.975081121058;22917.197898073486;23066.077356905873;1078.6224821312635;1101.0695961912172;1111.2388014210292;1116.944152792357;1123.5898896902572;12192.207506512048;12218.20581662189;12223.877761222451;12235.449404776806;12484.75030499385;230948;232615;232887;233410;233562;50576;50843;51139;51277.23084153741;51289;1384.349355802771;1431.8782903453114;1434.189682829219;1439.4961763382732;1446.6528033271939;22195.22316718907;22236;22280;22600.773992259867;22687.319380418612;36303.81848090742;36328.63671363253;36381.454278185316;36400.81799591091;36447.81776091103;192561;192629;192644;192947;193237;34031.14922126967;37068.44397334096;37556;38006.23987520179;38028;45183.32225016561;45674.77162614166;45843.77078114588;46144.307835383166;46265.53734462612;33186.17034574058;33459.498107528896;33533.83233083819;33808.32383352368;34472.82763586166;33763;33890.3221935555;33901.32197356089;33906.49140262947;34454.65545344615;36529.452058218616;38284.2343153141;38284.42573361346;38546.80726596349;38637;33060.33879322448;33101.503477447375;33122.17194570155;33255.84069513392;33821.32357352888;23963.640545392183;24158.63762043606;24420.63369049501;24580.262592122264;24779.876100620102 18:36:11 INFO - PID 16522 | |2;jquery.html;556.3129535024244;558.7569407307822;559.7676964059914;560.7140358417206;578.9739461724222;21950.231741889038;22103.557928841412;23031;23044;23059.077636894526;25638;26326.605100923454;26338.07816726415;26371.340716482096;26389.604155937628;6915.792526224209;7091.609961452118;7091.645417729119;7092.113485814273;7095.148582170147;4303.376010478479;5819.068948968166;7091.716331346765;7110.928890711079;7137.714491420337;6948.548344357626;6953.687084081229;6955;6957.79126626202;6961.408280296181;6889.311068893121;6922.10012698346;6928.515003949726;6954.165500140016;6954.860902781941;6818.863622727517;6823.317668233187;6839.692213850339;6840.828979275509;6843.110395648584;29442.852785736148;29975.700242997507;30639.080827574984;31272;31461.685383146105;2084.8436367272516;2127.106615221611;2130.8295336373053;2134.541073669169;2134.6584546472645;20120.19519219244;20694.172233110658;20706.68939965902;20762.688559671617;20769.376918692462;2479.987600062006;2546.2234018624313;2569.730178331267;2569.7687208151356;2664.280644226048;129043;132524;132716.33641831923;132803.33598332043;133045;19270.61458770817;20223.292184773756;20239.291624793063;21496.140154393648;21664.891675541523;21930.671039934412;22965.42586435319;23000.424989375064;23019.079236830676;41032.79483602622;10163.847542286872;10290.691279261635;10338;10339.741506462398;10457.424841633783;9625.71122866315;9664.806703866025;9723.854142187873;9775.755606109762;9888;8860.64557417696;8988.59551320192;9078.682246121354;9172.816543669089;9308;2557.156138474299;2570.588705807062;2644.2728249731476;2654.283343497245;2671.599260110999;953.1564565359706;974.7124598243536;976.1507488485064;985.3545927417553;987.3384832162468;19519.609607808048;19769.308074217333;19896.50312034646;20021.499462513555;20175.899120504302;1043.0560342889696;1047.6856942917095;1049.821530339844;1051.8369652703843;1053.7418332508585;10903.127749779998;10960.616378426717;10977.231593788358;11036.11711063113;11053.226274160732;508845;522823;527760;529991;531564;111385.44307278412;112426.43786781178;112621;113829;116197.41901290609;1162.4187906046977;1183.9408029598485;1184.4610702130683;1191.243560339191;1277.7700013997755;20942.162313507288;21181.470463238544;21242.893785531593;21306.36080917575;21622.67565986543;58262;59584.40415595962;60923;61290.693546531984;62468;72093.63953180201;72102.63948680223;72538.27461725526;72956.63521682358;72976.27023729695;14333.283335833376;14729.337179827147;14787.556373308818;14815.92592037076;14955.252237388304;23295.3011409658;23550.76449235554;23600;24433.267001989967;24557.631635525126;18746.437606871816;19020.23919043223;19080.236790528223;19185.32851350196;19227.711584325967;17020.829791701923;17289.221985010452;17299.82700172982;17585.208665610335;17716.468505944842;18915.243390264237;19880;19905.701414479085;20269.29057482981;20276.89861550742;14548.127112373291;14560.635984100481;14742.557723268319;14789.038712483654;14847.257637118317;18611.81388186101;18694.25222991065;18912.14895329741;18973.43079707611;19008.714869276697 18:36:11 INFO - PID 16522 | |3;mootools.html;1289.6969212235126;1338.759023375792;1340.9128406653567;1357.6266526705158;1370.945162193513;14427.422903083878;15029.774553381709;15096.54710358691;15101.169435681033;15157.545273641781;14603.926980365084;15189.164595947246;15533.301001454933;15622.84377156228;15623.687526249469;2733.2346942855984;2765.184270640166;2767.4741799058115;2782.4156927045346;2793.20393687799;2648.774854137401;2726.5228584997644;2739.6027576001466;2754.834709917401;2792;2733.904313349033;2742.684591271999;2773.3066733316673;2773.6394268745134;2776.7362100600412;2672.465506898628;2687.5968604709255;2725.11433784021;2729.1266794625726;2729.263098963269;2656.5616673249083;2668.973310266892;2674.251209661298;2676.0633778177676;2676.5449873521547;14313.141211527342;14588.854111458963;14622.049566778125;14627.63430914225;14629.634259143606;7792.961035194788;8090.716824911159;8096.959515202445;8101.351891848638;8110.9188908110755;5158.896822063575;5383.73081345935;5464.726763661801;5523.143912693498;5597.160425936101;1872.775266967959;1906.713992901062;1909.255390397746;1979.6337677529702;1984.6427643024394;25288.114915977854;25454;25489;25541.87229063843;25561.744382556124;6661.833454163588;6663.5002374821115;6730.899036514456;6825.726970921108;6846.110005699227;16668.74996875048;16676.74984875228;16681.416150434678;16686.33254669824;16716;7660.46376753633;7825.452218344662;7826.178251283647;7826.7651970440975;7836.647350869224;7126.750563730245;7265.273472652798;7345;7387.630618469055;7394.5193562419345;4732.645051621143;5159.613029022838;5212.869678257998;5409.702466364388;5469.261649677323;1447.8479759625297;1565.92953317101;1569.3016607609504;1580.1546172797498;1583.4141367694026;853.795089178605;855.8972923249125;871.7079778274349;881.7354793561906;894.6421431427481;7902.17027212146;8163.632636531371;8271.966004249469;8318.334533237326;8447.915520844836;923.9953800230955;948.9620415183454;978.7553111722069;987.4766373821886;997.8952210017989;4484.372187893698;4526.773661316921;4540.523245059288;4544.863654090316;4551.40831691878;19883.30408435698;20174.798252017586;20751.273705420237;20783.480412989797;20842.583148336947;19928;20458;21116.89441552813;21260.25589104374;21279.680804787942;11019.393933333744;11119.777604447865;11216.607418740305;11217.943910280559;11219.55121795119;7535.171131175457;7849.921500784919;7997.520148791091;8013.8397232056195;8055.033395992518;17747.733783992993;17893.552661183574;18225.088745562403;18314.99267540298;18592.7211091831;18564.535886602942;18601.255949761857;18613.348532801287;18648.44054678362;18786.53033674169;10671.46642667848;10684.25210235276;10816.891831081588;10816.891831081903;10833.783324333626;11204.49579768896;13286.601401957956;13369.197848129143;13438.193708377528;13443.932780336427;9125.315601329794;10298.639547615798;10717;10784.676459706221;10878.40168790724;10235.283530152818;10282.794344113225;10345.068943795095;10541.947290263499;10624.681259562225;9623.663171788954;9820.754481138028;9840.852387214054;9841.655542055994;9846.507674616383;6980.755673551402;6993.720251189896;7042.225355211025;7042.366187043191;7050.682719277543;7169.641517923978;7187.5687458752645;7249.565026098451;7292.598907060163;7328.5969271690565 18:36:11 INFO - PID 16522 | |4;prototype.html;496.83123386276503;500.23370759698867;504.57867680486794;520.2196704942586;522.1280461629082;6378.298387177407;6402.391772781578;6426.678666066689;6436.903446448301;6479.352064793524;6055.2430946131735;6249.187605611264;6279.053493417044;6283.528735344845;6301.77943771967;5021.949780502185;5023.346964894558;5058.645894787366;5118.846434606968;5140.742962851842;4864.951350486485;4989.301497790312;5013.298138260647;5013.774380152884;5055.248066817198;4942.110420124376;4990.101781679296;5032.11937910866;5050.393952725678;5059.367579052619;5008.749562521859;5009.974950125263;5019.52314530119;5037.773300201481;5041.294218809369;4519.661025423106;4519.932201016987;4528.3207518872105;4555.362249285103;4583.335416364642;6309.652969086698;6312.589681670676;6315.305316415191;6335.746570137235;6347.9051912868645;5660.433956604348;5679.858003549903;5688.800891968761;5713.257276554063;5788.42115788422;6568.967155164241;6601.603903765789;6649.667516624198;6649.70330785497;6653.201615806134;4858.125537403266;4863.343448634424;4883.99878025004;4899.951000490021;4920.630952678528;6878.38094571486;6984.511084224056;6997.6411322565455;6999.405050570656;7006.369426751617;4909.631777616622;4978.8257410990445;4983;5033.1695270280725;5045.974770126126;5067.923981140286;5103.081445339801;5104.897902042013;5143.202803565453;5152.227165925141;5662.924044431545;5692.544596432275;5719.5996280260815;5719.914201286984;5720.570957178145;6281.685915704196;6531.5427920046095;6555.573887697285;6653.401193892476;6675.1989761228;1678.3789997700928;1703.4038086669814;1715.8455738983303;1734.3929624631278;1736.1666400127756;1210.9212901161397;1211.5275042733342;1213.854337479508;1228.3858070964518;1231.8829711177423;5778.711064446761;5803.47768700819;5821.53427725781;5857.677827719516;5874.794382196688;6598.241202261801;6635.5070109225035;6769.72921083161;6794.490413219029;6854.9657251713425;5934.910976335358;5941.405859414024;5953.970230148821;5977;5978.581499295007;3619.9638003619625;3628.4194528875255;3639.6906262967414;3645.9635403646153;3675.5221821163614;6492.675366231764;6556.049372840911;6572.718319927568;6685.53201275906;6728.259891411941;6937.7224911004005;6941.895871561931;6996.6151861648095;7009.053777740041;7088.746751948789;6402.551821372459;6580.276169621337;6585.308542603149;6662.267150613428;6672.399484046362;6847.727250017359;6847.863042739217;6887.896681549881;6901.102856628606;6915.792526224222;6814.7289301136725;6831.282715315019;6848.554843935129;6860.5540639858245;6883.827904302232;6496.512761542997;6629.303923088007;6685.966570167118;6693.799186024427;6701.865962680817;5435.945640543544;5470.261514695624;5477.643953143033;5507.5593952484505;5521;6492.74029038852;6758.797236082925;6760.180498018603;6806.9659651703405;6943.375096241362;6314.652694101685;6443.001334793114;6510.707018184098;6560.671966401565;6620.470362371095;6524.4454221389815;6542.901856472252;6594.934050659432;6600.53796234259;6626.966865165643;6523.380278873499;6562.639054852029;6565.638889861103;6578.506612004023;6597.505187111082;6575.638339891352;6618.271990080991;6618.933810661832;6662.7673880332295;6698.765543205965;6371.55399122057;6396.520260980538;6421.71102300407;6501.4798816095545;6507.707153178024 18:36:11 INFO - PID 16522 | |5;yui.html;874.9562521873909;887.5828360670483;893.6648756716231;903.8463461211593;905.9592318345674;16205.675886482264;16434.424795132174;16441.999999999985;16474.83525164748;19847.900760496213;20443.182272709073;21202.681959770616;21225.787742122575;21290.680639790415;21312.786872131273;912.5574096563178;926.5506229478698;934.9158575728183;935.3779736475236;935.8362286599852;890.4345740454826;924.9213816825544;931.9161275485205;934.1125930366145;935.2845073518764;989.9109080182782;993.0417147452707;994.4878387630376;994.8010397920409;995.3629677006685;983.1004630762843;984.7685793838442;988.6193815381095;990.3859607043607;991.1575161113068;725.3326939215934;725.4812808841704;725.9673314700851;726.7020521586124;728.5009768308726;17965;18127.818721812746;18340.908295458437;18363.72454413185;18485.63028739418;21131.788682113136;21174.894125529427;21462.24882129118;21741.13035478579;21757.12971481155;16487.17564121801;16562.1718914055;16577;16591.41930032443;17271.309147634078;3823.1397935464574;3869.3808990561656;3871.496705428276;3885.1452680410266;3888.9027774305587;112704.4364778179;114607.99999999917;115175;116884;118841;16403.42588009414;16513.752293715603;16777.664446711242;16779.496615101565;16808.495745127664;29001.41997160045;30119.849400753294;30328.84835575808;30399.240018999702;30421.39157216843;8765.824683506295;8804.735857924272;8952.462852228888;8982.056884027214;8987.41581797181;3989.860354887565;4065.207284579538;4067.6339129477897;4069.7558146511305;4087.97956010224;5209.984053109683;5245.055889939771;5272.182811664198;5311.362636483646;5350.91973620396;890.6303883888244;892.8705337725993;916.2715641065369;917.9541022948825;919.2875521470913;686.7047169716969;703.3810246982623;704.9224585295614;713.7680253917498;715.2561336210283;10726.356418614909;11299.378534180534;11624;12212.75574488523;12217;670.8926571748497;692.2419950154573;694.187800273681;700.9719611215596;704.264044073944;5206.557442617344;5252.185911183773;5277.366715994029;5330.360356757214;5433.1850222466155;110202;110389.44805276082;110702.44648776866;111318.44340778244;116101;94637;96829;97899;102600;103240;86425;86513.56743216244;88256.558717206;88468;92163;9882.407055576688;10027.548760305657;10031.097201251923;10063.647772327933;10176.694699159038;39936.60063399329;40356.59643403528;40367.58713445015;40580.39129413002;41103.794481027406;50042.49957500524;50838.74580627073;51165.48834511607;52323.73838130785;52568;14422.206778626856;14782.334794934039;14922.701545969237;14926.776098358316;14983.700325993637;16160;16183.838161618232;16263.26815293291;16266.67466650684;16387.918060409622;15261.160636165117;15278.923605381902;15291.464798731991;15310.540683779505;15414.691706166039;13883.375248113656;14031.087979281314;14068.648283792987;14097.436102555783;14101.858981410054;15265.3893844245;15271.312790924658;15302.770458443354;15364.692706145592;15439.305231264396;11129.387883666475;11312.095032397554;11412.258203216765;11491.942540287246;11638.127140464669;10981.725456863642;11118.166137539554;11204.383758893338;11272.887271127513;11361.204715669825 18:36:11 INFO - PID 16522 | __end_tp_report 18:36:11 INFO - PID 16522 | __start_cc_report 18:36:11 INFO - PID 16522 | _x_x_mozilla_cycle_collect,395 18:36:11 INFO - PID 16522 | __end_cc_report 18:36:11 INFO - PID 16522 | __startTimestamp1503884171038__endTimestamp 18:36:11 INFO - PID 16522 | 18:36:11 INFO - PID 16522 | ------- Summary: start ------- 18:36:11 INFO - PID 16522 | Number of tests: 6 18:36:11 INFO - PID 16522 | 18:36:11 INFO - PID 16522 | [#0] dojo.html Cycles:165 Average:6310.52 Median:6556.05 stddev:741.94 (11.3%) stddev-sans-first:704.83 18:36:11 INFO - PID 16522 | Values: 3270.0 3274.3 3297.5 3302.6 3311.1 6365.1 6373.6 6410.4 6411.6 6457.2 6278.7 6386.1 6399.6 6401.2 6423.6 5823.9 5848.8 5850.9 5865.8 5883.6 5847.2 5856.1 5937.6 5950.5 5977.7 5875.9 5967.2 5967.9 5986.0 5991.2 5689.1 5780.3 5804.3 5884.8 5915.6 5604.5 5792.3 5843.8 5913.1 5956.6 6358.8 6378.2 6382.7 6426.2 6454.4 6711.3 6779.7 6843.4 6880.4 6887.9 6863.1 6924.8 6935.3 6946.1 6957.7 6320.6 6334.2 6351.9 6430.7 6437.3 6891.2 7022.3 7026.4 7034.7 7121.3 6671.6 6673.3 6674.1 6675.2 6693.2 6696.7 6758.0 6769.2 6907.9 6920.9 6787.8 6873.0 6877.9 6878.9 6879.5 6797.1 6807.7 6816.1 6893.2 6921.4 5325.8 5381.7 5383.0 5383.1 5439.7 4702.7 4736.0 4751.5 4786.0 4798.2 5952.8 6007.2 6011.3 6018.7 6020.1 6898.4 6907.3 6960.4 6962.8 7006.1 5892.3 5977.1 5984.1 6071.9 6078.2 5927.0 5940.9 5942.6 5970.0 5978.3 6598.6 6601.3 6610.5 6665.2 6704.8 6735.4 6853.7 6868.0 6960.3 6962.9 6531.9 6540.7 6544.1 6558.3 6572.6 6675.4 6684.9 6793.2 6803.8 6807.0 6877.2 6895.0 6992.9 6994.8 6997.7 6650.9 6654.3 6674.0 6748.2 6750.8 6557.1 6613.3 6648.0 6705.9 6707.3 6622.0 6645.2 6655.8 6668.2 6669.8 6699.9 6750.3 6804.5 6829.6 6835.0 6385.0 6482.4 6491.5 6555.4 6556.0 18:36:11 INFO - PID 16522 | 18:36:11 INFO - PID 16522 | [#1] ext.html Cycles:180 Average:37155.58 Median:3511.36 stddev:52346.30 (1490.8%) stddev-sans-first:52421.98 18:36:11 INFO - PID 16522 | Values: 804.9 827.0 851.1 854.6 1137.1 30187.9 30721.5 40882.8 41372.0 41399.0 41648.0 41968.6 41990.8 42009.8 42016.9 7880.4 8079.9 8085.0 8088.9 8089.3 7956.7 8068.8 8071.8 8072.8 8074.8 8048.9 8103.5 8109.2 8115.5 8124.6 8036.7 8059.6 8065.0 8085.3 8089.4 7904.8 7959.8 7976.1 7977.8 7985.5 36942.4 37295.3 37313.0 37335.8 37434.1 3473.7 3506.0 3506.9 3509.0 3513.7 30651.5 30674.2 30684.8 30689.5 30742.7 4682.4 4861.1 4881.7 4905.2 4945.8 175833.0 176977.1 177447.2 177457.0 178036.0 46255.8 46257.3 46287.0 46324.3 46606.0 49531.3 49547.8 49651.0 49674.8 49715.0 14092.5 14330.2 15863.3 16026.2 16028.8 12935.9 13207.9 13210.9 13211.4 13234.5 14086.9 14127.4 14162.2 14172.6 14237.1 5505.9 5540.6 5678.5 5683.4 5742.7 990.9 995.9 1003.4 1010.5 1017.7 22423.2 22620.4 22776.0 22917.2 23066.1 1078.6 1101.1 1111.2 1116.9 1123.6 12192.2 12218.2 12223.9 12235.4 12484.8 230948.0 232615.0 232887.0 233410.0 233562.0 50576.0 50843.0 51139.0 51277.2 51289.0 1384.3 1431.9 1434.2 1439.5 1446.7 22195.2 22236.0 22280.0 22600.8 22687.3 36303.8 36328.6 36381.5 36400.8 36447.8 192561.0 192629.0 192644.0 192947.0 193237.0 34031.1 37068.4 37556.0 38006.2 38028.0 45183.3 45674.8 45843.8 46144.3 46265.5 33186.2 33459.5 33533.8 33808.3 34472.8 33763.0 33890.3 33901.3 33906.5 34454.7 36529.5 38284.2 38284.4 38546.8 38637.0 33060.3 33101.5 33122.2 33255.8 33821.3 23963.6 24158.6 24420.6 24580.3 24779.9 18:36:11 INFO - PID 16522 | 18:36:11 INFO - PID 16522 | [#2] jquery.html Cycles:180 Average:36233.20 Median:23025.04 stddev:87555.48 (380.3%) stddev-sans-first:87760.12 18:36:11 INFO - PID 16522 | Values: 556.3 558.8 559.8 560.7 579.0 21950.2 22103.6 23031.0 23044.0 23059.1 25638.0 26326.6 26338.1 26371.3 26389.6 6915.8 7091.6 7091.6 7092.1 7095.1 4303.4 5819.1 7091.7 7110.9 7137.7 6948.5 6953.7 6955.0 6957.8 6961.4 6889.3 6922.1 6928.5 6954.2 6954.9 6818.9 6823.3 6839.7 6840.8 6843.1 29442.9 29975.7 30639.1 31272.0 31461.7 2084.8 2127.1 2130.8 2134.5 2134.7 20120.2 20694.2 20706.7 20762.7 20769.4 2480.0 2546.2 2569.7 2569.8 2664.3 129043.0 132524.0 132716.3 132803.3 133045.0 19270.6 20223.3 20239.3 21496.1 21664.9 21930.7 22965.4 23000.4 23019.1 41032.8 10163.8 10290.7 10338.0 10339.7 10457.4 9625.7 9664.8 9723.9 9775.8 9888.0 8860.6 8988.6 9078.7 9172.8 9308.0 2557.2 2570.6 2644.3 2654.3 2671.6 953.2 974.7 976.2 985.4 987.3 19519.6 19769.3 19896.5 20021.5 20175.9 1043.1 1047.7 1049.8 1051.8 1053.7 10903.1 10960.6 10977.2 11036.1 11053.2 508845.0 522823.0 527760.0 529991.0 531564.0 111385.4 112426.4 112621.0 113829.0 116197.4 1162.4 1183.9 1184.5 1191.2 1277.8 20942.2 21181.5 21242.9 21306.4 21622.7 58262.0 59584.4 60923.0 61290.7 62468.0 72093.6 72102.6 72538.3 72956.6 72976.3 14333.3 14729.3 14787.6 14815.9 14955.3 23295.3 23550.8 23600.0 24433.3 24557.6 18746.4 19020.2 19080.2 19185.3 19227.7 17020.8 17289.2 17299.8 17585.2 17716.5 18915.2 19880.0 19905.7 20269.3 20276.9 14548.1 14560.6 14742.6 14789.0 14847.3 18611.8 18694.3 18912.1 18973.4 19008.7 18:36:11 INFO - PID 16522 | 18:36:11 INFO - PID 16522 | [#3] mootools.html Cycles:180 Average:9061.80 Median:2682.07 stddev:6413.32 (239.1%) stddev-sans-first:6404.72 18:36:11 INFO - PID 16522 | Values: 1289.7 1338.8 1340.9 1357.6 1370.9 14427.4 15029.8 15096.5 15101.2 15157.5 14603.9 15189.2 15533.3 15622.8 15623.7 2733.2 2765.2 2767.5 2782.4 2793.2 2648.8 2726.5 2739.6 2754.8 2792.0 2733.9 2742.7 2773.3 2773.6 2776.7 2672.5 2687.6 2725.1 2729.1 2729.3 2656.6 2669.0 2674.3 2676.1 2676.5 14313.1 14588.9 14622.0 14627.6 14629.6 7793.0 8090.7 8097.0 8101.4 8110.9 5158.9 5383.7 5464.7 5523.1 5597.2 1872.8 1906.7 1909.3 1979.6 1984.6 25288.1 25454.0 25489.0 25541.9 25561.7 6661.8 6663.5 6730.9 6825.7 6846.1 16668.7 16676.7 16681.4 16686.3 16716.0 7660.5 7825.5 7826.2 7826.8 7836.6 7126.8 7265.3 7345.0 7387.6 7394.5 4732.6 5159.6 5212.9 5409.7 5469.3 1447.8 1565.9 1569.3 1580.2 1583.4 853.8 855.9 871.7 881.7 894.6 7902.2 8163.6 8272.0 8318.3 8447.9 924.0 949.0 978.8 987.5 997.9 4484.4 4526.8 4540.5 4544.9 4551.4 19883.3 20174.8 20751.3 20783.5 20842.6 19928.0 20458.0 21116.9 21260.3 21279.7 11019.4 11119.8 11216.6 11217.9 11219.6 7535.2 7849.9 7997.5 8013.8 8055.0 17747.7 17893.6 18225.1 18315.0 18592.7 18564.5 18601.3 18613.3 18648.4 18786.5 10671.5 10684.3 10816.9 10816.9 10833.8 11204.5 13286.6 13369.2 13438.2 13443.9 9125.3 10298.6 10717.0 10784.7 10878.4 10235.3 10282.8 10345.1 10541.9 10624.7 9623.7 9820.8 9840.9 9841.7 9846.5 6980.8 6993.7 7042.2 7042.4 7050.7 7169.6 7187.6 7249.6 7292.6 7328.6 18:36:11 INFO - PID 16522 | 18:36:11 INFO - PID 16522 | [#4] prototype.html Cycles:180 Average:5590.33 Median:6305.72 stddev:1573.57 (25.0%) stddev-sans-first:1530.84 18:36:11 INFO - PID 16522 | Values: 496.8 500.2 504.6 520.2 522.1 6378.3 6402.4 6426.7 6436.9 6479.4 6055.2 6249.2 6279.1 6283.5 6301.8 5021.9 5023.3 5058.6 5118.8 5140.7 4865.0 4989.3 5013.3 5013.8 5055.2 4942.1 4990.1 5032.1 5050.4 5059.4 5008.7 5010.0 5019.5 5037.8 5041.3 4519.7 4519.9 4528.3 4555.4 4583.3 6309.7 6312.6 6315.3 6335.7 6347.9 5660.4 5679.9 5688.8 5713.3 5788.4 6569.0 6601.6 6649.7 6649.7 6653.2 4858.1 4863.3 4884.0 4900.0 4920.6 6878.4 6984.5 6997.6 6999.4 7006.4 4909.6 4978.8 4983.0 5033.2 5046.0 5067.9 5103.1 5104.9 5143.2 5152.2 5662.9 5692.5 5719.6 5719.9 5720.6 6281.7 6531.5 6555.6 6653.4 6675.2 1678.4 1703.4 1715.8 1734.4 1736.2 1210.9 1211.5 1213.9 1228.4 1231.9 5778.7 5803.5 5821.5 5857.7 5874.8 6598.2 6635.5 6769.7 6794.5 6855.0 5934.9 5941.4 5954.0 5977.0 5978.6 3620.0 3628.4 3639.7 3646.0 3675.5 6492.7 6556.0 6572.7 6685.5 6728.3 6937.7 6941.9 6996.6 7009.1 7088.7 6402.6 6580.3 6585.3 6662.3 6672.4 6847.7 6847.9 6887.9 6901.1 6915.8 6814.7 6831.3 6848.6 6860.6 6883.8 6496.5 6629.3 6686.0 6693.8 6701.9 5435.9 5470.3 5477.6 5507.6 5521.0 6492.7 6758.8 6760.2 6807.0 6943.4 6314.7 6443.0 6510.7 6560.7 6620.5 6524.4 6542.9 6594.9 6600.5 6627.0 6523.4 6562.6 6565.6 6578.5 6597.5 6575.6 6618.3 6618.9 6662.8 6698.8 6371.6 6396.5 6421.7 6501.5 6507.7 18:36:11 INFO - PID 16522 | 18:36:11 INFO - PID 16522 | [#5] yui.html Cycles:180 Average:22307.60 Median:17122.27 stddev:31121.23 (181.8%) stddev-sans-first:31166.92 18:36:11 INFO - PID 16522 | Values: 875.0 887.6 893.7 903.8 906.0 16205.7 16434.4 16442.0 16474.8 19847.9 20443.2 21202.7 21225.8 21290.7 21312.8 912.6 926.6 934.9 935.4 935.8 890.4 924.9 931.9 934.1 935.3 989.9 993.0 994.5 994.8 995.4 983.1 984.8 988.6 990.4 991.2 725.3 725.5 726.0 726.7 728.5 17965.0 18127.8 18340.9 18363.7 18485.6 21131.8 21174.9 21462.2 21741.1 21757.1 16487.2 16562.2 16577.0 16591.4 17271.3 3823.1 3869.4 3871.5 3885.1 3888.9 112704.4 114608.0 115175.0 116884.0 118841.0 16403.4 16513.8 16777.7 16779.5 16808.5 29001.4 30119.8 30328.8 30399.2 30421.4 8765.8 8804.7 8952.5 8982.1 8987.4 3989.9 4065.2 4067.6 4069.8 4088.0 5210.0 5245.1 5272.2 5311.4 5350.9 890.6 892.9 916.3 918.0 919.3 686.7 703.4 704.9 713.8 715.3 10726.4 11299.4 11624.0 12212.8 12217.0 670.9 692.2 694.2 701.0 704.3 5206.6 5252.2 5277.4 5330.4 5433.2 110202.0 110389.4 110702.4 111318.4 116101.0 94637.0 96829.0 97899.0 102600.0 103240.0 86425.0 86513.6 88256.6 88468.0 92163.0 9882.4 10027.5 10031.1 10063.6 10176.7 39936.6 40356.6 40367.6 40580.4 41103.8 50042.5 50838.7 51165.5 52323.7 52568.0 14422.2 14782.3 14922.7 14926.8 14983.7 16160.0 16183.8 16263.3 16266.7 16387.9 15261.2 15278.9 15291.5 15310.5 15414.7 13883.4 14031.1 14068.6 14097.4 14101.9 15265.4 15271.3 15302.8 15364.7 15439.3 11129.4 11312.1 11412.3 11491.9 11638.1 10981.7 11118.2 11204.4 11272.9 11361.2 18:36:11 INFO - PID 16522 | -------- Summary: end -------- 18:36:11 INFO - PID 16522 | 18:36:11 INFO - TEST-INFO | 16522: exit 0 18:36:11 INFO - TEST-OK | dromaeo_css | took 1111076ms 18:36:11 INFO - TEST-START | kraken 18:36:11 INFO - Initialising browser for kraken test... 18:36:11 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:44180/getInfo.html -profile /tmp/tmp2WHcue/profile 18:36:11 INFO - TEST-INFO | started process 16945 (/builds/slave/test/build/application/firefox/firefox http://localhost:44180/getInfo.html) 18:36:17 INFO - TEST-INFO | 16945: exit 0 18:36:17 INFO - Browser initialized. 18:36:17 INFO - Running cycle 1/1 for kraken test... 18:36:17 INFO - Using env: {'DISPLAY': ':0', 18:36:17 INFO - 'HOME': '/home/cltbld', 18:36:17 INFO - 'JSGC_DISABLE_POISONING': '1', 18:36:17 INFO - 'LANG': 'en_US.UTF-8', 18:36:17 INFO - 'LANGUAGE': 'en_US:en', 18:36:17 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 18:36:17 INFO - 'LOGNAME': 'cltbld', 18:36:17 INFO - 'MAIL': '/var/mail/cltbld', 18:36:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:36:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:36:17 INFO - 'MOZ_CRASHREPORTER': '1', 18:36:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:36:17 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 18:36:17 INFO - 'MOZ_NO_REMOTE': '1', 18:36:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:36:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:36:17 INFO - 'NO_EM_RESTART': '1', 18:36:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:36:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:36:17 INFO - 'PWD': '/builds/slave/test', 18:36:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:36:17 INFO - 'RUST_BACKTRACE': 'full', 18:36:17 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:36:17 INFO - 'SHELL': '/bin/bash', 18:36:17 INFO - 'SHLVL': '1', 18:36:17 INFO - 'STYLO_FORCE_ENABLED': '1', 18:36:17 INFO - 'TERM': 'linux', 18:36:17 INFO - 'TMOUT': '86400', 18:36:17 INFO - 'USER': 'cltbld', 18:36:17 INFO - 'XDG_SESSION_COOKIE': '8c32c1c60be582960b75045f000001c1-1503873263.99089-675433845', 18:36:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:36:17 INFO - '_': '/tools/buildbot/bin/python'} 18:36:17 INFO - TEST-INFO | started process 17137 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2WHcue/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:36:18 INFO - PID 17137 | 18:36:18 INFO - PID 17137 | (/builds/slave/test/build/application/firefox/firefox:17186): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:36:18 INFO - PID 17137 | 18:36:18 INFO - PID 17137 | 18:36:18 INFO - PID 17137 | (/builds/slave/test/build/application/firefox/firefox:17247): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:36:18 INFO - PID 17137 | 18:36:18 INFO - PID 17137 | __metrics Screen width/height:1600/1200 18:36:18 INFO - PID 17137 | colorDepth:24 18:36:18 INFO - PID 17137 | Browser inner width/height: 1024/768 18:36:18 INFO - PID 17137 | __metrics 18:36:28 INFO - PID 17137 | RSS: Main: 174800896 18:36:28 INFO - PID 17137 | 18:36:31 INFO - PID 17137 | Cycle 1(1): loaded http://localhost:44180/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44180/tests/kraken/driver.html?testName=audio-beat-detection) 18:36:32 INFO - PID 17137 | RSS: Main: 180391936 18:36:32 INFO - PID 17137 | 18:36:34 INFO - PID 17137 | Cycle 1(1): loaded http://localhost:44180/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44180/tests/kraken/driver.html?testName=audio-dft) 18:36:35 INFO - PID 17137 | RSS: Main: 181870592 18:36:35 INFO - PID 17137 | 18:36:38 INFO - PID 17137 | Cycle 1(1): loaded http://localhost:44180/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44180/tests/kraken/driver.html?testName=audio-fft) 18:36:39 INFO - PID 17137 | RSS: Main: 174919680 18:36:39 INFO - PID 17137 | 18:36:41 INFO - PID 17137 | Cycle 1(1): loaded http://localhost:44180/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44180/tests/kraken/driver.html?testName=audio-oscillator) 18:36:41 INFO - PID 17137 | RSS: Main: 174821376 18:36:41 INFO - PID 17137 | 18:36:44 INFO - PID 17137 | Cycle 1(1): loaded http://localhost:44180/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44180/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:36:44 INFO - PID 17137 | RSS: Main: 175280128 18:36:44 INFO - PID 17137 | 18:36:47 INFO - PID 17137 | Cycle 1(1): loaded http://localhost:44180/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44180/tests/kraken/driver.html?testName=imaging-darkroom) 18:36:48 INFO - PID 17137 | RSS: Main: 176586752 18:36:48 INFO - PID 17137 | 18:36:51 INFO - PID 17137 | Cycle 1(1): loaded http://localhost:44180/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44180/tests/kraken/driver.html?testName=imaging-desaturate) 18:36:52 INFO - PID 17137 | RSS: Main: 176345088 18:36:52 INFO - PID 17137 | 18:36:55 INFO - PID 17137 | Cycle 1(1): loaded http://localhost:44180/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44180/tests/kraken/driver.html?testName=json-parse-financial) 18:36:56 INFO - PID 17137 | RSS: Main: 176930816 18:36:56 INFO - PID 17137 | 18:36:57 INFO - PID 17137 | Cycle 1(1): loaded http://localhost:44180/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44180/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:36:58 INFO - PID 17137 | RSS: Main: 177418240 18:36:58 INFO - PID 17137 | 18:36:59 INFO - PID 17137 | Cycle 1(1): loaded http://localhost:44180/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44180/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:37:00 INFO - PID 17137 | RSS: Main: 177201152 18:37:00 INFO - PID 17137 | 18:37:02 INFO - PID 17137 | Cycle 1(1): loaded http://localhost:44180/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44180/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:37:03 INFO - PID 17137 | RSS: Main: 176750592 18:37:03 INFO - PID 17137 | 18:37:05 INFO - PID 17137 | Cycle 1(1): loaded http://localhost:44180/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44180/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:37:06 INFO - PID 17137 | RSS: Main: 177192960 18:37:06 INFO - PID 17137 | 18:37:09 INFO - PID 17137 | Cycle 1(1): loaded http://localhost:44180/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44180/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:37:10 INFO - PID 17137 | RSS: Main: 177381376 18:37:10 INFO - PID 17137 | 18:37:12 INFO - PID 17137 | Cycle 1(1): loaded http://localhost:44180/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44180/tests/kraken/driver.html?testName=ai-astar) 18:37:12 INFO - PID 17137 | RSS: Main: 178999296 18:37:12 INFO - PID 17137 | 18:37:12 INFO - PID 17137 | __start_tp_report 18:37:12 INFO - PID 17137 | _x_x_mozilla_page_load 18:37:12 INFO - PID 17137 | _x_x_mozilla_page_load_details 18:37:12 INFO - PID 17137 | |i|pagename|runs| 18:37:12 INFO - PID 17137 | |0;ai-astar;98;108;100;107;109;110;108;99;108;106 18:37:12 INFO - PID 17137 | |1;audio-beat-detection;144;124;146;175;116;146;144;118;167;125 18:37:12 INFO - PID 17137 | |2;audio-dft;161;161;161;162;162;160;165;167;161;164 18:37:12 INFO - PID 17137 | |3;audio-fft;73;73;73;194;75;74;74;88;100;85 18:37:12 INFO - PID 17137 | |4;audio-oscillator;79;79;134;79;78;80;126;80;79;127 18:37:12 INFO - PID 17137 | |5;imaging-gaussian-blur;114;114;115;114;114;114;114;113;113;115 18:37:12 INFO - PID 17137 | |6;imaging-darkroom;147;148;148;149;147;149;148;149;147;148 18:37:12 INFO - PID 17137 | |7;imaging-desaturate;95;93;94;93;94;94;94;93;93;93 18:37:12 INFO - PID 17137 | |8;json-parse-financial;54;54;53;54;53;54;52;52;54;52 18:37:12 INFO - PID 17137 | |9;json-stringify-tinderbox;38;38;37;38;38;38;38;38;38;37 18:37:12 INFO - PID 17137 | |10;stanford-crypto-aes;76;75;80;83;75;74;80;74;74;77 18:37:12 INFO - PID 17137 | |11;stanford-crypto-ccm;118;127;118;118;118;127;121;115;116;152 18:37:12 INFO - PID 17137 | |12;stanford-crypto-pbkdf2;180;168;180;168;171;167;179;170;166;166 18:37:12 INFO - PID 17137 | |13;stanford-crypto-sha256-iterative;67;64;71;65;63;64;65;71;68;67 18:37:12 INFO - PID 17137 | __end_tp_report 18:37:12 INFO - PID 17137 | __start_cc_report 18:37:12 INFO - PID 17137 | _x_x_mozilla_cycle_collect,943 18:37:12 INFO - PID 17137 | __end_cc_report 18:37:12 INFO - PID 17137 | __startTimestamp1503884232288__endTimestamp 18:37:12 INFO - PID 17137 | 18:37:12 INFO - PID 17137 | ------- Summary: start ------- 18:37:12 INFO - PID 17137 | Number of tests: 14 18:37:12 INFO - PID 17137 | 18:37:12 INFO - PID 17137 | [#0] ai-astar Cycles:10 Average:105.30 Median:108.50 stddev:4.50 (4.1%) stddev-sans-first:3.92 18:37:12 INFO - PID 17137 | Values: 98.0 108.0 100.0 107.0 109.0 110.0 108.0 99.0 108.0 106.0 18:37:12 INFO - PID 17137 | 18:37:12 INFO - PID 17137 | [#1] audio-beat-detection Cycles:10 Average:140.50 Median:145.00 stddev:19.99 (13.8%) stddev-sans-first:21.16 18:37:12 INFO - PID 17137 | Values: 144.0 124.0 146.0 175.0 116.0 146.0 144.0 118.0 167.0 125.0 18:37:12 INFO - PID 17137 | 18:37:12 INFO - PID 17137 | [#2] audio-dft Cycles:10 Average:162.40 Median:162.00 stddev:2.22 (1.4%) stddev-sans-first:2.30 18:37:12 INFO - PID 17137 | Values: 161.0 161.0 161.0 162.0 162.0 160.0 165.0 167.0 161.0 164.0 18:37:12 INFO - PID 17137 | 18:37:12 INFO - PID 17137 | [#3] audio-fft Cycles:10 Average:90.90 Median:74.00 stddev:37.33 (50.4%) stddev-sans-first:39.03 18:37:12 INFO - PID 17137 | Values: 73.0 73.0 73.0 194.0 75.0 74.0 74.0 88.0 100.0 85.0 18:37:12 INFO - PID 17137 | 18:37:12 INFO - PID 17137 | [#4] audio-oscillator Cycles:10 Average:94.10 Median:79.00 stddev:24.18 (30.6%) stddev-sans-first:25.02 18:37:12 INFO - PID 17137 | Values: 79.0 79.0 134.0 79.0 78.0 80.0 126.0 80.0 79.0 127.0 18:37:12 INFO - PID 17137 | 18:37:12 INFO - PID 17137 | [#5] imaging-gaussian-blur Cycles:10 Average:114.00 Median:114.00 stddev:0.67 (0.6%) stddev-sans-first:0.71 18:37:12 INFO - PID 17137 | Values: 114.0 114.0 115.0 114.0 114.0 114.0 114.0 113.0 113.0 115.0 18:37:12 INFO - PID 17137 | 18:37:12 INFO - PID 17137 | [#6] imaging-darkroom Cycles:10 Average:148.00 Median:148.00 stddev:0.82 (0.6%) stddev-sans-first:0.78 18:37:12 INFO - PID 17137 | Values: 147.0 148.0 148.0 149.0 147.0 149.0 148.0 149.0 147.0 148.0 18:37:12 INFO - PID 17137 | 18:37:12 INFO - PID 17137 | [#7] imaging-desaturate Cycles:10 Average:93.60 Median:94.00 stddev:0.70 (0.7%) stddev-sans-first:0.53 18:37:12 INFO - PID 17137 | Values: 95.0 93.0 94.0 93.0 94.0 94.0 94.0 93.0 93.0 93.0 18:37:12 INFO - PID 17137 | 18:37:12 INFO - PID 17137 | [#8] json-parse-financial Cycles:10 Average:53.20 Median:54.00 stddev:0.92 (1.7%) stddev-sans-first:0.93 18:37:12 INFO - PID 17137 | Values: 54.0 54.0 53.0 54.0 53.0 54.0 52.0 52.0 54.0 52.0 18:37:12 INFO - PID 17137 | 18:37:12 INFO - PID 17137 | [#9] json-stringify-tinderbox Cycles:10 Average:37.80 Median:38.00 stddev:0.42 (1.1%) stddev-sans-first:0.44 18:37:12 INFO - PID 17137 | Values: 38.0 38.0 37.0 38.0 38.0 38.0 38.0 38.0 38.0 37.0 18:37:12 INFO - PID 17137 | 18:37:12 INFO - PID 17137 | [#10] stanford-crypto-aes Cycles:10 Average:76.80 Median:76.50 stddev:3.16 (4.1%) stddev-sans-first:3.33 18:37:12 INFO - PID 17137 | Values: 76.0 75.0 80.0 83.0 75.0 74.0 80.0 74.0 74.0 77.0 18:37:12 INFO - PID 17137 | 18:37:12 INFO - PID 17137 | [#11] stanford-crypto-ccm Cycles:10 Average:123.00 Median:119.50 stddev:11.01 (9.2%) stddev-sans-first:11.52 18:37:12 INFO - PID 17137 | Values: 118.0 127.0 118.0 118.0 118.0 127.0 121.0 115.0 116.0 152.0 18:37:12 INFO - PID 17137 | 18:37:12 INFO - PID 17137 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.50 Median:170.50 stddev:5.85 (3.4%) stddev-sans-first:5.34 18:37:12 INFO - PID 17137 | Values: 180.0 168.0 180.0 168.0 171.0 167.0 179.0 170.0 166.0 166.0 18:37:12 INFO - PID 17137 | 18:37:12 INFO - PID 17137 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.50 Median:67.00 stddev:2.84 (4.2%) stddev-sans-first:3.00 18:37:12 INFO - PID 17137 | Values: 67.0 64.0 71.0 65.0 63.0 64.0 65.0 71.0 68.0 67.0 18:37:12 INFO - PID 17137 | -------- Summary: end -------- 18:37:12 INFO - PID 17137 | 18:37:12 INFO - PID 17137 | 18:37:12 INFO - PID 17137 | ###!!! [Parent][MessageChannel] Error: (msgtype=0x150082,name=PBrowser::Msg_Destroy) Closed channel: cannot send/recv 18:37:12 INFO - PID 17137 | 18:37:12 INFO - PID 17137 | 18:37:12 INFO - PID 17137 | ###!!! [Child][MessageChannel] Error: (msgtype=0x150001,name=PBrowser::Msg_AsyncMessage) Closed channel: cannot send/recv 18:37:12 INFO - PID 17137 | 18:37:12 INFO - PID 17137 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 18:37:12 INFO - TEST-INFO | 17137: exit 0 18:37:13 INFO - TEST-OK | kraken | took 61257ms 18:37:13 INFO - SUITE-END | took 1172s 18:37:13 INFO - Completed test suite (00:19:32) 18:37:13 INFO - javascript benchmark 18:37:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8517.872404160667, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3269.986304245684, 3274.345130973805, 3297.472404415293, 3302.6367099619024, 3311.0894504011394, 6365.077063825743, 6373.585716928396, 6410.358964103593, 6411.615303081812, 6457.160569126007, 6278.686065696708, 6386.137871387361, 6399.616023038621, 6401.231852177745, 6423.614583125016, 5823.912641310384, 5848.795292164754, 5850.853728656796, 5865.794697185599, 5883.646981181121, 5847.239858818369, 5856.121581762748, 5937.584369094165, 5950.494207992326, 5977.671228082454, 5875.941240587604, 5967.16459695643, 5967.910481342783, 5985.970070149665, 5991.221141251653, 5689.061304884689, 5780.335261444903, 5804.332501762309, 5884.823455296348, 5915.615484993505, 5604.467575580314, 5792.333881603585, 5843.824685259449, 5913.083472061838, 5956.612820166719, 6358.7774427895265, 6378.170837791104, 6382.712777924958, 6426.164598602152, 6454.386833250881, 6711.295313992009, 6779.661016949133, 6843.418309443703, 6880.380765731058, 6887.931120688779, 6863.10779598654, 6924.792256232321, 6935.271796461399, 6946.0622815920215, 6957.7216911323485, 6320.557560970734, 6334.208223972004, 6351.904721429136, 6430.742770289136, 6437.324080971477, 6891.173059232924, 7022.297770223039, 7026.402755765817, 7034.6834392452465, 7121.28787121294, 6671.566348187353, 6673.265940746514, 6674.098996635489, 6675.198976122896, 6693.163354580677, 6696.698648560827, 6757.966210168917, 6769.153855768029, 6907.896381554281, 6920.8961865572055, 6787.830304242433, 6873.0, 6877.931220687829, 6878.8624227516175, 6879.5184337095925, 6797.116374871235, 6807.727690892408, 6816.113905192393, 6893.20728116263, 6921.411680007155, 5325.813596524103, 5381.67709937405, 5382.973085134549, 5383.111786555253, 5439.728013599383, 4702.694324868872, 4735.95264047362, 4751.54860288279, 4786.0, 4798.2082956311815, 5952.821415357546, 6007.189029481052, 6011.308699499614, 6018.668973206516, 6020.09698545213, 6898.379145876896, 6907.309269073053, 6960.443164546926, 6962.7911162665205, 7006.054182685374, 5892.322382925932, 5977.133315669291, 5984.132300816357, 6071.939280607226, 6078.179445774764, 5927.0, 5940.851478713067, 5942.64344139353, 5969.970150149222, 5978.282606087298, 6598.60408375499, 6601.33986601336, 6610.504212184105, 6665.166854143232, 6704.765333213314, 6735.360140786617, 6853.691583878837, 6868.0, 6960.303969602999, 6962.895556566757, 6531.934680653133, 6540.672966351759, 6544.116544266463, 6558.344165583403, 6572.605643661395, 6675.4325882300545, 6684.866302674017, 6793.184817821893, 6803.795886123424, 6806.965965170142, 6877.243503214742, 6894.965525172342, 6992.8811390177325, 6994.790156295319, 6997.650117494003, 6650.90023649636, 6654.3012983637955, 6673.9666301668185, 6748.19021717397, 6750.831229219309, 6557.147570815763, 6613.338666133348, 6648.0, 6705.932940670531, 6707.295733947865, 6622.039804228456, 6645.235797883112, 6655.833604159741, 6668.233153187445, 6669.799906002828, 6699.899501507579, 6750.32496750321, 6804.489663275175, 6829.6243706596615, 6835.0, 6385.0, 6482.35176482348, 6491.545591808528, 6555.3772391622715, 6556.0493728410065], "value": 6255.5482216806495, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [804.8591496488117, 826.9586520673965, 851.0510780479767, 854.5983387807728, 1137.0562433180462, 30187.943421980235, 30721.539176912338, 40882.795586022105, 41372.0, 41399.0, 41648.0, 41968.58031419685, 41990.790046049806, 42009.789951050276, 42016.94957626061, 7880.4089693272945, 8079.919200807975, 8084.959575202116, 8088.919110808904, 8089.312408445289, 7956.6817327306835, 8068.838623227532, 8071.798205044862, 8072.798180045487, 8074.798130046737, 8048.919510804905, 8103.473274237156, 8109.229623185788, 8115.513069215866, 8124.5937703114905, 8036.678532858678, 8059.63731632075, 8064.959675201645, 8085.3127484163915, 8089.352851771873, 7904.762857114296, 7959.761207163794, 7976.122626511079, 7977.760667179936, 7985.520868747835, 36942.445863312074, 37295.25409491822, 37313.0, 37335.81332093322, 37434.06414839624, 3473.722102231841, 3506.0358401439553, 3506.912327191815, 3508.9823951054163, 3513.736469764752, 30651.540226896614, 30674.233144171576, 30684.846575767202, 30689.539656905163, 30742.69257307443, 4682.391289132425, 4861.076395484882, 4881.658283920127, 4905.215165573527, 4945.7774400152075, 175833.0, 176977.1151144249, 177447.22552774436, 177457.0, 178036.0, 46255.76872115618, 46257.30614040792, 46287.0, 46324.305135423, 46606.0, 49531.25703114456, 49547.752261238464, 49651.0, 49674.75162624236, 49715.0, 14092.506762263272, 14330.211838348783, 15863.286152123183, 16026.19869006545, 16028.75956860648, 12935.935320323339, 13207.933960330136, 13210.933945330404, 13211.405486752927, 13234.536791212262, 14086.859131408555, 14127.434902603985, 14162.150270983773, 14172.64568385778, 14237.074590151815, 5505.917411238835, 5540.56215265282, 5678.517326027251, 5683.403242659544, 5742.684152371659, 990.8761404824397, 995.9103680668775, 1003.3895597565802, 1010.4644538394663, 1017.7150397888455, 22423.21518746836, 22620.434489137904, 22775.975081121058, 22917.197898073486, 23066.077356905873, 1078.6224821312635, 1101.0695961912172, 1111.2388014210292, 1116.944152792357, 1123.5898896902572, 12192.207506512048, 12218.20581662189, 12223.877761222451, 12235.449404776806, 12484.75030499385, 230948.0, 232615.0, 232887.0, 233410.0, 233562.0, 50576.0, 50843.0, 51139.0, 51277.23084153741, 51289.0, 1384.349355802771, 1431.8782903453114, 1434.189682829219, 1439.4961763382732, 1446.6528033271939, 22195.22316718907, 22236.0, 22280.0, 22600.773992259867, 22687.319380418612, 36303.81848090742, 36328.63671363253, 36381.454278185316, 36400.81799591091, 36447.81776091103, 192561.0, 192629.0, 192644.0, 192947.0, 193237.0, 34031.14922126967, 37068.44397334096, 37556.0, 38006.23987520179, 38028.0, 45183.32225016561, 45674.77162614166, 45843.77078114588, 46144.307835383166, 46265.53734462612, 33186.17034574058, 33459.498107528896, 33533.83233083819, 33808.32383352368, 34472.82763586166, 33763.0, 33890.3221935555, 33901.32197356089, 33906.49140262947, 34454.65545344615, 36529.452058218616, 38284.2343153141, 38284.42573361346, 38546.80726596349, 38637.0, 33060.33879322448, 33101.503477447375, 33122.17194570155, 33255.84069513392, 33821.32357352888, 23963.640545392183, 24158.63762043606, 24420.63369049501, 24580.262592122264, 24779.876100620102], "value": 17187.31034992022, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [556.3129535024244, 558.7569407307822, 559.7676964059914, 560.7140358417206, 578.9739461724222, 21950.231741889038, 22103.557928841412, 23031.0, 23044.0, 23059.077636894526, 25638.0, 26326.605100923454, 26338.07816726415, 26371.340716482096, 26389.604155937628, 6915.792526224209, 7091.609961452118, 7091.645417729119, 7092.113485814273, 7095.148582170147, 4303.376010478479, 5819.068948968166, 7091.716331346765, 7110.928890711079, 7137.714491420337, 6948.548344357626, 6953.687084081229, 6955.0, 6957.79126626202, 6961.408280296181, 6889.311068893121, 6922.10012698346, 6928.515003949726, 6954.165500140016, 6954.860902781941, 6818.863622727517, 6823.317668233187, 6839.692213850339, 6840.828979275509, 6843.110395648584, 29442.852785736148, 29975.700242997507, 30639.080827574984, 31272.0, 31461.685383146105, 2084.8436367272516, 2127.106615221611, 2130.8295336373053, 2134.541073669169, 2134.6584546472645, 20120.19519219244, 20694.172233110658, 20706.68939965902, 20762.688559671617, 20769.376918692462, 2479.987600062006, 2546.2234018624313, 2569.730178331267, 2569.7687208151356, 2664.280644226048, 129043.0, 132524.0, 132716.33641831923, 132803.33598332043, 133045.0, 19270.61458770817, 20223.292184773756, 20239.291624793063, 21496.140154393648, 21664.891675541523, 21930.671039934412, 22965.42586435319, 23000.424989375064, 23019.079236830676, 41032.79483602622, 10163.847542286872, 10290.691279261635, 10338.0, 10339.741506462398, 10457.424841633783, 9625.71122866315, 9664.806703866025, 9723.854142187873, 9775.755606109762, 9888.0, 8860.64557417696, 8988.59551320192, 9078.682246121354, 9172.816543669089, 9308.0, 2557.156138474299, 2570.588705807062, 2644.2728249731476, 2654.283343497245, 2671.599260110999, 953.1564565359706, 974.7124598243536, 976.1507488485064, 985.3545927417553, 987.3384832162468, 19519.609607808048, 19769.308074217333, 19896.50312034646, 20021.499462513555, 20175.899120504302, 1043.0560342889696, 1047.6856942917095, 1049.821530339844, 1051.8369652703843, 1053.7418332508585, 10903.127749779998, 10960.616378426717, 10977.231593788358, 11036.11711063113, 11053.226274160732, 508845.0, 522823.0, 527760.0, 529991.0, 531564.0, 111385.44307278412, 112426.43786781178, 112621.0, 113829.0, 116197.41901290609, 1162.4187906046977, 1183.9408029598485, 1184.4610702130683, 1191.243560339191, 1277.7700013997755, 20942.162313507288, 21181.470463238544, 21242.893785531593, 21306.36080917575, 21622.67565986543, 58262.0, 59584.40415595962, 60923.0, 61290.693546531984, 62468.0, 72093.63953180201, 72102.63948680223, 72538.27461725526, 72956.63521682358, 72976.27023729695, 14333.283335833376, 14729.337179827147, 14787.556373308818, 14815.92592037076, 14955.252237388304, 23295.3011409658, 23550.76449235554, 23600.0, 24433.267001989967, 24557.631635525126, 18746.437606871816, 19020.23919043223, 19080.236790528223, 19185.32851350196, 19227.711584325967, 17020.829791701923, 17289.221985010452, 17299.82700172982, 17585.208665610335, 17716.468505944842, 18915.243390264237, 19880.0, 19905.701414479085, 20269.29057482981, 20276.89861550742, 14548.127112373291, 14560.635984100481, 14742.557723268319, 14789.038712483654, 14847.257637118317, 18611.81388186101, 18694.25222991065, 18912.14895329741, 18973.43079707611, 19008.714869276697], "value": 12459.851187488175, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1289.6969212235126, 1338.759023375792, 1340.9128406653567, 1357.6266526705158, 1370.945162193513, 14427.422903083878, 15029.774553381709, 15096.54710358691, 15101.169435681033, 15157.545273641781, 14603.926980365084, 15189.164595947246, 15533.301001454933, 15622.84377156228, 15623.687526249469, 2733.2346942855984, 2765.184270640166, 2767.4741799058115, 2782.4156927045346, 2793.20393687799, 2648.774854137401, 2726.5228584997644, 2739.6027576001466, 2754.834709917401, 2792.0, 2733.904313349033, 2742.684591271999, 2773.3066733316673, 2773.6394268745134, 2776.7362100600412, 2672.465506898628, 2687.5968604709255, 2725.11433784021, 2729.1266794625726, 2729.263098963269, 2656.5616673249083, 2668.973310266892, 2674.251209661298, 2676.0633778177676, 2676.5449873521547, 14313.141211527342, 14588.854111458963, 14622.049566778125, 14627.63430914225, 14629.634259143606, 7792.961035194788, 8090.716824911159, 8096.959515202445, 8101.351891848638, 8110.9188908110755, 5158.896822063575, 5383.73081345935, 5464.726763661801, 5523.143912693498, 5597.160425936101, 1872.775266967959, 1906.713992901062, 1909.255390397746, 1979.6337677529702, 1984.6427643024394, 25288.114915977854, 25454.0, 25489.0, 25541.87229063843, 25561.744382556124, 6661.833454163588, 6663.5002374821115, 6730.899036514456, 6825.726970921108, 6846.110005699227, 16668.74996875048, 16676.74984875228, 16681.416150434678, 16686.33254669824, 16716.0, 7660.46376753633, 7825.452218344662, 7826.178251283647, 7826.7651970440975, 7836.647350869224, 7126.750563730245, 7265.273472652798, 7345.0, 7387.630618469055, 7394.5193562419345, 4732.645051621143, 5159.613029022838, 5212.869678257998, 5409.702466364388, 5469.261649677323, 1447.8479759625297, 1565.92953317101, 1569.3016607609504, 1580.1546172797498, 1583.4141367694026, 853.795089178605, 855.8972923249125, 871.7079778274349, 881.7354793561906, 894.6421431427481, 7902.17027212146, 8163.632636531371, 8271.966004249469, 8318.334533237326, 8447.915520844836, 923.9953800230955, 948.9620415183454, 978.7553111722069, 987.4766373821886, 997.8952210017989, 4484.372187893698, 4526.773661316921, 4540.523245059288, 4544.863654090316, 4551.40831691878, 19883.30408435698, 20174.798252017586, 20751.273705420237, 20783.480412989797, 20842.583148336947, 19928.0, 20458.0, 21116.89441552813, 21260.25589104374, 21279.680804787942, 11019.393933333744, 11119.777604447865, 11216.607418740305, 11217.943910280559, 11219.55121795119, 7535.171131175457, 7849.921500784919, 7997.520148791091, 8013.8397232056195, 8055.033395992518, 17747.733783992993, 17893.552661183574, 18225.088745562403, 18314.99267540298, 18592.7211091831, 18564.535886602942, 18601.255949761857, 18613.348532801287, 18648.44054678362, 18786.53033674169, 10671.46642667848, 10684.25210235276, 10816.891831081588, 10816.891831081903, 10833.783324333626, 11204.49579768896, 13286.601401957956, 13369.197848129143, 13438.193708377528, 13443.932780336427, 9125.315601329794, 10298.639547615798, 10717.0, 10784.676459706221, 10878.40168790724, 10235.283530152818, 10282.794344113225, 10345.068943795095, 10541.947290263499, 10624.681259562225, 9623.663171788954, 9820.754481138028, 9840.852387214054, 9841.655542055994, 9846.507674616383, 6980.755673551402, 6993.720251189896, 7042.225355211025, 7042.366187043191, 7050.682719277543, 7169.641517923978, 7187.5687458752645, 7249.565026098451, 7292.598907060163, 7328.5969271690565], "value": 6535.341349488496, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [496.83123386276503, 500.23370759698867, 504.57867680486794, 520.2196704942586, 522.1280461629082, 6378.298387177407, 6402.391772781578, 6426.678666066689, 6436.903446448301, 6479.352064793524, 6055.2430946131735, 6249.187605611264, 6279.053493417044, 6283.528735344845, 6301.77943771967, 5021.949780502185, 5023.346964894558, 5058.645894787366, 5118.846434606968, 5140.742962851842, 4864.951350486485, 4989.301497790312, 5013.298138260647, 5013.774380152884, 5055.248066817198, 4942.110420124376, 4990.101781679296, 5032.11937910866, 5050.393952725678, 5059.367579052619, 5008.749562521859, 5009.974950125263, 5019.52314530119, 5037.773300201481, 5041.294218809369, 4519.661025423106, 4519.932201016987, 4528.3207518872105, 4555.362249285103, 4583.335416364642, 6309.652969086698, 6312.589681670676, 6315.305316415191, 6335.746570137235, 6347.9051912868645, 5660.433956604348, 5679.858003549903, 5688.800891968761, 5713.257276554063, 5788.42115788422, 6568.967155164241, 6601.603903765789, 6649.667516624198, 6649.70330785497, 6653.201615806134, 4858.125537403266, 4863.343448634424, 4883.99878025004, 4899.951000490021, 4920.630952678528, 6878.38094571486, 6984.511084224056, 6997.6411322565455, 6999.405050570656, 7006.369426751617, 4909.631777616622, 4978.8257410990445, 4983.0, 5033.1695270280725, 5045.974770126126, 5067.923981140286, 5103.081445339801, 5104.897902042013, 5143.202803565453, 5152.227165925141, 5662.924044431545, 5692.544596432275, 5719.5996280260815, 5719.914201286984, 5720.570957178145, 6281.685915704196, 6531.5427920046095, 6555.573887697285, 6653.401193892476, 6675.1989761228, 1678.3789997700928, 1703.4038086669814, 1715.8455738983303, 1734.3929624631278, 1736.1666400127756, 1210.9212901161397, 1211.5275042733342, 1213.854337479508, 1228.3858070964518, 1231.8829711177423, 5778.711064446761, 5803.47768700819, 5821.53427725781, 5857.677827719516, 5874.794382196688, 6598.241202261801, 6635.5070109225035, 6769.72921083161, 6794.490413219029, 6854.9657251713425, 5934.910976335358, 5941.405859414024, 5953.970230148821, 5977.0, 5978.581499295007, 3619.9638003619625, 3628.4194528875255, 3639.6906262967414, 3645.9635403646153, 3675.5221821163614, 6492.675366231764, 6556.049372840911, 6572.718319927568, 6685.53201275906, 6728.259891411941, 6937.7224911004005, 6941.895871561931, 6996.6151861648095, 7009.053777740041, 7088.746751948789, 6402.551821372459, 6580.276169621337, 6585.308542603149, 6662.267150613428, 6672.399484046362, 6847.727250017359, 6847.863042739217, 6887.896681549881, 6901.102856628606, 6915.792526224222, 6814.7289301136725, 6831.282715315019, 6848.554843935129, 6860.5540639858245, 6883.827904302232, 6496.512761542997, 6629.303923088007, 6685.966570167118, 6693.799186024427, 6701.865962680817, 5435.945640543544, 5470.261514695624, 5477.643953143033, 5507.5593952484505, 5521.0, 6492.74029038852, 6758.797236082925, 6760.180498018603, 6806.9659651703405, 6943.375096241362, 6314.652694101685, 6443.001334793114, 6510.707018184098, 6560.671966401565, 6620.470362371095, 6524.4454221389815, 6542.901856472252, 6594.934050659432, 6600.53796234259, 6626.966865165643, 6523.380278873499, 6562.639054852029, 6565.638889861103, 6578.506612004023, 6597.505187111082, 6575.638339891352, 6618.271990080991, 6618.933810661832, 6662.7673880332295, 6698.765543205965, 6371.55399122057, 6396.520260980538, 6421.71102300407, 6501.4798816095545, 6507.707153178024], "value": 5120.763871435905, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [874.9562521873909, 887.5828360670483, 893.6648756716231, 903.8463461211593, 905.9592318345674, 16205.675886482264, 16434.424795132174, 16441.999999999985, 16474.83525164748, 19847.900760496213, 20443.182272709073, 21202.681959770616, 21225.787742122575, 21290.680639790415, 21312.786872131273, 912.5574096563178, 926.5506229478698, 934.9158575728183, 935.3779736475236, 935.8362286599852, 890.4345740454826, 924.9213816825544, 931.9161275485205, 934.1125930366145, 935.2845073518764, 989.9109080182782, 993.0417147452707, 994.4878387630376, 994.8010397920409, 995.3629677006685, 983.1004630762843, 984.7685793838442, 988.6193815381095, 990.3859607043607, 991.1575161113068, 725.3326939215934, 725.4812808841704, 725.9673314700851, 726.7020521586124, 728.5009768308726, 17965.0, 18127.818721812746, 18340.908295458437, 18363.72454413185, 18485.63028739418, 21131.788682113136, 21174.894125529427, 21462.24882129118, 21741.13035478579, 21757.12971481155, 16487.17564121801, 16562.1718914055, 16577.0, 16591.41930032443, 17271.309147634078, 3823.1397935464574, 3869.3808990561656, 3871.496705428276, 3885.1452680410266, 3888.9027774305587, 112704.4364778179, 114607.99999999917, 115175.0, 116884.0, 118841.0, 16403.42588009414, 16513.752293715603, 16777.664446711242, 16779.496615101565, 16808.495745127664, 29001.41997160045, 30119.849400753294, 30328.84835575808, 30399.240018999702, 30421.39157216843, 8765.824683506295, 8804.735857924272, 8952.462852228888, 8982.056884027214, 8987.41581797181, 3989.860354887565, 4065.207284579538, 4067.6339129477897, 4069.7558146511305, 4087.97956010224, 5209.984053109683, 5245.055889939771, 5272.182811664198, 5311.362636483646, 5350.91973620396, 890.6303883888244, 892.8705337725993, 916.2715641065369, 917.9541022948825, 919.2875521470913, 686.7047169716969, 703.3810246982623, 704.9224585295614, 713.7680253917498, 715.2561336210283, 10726.356418614909, 11299.378534180534, 11624.0, 12212.75574488523, 12217.0, 670.8926571748497, 692.2419950154573, 694.187800273681, 700.9719611215596, 704.264044073944, 5206.557442617344, 5252.185911183773, 5277.366715994029, 5330.360356757214, 5433.1850222466155, 110202.0, 110389.44805276082, 110702.44648776866, 111318.44340778244, 116101.0, 94637.0, 96829.0, 97899.0, 102600.0, 103240.0, 86425.0, 86513.56743216244, 88256.558717206, 88468.0, 92163.0, 9882.407055576688, 10027.548760305657, 10031.097201251923, 10063.647772327933, 10176.694699159038, 39936.60063399329, 40356.59643403528, 40367.58713445015, 40580.39129413002, 41103.794481027406, 50042.49957500524, 50838.74580627073, 51165.48834511607, 52323.73838130785, 52568.0, 14422.206778626856, 14782.334794934039, 14922.701545969237, 14926.776098358316, 14983.700325993637, 16160.0, 16183.838161618232, 16263.26815293291, 16266.67466650684, 16387.918060409622, 15261.160636165117, 15278.923605381902, 15291.464798731991, 15310.540683779505, 15414.691706166039, 13883.375248113656, 14031.087979281314, 14068.648283792987, 14097.436102555783, 14101.858981410054, 15265.3893844245, 15271.312790924658, 15302.770458443354, 15364.692706145592, 15439.305231264396, 11129.387883666475, 11312.095032397554, 11412.258203216765, 11491.942540287246, 11638.127140464669, 10981.725456863642, 11118.166137539554, 11204.383758893338, 11272.887271127513, 11361.204715669825], "value": 8518.668788410692, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1477.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 108.0, 100.0, 107.0, 109.0, 110.0, 108.0, 99.0, 108.0, 106.0], "value": 105.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 124.0, 146.0, 175.0, 116.0, 146.0, 144.0, 118.0, 167.0, 125.0], "value": 140.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 161.0, 162.0, 162.0, 160.0, 165.0, 167.0, 161.0, 164.0], "value": 162.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 73.0, 194.0, 75.0, 74.0, 74.0, 88.0, 100.0, 85.0], "value": 90.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 134.0, 79.0, 78.0, 80.0, 126.0, 80.0, 79.0, 127.0], "value": 94.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 115.0, 114.0, 114.0, 114.0, 114.0, 113.0, 113.0, 115.0], "value": 114.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 148.0, 148.0, 149.0, 147.0, 149.0, 148.0, 149.0, 147.0, 148.0], "value": 148.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 93.0, 94.0, 93.0, 94.0, 94.0, 94.0, 93.0, 93.0, 93.0], "value": 93.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 53.0, 54.0, 53.0, 54.0, 52.0, 52.0, 54.0, 52.0], "value": 53.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 38.0, 37.0, 38.0, 38.0, 38.0, 38.0, 38.0, 38.0, 37.0], "value": 37.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 80.0, 83.0, 75.0, 74.0, 80.0, 74.0, 74.0, 77.0], "value": 76.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 127.0, 118.0, 118.0, 118.0, 127.0, 121.0, 115.0, 116.0, 152.0], "value": 123.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 168.0, 180.0, 168.0, 171.0, 167.0, 179.0, 170.0, 166.0, 166.0], "value": 171.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 64.0, 71.0, 65.0, 63.0, 64.0, 65.0, 71.0, 68.0, 67.0], "value": 66.5, "unit": "score"}]}]} 18:37:13 INFO - Return code: 0 18:37:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:37:13 INFO - # TBPL SUCCESS # 18:37:13 INFO - Running post-action listener: _package_coverage_data 18:37:13 INFO - Running post-action listener: _resource_record_post_action 18:37:13 INFO - [mozharness: 2017-08-28 01:37:13.578025Z] Finished run-tests step (success) 18:37:13 INFO - Running post-run listener: _resource_record_post_run 18:37:13 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 0; Write bytes: 337862656; Read time: 0; Write time: 238360 18:37:13 INFO - TinderboxPrint: CPU usage
13.1% 18:37:13 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 18:37:13 INFO - TinderboxPrint: I/O write bytes / time
337,862,656 / 238,360 18:37:13 INFO - TinderboxPrint: CPU idle
8,251.4 (86.9%) 18:37:13 INFO - TinderboxPrint: CPU user
1,195.6 (12.6%) 18:37:13 INFO - TinderboxPrint: Swap in / out
0 / 0 18:37:13 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 12288000; Read time: 0; Write time: 1200 18:37:13 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 18:37:13 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 0; Write bytes: 325160960; Read time: 0; Write time: 237124 18:37:14 INFO - Running post-run listener: _upload_blobber_files 18:37:14 INFO - Blob upload gear active. 18:37:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:37:14 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:37:14 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:37:14 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:37:14 INFO - (blobuploader) - INFO - Open directory for files ... 18:37:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:37:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:37:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:37:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:37:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:37:15 INFO - (blobuploader) - INFO - Done attempting. 18:37:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:37:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:37:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:37:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:37:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:37:16 INFO - (blobuploader) - INFO - Done attempting. 18:37:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:37:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:37:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:37:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:37:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:37:24 INFO - (blobuploader) - INFO - Done attempting. 18:37:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:37:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:37:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:37:25 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:37:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:37:25 INFO - (blobuploader) - INFO - Done attempting. 18:37:25 INFO - (blobuploader) - INFO - Iteration through files over. 18:37:25 INFO - Return code: 0 18:37:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:37:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:37:25 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/22da816900e39af8957cf10025277fd0480f7e7a26fb797f4ae9c03dca2c8d0177e512ebd056da65dd7eb75288ded16b2859ab0e43181fabb72ac617d98202d4", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/00c937b53401edc6df583f271fc9ef44fee2248c29726ed723015c05d6db28f8767952b01317aa2d44d9196756c5b9eceeacb111ff4a8ef185a8457c74cb41de", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d5cd1fe2527628767c405d5a6489e659c193cdefc0526a219ed2246a05929dbfeb514446fb75d13f7a2c272ffcb042d2cba5b13547782fac6b101091c608a740", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0711362768d504dff698089769135f0d08979f2ab046ff946282fb2be17f648a17847b5b248223e8b6f35b5fbba141d14bc9e41abb9c9e61a708a2be0420965f"} 18:37:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:37:25 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:37:25 INFO - Contents: 18:37:25 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/22da816900e39af8957cf10025277fd0480f7e7a26fb797f4ae9c03dca2c8d0177e512ebd056da65dd7eb75288ded16b2859ab0e43181fabb72ac617d98202d4", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/00c937b53401edc6df583f271fc9ef44fee2248c29726ed723015c05d6db28f8767952b01317aa2d44d9196756c5b9eceeacb111ff4a8ef185a8457c74cb41de", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d5cd1fe2527628767c405d5a6489e659c193cdefc0526a219ed2246a05929dbfeb514446fb75d13f7a2c272ffcb042d2cba5b13547782fac6b101091c608a740", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0711362768d504dff698089769135f0d08979f2ab046ff946282fb2be17f648a17847b5b248223e8b6f35b5fbba141d14bc9e41abb9c9e61a708a2be0420965f"} 18:37:25 INFO - Running post-run listener: copy_logs_to_upload_dir 18:37:25 INFO - Copying logs to upload dir... 18:37:25 INFO - mkdir: /builds/slave/test/build/upload/logs 18:37:25 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1315.483673 ========= master_lag: -0.50 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 54 secs) (at 2017-08-27 18:37:24.566132) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-27 18:37:24.574884) ========= 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=8c32c1c60be582960b75045f000001c1-1503873263.99089-675433845 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/22da816900e39af8957cf10025277fd0480f7e7a26fb797f4ae9c03dca2c8d0177e512ebd056da65dd7eb75288ded16b2859ab0e43181fabb72ac617d98202d4", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/00c937b53401edc6df583f271fc9ef44fee2248c29726ed723015c05d6db28f8767952b01317aa2d44d9196756c5b9eceeacb111ff4a8ef185a8457c74cb41de", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d5cd1fe2527628767c405d5a6489e659c193cdefc0526a219ed2246a05929dbfeb514446fb75d13f7a2c272ffcb042d2cba5b13547782fac6b101091c608a740", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0711362768d504dff698089769135f0d08979f2ab046ff946282fb2be17f648a17847b5b248223e8b6f35b5fbba141d14bc9e41abb9c9e61a708a2be0420965f"} build_url:https://queue.taskcluster.net/v1/task/fJ4-8OdcRqSrwqD2wKF_lQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011473 build_url: 'https://queue.taskcluster.net/v1/task/fJ4-8OdcRqSrwqD2wKF_lQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/22da816900e39af8957cf10025277fd0480f7e7a26fb797f4ae9c03dca2c8d0177e512ebd056da65dd7eb75288ded16b2859ab0e43181fabb72ac617d98202d4", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/00c937b53401edc6df583f271fc9ef44fee2248c29726ed723015c05d6db28f8767952b01317aa2d44d9196756c5b9eceeacb111ff4a8ef185a8457c74cb41de", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d5cd1fe2527628767c405d5a6489e659c193cdefc0526a219ed2246a05929dbfeb514446fb75d13f7a2c272ffcb042d2cba5b13547782fac6b101091c608a740", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0711362768d504dff698089769135f0d08979f2ab046ff946282fb2be17f648a17847b5b248223e8b6f35b5fbba141d14bc9e41abb9c9e61a708a2be0420965f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-27 18:37:24.607006) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-27 18:37:24.607346) ========= 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=8c32c1c60be582960b75045f000001c1-1503873263.99089-675433845 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004428 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-27 18:37:24.689070) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-27 18:37:24.692458) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-27 18:37:24.692806) ========= ========= Total master_lag: -0.32 =========