builder: mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s slave: talos-linux64-ix-084 starttime: 1504258103.19 results: success (0) revision: a3585c77e2b1bc5f5fea907e97762f7b47a12033 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-01 02:28:23.187537) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-01 02:28:23.188500) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-01 02:28:23.189193) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-01 02:28:23.215352) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 02:28:23.215889) ========= 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=ea65d483da93c827905c2090000001f5-1504257792.242887-1504466522 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-01 02:28:23-- 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% 107M=0s 2017-09-01 02:28:23 (107 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.159279 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 02:28:23.397404) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 02:28:23.398202) ========= 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=ea65d483da93c827905c2090000001f5-1504257792.242887-1504466522 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019375 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 02:28:23.451946) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 13 secs) (at 2017-09-01 02:28:23.452334) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev a3585c77e2b1bc5f5fea907e97762f7b47a12033 --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 a3585c77e2b1bc5f5fea907e97762f7b47a12033 --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=ea65d483da93c827905c2090000001f5-1504257792.242887-1504466522 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-01 02:28:23,502 truncating revision to first 12 chars 2017-09-01 02:28:23,502 Setting DEBUG logging. 2017-09-01 02:28:23,502 attempt 1/10 2017-09-01 02:28:23,502 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/a3585c77e2b1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-01 02:28:24,283 attempt 1/10 2017-09-01 02:28:24,821 current task status: no status available at this point. state: STARTED 2017-09-01 02:28:24,821 sleeping for 11.00s (attempt 1/10) 2017-09-01 02:28:35,833 attempt 2/10 2017-09-01 02:28:36,168 current task status: Task completed! Check 's3_urls' for upload locations. state: SUCCESS 2017-09-01 02:28:36,353 unpacking tar archive at: mozilla-central-a3585c77e2b1/testing/mozharness/ program finished with exit code 0 elapsedTime=13.577698 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 13 secs) (at 2017-09-01 02:28:37.045291) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-01 02:28:37.045711) ========= script_repo_revision: a3585c77e2b1bc5f5fea907e97762f7b47a12033 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-01 02:28:37.046161) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-01 02:28:37.046547) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-01 02:28:37.057757) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2017-09-01 02:28:37.058087) ========= /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=ea65d483da93c827905c2090000001f5-1504257792.242887-1504466522 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 02:28:37 INFO - MultiFileLogger online at 20170901 02:28:37 in /builds/slave/test 02:28:37 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 02:28:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:28:37 INFO - {'append_to_log': False, 02:28:37 INFO - 'base_work_dir': '/builds/slave/test', 02:28:37 INFO - 'blob_upload_branch': 'Firefox', 02:28:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:28:37 INFO - 'branch': 'Firefox', 02:28:37 INFO - 'buildbot_json_path': 'buildprops.json', 02:28:37 INFO - 'code_coverage': False, 02:28:37 INFO - 'config_files': ('talos/linux_config.py',), 02:28:37 INFO - 'default_actions': ('clobber', 02:28:37 INFO - 'read-buildbot-config', 02:28:37 INFO - 'download-and-extract', 02:28:37 INFO - 'populate-webroot', 02:28:37 INFO - 'create-virtualenv', 02:28:37 INFO - 'install', 02:28:37 INFO - 'setup-mitmproxy', 02:28:37 INFO - 'run-tests'), 02:28:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:28:37 INFO - 'disable_ccov_upload': False, 02:28:37 INFO - 'disable_stylo': False, 02:28:37 INFO - 'download_minidump_stackwalk': True, 02:28:37 INFO - 'download_symbols': 'ondemand', 02:28:37 INFO - 'enable_stylo': False, 02:28:37 INFO - 'enable_webrender': False, 02:28:37 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 02:28:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:28:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:28:37 INFO - 'gecko_profile': False, 02:28:37 INFO - 'gecko_profile_interval': 0, 02:28:37 INFO - 'installer_path': 'installer.exe', 02:28:37 INFO - 'log_level': 'info', 02:28:37 INFO - 'log_name': 'talos', 02:28:37 INFO - 'log_to_console': True, 02:28:37 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 02:28:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 02:28:37 INFO - 'opt_config_files': (), 02:28:37 INFO - 'pip_index': False, 02:28:37 INFO - 'suite': 'dromaeojs-e10s', 02:28:37 INFO - 'system_bits': '32', 02:28:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:28:37 INFO - 'title': 'talos-linux64-ix-084', 02:28:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:28:37 INFO - 'use_talos_json': True, 02:28:37 INFO - 'verify': 'False', 02:28:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:28:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:28:37 INFO - 'work_dir': 'build'} 02:28:37 INFO - [mozharness: 2017-09-01 09:28:37.250819Z] Running clobber step. 02:28:37 INFO - Running pre-action listener: _resource_record_pre_action 02:28:37 INFO - Running main action method: clobber 02:28:37 INFO - rmtree: /builds/slave/test/build 02:28:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:28:38 INFO - Running post-action listener: _resource_record_post_action 02:28:38 INFO - [mozharness: 2017-09-01 09:28:38.255567Z] Finished clobber step (success) 02:28:38 INFO - [mozharness: 2017-09-01 09:28:38.255665Z] Running read-buildbot-config step. 02:28:38 INFO - Running pre-action listener: _resource_record_pre_action 02:28:38 INFO - Running main action method: read_buildbot_config 02:28:38 INFO - Using buildbot properties: 02:28:38 INFO - { 02:28:38 INFO - "project": "", 02:28:38 INFO - "product": "firefox", 02:28:38 INFO - "who": "archaeopteryx@coole-files.de", 02:28:38 INFO - "installer_path": "public/build/target.tar.bz2", 02:28:38 INFO - "builddir": "mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s", 02:28:38 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 02:28:38 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo-sequential mozilla-central talos dromaeojs-e10s", 02:28:38 INFO - "stage_platform": "linux64-stylosequential", 02:28:38 INFO - "basedir": "/builds/slave/test", 02:28:38 INFO - "buildnumber": 223, 02:28:38 INFO - "platform": "ubuntu64_hw_styloseq", 02:28:38 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 02:28:38 INFO - "slavebuilddir": "test", 02:28:38 INFO - "taskId": "TkyO76_VQNeRzf7zE1B5_g", 02:28:38 INFO - "branch": "mozilla-central", 02:28:38 INFO - "script_repo_revision": "production", 02:28:38 INFO - "revision": "a3585c77e2b1bc5f5fea907e97762f7b47a12033", 02:28:38 INFO - "slavename": "talos-linux64-ix-084", 02:28:38 INFO - "repo_path": "mozilla-central" 02:28:38 INFO - } 02:28:38 INFO - Finding installer, test and symbols from parent task. 02:28:38 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TkyO76_VQNeRzf7zE1B5_g'}, attempt #1 02:28:38 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TkyO76_VQNeRzf7zE1B5_g'}, attempt #1 02:28:39 INFO - Task dependencies: QjwniFnmR7exVsWnUkO_3Q 02:28:39 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QjwniFnmR7exVsWnUkO_3Q'}, attempt #1 02:28:39 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/QjwniFnmR7exVsWnUkO_3Q/artifacts/public/build/target.tar.bz2 02:28:39 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/QjwniFnmR7exVsWnUkO_3Q/artifacts/public/build/target.test_packages.json 02:28:39 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/QjwniFnmR7exVsWnUkO_3Q/artifacts/public/build/target.crashreporter-symbols.zip 02:28:39 INFO - Running post-action listener: _resource_record_post_action 02:28:39 INFO - [mozharness: 2017-09-01 09:28:39.619556Z] Finished read-buildbot-config step (success) 02:28:39 INFO - [mozharness: 2017-09-01 09:28:39.619773Z] Running download-and-extract step. 02:28:39 INFO - Running pre-action listener: _resource_record_pre_action 02:28:39 INFO - Running main action method: download_and_extract 02:28:39 INFO - mkdir: /builds/slave/test/build/tests 02:28:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:28:39 INFO - trying https://queue.taskcluster.net/v1/task/QjwniFnmR7exVsWnUkO_3Q/artifacts/public/build/target.test_packages.json 02:28:39 INFO - Downloading https://queue.taskcluster.net/v1/task/QjwniFnmR7exVsWnUkO_3Q/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 02:28:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QjwniFnmR7exVsWnUkO_3Q/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 02:28:40 INFO - Downloaded 989 bytes. 02:28:40 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 02:28:40 INFO - Using the following test package requirements: 02:28:40 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 02:28:40 INFO - u'common': [u'target.common.tests.zip'], 02:28:40 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 02:28:40 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 02:28:40 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 02:28:40 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 02:28:40 INFO - u'mozbase': [u'target.common.tests.zip'], 02:28:40 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 02:28:40 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 02:28:40 INFO - u'web-platform': [u'target.common.tests.zip', 02:28:40 INFO - u'target.web-platform.tests.tar.gz'], 02:28:40 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 02:28:40 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 02:28:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QjwniFnmR7exVsWnUkO_3Q/artifacts/public/build/target.common.tests.zip 02:28:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QjwniFnmR7exVsWnUkO_3Q/artifacts/public/build/target.common.tests.zip'}, attempt #1 02:28:40 INFO - Fetch https://queue.taskcluster.net/v1/task/QjwniFnmR7exVsWnUkO_3Q/artifacts/public/build/target.common.tests.zip into memory 02:28:41 INFO - Content-Length response header: 54957508 02:28:41 INFO - Bytes received: 54957508 02:28:52 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 02:28:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QjwniFnmR7exVsWnUkO_3Q/artifacts/public/build/target.common.tests.zip 02:28:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QjwniFnmR7exVsWnUkO_3Q/artifacts/public/build/target.common.tests.zip'}, attempt #1 02:28:52 INFO - Fetch https://queue.taskcluster.net/v1/task/QjwniFnmR7exVsWnUkO_3Q/artifacts/public/build/target.common.tests.zip into memory 02:28:53 INFO - Content-Length response header: 54957508 02:28:53 INFO - Bytes received: 54957508 02:29:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QjwniFnmR7exVsWnUkO_3Q/artifacts/public/build/target.talos.tests.zip 02:29:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QjwniFnmR7exVsWnUkO_3Q/artifacts/public/build/target.talos.tests.zip'}, attempt #1 02:29:05 INFO - Fetch https://queue.taskcluster.net/v1/task/QjwniFnmR7exVsWnUkO_3Q/artifacts/public/build/target.talos.tests.zip into memory 02:29:06 INFO - Content-Length response header: 14496650 02:29:06 INFO - Bytes received: 14496650 02:29:06 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')]} 02:29:06 INFO - trying https://queue.taskcluster.net/v1/task/QjwniFnmR7exVsWnUkO_3Q/artifacts/public/build/target.tar.bz2 02:29:06 INFO - Downloading https://queue.taskcluster.net/v1/task/QjwniFnmR7exVsWnUkO_3Q/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 02:29:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QjwniFnmR7exVsWnUkO_3Q/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 02:29:07 INFO - Downloaded 62582204 bytes. 02:29:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QjwniFnmR7exVsWnUkO_3Q/artifacts/public/build/target.tar.bz2 02:29:07 INFO - mkdir: /builds/slave/test/properties 02:29:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:29:07 INFO - Writing to file /builds/slave/test/properties/build_url 02:29:07 INFO - Contents: 02:29:07 INFO - build_url:https://queue.taskcluster.net/v1/task/QjwniFnmR7exVsWnUkO_3Q/artifacts/public/build/target.tar.bz2 02:29:07 INFO - Running post-action listener: _resource_record_post_action 02:29:07 INFO - Running post-action listener: find_tests_for_verification 02:29:07 INFO - Running post-action listener: set_extra_try_arguments 02:29:07 INFO - [mozharness: 2017-09-01 09:29:07.549333Z] Finished download-and-extract step (success) 02:29:07 INFO - [mozharness: 2017-09-01 09:29:07.549428Z] Running populate-webroot step. 02:29:07 INFO - Running pre-action listener: _resource_record_pre_action 02:29:07 INFO - Running main action method: populate_webroot 02:29:07 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:29:07 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:29:07 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:29:07 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:29:07 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:29:07 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:29:07 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:29:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:29:07 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 02:29:07 INFO - 'talos_options': ['--enable-stylo'], 02:29:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:29:07 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:29:07 INFO - 'tests': ['damp', 'tps']}, 02:29:07 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 02:29:07 INFO - 'talos_options': ['--enable-stylo'], 02:29:07 INFO - 'tests': ['damp', 'tps']}, 02:29:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:29:07 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 02:29:07 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:29:07 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 02:29:07 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 02:29:07 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 02:29:07 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 02:29:07 INFO - 'talos_options': ['--enable-stylo'], 02:29:07 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 02:29:07 INFO - 'other-e10s': {'tests': ['a11yr', 02:29:07 INFO - 'ts_paint', 02:29:07 INFO - 'tpaint', 02:29:07 INFO - 'sessionrestore', 02:29:07 INFO - 'sessionrestore_many_windows', 02:29:07 INFO - 'sessionrestore_no_auto_restore', 02:29:07 INFO - 'tabpaint']}, 02:29:07 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:29:07 INFO - 'tests': ['a11yr', 02:29:07 INFO - 'ts_paint', 02:29:07 INFO - 'tpaint', 02:29:07 INFO - 'sessionrestore', 02:29:07 INFO - 'sessionrestore_many_windows', 02:29:07 INFO - 'sessionrestore_no_auto_restore', 02:29:07 INFO - 'tabpaint']}, 02:29:07 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 02:29:07 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 02:29:07 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:29:07 INFO - 'tests': ['bloom_basic_singleton']}, 02:29:07 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:29:07 INFO - 'tests': ['bloom_basic']}, 02:29:07 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:29:07 INFO - 'tsvgr_opacity', 02:29:07 INFO - 'tart', 02:29:07 INFO - 'tscrollx', 02:29:07 INFO - 'tsvg_static']}, 02:29:07 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:29:07 INFO - 'tests': ['tsvgx', 02:29:07 INFO - 'tsvgr_opacity', 02:29:07 INFO - 'tart', 02:29:07 INFO - 'tscrollx', 02:29:07 INFO - 'tsvg_static']}, 02:29:07 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:29:07 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 02:29:07 INFO - 'talos_options': ['--enable-stylo'], 02:29:07 INFO - 'tests': ['tp5o']}, 02:29:07 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:29:07 INFO - 'talos_options': ['--mitmproxy', 02:29:07 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:29:07 INFO - '--firstNonBlankPaint'], 02:29:07 INFO - 'tests': ['tp6_google', 02:29:07 INFO - 'tp6_youtube', 02:29:07 INFO - 'tp6_amazon', 02:29:07 INFO - 'tp6_facebook']}, 02:29:07 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:29:07 INFO - 'talos_options': ['--enable-stylo', 02:29:07 INFO - '--mitmproxy', 02:29:07 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:29:07 INFO - '--firstNonBlankPaint'], 02:29:07 INFO - 'tests': ['tp6_google', 02:29:07 INFO - 'tp6_youtube', 02:29:07 INFO - 'tp6_amazon', 02:29:07 INFO - 'tp6_facebook']}, 02:29:07 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:29:07 INFO - 'talos_options': ['--enable-stylo', 02:29:07 INFO - '--stylo-threads=1', 02:29:07 INFO - '--mitmproxy', 02:29:07 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:29:07 INFO - '--firstNonBlankPaint'], 02:29:07 INFO - 'tests': ['tp6_google', 02:29:07 INFO - 'tp6_youtube', 02:29:07 INFO - 'tp6_amazon', 02:29:07 INFO - 'tp6_facebook']}, 02:29:07 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:29:07 INFO - 'talos_options': ['--xperf_path', 02:29:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:29:07 INFO - 'tests': ['tp5n']}, 02:29:07 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 02:29:07 INFO - 'talos_options': ['--enable-stylo', 02:29:07 INFO - '--xperf_path', 02:29:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:29:07 INFO - 'tests': ['tp5n']}}} 02:29:07 INFO - Running post-action listener: _resource_record_post_action 02:29:07 INFO - [mozharness: 2017-09-01 09:29:07.563875Z] Finished populate-webroot step (success) 02:29:07 INFO - [mozharness: 2017-09-01 09:29:07.563965Z] Running create-virtualenv step. 02:29:07 INFO - Running pre-action listener: _resource_record_pre_action 02:29:07 INFO - Running main action method: create_virtualenv 02:29:07 INFO - Creating virtualenv /builds/slave/test/build/venv 02:29:07 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 02:29:07 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 02:29:07 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 02:29:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:29:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:29:08 INFO - Installing distribute.............................................................................................................................................................................................done. 02:29:08 INFO - Installing pip...............done. 02:29:08 INFO - Return code: 0 02:29:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:29:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:29:08 INFO - Reading from file tmpfile_stdout 02:29:08 INFO - Output received: 02:29:08 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 02:29:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:29:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:08 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 0x21191f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22e2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2485a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2489090>, '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 0x2489d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2483f90>, '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': 'ea65d483da93c827905c2090000001f5-1504257792.242887-1504466522', '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 02:29:08 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 02:29:08 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 02:29:08 INFO - Using env: {'DISPLAY': ':0', 02:29:08 INFO - 'HOME': '/home/cltbld', 02:29:08 INFO - 'LANG': 'en_US.UTF-8', 02:29:08 INFO - 'LANGUAGE': 'en_US:en', 02:29:08 INFO - 'LOGNAME': 'cltbld', 02:29:08 INFO - 'MAIL': '/var/mail/cltbld', 02:29:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:08 INFO - 'MOZ_NO_REMOTE': '1', 02:29:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:29:08 INFO - 'NO_EM_RESTART': '1', 02:29:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:29:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:08 INFO - 'PWD': '/builds/slave/test', 02:29:08 INFO - 'SHELL': '/bin/bash', 02:29:08 INFO - 'SHLVL': '1', 02:29:08 INFO - 'TERM': 'linux', 02:29:08 INFO - 'TMOUT': '86400', 02:29:08 INFO - 'USER': 'cltbld', 02:29:08 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1504257792.242887-1504466522', 02:29:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:08 INFO - '_': '/tools/buildbot/bin/python'} 02:29:09 INFO - Ignoring indexes: http://pypi.python.org/simple/ 02:29:09 INFO - Downloading/unpacking pip>=1.5 02:29:09 INFO - Running setup.py egg_info for package pip 02:29:09 INFO - warning: no files found matching 'pip/cacert.pem' 02:29:09 INFO - warning: no files found matching '*.html' under directory 'docs' 02:29:09 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 02:29:09 INFO - no previously-included directories found matching 'docs/_build/_sources' 02:29:09 INFO - Installing collected packages: pip 02:29:09 INFO - Found existing installation: pip 1.1 02:29:09 INFO - Uninstalling pip: 02:29:09 INFO - Successfully uninstalled pip 02:29:09 INFO - Running setup.py install for pip 02:29:09 INFO - warning: no files found matching 'pip/cacert.pem' 02:29:09 INFO - warning: no files found matching '*.html' under directory 'docs' 02:29:09 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 02:29:09 INFO - no previously-included directories found matching 'docs/_build/_sources' 02:29:09 INFO - Installing pip script to /builds/slave/test/build/venv/bin 02:29:09 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 02:29:09 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 02:29:09 INFO - Successfully installed pip 02:29:09 INFO - Cleaning up... 02:29:09 INFO - Return code: 0 02:29:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:29: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')]} 02:29:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29: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')]} 02:29:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29: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', '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 0x21191f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22e2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2485a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2489090>, '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 0x2489d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2483f90>, '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': 'ea65d483da93c827905c2090000001f5-1504257792.242887-1504466522', '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 02:29: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', 'psutil>=3.1.1'] in /builds/slave/test/build 02:29: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 psutil>=3.1.1 02:29:09 INFO - Using env: {'DISPLAY': ':0', 02:29:09 INFO - 'HOME': '/home/cltbld', 02:29:09 INFO - 'LANG': 'en_US.UTF-8', 02:29:09 INFO - 'LANGUAGE': 'en_US:en', 02:29:09 INFO - 'LOGNAME': 'cltbld', 02:29:09 INFO - 'MAIL': '/var/mail/cltbld', 02:29:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:09 INFO - 'MOZ_NO_REMOTE': '1', 02:29:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:29:09 INFO - 'NO_EM_RESTART': '1', 02:29:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:29:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:09 INFO - 'PWD': '/builds/slave/test', 02:29:09 INFO - 'SHELL': '/bin/bash', 02:29:09 INFO - 'SHLVL': '1', 02:29:09 INFO - 'TERM': 'linux', 02:29:09 INFO - 'TMOUT': '86400', 02:29:09 INFO - 'USER': 'cltbld', 02:29:09 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1504257792.242887-1504466522', 02:29:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:09 INFO - '_': '/tools/buildbot/bin/python'} 02:29:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:09 INFO - Downloading/unpacking psutil>=3.1.1 02:29: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 02:29: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 02:29: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 02:29: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 02:29:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:29:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:29:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:29:11 INFO - Installing collected packages: psutil 02:29:11 INFO - Running setup.py install for psutil 02:29:11 INFO - building 'psutil._psutil_linux' extension 02:29:11 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 02:29:11 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 02:29:11 INFO - building 'psutil._psutil_posix' extension 02:29:11 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 02:29:11 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 02:29:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:29:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:29:11 INFO - Successfully installed psutil 02:29:11 INFO - Cleaning up... 02:29:11 INFO - Return code: 0 02:29:11 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:29:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21191f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22e2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2485a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2489090>, '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 0x2489d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2483f90>, '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': 'ea65d483da93c827905c2090000001f5-1504257792.242887-1504466522', '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 02:29:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 02:29:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 02:29:11 INFO - Using env: {'DISPLAY': ':0', 02:29:11 INFO - 'HOME': '/home/cltbld', 02:29:11 INFO - 'LANG': 'en_US.UTF-8', 02:29:11 INFO - 'LANGUAGE': 'en_US:en', 02:29:11 INFO - 'LOGNAME': 'cltbld', 02:29:11 INFO - 'MAIL': '/var/mail/cltbld', 02:29:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:11 INFO - 'MOZ_NO_REMOTE': '1', 02:29:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:29:11 INFO - 'NO_EM_RESTART': '1', 02:29:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:29:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:11 INFO - 'PWD': '/builds/slave/test', 02:29:11 INFO - 'SHELL': '/bin/bash', 02:29:11 INFO - 'SHLVL': '1', 02:29:11 INFO - 'TERM': 'linux', 02:29:11 INFO - 'TMOUT': '86400', 02:29:11 INFO - 'USER': 'cltbld', 02:29:11 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1504257792.242887-1504466522', 02:29:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:11 INFO - '_': '/tools/buildbot/bin/python'} 02:29:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:11 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:29: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 02:29: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 02:29: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 02:29: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 02:29:13 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:29:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:29:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:29:13 INFO - Installing collected packages: mozsystemmonitor 02:29:13 INFO - Running setup.py install for mozsystemmonitor 02:29:13 INFO - Successfully installed mozsystemmonitor 02:29:13 INFO - Cleaning up... 02:29:13 INFO - Return code: 0 02:29:13 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:29: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')]} 02:29:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29: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')]} 02:29:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x21191f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22e2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2485a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2489090>, '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 0x2489d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2483f90>, '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': 'ea65d483da93c827905c2090000001f5-1504257792.242887-1504466522', '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 02:29:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:29:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 02:29:13 INFO - Using env: {'DISPLAY': ':0', 02:29:13 INFO - 'HOME': '/home/cltbld', 02:29:13 INFO - 'LANG': 'en_US.UTF-8', 02:29:13 INFO - 'LANGUAGE': 'en_US:en', 02:29:13 INFO - 'LOGNAME': 'cltbld', 02:29:13 INFO - 'MAIL': '/var/mail/cltbld', 02:29:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:13 INFO - 'MOZ_NO_REMOTE': '1', 02:29:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:29:13 INFO - 'NO_EM_RESTART': '1', 02:29:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:29:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:13 INFO - 'PWD': '/builds/slave/test', 02:29:13 INFO - 'SHELL': '/bin/bash', 02:29:13 INFO - 'SHLVL': '1', 02:29:13 INFO - 'TERM': 'linux', 02:29:13 INFO - 'TMOUT': '86400', 02:29:13 INFO - 'USER': 'cltbld', 02:29:13 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1504257792.242887-1504466522', 02:29:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:13 INFO - '_': '/tools/buildbot/bin/python'} 02:29:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:13 INFO - Downloading/unpacking jsonschema==2.5.1 02:29:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:29:14 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:29:14 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 02:29:14 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 02:29:14 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 02:29:14 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 02:29:14 INFO - Downloading functools32-3.2.3-2.tar.gz 02:29:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:29:14 INFO - warning: no files found matching '*.txt' 02:29:14 INFO - no previously-included directories found matching 'build' 02:29:14 INFO - no previously-included directories found matching 'dist' 02:29:14 INFO - no previously-included directories found matching '.git*' 02:29:14 INFO - Installing collected packages: jsonschema, functools32 02:29:14 INFO - Running setup.py install for functools32 02:29:14 INFO - warning: no files found matching '*.txt' 02:29:14 INFO - no previously-included directories found matching 'build' 02:29:14 INFO - no previously-included directories found matching 'dist' 02:29:14 INFO - no previously-included directories found matching '.git*' 02:29:14 INFO - Successfully installed jsonschema functools32 02:29:14 INFO - Cleaning up... 02:29:14 INFO - Return code: 0 02:29:14 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:29:14 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')]} 02:29:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:14 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')]} 02:29:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:14 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 0x21191f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22e2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2485a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2489090>, '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 0x2489d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2483f90>, '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': 'ea65d483da93c827905c2090000001f5-1504257792.242887-1504466522', '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 02:29:14 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 02:29:14 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 02:29:14 INFO - Using env: {'DISPLAY': ':0', 02:29:14 INFO - 'HOME': '/home/cltbld', 02:29:14 INFO - 'LANG': 'en_US.UTF-8', 02:29:14 INFO - 'LANGUAGE': 'en_US:en', 02:29:14 INFO - 'LOGNAME': 'cltbld', 02:29:14 INFO - 'MAIL': '/var/mail/cltbld', 02:29:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:14 INFO - 'MOZ_NO_REMOTE': '1', 02:29:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:29:14 INFO - 'NO_EM_RESTART': '1', 02:29:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:29:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:14 INFO - 'PWD': '/builds/slave/test', 02:29:14 INFO - 'SHELL': '/bin/bash', 02:29:14 INFO - 'SHLVL': '1', 02:29:14 INFO - 'TERM': 'linux', 02:29:14 INFO - 'TMOUT': '86400', 02:29:14 INFO - 'USER': 'cltbld', 02:29:14 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1504257792.242887-1504466522', 02:29:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:14 INFO - '_': '/tools/buildbot/bin/python'} 02:29:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:15 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:29:15 INFO - Cleaning up... 02:29:15 INFO - Return code: 0 02:29:15 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:29:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x21191f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22e2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2485a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2489090>, '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 0x2489d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2483f90>, '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': 'ea65d483da93c827905c2090000001f5-1504257792.242887-1504466522', '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 02:29:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:29:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:29:15 INFO - Using env: {'DISPLAY': ':0', 02:29:15 INFO - 'HOME': '/home/cltbld', 02:29:15 INFO - 'LANG': 'en_US.UTF-8', 02:29:15 INFO - 'LANGUAGE': 'en_US:en', 02:29:15 INFO - 'LOGNAME': 'cltbld', 02:29:15 INFO - 'MAIL': '/var/mail/cltbld', 02:29:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:15 INFO - 'MOZ_NO_REMOTE': '1', 02:29:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:29:15 INFO - 'NO_EM_RESTART': '1', 02:29:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:29:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:15 INFO - 'PWD': '/builds/slave/test', 02:29:15 INFO - 'SHELL': '/bin/bash', 02:29:15 INFO - 'SHLVL': '1', 02:29:15 INFO - 'TERM': 'linux', 02:29:15 INFO - 'TMOUT': '86400', 02:29:15 INFO - 'USER': 'cltbld', 02:29:15 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1504257792.242887-1504466522', 02:29:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:15 INFO - '_': '/tools/buildbot/bin/python'} 02:29:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:15 INFO - Downloading/unpacking blobuploader==1.2.4 02:29:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:16 INFO - Downloading blobuploader-1.2.4.tar.gz 02:29:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:29:16 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:29:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:29:16 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:29:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:29:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:29:16 INFO - Downloading docopt-0.6.1.tar.gz 02:29:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:29:17 INFO - Installing collected packages: blobuploader, requests, docopt 02:29:17 INFO - Running setup.py install for blobuploader 02:29:17 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:29:17 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:29:17 INFO - Running setup.py install for requests 02:29:17 INFO - Running setup.py install for docopt 02:29:17 INFO - Successfully installed blobuploader requests docopt 02:29:17 INFO - Cleaning up... 02:29:17 INFO - Return code: 0 02:29:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:29:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:17 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 0x21191f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22e2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2485a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2489090>, '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 0x2489d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2483f90>, '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': 'ea65d483da93c827905c2090000001f5-1504257792.242887-1504466522', '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 02:29:17 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 02:29:17 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 02:29:17 INFO - Using env: {'DISPLAY': ':0', 02:29:17 INFO - 'HOME': '/home/cltbld', 02:29:17 INFO - 'LANG': 'en_US.UTF-8', 02:29:17 INFO - 'LANGUAGE': 'en_US:en', 02:29:17 INFO - 'LOGNAME': 'cltbld', 02:29:17 INFO - 'MAIL': '/var/mail/cltbld', 02:29:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:17 INFO - 'MOZ_NO_REMOTE': '1', 02:29:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:29:17 INFO - 'NO_EM_RESTART': '1', 02:29:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:29:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:17 INFO - 'PWD': '/builds/slave/test', 02:29:17 INFO - 'SHELL': '/bin/bash', 02:29:17 INFO - 'SHLVL': '1', 02:29:17 INFO - 'TERM': 'linux', 02:29:17 INFO - 'TMOUT': '86400', 02:29:17 INFO - 'USER': 'cltbld', 02:29:17 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1504257792.242887-1504466522', 02:29:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:17 INFO - '_': '/tools/buildbot/bin/python'} 02:29:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:29:17 INFO - Running setup.py (path:/tmp/pip-XyoQJY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:29:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:29:17 INFO - Running setup.py (path:/tmp/pip-YYucbj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:29:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:29:17 INFO - Running setup.py (path:/tmp/pip-OJ4uIa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:29:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:29:18 INFO - Running setup.py (path:/tmp/pip-Af7hql-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:29:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:29:18 INFO - Running setup.py (path:/tmp/pip-dbW5bU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:29:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:29:18 INFO - Running setup.py (path:/tmp/pip-MrzRFf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:29:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:29:18 INFO - Running setup.py (path:/tmp/pip-WXJlg4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:29:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:29:18 INFO - Running setup.py (path:/tmp/pip-aksOCo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:29:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:29:18 INFO - Running setup.py (path:/tmp/pip-l0GIHy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:29:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:29:18 INFO - Running setup.py (path:/tmp/pip-OZqT64-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:29:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:29:18 INFO - Running setup.py (path:/tmp/pip-dKtgJU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:29:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:29:18 INFO - Running setup.py (path:/tmp/pip-ohByBw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:29:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:29:18 INFO - Running setup.py (path:/tmp/pip-TovV80-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:29:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:29:18 INFO - Running setup.py (path:/tmp/pip-QzFhB8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:29:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:29:18 INFO - Running setup.py (path:/tmp/pip-BHnWUT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:29:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:29:18 INFO - Running setup.py (path:/tmp/pip-GMiHbu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:29:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:29:18 INFO - Running setup.py (path:/tmp/pip-aXJhjl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:29:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:29:19 INFO - Running setup.py install for manifestparser 02:29:19 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:29:19 INFO - Running setup.py install for mozcrash 02:29:19 INFO - Running setup.py install for mozdebug 02:29:19 INFO - Running setup.py install for mozdevice 02:29:19 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:29:19 INFO - Running setup.py install for mozfile 02:29:19 INFO - Running setup.py install for mozhttpd 02:29:19 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:29:19 INFO - Running setup.py install for mozinfo 02:29:19 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:29:19 INFO - Running setup.py install for mozInstall 02:29:19 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:29:19 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:29:19 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:29:19 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:29:19 INFO - Running setup.py install for mozleak 02:29:19 INFO - Running setup.py install for mozlog 02:29:20 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:29:20 INFO - Running setup.py install for moznetwork 02:29:20 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:29:20 INFO - Running setup.py install for mozprocess 02:29:20 INFO - Running setup.py install for mozprofile 02:29:20 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:29:20 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:29:20 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:29:20 INFO - Running setup.py install for mozrunner 02:29:20 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:29:20 INFO - Running setup.py install for mozscreenshot 02:29:20 INFO - Running setup.py install for moztest 02:29:20 INFO - Running setup.py install for mozversion 02:29:20 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:29:20 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:29:20 INFO - Cleaning up... 02:29:20 INFO - Return code: 0 02:29:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:29:20 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')]} 02:29:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:20 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')]} 02:29:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:20 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 0x21191f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22e2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2485a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2489090>, '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 0x2489d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2483f90>, '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': 'ea65d483da93c827905c2090000001f5-1504257792.242887-1504466522', '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 02:29:20 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 02:29:20 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 02:29:20 INFO - Using env: {'DISPLAY': ':0', 02:29:20 INFO - 'HOME': '/home/cltbld', 02:29:20 INFO - 'LANG': 'en_US.UTF-8', 02:29:20 INFO - 'LANGUAGE': 'en_US:en', 02:29:20 INFO - 'LOGNAME': 'cltbld', 02:29:20 INFO - 'MAIL': '/var/mail/cltbld', 02:29:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:20 INFO - 'MOZ_NO_REMOTE': '1', 02:29:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:29:20 INFO - 'NO_EM_RESTART': '1', 02:29:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:29:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:20 INFO - 'PWD': '/builds/slave/test', 02:29:20 INFO - 'SHELL': '/bin/bash', 02:29:20 INFO - 'SHLVL': '1', 02:29:20 INFO - 'TERM': 'linux', 02:29:20 INFO - 'TMOUT': '86400', 02:29:20 INFO - 'USER': 'cltbld', 02:29:20 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1504257792.242887-1504466522', 02:29:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:20 INFO - '_': '/tools/buildbot/bin/python'} 02:29:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:29:21 INFO - Running setup.py (path:/tmp/pip-p30V8l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:29:21 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)) 02:29:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:29:21 INFO - Running setup.py (path:/tmp/pip-zpp92z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:29:21 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)) 02:29:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:29:21 INFO - Running setup.py (path:/tmp/pip-AJuurJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:29:21 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)) 02:29:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:29:21 INFO - Running setup.py (path:/tmp/pip-QgD9Pg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:29:21 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)) 02:29:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:29:21 INFO - Running setup.py (path:/tmp/pip-KmLo9a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:29:21 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)) 02:29:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:29:21 INFO - Running setup.py (path:/tmp/pip-J6iVbv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:29:21 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)) 02:29:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:29:21 INFO - Running setup.py (path:/tmp/pip-z45CIT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:29:21 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)) 02:29:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:29:21 INFO - Running setup.py (path:/tmp/pip-sYu0NM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:29:21 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)) 02:29:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:29:21 INFO - Running setup.py (path:/tmp/pip-ER1Zt2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:29:21 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)) 02:29:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:29:21 INFO - Running setup.py (path:/tmp/pip-4d3yDQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:29:21 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)) 02:29:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:29:21 INFO - Running setup.py (path:/tmp/pip-k3nzjA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:29:21 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)) 02:29:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:29:21 INFO - Running setup.py (path:/tmp/pip-LKgs0X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:29:21 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)) 02:29:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:29:21 INFO - Running setup.py (path:/tmp/pip-8cqjzK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:29:21 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)) 02:29:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:29:21 INFO - Running setup.py (path:/tmp/pip-DfWBGc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:29:22 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)) 02:29:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:29:22 INFO - Running setup.py (path:/tmp/pip-2lRw6e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:29:22 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)) 02:29:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:29:22 INFO - Running setup.py (path:/tmp/pip-2aQnc0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:29:22 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)) 02:29:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:29:22 INFO - Running setup.py (path:/tmp/pip-Q8Bihx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:29:22 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)) 02:29:22 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)) 02:29:22 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)) 02:29:22 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)) 02:29:22 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)) 02:29:22 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)) 02:29:22 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)) 02:29:22 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:29:22 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 02:29:22 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 02:29:22 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 02:29:22 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 02:29:23 INFO - Downloading blessings-1.6.tar.gz 02:29:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:29:23 INFO - Installing collected packages: blessings 02:29:23 INFO - Running setup.py install for blessings 02:29:23 INFO - Successfully installed blessings 02:29:23 INFO - Cleaning up... 02:29:23 INFO - Return code: 0 02:29:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:29:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:29:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:29:23 INFO - Reading from file tmpfile_stdout 02:29:23 INFO - Current package versions: 02:29:23 INFO - argparse == 1.2.1 02:29:23 INFO - blessings == 1.6 02:29:23 INFO - blobuploader == 1.2.4 02:29:23 INFO - docopt == 0.6.1 02:29:23 INFO - functools32 == 3.2.3-2 02:29:23 INFO - jsonschema == 2.5.1 02:29:23 INFO - manifestparser == 1.1 02:29:23 INFO - mozInstall == 1.13 02:29:23 INFO - mozcrash == 1.0 02:29:23 INFO - mozdebug == 0.1 02:29:23 INFO - mozdevice == 0.51 02:29:23 INFO - mozfile == 1.2 02:29:23 INFO - mozhttpd == 0.7 02:29:23 INFO - mozinfo == 0.10 02:29:23 INFO - mozleak == 0.1 02:29:23 INFO - mozlog == 3.5 02:29:23 INFO - moznetwork == 0.27 02:29:23 INFO - mozprocess == 0.25 02:29:23 INFO - mozprofile == 0.28 02:29:23 INFO - mozrunner == 6.13 02:29:23 INFO - mozscreenshot == 0.1 02:29:23 INFO - mozsystemmonitor == 0.3 02:29:23 INFO - moztest == 0.8 02:29:23 INFO - mozversion == 1.4 02:29:23 INFO - psutil == 3.1.1 02:29:23 INFO - requests == 1.2.3 02:29:23 INFO - wsgiref == 0.1.2 02:29:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:29:23 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')]} 02:29:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:23 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')]} 02:29:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:23 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 0x21191f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22e2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2485a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2489090>, '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 0x2489d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2483f90>, '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': 'ea65d483da93c827905c2090000001f5-1504257792.242887-1504466522', '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 02:29:23 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 02:29:23 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 02:29:23 INFO - Using env: {'DISPLAY': ':0', 02:29:23 INFO - 'HOME': '/home/cltbld', 02:29:23 INFO - 'LANG': 'en_US.UTF-8', 02:29:23 INFO - 'LANGUAGE': 'en_US:en', 02:29:23 INFO - 'LOGNAME': 'cltbld', 02:29:23 INFO - 'MAIL': '/var/mail/cltbld', 02:29:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:23 INFO - 'MOZ_NO_REMOTE': '1', 02:29:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:29:23 INFO - 'NO_EM_RESTART': '1', 02:29:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:29:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:23 INFO - 'PWD': '/builds/slave/test', 02:29:23 INFO - 'SHELL': '/bin/bash', 02:29:23 INFO - 'SHLVL': '1', 02:29:23 INFO - 'TERM': 'linux', 02:29:23 INFO - 'TMOUT': '86400', 02:29:23 INFO - 'USER': 'cltbld', 02:29:23 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1504257792.242887-1504466522', 02:29:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:23 INFO - '_': '/tools/buildbot/bin/python'} 02:29:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:23 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)) 02:29:23 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)) 02:29:23 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)) 02:29:23 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)) 02:29:23 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)) 02:29:23 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)) 02:29:23 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)) 02:29:23 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)) 02:29:23 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)) 02:29:23 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 02:29:23 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 02:29:23 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 02:29:23 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 02:29:23 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 02:29:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 02:29:25 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)) 02:29:25 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)) 02:29:25 INFO - Installing collected packages: simplejson 02:29:25 INFO - Running setup.py install for simplejson 02:29:25 INFO - building 'simplejson._speedups' extension 02:29:25 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 02:29:26 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 02:29:26 INFO - Successfully installed simplejson 02:29:26 INFO - Cleaning up... 02:29:26 INFO - Return code: 0 02:29:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:29:26 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')]} 02:29:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:29:26 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')]} 02:29:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:29:26 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 0x21191f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22e2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2485a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2489090>, '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 0x2489d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2483f90>, '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': 'ea65d483da93c827905c2090000001f5-1504257792.242887-1504466522', '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 02:29:26 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 02:29:26 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 02:29:26 INFO - Using env: {'DISPLAY': ':0', 02:29:26 INFO - 'HOME': '/home/cltbld', 02:29:26 INFO - 'LANG': 'en_US.UTF-8', 02:29:26 INFO - 'LANGUAGE': 'en_US:en', 02:29:26 INFO - 'LOGNAME': 'cltbld', 02:29:26 INFO - 'MAIL': '/var/mail/cltbld', 02:29:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:26 INFO - 'MOZ_NO_REMOTE': '1', 02:29:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:29:26 INFO - 'NO_EM_RESTART': '1', 02:29:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:29:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:26 INFO - 'PWD': '/builds/slave/test', 02:29:26 INFO - 'SHELL': '/bin/bash', 02:29:26 INFO - 'SHLVL': '1', 02:29:26 INFO - 'TERM': 'linux', 02:29:26 INFO - 'TMOUT': '86400', 02:29:26 INFO - 'USER': 'cltbld', 02:29:26 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1504257792.242887-1504466522', 02:29:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:26 INFO - '_': '/tools/buildbot/bin/python'} 02:29:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:29:26 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:29:26 INFO - Cleaning up... 02:29:26 INFO - Return code: 0 02:29:26 INFO - Running post-action listener: _resource_record_post_action 02:29:26 INFO - Running post-action listener: _start_resource_monitoring 02:29:26 INFO - Starting resource monitoring. 02:29:26 INFO - [mozharness: 2017-09-01 09:29:26.477907Z] Finished create-virtualenv step (success) 02:29:26 INFO - [mozharness: 2017-09-01 09:29:26.478419Z] Running install step. 02:29:26 INFO - Running pre-action listener: _resource_record_pre_action 02:29:26 INFO - Running main action method: install 02:29:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:29:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:29:26 INFO - Reading from file tmpfile_stdout 02:29:26 INFO - Detecting whether we're running mozinstall >=1.0... 02:29:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:29:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:29:26 INFO - Reading from file tmpfile_stdout 02:29:26 INFO - Output received: 02:29:26 INFO - Usage: mozinstall [options] installer 02:29:26 INFO - Options: 02:29:26 INFO - -h, --help show this help message and exit 02:29:26 INFO - -d DEST, --destination=DEST 02:29:26 INFO - Directory to install application into. [default: 02:29:26 INFO - "/builds/slave/test"] 02:29:26 INFO - --app=APP Application being installed. [default: firefox] 02:29:26 INFO - mkdir: /builds/slave/test/build/application 02:29:26 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'] 02:29:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 02:29:43 INFO - Reading from file tmpfile_stdout 02:29:43 INFO - Output received: 02:29:43 INFO - /builds/slave/test/build/application/firefox/firefox 02:29:43 INFO - Running post-action listener: _resource_record_post_action 02:29:43 INFO - [mozharness: 2017-09-01 09:29:43.355648Z] Finished install step (success) 02:29:43 INFO - [mozharness: 2017-09-01 09:29:43.355900Z] Running setup-mitmproxy step. 02:29:43 INFO - Running pre-action listener: _resource_record_pre_action 02:29:43 INFO - Running main action method: setup_mitmproxy 02:29:43 INFO - Skipping: mitmproxy is not required 02:29:43 INFO - Running post-action listener: _resource_record_post_action 02:29:43 INFO - [mozharness: 2017-09-01 09:29:43.357100Z] Finished setup-mitmproxy step (success) 02:29:43 INFO - [mozharness: 2017-09-01 09:29:43.357319Z] Running run-tests step. 02:29:43 INFO - Running pre-action listener: _resource_record_pre_action 02:29:43 INFO - Running pre-action listener: _set_gcov_prefix 02:29:43 INFO - Running main action method: run_tests 02:29:43 WARNING - Try message not found. 02:29:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:29:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:29:43 INFO - Python 2.7.3 02:29:43 INFO - Return code: 0 02:29:43 INFO - grabbing minidump binary from tooltool 02:29:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:29:43 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 0x2489090>, '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 0x2489d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2483f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:29:43 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 02:29:43 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 02:29:43 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 02:29:43 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:29:43 INFO - Return code: 0 02:29:43 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 02:29:43 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:29:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:29:43 INFO - ENV: RUST_BACKTRACE is now full 02:29:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 02:29:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:29:43 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/QjwniFnmR7exVsWnUkO_3Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-084', '--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 02:29:43 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/QjwniFnmR7exVsWnUkO_3Q/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-084 --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 02:29:43 INFO - Using env: {'DISPLAY': ':0', 02:29:43 INFO - 'HOME': '/home/cltbld', 02:29:43 INFO - 'LANG': 'en_US.UTF-8', 02:29:43 INFO - 'LANGUAGE': 'en_US:en', 02:29:43 INFO - 'LOGNAME': 'cltbld', 02:29:43 INFO - 'MAIL': '/var/mail/cltbld', 02:29:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:29:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:29:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:43 INFO - 'MOZ_NO_REMOTE': '1', 02:29:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:29:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:29:43 INFO - 'NO_EM_RESTART': '1', 02:29:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:29:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:43 INFO - 'PWD': '/builds/slave/test', 02:29:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:29:43 INFO - 'RUST_BACKTRACE': 'full', 02:29:43 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 02:29:43 INFO - 'SHELL': '/bin/bash', 02:29:43 INFO - 'SHLVL': '1', 02:29:43 INFO - 'STYLO_FORCE_ENABLED': '1', 02:29:43 INFO - 'STYLO_THREADS': '1', 02:29:43 INFO - 'TERM': 'linux', 02:29:43 INFO - 'TMOUT': '86400', 02:29:43 INFO - 'USER': 'cltbld', 02:29:43 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1504257792.242887-1504466522', 02:29:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:43 INFO - '_': '/tools/buildbot/bin/python'} 02:29:43 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/QjwniFnmR7exVsWnUkO_3Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-084', '--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 02:29:43 INFO - mozversion application_buildid: 20170901083953 02:29:43 INFO - mozversion application_changeset: a3585c77e2b1bc5f5fea907e97762f7b47a12033 02:29:43 INFO - mozversion application_display_name: Nightly 02:29:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:29:43 INFO - mozversion application_name: Firefox 02:29:43 INFO - mozversion application_remotingname: firefox 02:29:43 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 02:29:43 INFO - mozversion application_vendor: Mozilla 02:29:43 INFO - mozversion application_version: 57.0a1 02:29:43 INFO - mozversion platform_buildid: 20170901083953 02:29:43 INFO - mozversion platform_changeset: a3585c77e2b1bc5f5fea907e97762f7b47a12033 02:29:43 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 02:29:43 INFO - mozversion platform_version: 57.0a1 02:29:43 INFO - using testdate: 1504258183 02:29:43 INFO - actual date: 1504258183 02:29:43 INFO - starting webserver on 'localhost:58960' 02:29:43 INFO - SUITE-START | Running 2 tests 02:29:43 INFO - TEST-START | dromaeo_css 02:29:43 INFO - Initialising browser for dromaeo_css test... 02:29:43 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:58960/getInfo.html -profile /tmp/tmpp387qY/profile 02:29:43 INFO - TEST-INFO | started process 31739 (/builds/slave/test/build/application/firefox/firefox http://localhost:58960/getInfo.html) 02:29:49 INFO - TEST-INFO | 31739: exit 0 02:29:49 INFO - Browser initialized. 02:29:49 INFO - Running cycle 1/1 for dromaeo_css test... 02:29:49 INFO - Using env: {'DISPLAY': ':0', 02:29:49 INFO - 'HOME': '/home/cltbld', 02:29:49 INFO - 'JSGC_DISABLE_POISONING': '1', 02:29:49 INFO - 'LANG': 'en_US.UTF-8', 02:29:49 INFO - 'LANGUAGE': 'en_US:en', 02:29:49 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 02:29:49 INFO - 'LOGNAME': 'cltbld', 02:29:49 INFO - 'MAIL': '/var/mail/cltbld', 02:29:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:29:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:29:49 INFO - 'MOZ_CRASHREPORTER': '1', 02:29:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:49 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 02:29:49 INFO - 'MOZ_NO_REMOTE': '1', 02:29:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:29:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:29:49 INFO - 'NO_EM_RESTART': '1', 02:29:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:29:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:49 INFO - 'PWD': '/builds/slave/test', 02:29:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:29:49 INFO - 'RUST_BACKTRACE': 'full', 02:29:49 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 02:29:49 INFO - 'SHELL': '/bin/bash', 02:29:49 INFO - 'SHLVL': '1', 02:29:49 INFO - 'STYLO_FORCE_ENABLED': '1', 02:29:49 INFO - 'STYLO_THREADS': '1', 02:29:49 INFO - 'TERM': 'linux', 02:29:49 INFO - 'TMOUT': '86400', 02:29:49 INFO - 'USER': 'cltbld', 02:29:49 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1504257792.242887-1504466522', 02:29:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:49 INFO - '_': '/tools/buildbot/bin/python'} 02:29:49 INFO - TEST-INFO | started process 31924 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpp387qY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 02:29:50 INFO - PID 31924 | 02:29:50 INFO - PID 31924 | (/builds/slave/test/build/application/firefox/firefox:31976): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 02:29:50 INFO - PID 31924 | 02:30:01 INFO - PID 31924 | RSS: Main: 171237376 02:30:01 INFO - PID 31924 | 02:33:00 INFO - PID 31924 | Cycle 1(1): loaded http://localhost:58960/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58960/tests/dromaeo/cssquery-ext.html) 02:33:01 INFO - PID 31924 | RSS: Main: 174788608 02:33:01 INFO - PID 31924 | 02:36:03 INFO - PID 31924 | Cycle 1(1): loaded http://localhost:58960/tests/dromaeo/cssquery-ext.html (next: http://localhost:58960/tests/dromaeo/cssquery-jquery.html) 02:36:03 INFO - PID 31924 | RSS: Main: 171347968 02:36:03 INFO - PID 31924 | 02:39:05 INFO - PID 31924 | Cycle 1(1): loaded http://localhost:58960/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58960/tests/dromaeo/cssquery-mootools.html) 02:39:06 INFO - PID 31924 | RSS: Main: 172945408 02:39:06 INFO - PID 31924 | 02:42:07 INFO - PID 31924 | Cycle 1(1): loaded http://localhost:58960/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58960/tests/dromaeo/cssquery-prototype.html) 02:42:08 INFO - PID 31924 | RSS: Main: 170803200 02:42:08 INFO - PID 31924 | 02:45:12 INFO - PID 31924 | Cycle 1(1): loaded http://localhost:58960/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58960/tests/dromaeo/cssquery-yui.html) 02:45:12 INFO - PID 31924 | RSS: Main: 172675072 02:45:12 INFO - PID 31924 | 02:48:14 INFO - PID 31924 | Cycle 1(1): loaded http://localhost:58960/tests/dromaeo/cssquery-yui.html (next: http://localhost:58960/tests/dromaeo/cssquery-dojo.html) 02:48:14 INFO - PID 31924 | RSS: Main: 175173632 02:48:14 INFO - PID 31924 | 02:48:14 INFO - PID 31924 | __start_tp_report 02:48:14 INFO - PID 31924 | _x_x_mozilla_page_load 02:48:14 INFO - PID 31924 | _x_x_mozilla_page_load_details 02:48:14 INFO - PID 31924 | |i|pagename|runs| 02:48:14 INFO - PID 31924 | |0;dojo.html;3325.3183097465017;3331.133905184651;3337.1657085728566;3353.195233144046;3379.915502112445;6379.330170332118;6480.027995800627;6485.80542583722;6501.2198536175665;6507.739690412378;6292.653904035276;6371.299157092716;6379.457746091576;6402.263739669927;6410.07053977173;5841.649501029951;5842.240508733869;5866.589338746289;5912.645241285515;6005.909911351333;5889.941100588982;5978.790742324019;5984.8503787405225;6030.969845150768;6055.545834062441;5790.594658373915;5872.324682661484;5903.26209223847;5975.820725378224;6009.819705408845;5811.651300921916;5869.471747542699;5958.225430694026;5999.880002399971;6005.219321488223;5821.446962538552;5865.178874957509;5924.881502369972;5944.762209511614;5952.375000624958;6182.84542886427;6294.0873573332065;6304.590201636879;6350.587211831216;6474.449671777905;6768.52620316578;6815.148106486689;6881.793546193572;6975.79072627822;7018.719251229996;7008.929910700878;7036.698210831013;7051.1186101737285;7061.046758687563;7067.293270672943;6377.1709677742065;6378.585391949508;6431.646259455728;6474.935250647527;6516.8696626067685;6883.862322753517;6963.512554121265;6973.965130174317;7055.506114572034;7174.3184397482155;6666.866662666719;6725.125733654593;6741.089952856301;6773.525853190229;6791.456683465311;6875.3812156906115;6896.379325860697;6912.239653638096;6918.135233095863;6954.269801670853;6822.692978815965;6843.623600702009;6882.7591034313555;6913.930860691429;6921.550099243533;6865.41643960269;6874.9656251719425;6945.548539345027;6972.895406568906;6973.267806880306;5317.494837990384;5427.592930530226;5451.454854514596;5490.69801160932;5493.972530137324;4671.065786842645;4808.374911261583;4816.735079570657;4821.807127714922;4847.127517046895;5978.55160862937;6070.119832624333;6074.787382441594;6085.756569737249;6096.69516524172;6853.828654283683;6871.278515755874;6957.234704182536;6959;6994.055802466602;5843.883122337615;5932.940670593239;5934.1692163097205;5937.584369094208;6075.5747097703625;5915.792947246826;5942.346341902387;5943.078822782476;5944.762209511658;5945.464908158287;6574.8356291093105;6670.799876003728;6678.098456708282;6712.060311556386;6789.524733268624;6945.409640180439;6968.756093536803;6988.05661235747;6991.720331186695;6996.335348141918;6672.83317917056;6759.763408280686;6784.253732089367;6795.490338224752;6795.592264464148;6843.8973415399805;6848.760293389708;6928.926016467455;6937.514373993772;6958.79123626292;6733.898991515033;6932.480063995321;6938.757143499954;7032.156141263081;7043.36609705129;6548.705308261045;6673.632950187592;6755.864882702417;6757.054012438164;6765.594064355958;6691.498137639794;6756.256811750605;6770.289119642367;6778.627175505395;6781.254062053072;6504.252011018793;6587.571807832475;6603.867922641616;6686.06395104676;6702.0952171458175;6722.361375669304;6743.15710536183;6745.156855393076;6787.490938179558;6872.10662613877;6359.491240700825;6492.091107244895;6495.805125846232;6495.9025614614875;6655.334466553306 02:48:14 INFO - PID 31924 | |1;ext.html;814.6822739131736;831.488634489789;845.2477295207266;876.7150676030292;877.7717793373721;40290;41038;41117.58882411171;41173.38239926396;41190.58809411905;37483.4377484338;37839.621603783955;37843.24313513735;37859;37892.621073789254;6914.343137401964;7014.22843487216;7020.754273600425;7020.929790702105;7022.613756243404;6973.407260382874;7046.682899269519;7047.471439642048;7049.612271325075;7049.647517624124;7692.884606730903;7932.325752311061;7934.642941067666;7937.206279372074;7948.642311095986;7806.76579702607;7888.64501097452;7894.131645518988;7894.565798881059;7897.447178697494;7450.254974502561;7668.923310766932;7675.424343174229;7739.883901741478;7813.570253636048;37035.25929481422;37402.251954960746;37420.43869341962;37474.62525374766;37529;3448.3620530201993;3490.092575930251;3494.1614012637037;3495.4931534927546;3496.0560648624983;28310.716892831013;28443.146705598865;28452.430951380855;28454.288642784097;28551.71448285532;5143.3570803649545;5145.279660847485;5167.224916262553;5179.637425380225;5194.480551944813;189830;190950;191099;192246;192481.03759481254;46035.079298414516;46435.76782116135;46599.767001165455;46600.76699616548;46757.532424675315;48161.277580836315;48624.5137548627;48655.270170947464;48709.512904871204;48759.26861097087;14477.203753793432;14538.85461145375;14619.634509137357;14624.707505849823;14642.682158605563;11798.46906889192;11892;11905.821412678817;11912.10659200546;11922.523099076114;13407.932960335136;13599.59201223965;13650.249236292098;13716.519921802688;13878.236696981561;5497;5583.804566840141;5602.8709090546145;5634.4365563443325;5656.971715141398;1002.7894142230214;1007.9748006299901;1016.6035246253966;1020.168562621476;1020.5509575786779;22427.663585046237;28292.575611365846;28761.56857647137;28781.136565903056;29413.85293073521;1072.8390741388698;1081.5781845080426;1087.396494945313;1097.8956999085074;1104.2491106047964;12461.875381246253;12496.312702801251;12589.370531473389;12665.936670316589;12740.936295318465;223132;225970;226379.60430593553;226851;226927;46960.765196174485;47511.287330690066;53819.19271210935;54228.18657720138;55916;1437.1448987852211;1443.1124858212333;1443.6390902274431;1450.2023886862394;1457.0383546859023;27067;27589.586156208075;28084.157475275773;28198.295042624097;28391.148265552067;44573.777131115436;44741.10517789691;44799.328010079225;44933.775331123135;45020.32469513026;210231;214889;215088;215089;215106;35098.298034038664;35106.473402899486;35259.82370088133;35879.82060089683;35939;42082;43401.13197735965;43581.78209109061;44010;44012.11975760531;31521.8423907879;31835.681643184198;32048.839755801073;32368.190795229366;32479.512807308383;31560.36879262448;31580.368392632477;31582;32241.193970150933;32603.836980814944;34674.133146671535;35965.28069438649;36111.097222569646;36114.63885361218;36351;30001.549976750804;30236.24409389783;30252;30505.54241686332;30580;23287.301380958597;23457.41356466102;23469.765302346757;23857.64213536833;23992.28023159308 02:48:14 INFO - PID 31924 | |2;jquery.html;572.5591294703077;584.5907864494854;586.6831910768185;588.4145275450927;588.9646621202728;22122.668159977595;22308.665370019422;22869.313920582368;22898.65652015221;22916.96873640688;25663.871680641572;26228.60657090145;26262.868685656544;26305.736942630567;26325.86837065817;6532.052852336408;6609.768658096968;6643.568168069084;6673.566218195826;6680.465562754992;6089.1170780236835;6709.362610552014;6713.127293451869;6715.093462382565;6716.865662686743;6676.399124078856;6676.833079173035;6677.399034086931;6681;6685.966570167142;6680.632565208912;6681.031250468704;6682.465402767767;6685;6685.130932978706;6200.596961197552;6204.844878878019;6211.409916058013;6213;6221.9688901555655;28301.292467688472;29015.12954611365;29952;30080.548791768142;30260;2047.5290683142812;2060.6702927531674;2066.8553201275918;2074.6991686205565;2076.532780124475;19532.21871125153;20034.69947950782;20065.8996705017;20069;20069.899650501655;2379.821513386503;2442.5481285962155;2469.592517234654;2500.3874050857476;2512.0705339024676;135698.3215083928;140629.99999999898;141307;141768;141863.29068354593;14316.713665726627;14348.928255358656;14445.49440769568;14906.850931490762;15010.399584016517;22341.553168936854;22880.771192287863;23512.882435588053;23558.646620300708;23569;9748.610055597697;9864.112229899343;9922;9968.850467242997;10039.799204015879;9027;9081.59132839024;9098.681546145854;9104.180623743894;9165.17513423795;9052.683156089637;9187.356885017985;9427;9439;9456.669016584387;2736.835789852615;2750.7111753265617;2756.682981457118;2767.8616069196864;2846.558783388578;983.2281658897731;988.2785566536469;991.3258983890879;998.4857798233916;1004.4626125023224;19959.700604490943;20071.29750458727;20277.790254882162;20319.4920126998;20740.792592073885;1059.3061544688242;1064.669952314785;1065.8720953485476;1076.9192310576739;1080.1143062688677;11263.605773797877;11423.485943132579;11448.828267575993;11474.368909710047;11517.654470365902;427666;435178;438295;440067;443433;104179;105321.47339263254;115329.4233528827;116033;116834.41582792031;1194.3073017649742;1213.1083653514659;1217.945192466341;1226.3561630144245;1227.9631611051682;17736.024518651597;17896.194671239566;17944;17994.280228790703;18114.818851811313;46306;48043;52640;56307.43692563186;57290;61007.38992610195;61238.69380653068;61431.078533821135;61639.38360616336;61944.69027654833;14288.42846286137;14638.194899280641;14752.262386880828;14769.92615036918;14791.038582492103;22274.443138921008;22368.993395297577;22405;22419.215327463462;23122;18355.265789368277;18457.077146142907;18654.53363665919;18685;18901.149448275137;16148.919255403647;16446.177691115157;16474.341026358812;16988.235529400958;16988.83011169922;18560.907195463937;18927.148278327233;19106.713399298744;19154.904225479342;19616.509587260432;14368.568942931728;14541.927290363481;14558.927205363905;14600.488982885548;14650.560483185522;17988.730169047736;18116.72824907654;18281.634367312847;18296.26814927388;18300.633987320445 02:48:14 INFO - PID 31924 | |3;mootools.html;1345.1659970818093;1395.3930040432413;1402.2427888939974;1406.6905280838212;1412.286795168439;14661.120332780027;14774.335154918028;15286.082835029893;15305.92347038266;15325.616859578517;15748.527544173694;15985.280662370193;16008.199590020511;16013.759793603076;16034.5991350216;2732.6857411397696;2767.723227677236;2768.0865314446182;2769.2246171072034;2771.7089705580925;2684.516786978343;2716.8234064785725;2732.631094802206;2733.3439974406097;2734.111413790526;2687.3013016615623;2717.402171522262;2718.6465759451344;2719.2657982344754;2719.5104881121392;2670.986645066772;2697.3121853927223;2717.388587567801;2720.7415295546894;2723.6459260296133;2681.423493948791;2690.219836247486;2691.3944362518473;2691.421344410961;2699.6490456240563;14694.11835289886;14968.700625987529;15035;15046.623834404118;15048.774268385983;7834.529928204326;8071.717489887885;8095.95952020242;8098.676052957874;8115.95942020292;5380.677159370451;5505.394406615269;5652.858678533028;5658.886822263532;5679.829605111853;1912.6365990461768;1964.9508762280914;1969.4879331373802;2019.0813180003063;2025.4025062606565;26037;26181.214563563124;26200.34499137518;26208.213753587424;26218;7036.577805331696;7236.457265705093;7237.203907570163;7248.021517095125;7269.963650181715;16545.090020049014;17132.40036598738;17187.31250749956;17196.31214751421;17240.051797151027;7708.267714567107;7886.0536735592095;7886.211378862068;7904.802379940433;7908.090569584571;6159.5072394208355;6353.332900045429;7109.146902371748;7293.744718934812;7314.634268286564;5280.155175171954;5366.195070739421;5384.380796208407;5396.514313711707;5415.945840541623;1568.7254730422358;1574.087029522874;1574.6220906982242;1585.0885740699143;1589.7933268675;889.3152272749946;910.6402970826487;911.7994041310901;920.1258804136097;920.7007722490217;8489.787755306043;8796.692115775917;8802.647894084164;8968.820623587622;9081;985.4038306824268;994.6170724271063;995.4624502768569;996.1234113979797;1026.2508368890603;5033.672811267256;5188.844334669966;5238.397584277857;5446.482584154499;5850.5612079094235;21291;21704.457388565097;21769.23807666724;22028;22133.77866221317;21047.263345782823;21330.253441129487;21467;21839.781602183775;21889.452763681034;11130.49912753928;11527.365994870363;11550.711232219093;11794.646160615195;11809.173357864867;8814.955925220333;8841.204291613785;8861.60122794463;8916.28669706409;8951.328650351119;18088.819111808712;18374;18534.16596253145;18548.629027419105;19471.123799429344;18248.36130735418;18894.05529723481;19079.52301192481;19225.903870481117;19604.411867643994;10108.444035578113;10426.374417534973;10432;12268.38658067111;12534.937325313314;14307.928460358047;14650.19423931694;14686.926565367105;14703.044302120328;14801.555953321418;11584.826227606423;11653.184277100521;11717.589884354007;11752.882471175179;11806.409679515818;11042.723931901766;11195.608153714908;11257.211995160587;11479.311241325548;11646.94176529112;9552.808943821223;9643.951780241054;9649.517524123625;9649.517524123907;9649.951750241486;6970.198427180736;7094.148702155774;7183.353498185188;7289.161746399232;7349.39096620692;7045.189803172496;7060.752873649603;7153.284671533013;7202.099737532809;7222.313880181375 02:48:14 INFO - PID 31924 | |4;prototype.html;502.2391077517559;502.9270755740418;529.3145376737125;529.6901312732051;529.8993191293654;6375.075614035962;6378.521610879191;6391.424771770539;6394.936050639492;6468.288488266287;6117.143599896018;6214.725981173849;6267.279262884758;6278.717457714402;6321.1150438938585;5007.52428519291;5073.923891141636;5077.263796749469;5087.287779710843;5107.61692873036;4842.9031419371595;5067.113255180348;5113.678941294619;5129.204973229137;5138.7173705446185;4962.900741985158;5007.724575148365;5028.622853286;5041.294218809369;5091.898162036757;4870.9269360959615;4959.776810043557;5026.472220416877;5032.521910418504;5073.898522029557;4432.113577284524;4474.574915383034;4498.752568608726;4514.909701805946;4532.682712210147;6103.375548514137;6162.568620196588;6180.752769889199;6215.782447614357;6241.625502469867;5734.627249228828;5762;5766.423357664242;5778.451047150556;5840.474357307863;6501.772437964649;6538.0519824625635;6564.522982328986;6599.307072757388;6617.602943823338;4856;4880.731559764212;4883.289774175331;4890.388701412324;4919.975400123012;6897.241303456616;6959.965200174018;6999.440044796455;7005.602282892793;7039.436845052383;5024.62315326352;5027.069992051483;5034.244863270539;5038.143515602356;5052.837847295143;5120.206368012964;5125.333706618116;5126.282320475137;5136.2552429898205;5151.2273159026445;5760.6255593386295;5767.538596912237;5780.450857168562;5781.797637082682;5809.564282678816;6549.214094308618;6628.270890202074;6648.66756662165;6696.1629796275465;6732.124823772878;1674.1796519705256;1693.9576510587087;1715.0995727243048;1724.3619860651388;1784.9553761156076;1148.0941636882735;1375.3810785146725;1441.7621092519616;1466.7213229486363;1482.5774654223494;5925.585209035326;5928.525717942554;5930.28836539618;5956.821295361146;5998.730057147439;6700.845475805032;6768.2216545097945;6776.28848970851;6798.116244888231;6802.319768023257;5906.202662640574;5916.171735956969;5958.523318134538;5959.22530071088;5988.341282458926;3608.188157664557;3630.4009838376373;3631.6005239423644;3642.1805093853673;3648.974818975494;6655.43428808547;6690.498212634072;6690.565113267622;6703.128593282842;6744.156980377453;7105.538140020882;7132;7134.429245660334;7136.181208422644;7154.642267886585;6660.933390666129;6690.933090669128;6691.933080669229;6695.397414232742;6701.363370479797;6756.155480564929;6774.8645027100165;6778.3221677831825;6797.66011699423;6825.726970921306;6784.3215678433735;6818.761343352959;6838.2136054352395;6851.383375496229;6867.9999999998;6605.141331626858;6641.483791022151;6655.267920528641;6683.9639855822425;6740.191791637709;4936.284238785428;4978.103941290457;5022.673526220784;5049.924251136163;5051.74741262928;6766.733144396485;6770.424513916372;6811.863762724817;6834.965825170842;6962.199347075151;6585.916731626422;6640.933590664032;6673.032410300576;6679.9332006679315;6727.82263103945;6622.205335359788;6649.736550055474;6651.600903945779;6667.499937504804;6671.833204169935;6221.688915554114;6359.904601431075;6441.613503189824;6492.577982431127;6619.3380661933425;6575.96712016456;6588.110605068255;6590.868182636224;6594.340565943367;6624.701888415129;6441.1304473895425;6444.806655800334;6470.255920569195;6492.961545762486;6511.641859697573 02:48:14 INFO - PID 31924 | |5;yui.html;901.7565257380508;909.8908131024278;925.4817302310704;927.5408672707011;927.795884905321;17413.738793918103;18408.723869141973;18409.355672551465;18442.907785461055;18461.7230741539;20485.283015094476;21362.46593835155;21381.358559243246;21396.14415423381;21429.892850535725;958.1951161024738;962.581277144441;982.6069572171117;987;991.2714155095998;988.4415305352453;1000.8999100090006;1007.9244056695741;1008.3093081239374;1008.3093081239374;1047.7799662070975;1048.6539441984128;1052.8210204265292;1053.4311471805254;1053.6522947427331;1042.2443728297;1042.5048102151493;1046.6284469013506;1047.0942634621074;1048.8951104889527;764.9694012239504;765.0819017179351;765.4412279036279;765.885117232414;766.0424469413233;19184.424467266006;19660.70508942367;19706.80293197064;19771;20484.79515204844;20219.696704549442;20254.392368228975;20351.796482035286;20914.790852091435;20918.5816283675;16155.838441615551;16275.837241627669;16468.505944821674;16495.175241237892;16846.578835529086;3767.1900541383734;3783.640554147379;3784.924301513954;3787.734858559902;3788.9431658525145;127055;127432;130928.99999999905;131365.34317328353;131398;15463.458778942682;15687.372505099896;15808;16975.405860794814;17510;30471.238219044702;31059.068227953197;31149.221269468446;31230.37539249248;31389;8882.289416846637;9133.223675987483;9140.588673509708;9151.908480915106;9153.771155721028;3521.3837578423672;3719.776813391205;4039.717219794586;4417.558244175621;4432.069265454291;5100.515451032146;5227.3465816772905;5233.947660523422;5234.2933703949475;5236.711980841091;1053.1785207538132;1078.6170909327234;1101.716500277168;1103.1395511501041;1103.674416047268;694.9027136200936;712.4620911212105;714.8891921752137;723.4537923867447;738.2875525118286;11091.944540277247;11920.827037297406;12181;12684.175528590613;12737.681557961125;675.2403546010738;686.2588404523103;688.2635579929428;700.8353037036293;707.3457052226772;5367.409584945653;5452.345718513804;5477.315335583052;5558.777648893999;5629.605927585029;106365.80902572935;107677.46161269143;107693;109166;110271;94998.52500737453;95538.5223073894;96859.51570242103;96876.51561742146;98191;84963;85070.57464712637;86259;86579.56710216534;89587;10208.948955255177;10293;10383.169346452114;12029.338386388832;12473.438695258918;39570.80214598909;41571;42312.36531451969;42366.36450453184;42470.36294455647;65104.67447662731;70230.64884675544;71057.64471177611;73012;77497.22502775125;18746.718799218295;18756.812431876053;18800.62398752045;18872.05639718036;18873;17784.288628454717;17855.821441785414;20568.074436650684;20666.689999649716;20782.064807083414;17412;18365;18501.629967400844;19596.510087247934;19772.11025503827;17595.208215630584;18061.277548898423;18298.176582053573;18928.14823332926;19058.809411905702;18131.456056318333;18222.817771822643;18358.357457488924;18453.63092738111;18552.628947420704;11967.341796201292;12185.329806860707;12727.618171454871;12817.423215955117;13057.21656700601;13188.472461101832;13209.207447553177;13536.917046636048;13655.658608534866;13754.793678095037 02:48:14 INFO - PID 31924 | __end_tp_report 02:48:14 INFO - PID 31924 | __start_cc_report 02:48:14 INFO - PID 31924 | _x_x_mozilla_cycle_collect,374 02:48:14 INFO - PID 31924 | __end_cc_report 02:48:14 INFO - PID 31924 | __startTimestamp1504259294874__endTimestamp 02:48:14 INFO - PID 31924 | 02:48:14 INFO - PID 31924 | ------- Summary: start ------- 02:48:14 INFO - PID 31924 | Number of tests: 6 02:48:14 INFO - PID 31924 | 02:48:14 INFO - PID 31924 | [#0] dojo.html Cycles:165 Average:6353.94 Median:6587.57 stddev:746.43 (11.3%) stddev-sans-first:709.90 02:48:14 INFO - PID 31924 | Values: 3325.3 3331.1 3337.2 3353.2 3379.9 6379.3 6480.0 6485.8 6501.2 6507.7 6292.7 6371.3 6379.5 6402.3 6410.1 5841.6 5842.2 5866.6 5912.6 6005.9 5889.9 5978.8 5984.9 6031.0 6055.5 5790.6 5872.3 5903.3 5975.8 6009.8 5811.7 5869.5 5958.2 5999.9 6005.2 5821.4 5865.2 5924.9 5944.8 5952.4 6182.8 6294.1 6304.6 6350.6 6474.4 6768.5 6815.1 6881.8 6975.8 7018.7 7008.9 7036.7 7051.1 7061.0 7067.3 6377.2 6378.6 6431.6 6474.9 6516.9 6883.9 6963.5 6974.0 7055.5 7174.3 6666.9 6725.1 6741.1 6773.5 6791.5 6875.4 6896.4 6912.2 6918.1 6954.3 6822.7 6843.6 6882.8 6913.9 6921.6 6865.4 6875.0 6945.5 6972.9 6973.3 5317.5 5427.6 5451.5 5490.7 5494.0 4671.1 4808.4 4816.7 4821.8 4847.1 5978.6 6070.1 6074.8 6085.8 6096.7 6853.8 6871.3 6957.2 6959.0 6994.1 5843.9 5932.9 5934.2 5937.6 6075.6 5915.8 5942.3 5943.1 5944.8 5945.5 6574.8 6670.8 6678.1 6712.1 6789.5 6945.4 6968.8 6988.1 6991.7 6996.3 6672.8 6759.8 6784.3 6795.5 6795.6 6843.9 6848.8 6928.9 6937.5 6958.8 6733.9 6932.5 6938.8 7032.2 7043.4 6548.7 6673.6 6755.9 6757.1 6765.6 6691.5 6756.3 6770.3 6778.6 6781.3 6504.3 6587.6 6603.9 6686.1 6702.1 6722.4 6743.2 6745.2 6787.5 6872.1 6359.5 6492.1 6495.8 6495.9 6655.3 02:48:14 INFO - PID 31924 | 02:48:14 INFO - PID 31924 | [#1] ext.html Cycles:180 Average:37894.79 Median:19297.18 stddev:54626.06 (283.1%) stddev-sans-first:54708.34 02:48:14 INFO - PID 31924 | Values: 814.7 831.5 845.2 876.7 877.8 40290.0 41038.0 41117.6 41173.4 41190.6 37483.4 37839.6 37843.2 37859.0 37892.6 6914.3 7014.2 7020.8 7020.9 7022.6 6973.4 7046.7 7047.5 7049.6 7049.6 7692.9 7932.3 7934.6 7937.2 7948.6 7806.8 7888.6 7894.1 7894.6 7897.4 7450.3 7668.9 7675.4 7739.9 7813.6 37035.3 37402.3 37420.4 37474.6 37529.0 3448.4 3490.1 3494.2 3495.5 3496.1 28310.7 28443.1 28452.4 28454.3 28551.7 5143.4 5145.3 5167.2 5179.6 5194.5 189830.0 190950.0 191099.0 192246.0 192481.0 46035.1 46435.8 46599.8 46600.8 46757.5 48161.3 48624.5 48655.3 48709.5 48759.3 14477.2 14538.9 14619.6 14624.7 14642.7 11798.5 11892.0 11905.8 11912.1 11922.5 13407.9 13599.6 13650.2 13716.5 13878.2 5497.0 5583.8 5602.9 5634.4 5657.0 1002.8 1008.0 1016.6 1020.2 1020.6 22427.7 28292.6 28761.6 28781.1 29413.9 1072.8 1081.6 1087.4 1097.9 1104.2 12461.9 12496.3 12589.4 12665.9 12740.9 223132.0 225970.0 226379.6 226851.0 226927.0 46960.8 47511.3 53819.2 54228.2 55916.0 1437.1 1443.1 1443.6 1450.2 1457.0 27067.0 27589.6 28084.2 28198.3 28391.1 44573.8 44741.1 44799.3 44933.8 45020.3 210231.0 214889.0 215088.0 215089.0 215106.0 35098.3 35106.5 35259.8 35879.8 35939.0 42082.0 43401.1 43581.8 44010.0 44012.1 31521.8 31835.7 32048.8 32368.2 32479.5 31560.4 31580.4 31582.0 32241.2 32603.8 34674.1 35965.3 36111.1 36114.6 36351.0 30001.5 30236.2 30252.0 30505.5 30580.0 23287.3 23457.4 23469.8 23857.6 23992.3 02:48:14 INFO - PID 31924 | 02:48:14 INFO - PID 31924 | [#2] jquery.html Cycles:180 Average:32900.78 Median:22889.71 stddev:74267.78 (324.5%) stddev-sans-first:74436.46 02:48:14 INFO - PID 31924 | Values: 572.6 584.6 586.7 588.4 589.0 22122.7 22308.7 22869.3 22898.7 22917.0 25663.9 26228.6 26262.9 26305.7 26325.9 6532.1 6609.8 6643.6 6673.6 6680.5 6089.1 6709.4 6713.1 6715.1 6716.9 6676.4 6676.8 6677.4 6681.0 6686.0 6680.6 6681.0 6682.5 6685.0 6685.1 6200.6 6204.8 6211.4 6213.0 6222.0 28301.3 29015.1 29952.0 30080.5 30260.0 2047.5 2060.7 2066.9 2074.7 2076.5 19532.2 20034.7 20065.9 20069.0 20069.9 2379.8 2442.5 2469.6 2500.4 2512.1 135698.3 140630.0 141307.0 141768.0 141863.3 14316.7 14348.9 14445.5 14906.9 15010.4 22341.6 22880.8 23512.9 23558.6 23569.0 9748.6 9864.1 9922.0 9968.9 10039.8 9027.0 9081.6 9098.7 9104.2 9165.2 9052.7 9187.4 9427.0 9439.0 9456.7 2736.8 2750.7 2756.7 2767.9 2846.6 983.2 988.3 991.3 998.5 1004.5 19959.7 20071.3 20277.8 20319.5 20740.8 1059.3 1064.7 1065.9 1076.9 1080.1 11263.6 11423.5 11448.8 11474.4 11517.7 427666.0 435178.0 438295.0 440067.0 443433.0 104179.0 105321.5 115329.4 116033.0 116834.4 1194.3 1213.1 1217.9 1226.4 1228.0 17736.0 17896.2 17944.0 17994.3 18114.8 46306.0 48043.0 52640.0 56307.4 57290.0 61007.4 61238.7 61431.1 61639.4 61944.7 14288.4 14638.2 14752.3 14769.9 14791.0 22274.4 22369.0 22405.0 22419.2 23122.0 18355.3 18457.1 18654.5 18685.0 18901.1 16148.9 16446.2 16474.3 16988.2 16988.8 18560.9 18927.1 19106.7 19154.9 19616.5 14368.6 14541.9 14558.9 14600.5 14650.6 17988.7 18116.7 18281.6 18296.3 18300.6 02:48:14 INFO - PID 31924 | 02:48:14 INFO - PID 31924 | [#3] mootools.html Cycles:180 Average:9412.56 Median:2690.81 stddev:6663.24 (247.6%) stddev-sans-first:6654.36 02:48:14 INFO - PID 31924 | Values: 1345.2 1395.4 1402.2 1406.7 1412.3 14661.1 14774.3 15286.1 15305.9 15325.6 15748.5 15985.3 16008.2 16013.8 16034.6 2732.7 2767.7 2768.1 2769.2 2771.7 2684.5 2716.8 2732.6 2733.3 2734.1 2687.3 2717.4 2718.6 2719.3 2719.5 2671.0 2697.3 2717.4 2720.7 2723.6 2681.4 2690.2 2691.4 2691.4 2699.6 14694.1 14968.7 15035.0 15046.6 15048.8 7834.5 8071.7 8096.0 8098.7 8116.0 5380.7 5505.4 5652.9 5658.9 5679.8 1912.6 1965.0 1969.5 2019.1 2025.4 26037.0 26181.2 26200.3 26208.2 26218.0 7036.6 7236.5 7237.2 7248.0 7270.0 16545.1 17132.4 17187.3 17196.3 17240.1 7708.3 7886.1 7886.2 7904.8 7908.1 6159.5 6353.3 7109.1 7293.7 7314.6 5280.2 5366.2 5384.4 5396.5 5415.9 1568.7 1574.1 1574.6 1585.1 1589.8 889.3 910.6 911.8 920.1 920.7 8489.8 8796.7 8802.6 8968.8 9081.0 985.4 994.6 995.5 996.1 1026.3 5033.7 5188.8 5238.4 5446.5 5850.6 21291.0 21704.5 21769.2 22028.0 22133.8 21047.3 21330.3 21467.0 21839.8 21889.5 11130.5 11527.4 11550.7 11794.6 11809.2 8815.0 8841.2 8861.6 8916.3 8951.3 18088.8 18374.0 18534.2 18548.6 19471.1 18248.4 18894.1 19079.5 19225.9 19604.4 10108.4 10426.4 10432.0 12268.4 12534.9 14307.9 14650.2 14686.9 14703.0 14801.6 11584.8 11653.2 11717.6 11752.9 11806.4 11042.7 11195.6 11257.2 11479.3 11646.9 9552.8 9644.0 9649.5 9649.5 9650.0 6970.2 7094.1 7183.4 7289.2 7349.4 7045.2 7060.8 7153.3 7202.1 7222.3 02:48:14 INFO - PID 31924 | 02:48:14 INFO - PID 31924 | [#4] prototype.html Cycles:180 Average:5600.78 Median:6218.74 stddev:1572.37 (25.3%) stddev-sans-first:1529.50 02:48:14 INFO - PID 31924 | Values: 502.2 502.9 529.3 529.7 529.9 6375.1 6378.5 6391.4 6394.9 6468.3 6117.1 6214.7 6267.3 6278.7 6321.1 5007.5 5073.9 5077.3 5087.3 5107.6 4842.9 5067.1 5113.7 5129.2 5138.7 4962.9 5007.7 5028.6 5041.3 5091.9 4870.9 4959.8 5026.5 5032.5 5073.9 4432.1 4474.6 4498.8 4514.9 4532.7 6103.4 6162.6 6180.8 6215.8 6241.6 5734.6 5762.0 5766.4 5778.5 5840.5 6501.8 6538.1 6564.5 6599.3 6617.6 4856.0 4880.7 4883.3 4890.4 4920.0 6897.2 6960.0 6999.4 7005.6 7039.4 5024.6 5027.1 5034.2 5038.1 5052.8 5120.2 5125.3 5126.3 5136.3 5151.2 5760.6 5767.5 5780.5 5781.8 5809.6 6549.2 6628.3 6648.7 6696.2 6732.1 1674.2 1694.0 1715.1 1724.4 1785.0 1148.1 1375.4 1441.8 1466.7 1482.6 5925.6 5928.5 5930.3 5956.8 5998.7 6700.8 6768.2 6776.3 6798.1 6802.3 5906.2 5916.2 5958.5 5959.2 5988.3 3608.2 3630.4 3631.6 3642.2 3649.0 6655.4 6690.5 6690.6 6703.1 6744.2 7105.5 7132.0 7134.4 7136.2 7154.6 6660.9 6690.9 6691.9 6695.4 6701.4 6756.2 6774.9 6778.3 6797.7 6825.7 6784.3 6818.8 6838.2 6851.4 6868.0 6605.1 6641.5 6655.3 6684.0 6740.2 4936.3 4978.1 5022.7 5049.9 5051.7 6766.7 6770.4 6811.9 6835.0 6962.2 6585.9 6640.9 6673.0 6679.9 6727.8 6622.2 6649.7 6651.6 6667.5 6671.8 6221.7 6359.9 6441.6 6492.6 6619.3 6576.0 6588.1 6590.9 6594.3 6624.7 6441.1 6444.8 6470.3 6493.0 6511.6 02:48:14 INFO - PID 31924 | 02:48:14 INFO - PID 31924 | [#5] yui.html Cycles:180 Average:23786.30 Median:20237.04 stddev:32343.17 (159.8%) stddev-sans-first:32388.26 02:48:14 INFO - PID 31924 | Values: 901.8 909.9 925.5 927.5 927.8 17413.7 18408.7 18409.4 18442.9 18461.7 20485.3 21362.5 21381.4 21396.1 21429.9 958.2 962.6 982.6 987.0 991.3 988.4 1000.9 1007.9 1008.3 1008.3 1047.8 1048.7 1052.8 1053.4 1053.7 1042.2 1042.5 1046.6 1047.1 1048.9 765.0 765.1 765.4 765.9 766.0 19184.4 19660.7 19706.8 19771.0 20484.8 20219.7 20254.4 20351.8 20914.8 20918.6 16155.8 16275.8 16468.5 16495.2 16846.6 3767.2 3783.6 3784.9 3787.7 3788.9 127055.0 127432.0 130929.0 131365.3 131398.0 15463.5 15687.4 15808.0 16975.4 17510.0 30471.2 31059.1 31149.2 31230.4 31389.0 8882.3 9133.2 9140.6 9151.9 9153.8 3521.4 3719.8 4039.7 4417.6 4432.1 5100.5 5227.3 5233.9 5234.3 5236.7 1053.2 1078.6 1101.7 1103.1 1103.7 694.9 712.5 714.9 723.5 738.3 11091.9 11920.8 12181.0 12684.2 12737.7 675.2 686.3 688.3 700.8 707.3 5367.4 5452.3 5477.3 5558.8 5629.6 106365.8 107677.5 107693.0 109166.0 110271.0 94998.5 95538.5 96859.5 96876.5 98191.0 84963.0 85070.6 86259.0 86579.6 89587.0 10208.9 10293.0 10383.2 12029.3 12473.4 39570.8 41571.0 42312.4 42366.4 42470.4 65104.7 70230.6 71057.6 73012.0 77497.2 18746.7 18756.8 18800.6 18872.1 18873.0 17784.3 17855.8 20568.1 20666.7 20782.1 17412.0 18365.0 18501.6 19596.5 19772.1 17595.2 18061.3 18298.2 18928.1 19058.8 18131.5 18222.8 18358.4 18453.6 18552.6 11967.3 12185.3 12727.6 12817.4 13057.2 13188.5 13209.2 13536.9 13655.7 13754.8 02:48:14 INFO - PID 31924 | -------- Summary: end -------- 02:48:14 INFO - PID 31924 | 02:48:15 INFO - TEST-INFO | 31924: exit 0 02:48:15 INFO - TEST-OK | dromaeo_css | took 1111832ms 02:48:15 INFO - TEST-START | kraken 02:48:15 INFO - Initialising browser for kraken test... 02:48:15 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:58960/getInfo.html -profile /tmp/tmpLUCSie/profile 02:48:15 INFO - TEST-INFO | started process 32300 (/builds/slave/test/build/application/firefox/firefox http://localhost:58960/getInfo.html) 02:48:20 INFO - TEST-INFO | 32300: exit 0 02:48:21 INFO - Browser initialized. 02:48:21 INFO - Running cycle 1/1 for kraken test... 02:48:21 INFO - Using env: {'DISPLAY': ':0', 02:48:21 INFO - 'HOME': '/home/cltbld', 02:48:21 INFO - 'JSGC_DISABLE_POISONING': '1', 02:48:21 INFO - 'LANG': 'en_US.UTF-8', 02:48:21 INFO - 'LANGUAGE': 'en_US:en', 02:48:21 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 02:48:21 INFO - 'LOGNAME': 'cltbld', 02:48:21 INFO - 'MAIL': '/var/mail/cltbld', 02:48:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:48:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:48:21 INFO - 'MOZ_CRASHREPORTER': '1', 02:48:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:48:21 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 02:48:21 INFO - 'MOZ_NO_REMOTE': '1', 02:48:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:48:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:48:21 INFO - 'NO_EM_RESTART': '1', 02:48:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:48:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:48:21 INFO - 'PWD': '/builds/slave/test', 02:48:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:48:21 INFO - 'RUST_BACKTRACE': 'full', 02:48:21 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 02:48:21 INFO - 'SHELL': '/bin/bash', 02:48:21 INFO - 'SHLVL': '1', 02:48:21 INFO - 'STYLO_FORCE_ENABLED': '1', 02:48:21 INFO - 'STYLO_THREADS': '1', 02:48:21 INFO - 'TERM': 'linux', 02:48:21 INFO - 'TMOUT': '86400', 02:48:21 INFO - 'USER': 'cltbld', 02:48:21 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1504257792.242887-1504466522', 02:48:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:48:21 INFO - '_': '/tools/buildbot/bin/python'} 02:48:21 INFO - TEST-INFO | started process 32481 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLUCSie/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 02:48:22 INFO - PID 32481 | 02:48:22 INFO - PID 32481 | (/builds/slave/test/build/application/firefox/firefox:32532): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 02:48:22 INFO - PID 32481 | 02:48:32 INFO - PID 32481 | RSS: Main: 172761088 02:48:32 INFO - PID 32481 | 02:48:34 INFO - PID 32481 | Cycle 1(1): loaded http://localhost:58960/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58960/tests/kraken/driver.html?testName=audio-beat-detection) 02:48:35 INFO - PID 32481 | RSS: Main: 177774592 02:48:35 INFO - PID 32481 | 02:48:38 INFO - PID 32481 | Cycle 1(1): loaded http://localhost:58960/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58960/tests/kraken/driver.html?testName=audio-dft) 02:48:39 INFO - PID 32481 | RSS: Main: 181723136 02:48:39 INFO - PID 32481 | 02:48:42 INFO - PID 32481 | Cycle 1(1): loaded http://localhost:58960/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58960/tests/kraken/driver.html?testName=audio-fft) 02:48:42 INFO - PID 32481 | RSS: Main: 174882816 02:48:42 INFO - PID 32481 | 02:48:44 INFO - PID 32481 | Cycle 1(1): loaded http://localhost:58960/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58960/tests/kraken/driver.html?testName=audio-oscillator) 02:48:45 INFO - PID 32481 | RSS: Main: 175419392 02:48:45 INFO - PID 32481 | 02:48:47 INFO - PID 32481 | Cycle 1(1): loaded http://localhost:58960/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58960/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:48:48 INFO - PID 32481 | RSS: Main: 175702016 02:48:48 INFO - PID 32481 | 02:48:51 INFO - PID 32481 | Cycle 1(1): loaded http://localhost:58960/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58960/tests/kraken/driver.html?testName=imaging-darkroom) 02:48:51 INFO - PID 32481 | RSS: Main: 176439296 02:48:51 INFO - PID 32481 | 02:48:55 INFO - PID 32481 | Cycle 1(1): loaded http://localhost:58960/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58960/tests/kraken/driver.html?testName=imaging-desaturate) 02:48:56 INFO - PID 32481 | RSS: Main: 176766976 02:48:56 INFO - PID 32481 | 02:48:58 INFO - PID 32481 | Cycle 1(1): loaded http://localhost:58960/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58960/tests/kraken/driver.html?testName=json-parse-financial) 02:48:59 INFO - PID 32481 | RSS: Main: 176934912 02:48:59 INFO - PID 32481 | 02:49:01 INFO - PID 32481 | Cycle 1(1): loaded http://localhost:58960/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58960/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:49:01 INFO - PID 32481 | RSS: Main: 177364992 02:49:01 INFO - PID 32481 | 02:49:03 INFO - PID 32481 | Cycle 1(1): loaded http://localhost:58960/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58960/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:49:04 INFO - PID 32481 | RSS: Main: 177352704 02:49:04 INFO - PID 32481 | 02:49:06 INFO - PID 32481 | Cycle 1(1): loaded http://localhost:58960/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58960/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:49:06 INFO - PID 32481 | RSS: Main: 174055424 02:49:06 INFO - PID 32481 | 02:49:09 INFO - PID 32481 | Cycle 1(1): loaded http://localhost:58960/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58960/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:49:10 INFO - PID 32481 | RSS: Main: 174682112 02:49:10 INFO - PID 32481 | 02:49:13 INFO - PID 32481 | Cycle 1(1): loaded http://localhost:58960/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58960/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:49:13 INFO - PID 32481 | RSS: Main: 174600192 02:49:13 INFO - PID 32481 | 02:49:15 INFO - PID 32481 | Cycle 1(1): loaded http://localhost:58960/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58960/tests/kraken/driver.html?testName=ai-astar) 02:49:15 INFO - PID 32481 | RSS: Main: 175853568 02:49:15 INFO - PID 32481 | 02:49:15 INFO - PID 32481 | __start_tp_report 02:49:15 INFO - PID 32481 | _x_x_mozilla_page_load 02:49:15 INFO - PID 32481 | _x_x_mozilla_page_load_details 02:49:15 INFO - PID 32481 | |i|pagename|runs| 02:49:15 INFO - PID 32481 | |0;ai-astar;97;107;99;107;107;108;111;99;107;109 02:49:15 INFO - PID 32481 | |1;audio-beat-detection;144;124;142;171;115;142;147;116;174;117 02:49:15 INFO - PID 32481 | |2;audio-dft;164;166;162;161;163;161;161;165;165;166 02:49:15 INFO - PID 32481 | |3;audio-fft;82;72;76;166;75;75;74;73;156;75 02:49:15 INFO - PID 32481 | |4;audio-oscillator;80;80;135;79;80;80;125;79;80;131 02:49:15 INFO - PID 32481 | |5;imaging-gaussian-blur;114;114;115;117;114;113;118;117;114;114 02:49:15 INFO - PID 32481 | |6;imaging-darkroom;148;148;148;149;148;148;147;149;148;149 02:49:15 INFO - PID 32481 | |7;imaging-desaturate;94;94;94;93;94;94;100;99;94;94 02:49:15 INFO - PID 32481 | |8;json-parse-financial;54;59;54;54;53;54;54;53;55;55 02:49:15 INFO - PID 32481 | |9;json-stringify-tinderbox;38;38;39;37;38;39;38;38;39;38 02:49:15 INFO - PID 32481 | |10;stanford-crypto-aes;76;76;75;74;76;82;75;75;74;80 02:49:15 INFO - PID 32481 | |11;stanford-crypto-ccm;118;126;115;121;118;126;123;125;121;157 02:49:15 INFO - PID 32481 | |12;stanford-crypto-pbkdf2;172;172;170;172;174;170;173;179;187;175 02:49:15 INFO - PID 32481 | |13;stanford-crypto-sha256-iterative;68;73;64;66;66;65;68;67;65;66 02:49:15 INFO - PID 32481 | __end_tp_report 02:49:15 INFO - PID 32481 | __start_cc_report 02:49:15 INFO - PID 32481 | _x_x_mozilla_cycle_collect,952 02:49:15 INFO - PID 32481 | __end_cc_report 02:49:15 INFO - PID 32481 | __startTimestamp1504259355900__endTimestamp 02:49:15 INFO - PID 32481 | 02:49:15 INFO - PID 32481 | ------- Summary: start ------- 02:49:15 INFO - PID 32481 | Number of tests: 14 02:49:15 INFO - PID 32481 | 02:49:15 INFO - PID 32481 | [#0] ai-astar Cycles:10 Average:105.10 Median:110.00 stddev:4.86 (4.4%) stddev-sans-first:4.18 02:49:15 INFO - PID 32481 | Values: 97.0 107.0 99.0 107.0 107.0 108.0 111.0 99.0 107.0 109.0 02:49:15 INFO - PID 32481 | 02:49:15 INFO - PID 32481 | [#1] audio-beat-detection Cycles:10 Average:139.20 Median:143.00 stddev:21.52 (15.1%) stddev-sans-first:22.76 02:49:15 INFO - PID 32481 | Values: 144.0 124.0 142.0 171.0 115.0 142.0 147.0 116.0 174.0 117.0 02:49:15 INFO - PID 32481 | 02:49:15 INFO - PID 32481 | [#2] audio-dft Cycles:10 Average:163.40 Median:164.50 stddev:2.07 (1.3%) stddev-sans-first:2.18 02:49:15 INFO - PID 32481 | Values: 164.0 166.0 162.0 161.0 163.0 161.0 161.0 165.0 165.0 166.0 02:49:15 INFO - PID 32481 | 02:49:15 INFO - PID 32481 | [#3] audio-fft Cycles:10 Average:92.40 Median:75.00 stddev:36.33 (48.4%) stddev-sans-first:38.34 02:49:15 INFO - PID 32481 | Values: 82.0 72.0 76.0 166.0 75.0 75.0 74.0 73.0 156.0 75.0 02:49:15 INFO - PID 32481 | 02:49:15 INFO - PID 32481 | [#4] audio-oscillator Cycles:10 Average:94.90 Median:80.00 stddev:24.57 (30.7%) stddev-sans-first:25.46 02:49:15 INFO - PID 32481 | Values: 80.0 80.0 135.0 79.0 80.0 80.0 125.0 79.0 80.0 131.0 02:49:15 INFO - PID 32481 | 02:49:15 INFO - PID 32481 | [#5] imaging-gaussian-blur Cycles:10 Average:115.00 Median:114.50 stddev:1.70 (1.5%) stddev-sans-first:1.76 02:49:15 INFO - PID 32481 | Values: 114.0 114.0 115.0 117.0 114.0 113.0 118.0 117.0 114.0 114.0 02:49:15 INFO - PID 32481 | 02:49:15 INFO - PID 32481 | [#6] imaging-darkroom Cycles:10 Average:148.20 Median:148.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 02:49:15 INFO - PID 32481 | Values: 148.0 148.0 148.0 149.0 148.0 148.0 147.0 149.0 148.0 149.0 02:49:15 INFO - PID 32481 | 02:49:15 INFO - PID 32481 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:94.00 stddev:2.40 (2.6%) stddev-sans-first:2.52 02:49:15 INFO - PID 32481 | Values: 94.0 94.0 94.0 93.0 94.0 94.0 100.0 99.0 94.0 94.0 02:49:15 INFO - PID 32481 | 02:49:15 INFO - PID 32481 | [#8] json-parse-financial Cycles:10 Average:54.50 Median:54.00 stddev:1.72 (3.2%) stddev-sans-first:1.81 02:49:15 INFO - PID 32481 | Values: 54.0 59.0 54.0 54.0 53.0 54.0 54.0 53.0 55.0 55.0 02:49:15 INFO - PID 32481 | 02:49:15 INFO - PID 32481 | [#9] json-stringify-tinderbox Cycles:10 Average:38.20 Median:38.00 stddev:0.63 (1.7%) stddev-sans-first:0.67 02:49:15 INFO - PID 32481 | Values: 38.0 38.0 39.0 37.0 38.0 39.0 38.0 38.0 39.0 38.0 02:49:15 INFO - PID 32481 | 02:49:15 INFO - PID 32481 | [#10] stanford-crypto-aes Cycles:10 Average:76.30 Median:76.00 stddev:2.63 (3.5%) stddev-sans-first:2.78 02:49:15 INFO - PID 32481 | Values: 76.0 76.0 75.0 74.0 76.0 82.0 75.0 75.0 74.0 80.0 02:49:15 INFO - PID 32481 | 02:49:15 INFO - PID 32481 | [#11] stanford-crypto-ccm Cycles:10 Average:125.00 Median:124.00 stddev:11.83 (9.5%) stddev-sans-first:12.28 02:49:15 INFO - PID 32481 | Values: 118.0 126.0 115.0 121.0 118.0 126.0 123.0 125.0 121.0 157.0 02:49:15 INFO - PID 32481 | 02:49:15 INFO - PID 32481 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.40 Median:173.50 stddev:5.15 (3.0%) stddev-sans-first:5.39 02:49:15 INFO - PID 32481 | Values: 172.0 172.0 170.0 172.0 174.0 170.0 173.0 179.0 187.0 175.0 02:49:15 INFO - PID 32481 | 02:49:15 INFO - PID 32481 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.80 Median:66.50 stddev:2.53 (3.8%) stddev-sans-first:2.65 02:49:15 INFO - PID 32481 | Values: 68.0 73.0 64.0 66.0 66.0 65.0 68.0 67.0 65.0 66.0 02:49:15 INFO - PID 32481 | -------- Summary: end -------- 02:49:15 INFO - PID 32481 | 02:49:16 INFO - PID 32481 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 02:49:16 INFO - TEST-INFO | 32481: exit 0 02:49:16 INFO - TEST-OK | kraken | took 61042ms 02:49:16 INFO - SUITE-END | took 1172s 02:49:17 INFO - Completed test suite (00:19:33) 02:49:17 INFO - javascript benchmark 02:49:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8613.936699436808, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3325.3183097465017, 3331.133905184651, 3337.1657085728566, 3353.195233144046, 3379.915502112445, 6379.330170332118, 6480.027995800627, 6485.80542583722, 6501.2198536175665, 6507.739690412378, 6292.653904035276, 6371.299157092716, 6379.457746091576, 6402.263739669927, 6410.07053977173, 5841.649501029951, 5842.240508733869, 5866.589338746289, 5912.645241285515, 6005.909911351333, 5889.941100588982, 5978.790742324019, 5984.8503787405225, 6030.969845150768, 6055.545834062441, 5790.594658373915, 5872.324682661484, 5903.26209223847, 5975.820725378224, 6009.819705408845, 5811.651300921916, 5869.471747542699, 5958.225430694026, 5999.880002399971, 6005.219321488223, 5821.446962538552, 5865.178874957509, 5924.881502369972, 5944.762209511614, 5952.375000624958, 6182.84542886427, 6294.0873573332065, 6304.590201636879, 6350.587211831216, 6474.449671777905, 6768.52620316578, 6815.148106486689, 6881.793546193572, 6975.79072627822, 7018.719251229996, 7008.929910700878, 7036.698210831013, 7051.1186101737285, 7061.046758687563, 7067.293270672943, 6377.1709677742065, 6378.585391949508, 6431.646259455728, 6474.935250647527, 6516.8696626067685, 6883.862322753517, 6963.512554121265, 6973.965130174317, 7055.506114572034, 7174.3184397482155, 6666.866662666719, 6725.125733654593, 6741.089952856301, 6773.525853190229, 6791.456683465311, 6875.3812156906115, 6896.379325860697, 6912.239653638096, 6918.135233095863, 6954.269801670853, 6822.692978815965, 6843.623600702009, 6882.7591034313555, 6913.930860691429, 6921.550099243533, 6865.41643960269, 6874.9656251719425, 6945.548539345027, 6972.895406568906, 6973.267806880306, 5317.494837990384, 5427.592930530226, 5451.454854514596, 5490.69801160932, 5493.972530137324, 4671.065786842645, 4808.374911261583, 4816.735079570657, 4821.807127714922, 4847.127517046895, 5978.55160862937, 6070.119832624333, 6074.787382441594, 6085.756569737249, 6096.69516524172, 6853.828654283683, 6871.278515755874, 6957.234704182536, 6959.0, 6994.055802466602, 5843.883122337615, 5932.940670593239, 5934.1692163097205, 5937.584369094208, 6075.5747097703625, 5915.792947246826, 5942.346341902387, 5943.078822782476, 5944.762209511658, 5945.464908158287, 6574.8356291093105, 6670.799876003728, 6678.098456708282, 6712.060311556386, 6789.524733268624, 6945.409640180439, 6968.756093536803, 6988.05661235747, 6991.720331186695, 6996.335348141918, 6672.83317917056, 6759.763408280686, 6784.253732089367, 6795.490338224752, 6795.592264464148, 6843.8973415399805, 6848.760293389708, 6928.926016467455, 6937.514373993772, 6958.79123626292, 6733.898991515033, 6932.480063995321, 6938.757143499954, 7032.156141263081, 7043.36609705129, 6548.705308261045, 6673.632950187592, 6755.864882702417, 6757.054012438164, 6765.594064355958, 6691.498137639794, 6756.256811750605, 6770.289119642367, 6778.627175505395, 6781.254062053072, 6504.252011018793, 6587.571807832475, 6603.867922641616, 6686.06395104676, 6702.0952171458175, 6722.361375669304, 6743.15710536183, 6745.156855393076, 6787.490938179558, 6872.10662613877, 6359.491240700825, 6492.091107244895, 6495.805125846232, 6495.9025614614875, 6655.334466553306], "value": 6299.189147945445, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [814.6822739131736, 831.488634489789, 845.2477295207266, 876.7150676030292, 877.7717793373721, 40290.0, 41038.0, 41117.58882411171, 41173.38239926396, 41190.58809411905, 37483.4377484338, 37839.621603783955, 37843.24313513735, 37859.0, 37892.621073789254, 6914.343137401964, 7014.22843487216, 7020.754273600425, 7020.929790702105, 7022.613756243404, 6973.407260382874, 7046.682899269519, 7047.471439642048, 7049.612271325075, 7049.647517624124, 7692.884606730903, 7932.325752311061, 7934.642941067666, 7937.206279372074, 7948.642311095986, 7806.76579702607, 7888.64501097452, 7894.131645518988, 7894.565798881059, 7897.447178697494, 7450.254974502561, 7668.923310766932, 7675.424343174229, 7739.883901741478, 7813.570253636048, 37035.25929481422, 37402.251954960746, 37420.43869341962, 37474.62525374766, 37529.0, 3448.3620530201993, 3490.092575930251, 3494.1614012637037, 3495.4931534927546, 3496.0560648624983, 28310.716892831013, 28443.146705598865, 28452.430951380855, 28454.288642784097, 28551.71448285532, 5143.3570803649545, 5145.279660847485, 5167.224916262553, 5179.637425380225, 5194.480551944813, 189830.0, 190950.0, 191099.0, 192246.0, 192481.03759481254, 46035.079298414516, 46435.76782116135, 46599.767001165455, 46600.76699616548, 46757.532424675315, 48161.277580836315, 48624.5137548627, 48655.270170947464, 48709.512904871204, 48759.26861097087, 14477.203753793432, 14538.85461145375, 14619.634509137357, 14624.707505849823, 14642.682158605563, 11798.46906889192, 11892.0, 11905.821412678817, 11912.10659200546, 11922.523099076114, 13407.932960335136, 13599.59201223965, 13650.249236292098, 13716.519921802688, 13878.236696981561, 5497.0, 5583.804566840141, 5602.8709090546145, 5634.4365563443325, 5656.971715141398, 1002.7894142230214, 1007.9748006299901, 1016.6035246253966, 1020.168562621476, 1020.5509575786779, 22427.663585046237, 28292.575611365846, 28761.56857647137, 28781.136565903056, 29413.85293073521, 1072.8390741388698, 1081.5781845080426, 1087.396494945313, 1097.8956999085074, 1104.2491106047964, 12461.875381246253, 12496.312702801251, 12589.370531473389, 12665.936670316589, 12740.936295318465, 223132.0, 225970.0, 226379.60430593553, 226851.0, 226927.0, 46960.765196174485, 47511.287330690066, 53819.19271210935, 54228.18657720138, 55916.0, 1437.1448987852211, 1443.1124858212333, 1443.6390902274431, 1450.2023886862394, 1457.0383546859023, 27067.0, 27589.586156208075, 28084.157475275773, 28198.295042624097, 28391.148265552067, 44573.777131115436, 44741.10517789691, 44799.328010079225, 44933.775331123135, 45020.32469513026, 210231.0, 214889.0, 215088.0, 215089.0, 215106.0, 35098.298034038664, 35106.473402899486, 35259.82370088133, 35879.82060089683, 35939.0, 42082.0, 43401.13197735965, 43581.78209109061, 44010.0, 44012.11975760531, 31521.8423907879, 31835.681643184198, 32048.839755801073, 32368.190795229366, 32479.512807308383, 31560.36879262448, 31580.368392632477, 31582.0, 32241.193970150933, 32603.836980814944, 34674.133146671535, 35965.28069438649, 36111.097222569646, 36114.63885361218, 36351.0, 30001.549976750804, 30236.24409389783, 30252.0, 30505.54241686332, 30580.0, 23287.301380958597, 23457.41356466102, 23469.765302346757, 23857.64213536833, 23992.28023159308], "value": 17084.27325180076, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [572.5591294703077, 584.5907864494854, 586.6831910768185, 588.4145275450927, 588.9646621202728, 22122.668159977595, 22308.665370019422, 22869.313920582368, 22898.65652015221, 22916.96873640688, 25663.871680641572, 26228.60657090145, 26262.868685656544, 26305.736942630567, 26325.86837065817, 6532.052852336408, 6609.768658096968, 6643.568168069084, 6673.566218195826, 6680.465562754992, 6089.1170780236835, 6709.362610552014, 6713.127293451869, 6715.093462382565, 6716.865662686743, 6676.399124078856, 6676.833079173035, 6677.399034086931, 6681.0, 6685.966570167142, 6680.632565208912, 6681.031250468704, 6682.465402767767, 6685.0, 6685.130932978706, 6200.596961197552, 6204.844878878019, 6211.409916058013, 6213.0, 6221.9688901555655, 28301.292467688472, 29015.12954611365, 29952.0, 30080.548791768142, 30260.0, 2047.5290683142812, 2060.6702927531674, 2066.8553201275918, 2074.6991686205565, 2076.532780124475, 19532.21871125153, 20034.69947950782, 20065.8996705017, 20069.0, 20069.899650501655, 2379.821513386503, 2442.5481285962155, 2469.592517234654, 2500.3874050857476, 2512.0705339024676, 135698.3215083928, 140629.99999999898, 141307.0, 141768.0, 141863.29068354593, 14316.713665726627, 14348.928255358656, 14445.49440769568, 14906.850931490762, 15010.399584016517, 22341.553168936854, 22880.771192287863, 23512.882435588053, 23558.646620300708, 23569.0, 9748.610055597697, 9864.112229899343, 9922.0, 9968.850467242997, 10039.799204015879, 9027.0, 9081.59132839024, 9098.681546145854, 9104.180623743894, 9165.17513423795, 9052.683156089637, 9187.356885017985, 9427.0, 9439.0, 9456.669016584387, 2736.835789852615, 2750.7111753265617, 2756.682981457118, 2767.8616069196864, 2846.558783388578, 983.2281658897731, 988.2785566536469, 991.3258983890879, 998.4857798233916, 1004.4626125023224, 19959.700604490943, 20071.29750458727, 20277.790254882162, 20319.4920126998, 20740.792592073885, 1059.3061544688242, 1064.669952314785, 1065.8720953485476, 1076.9192310576739, 1080.1143062688677, 11263.605773797877, 11423.485943132579, 11448.828267575993, 11474.368909710047, 11517.654470365902, 427666.0, 435178.0, 438295.0, 440067.0, 443433.0, 104179.0, 105321.47339263254, 115329.4233528827, 116033.0, 116834.41582792031, 1194.3073017649742, 1213.1083653514659, 1217.945192466341, 1226.3561630144245, 1227.9631611051682, 17736.024518651597, 17896.194671239566, 17944.0, 17994.280228790703, 18114.818851811313, 46306.0, 48043.0, 52640.0, 56307.43692563186, 57290.0, 61007.38992610195, 61238.69380653068, 61431.078533821135, 61639.38360616336, 61944.69027654833, 14288.42846286137, 14638.194899280641, 14752.262386880828, 14769.92615036918, 14791.038582492103, 22274.443138921008, 22368.993395297577, 22405.0, 22419.215327463462, 23122.0, 18355.265789368277, 18457.077146142907, 18654.53363665919, 18685.0, 18901.149448275137, 16148.919255403647, 16446.177691115157, 16474.341026358812, 16988.235529400958, 16988.83011169922, 18560.907195463937, 18927.148278327233, 19106.713399298744, 19154.904225479342, 19616.509587260432, 14368.568942931728, 14541.927290363481, 14558.927205363905, 14600.488982885548, 14650.560483185522, 17988.730169047736, 18116.72824907654, 18281.634367312847, 18296.26814927388, 18300.633987320445], "value": 11944.162363446976, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1345.1659970818093, 1395.3930040432413, 1402.2427888939974, 1406.6905280838212, 1412.286795168439, 14661.120332780027, 14774.335154918028, 15286.082835029893, 15305.92347038266, 15325.616859578517, 15748.527544173694, 15985.280662370193, 16008.199590020511, 16013.759793603076, 16034.5991350216, 2732.6857411397696, 2767.723227677236, 2768.0865314446182, 2769.2246171072034, 2771.7089705580925, 2684.516786978343, 2716.8234064785725, 2732.631094802206, 2733.3439974406097, 2734.111413790526, 2687.3013016615623, 2717.402171522262, 2718.6465759451344, 2719.2657982344754, 2719.5104881121392, 2670.986645066772, 2697.3121853927223, 2717.388587567801, 2720.7415295546894, 2723.6459260296133, 2681.423493948791, 2690.219836247486, 2691.3944362518473, 2691.421344410961, 2699.6490456240563, 14694.11835289886, 14968.700625987529, 15035.0, 15046.623834404118, 15048.774268385983, 7834.529928204326, 8071.717489887885, 8095.95952020242, 8098.676052957874, 8115.95942020292, 5380.677159370451, 5505.394406615269, 5652.858678533028, 5658.886822263532, 5679.829605111853, 1912.6365990461768, 1964.9508762280914, 1969.4879331373802, 2019.0813180003063, 2025.4025062606565, 26037.0, 26181.214563563124, 26200.34499137518, 26208.213753587424, 26218.0, 7036.577805331696, 7236.457265705093, 7237.203907570163, 7248.021517095125, 7269.963650181715, 16545.090020049014, 17132.40036598738, 17187.31250749956, 17196.31214751421, 17240.051797151027, 7708.267714567107, 7886.0536735592095, 7886.211378862068, 7904.802379940433, 7908.090569584571, 6159.5072394208355, 6353.332900045429, 7109.146902371748, 7293.744718934812, 7314.634268286564, 5280.155175171954, 5366.195070739421, 5384.380796208407, 5396.514313711707, 5415.945840541623, 1568.7254730422358, 1574.087029522874, 1574.6220906982242, 1585.0885740699143, 1589.7933268675, 889.3152272749946, 910.6402970826487, 911.7994041310901, 920.1258804136097, 920.7007722490217, 8489.787755306043, 8796.692115775917, 8802.647894084164, 8968.820623587622, 9081.0, 985.4038306824268, 994.6170724271063, 995.4624502768569, 996.1234113979797, 1026.2508368890603, 5033.672811267256, 5188.844334669966, 5238.397584277857, 5446.482584154499, 5850.5612079094235, 21291.0, 21704.457388565097, 21769.23807666724, 22028.0, 22133.77866221317, 21047.263345782823, 21330.253441129487, 21467.0, 21839.781602183775, 21889.452763681034, 11130.49912753928, 11527.365994870363, 11550.711232219093, 11794.646160615195, 11809.173357864867, 8814.955925220333, 8841.204291613785, 8861.60122794463, 8916.28669706409, 8951.328650351119, 18088.819111808712, 18374.0, 18534.16596253145, 18548.629027419105, 19471.123799429344, 18248.36130735418, 18894.05529723481, 19079.52301192481, 19225.903870481117, 19604.411867643994, 10108.444035578113, 10426.374417534973, 10432.0, 12268.38658067111, 12534.937325313314, 14307.928460358047, 14650.19423931694, 14686.926565367105, 14703.044302120328, 14801.555953321418, 11584.826227606423, 11653.184277100521, 11717.589884354007, 11752.882471175179, 11806.409679515818, 11042.723931901766, 11195.608153714908, 11257.211995160587, 11479.311241325548, 11646.94176529112, 9552.808943821223, 9643.951780241054, 9649.517524123625, 9649.517524123907, 9649.951750241486, 6970.198427180736, 7094.148702155774, 7183.353498185188, 7289.161746399232, 7349.39096620692, 7045.189803172496, 7060.752873649603, 7153.284671533013, 7202.099737532809, 7222.313880181375], "value": 6760.44319412028, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [502.2391077517559, 502.9270755740418, 529.3145376737125, 529.6901312732051, 529.8993191293654, 6375.075614035962, 6378.521610879191, 6391.424771770539, 6394.936050639492, 6468.288488266287, 6117.143599896018, 6214.725981173849, 6267.279262884758, 6278.717457714402, 6321.1150438938585, 5007.52428519291, 5073.923891141636, 5077.263796749469, 5087.287779710843, 5107.61692873036, 4842.9031419371595, 5067.113255180348, 5113.678941294619, 5129.204973229137, 5138.7173705446185, 4962.900741985158, 5007.724575148365, 5028.622853286, 5041.294218809369, 5091.898162036757, 4870.9269360959615, 4959.776810043557, 5026.472220416877, 5032.521910418504, 5073.898522029557, 4432.113577284524, 4474.574915383034, 4498.752568608726, 4514.909701805946, 4532.682712210147, 6103.375548514137, 6162.568620196588, 6180.752769889199, 6215.782447614357, 6241.625502469867, 5734.627249228828, 5762.0, 5766.423357664242, 5778.451047150556, 5840.474357307863, 6501.772437964649, 6538.0519824625635, 6564.522982328986, 6599.307072757388, 6617.602943823338, 4856.0, 4880.731559764212, 4883.289774175331, 4890.388701412324, 4919.975400123012, 6897.241303456616, 6959.965200174018, 6999.440044796455, 7005.602282892793, 7039.436845052383, 5024.62315326352, 5027.069992051483, 5034.244863270539, 5038.143515602356, 5052.837847295143, 5120.206368012964, 5125.333706618116, 5126.282320475137, 5136.2552429898205, 5151.2273159026445, 5760.6255593386295, 5767.538596912237, 5780.450857168562, 5781.797637082682, 5809.564282678816, 6549.214094308618, 6628.270890202074, 6648.66756662165, 6696.1629796275465, 6732.124823772878, 1674.1796519705256, 1693.9576510587087, 1715.0995727243048, 1724.3619860651388, 1784.9553761156076, 1148.0941636882735, 1375.3810785146725, 1441.7621092519616, 1466.7213229486363, 1482.5774654223494, 5925.585209035326, 5928.525717942554, 5930.28836539618, 5956.821295361146, 5998.730057147439, 6700.845475805032, 6768.2216545097945, 6776.28848970851, 6798.116244888231, 6802.319768023257, 5906.202662640574, 5916.171735956969, 5958.523318134538, 5959.22530071088, 5988.341282458926, 3608.188157664557, 3630.4009838376373, 3631.6005239423644, 3642.1805093853673, 3648.974818975494, 6655.43428808547, 6690.498212634072, 6690.565113267622, 6703.128593282842, 6744.156980377453, 7105.538140020882, 7132.0, 7134.429245660334, 7136.181208422644, 7154.642267886585, 6660.933390666129, 6690.933090669128, 6691.933080669229, 6695.397414232742, 6701.363370479797, 6756.155480564929, 6774.8645027100165, 6778.3221677831825, 6797.66011699423, 6825.726970921306, 6784.3215678433735, 6818.761343352959, 6838.2136054352395, 6851.383375496229, 6867.9999999998, 6605.141331626858, 6641.483791022151, 6655.267920528641, 6683.9639855822425, 6740.191791637709, 4936.284238785428, 4978.103941290457, 5022.673526220784, 5049.924251136163, 5051.74741262928, 6766.733144396485, 6770.424513916372, 6811.863762724817, 6834.965825170842, 6962.199347075151, 6585.916731626422, 6640.933590664032, 6673.032410300576, 6679.9332006679315, 6727.82263103945, 6622.205335359788, 6649.736550055474, 6651.600903945779, 6667.499937504804, 6671.833204169935, 6221.688915554114, 6359.904601431075, 6441.613503189824, 6492.577982431127, 6619.3380661933425, 6575.96712016456, 6588.110605068255, 6590.868182636224, 6594.340565943367, 6624.701888415129, 6441.1304473895425, 6444.806655800334, 6470.255920569195, 6492.961545762486, 6511.641859697573], "value": 5143.926131452385, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [901.7565257380508, 909.8908131024278, 925.4817302310704, 927.5408672707011, 927.795884905321, 17413.738793918103, 18408.723869141973, 18409.355672551465, 18442.907785461055, 18461.7230741539, 20485.283015094476, 21362.46593835155, 21381.358559243246, 21396.14415423381, 21429.892850535725, 958.1951161024738, 962.581277144441, 982.6069572171117, 987.0, 991.2714155095998, 988.4415305352453, 1000.8999100090006, 1007.9244056695741, 1008.3093081239374, 1008.3093081239374, 1047.7799662070975, 1048.6539441984128, 1052.8210204265292, 1053.4311471805254, 1053.6522947427331, 1042.2443728297, 1042.5048102151493, 1046.6284469013506, 1047.0942634621074, 1048.8951104889527, 764.9694012239504, 765.0819017179351, 765.4412279036279, 765.885117232414, 766.0424469413233, 19184.424467266006, 19660.70508942367, 19706.80293197064, 19771.0, 20484.79515204844, 20219.696704549442, 20254.392368228975, 20351.796482035286, 20914.790852091435, 20918.5816283675, 16155.838441615551, 16275.837241627669, 16468.505944821674, 16495.175241237892, 16846.578835529086, 3767.1900541383734, 3783.640554147379, 3784.924301513954, 3787.734858559902, 3788.9431658525145, 127055.0, 127432.0, 130928.99999999905, 131365.34317328353, 131398.0, 15463.458778942682, 15687.372505099896, 15808.0, 16975.405860794814, 17510.0, 30471.238219044702, 31059.068227953197, 31149.221269468446, 31230.37539249248, 31389.0, 8882.289416846637, 9133.223675987483, 9140.588673509708, 9151.908480915106, 9153.771155721028, 3521.3837578423672, 3719.776813391205, 4039.717219794586, 4417.558244175621, 4432.069265454291, 5100.515451032146, 5227.3465816772905, 5233.947660523422, 5234.2933703949475, 5236.711980841091, 1053.1785207538132, 1078.6170909327234, 1101.716500277168, 1103.1395511501041, 1103.674416047268, 694.9027136200936, 712.4620911212105, 714.8891921752137, 723.4537923867447, 738.2875525118286, 11091.944540277247, 11920.827037297406, 12181.0, 12684.175528590613, 12737.681557961125, 675.2403546010738, 686.2588404523103, 688.2635579929428, 700.8353037036293, 707.3457052226772, 5367.409584945653, 5452.345718513804, 5477.315335583052, 5558.777648893999, 5629.605927585029, 106365.80902572935, 107677.46161269143, 107693.0, 109166.0, 110271.0, 94998.52500737453, 95538.5223073894, 96859.51570242103, 96876.51561742146, 98191.0, 84963.0, 85070.57464712637, 86259.0, 86579.56710216534, 89587.0, 10208.948955255177, 10293.0, 10383.169346452114, 12029.338386388832, 12473.438695258918, 39570.80214598909, 41571.0, 42312.36531451969, 42366.36450453184, 42470.36294455647, 65104.67447662731, 70230.64884675544, 71057.64471177611, 73012.0, 77497.22502775125, 18746.718799218295, 18756.812431876053, 18800.62398752045, 18872.05639718036, 18873.0, 17784.288628454717, 17855.821441785414, 20568.074436650684, 20666.689999649716, 20782.064807083414, 17412.0, 18365.0, 18501.629967400844, 19596.510087247934, 19772.11025503827, 17595.208215630584, 18061.277548898423, 18298.176582053573, 18928.14823332926, 19058.809411905702, 18131.456056318333, 18222.817771822643, 18358.357457488924, 18453.63092738111, 18552.628947420704, 11967.341796201292, 12185.329806860707, 12727.618171454871, 12817.423215955117, 13057.21656700601, 13188.472461101832, 13209.207447553177, 13536.917046636048, 13655.658608534866, 13754.793678095037], "value": 9138.58386338908, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1488.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 107.0, 99.0, 107.0, 107.0, 108.0, 111.0, 99.0, 107.0, 109.0], "value": 105.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 124.0, 142.0, 171.0, 115.0, 142.0, 147.0, 116.0, 174.0, 117.0], "value": 139.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 166.0, 162.0, 161.0, 163.0, 161.0, 161.0, 165.0, 165.0, 166.0], "value": 163.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 72.0, 76.0, 166.0, 75.0, 75.0, 74.0, 73.0, 156.0, 75.0], "value": 92.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 80.0, 135.0, 79.0, 80.0, 80.0, 125.0, 79.0, 80.0, 131.0], "value": 94.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 115.0, 117.0, 114.0, 113.0, 118.0, 117.0, 114.0, 114.0], "value": 115.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 148.0, 149.0, 148.0, 148.0, 147.0, 149.0, 148.0, 149.0], "value": 148.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 93.0, 94.0, 94.0, 100.0, 99.0, 94.0, 94.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 59.0, 54.0, 54.0, 53.0, 54.0, 54.0, 53.0, 55.0, 55.0], "value": 54.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 38.0, 39.0, 37.0, 38.0, 39.0, 38.0, 38.0, 39.0, 38.0], "value": 38.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 75.0, 74.0, 76.0, 82.0, 75.0, 75.0, 74.0, 80.0], "value": 76.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 126.0, 115.0, 121.0, 118.0, 126.0, 123.0, 125.0, 121.0, 157.0], "value": 125.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 172.0, 170.0, 172.0, 174.0, 170.0, 173.0, 179.0, 187.0, 175.0], "value": 174.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 73.0, 64.0, 66.0, 66.0, 65.0, 68.0, 67.0, 65.0, 66.0], "value": 66.8, "unit": "score"}]}]} 02:49:17 INFO - Return code: 0 02:49:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:49:17 INFO - # TBPL SUCCESS # 02:49:17 INFO - Running post-action listener: _package_coverage_data 02:49:17 INFO - Running post-action listener: _resource_record_post_action 02:49:17 INFO - [mozharness: 2017-09-01 09:49:17.189119Z] Finished run-tests step (success) 02:49:17 INFO - Running post-run listener: _resource_record_post_run 02:49:17 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 4096; Write bytes: 395886592; Read time: 12; Write time: 348812 02:49:17 INFO - TinderboxPrint: CPU usage
13.1% 02:49:17 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 02:49:17 INFO - TinderboxPrint: I/O write bytes / time
395,886,592 / 348,812 02:49:17 INFO - TinderboxPrint: CPU idle
8,243.0 (86.9%) 02:49:17 INFO - TinderboxPrint: CPU user
1,194.0 (12.6%) 02:49:17 INFO - TinderboxPrint: Swap in / out
0 / 0 02:49:17 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 109518848; Read time: 0; Write time: 143284 02:49:17 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 02:49:17 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 4096; Write bytes: 286367744; Read time: 12; Write time: 205528 02:49:17 INFO - Running post-run listener: _upload_blobber_files 02:49:17 INFO - Blob upload gear active. 02:49:17 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:49:17 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 02:49:17 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'] 02:49:17 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 02:49:18 INFO - (blobuploader) - INFO - Open directory for files ... 02:49:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 02:49:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:49:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:49:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 02:49:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:49:19 INFO - (blobuploader) - INFO - Done attempting. 02:49:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:49:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:49:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:49:22 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:49:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:49:22 INFO - (blobuploader) - INFO - Done attempting. 02:49:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 02:49:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:49:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:49:23 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 02:49:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:49:23 INFO - (blobuploader) - INFO - Done attempting. 02:49:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 02:49:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:49:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:49:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 02:49:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:49:24 INFO - (blobuploader) - INFO - Done attempting. 02:49:24 INFO - (blobuploader) - INFO - Iteration through files over. 02:49:24 INFO - Return code: 0 02:49:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:49:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:49:24 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/21f0d4fc77bf41be357a2ed04569f6f1dd397f3a21d3847196ae9b8052fe86247cf65ac2c5e4875d80a32dc9fa84637c6c16608f9ac7c04e5466b68c4bfacce3", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/51f5859faad5150a2c1de66613eab413c6ff8c0374a4e517e89acba1d7766d1343e5a1ccdaa6b5638eaebbfe8b000453a145ffa6a19deb0fd999c15d000906b8", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/65552a26bee609df1122bb670e228a1e8eabd6c0186827209024b56ecad1df0706228c763ce99ecaa2f4d713a2fc74ab8e90130cc52cd8b40d8e91b27ec7c716", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/92c93c9f375a7fd99f9e305575cdfd39f67d102033fda595c71976a1ff30174b4cf9b3137613c67ed9db0e186ddd848acdb2380054619800fb120c6c7043e881"} 02:49:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:49:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:49:24 INFO - Contents: 02:49:24 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/21f0d4fc77bf41be357a2ed04569f6f1dd397f3a21d3847196ae9b8052fe86247cf65ac2c5e4875d80a32dc9fa84637c6c16608f9ac7c04e5466b68c4bfacce3", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/51f5859faad5150a2c1de66613eab413c6ff8c0374a4e517e89acba1d7766d1343e5a1ccdaa6b5638eaebbfe8b000453a145ffa6a19deb0fd999c15d000906b8", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/65552a26bee609df1122bb670e228a1e8eabd6c0186827209024b56ecad1df0706228c763ce99ecaa2f4d713a2fc74ab8e90130cc52cd8b40d8e91b27ec7c716", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/92c93c9f375a7fd99f9e305575cdfd39f67d102033fda595c71976a1ff30174b4cf9b3137613c67ed9db0e186ddd848acdb2380054619800fb120c6c7043e881"} 02:49:24 INFO - Running post-run listener: copy_logs_to_upload_dir 02:49:24 INFO - Copying logs to upload dir... 02:49:24 INFO - mkdir: /builds/slave/test/build/upload/logs 02:49:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1247.460651 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2017-09-01 02:49:24.555394) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-01 02:49:24.558980) ========= 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=ea65d483da93c827905c2090000001f5-1504257792.242887-1504466522 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/21f0d4fc77bf41be357a2ed04569f6f1dd397f3a21d3847196ae9b8052fe86247cf65ac2c5e4875d80a32dc9fa84637c6c16608f9ac7c04e5466b68c4bfacce3", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/51f5859faad5150a2c1de66613eab413c6ff8c0374a4e517e89acba1d7766d1343e5a1ccdaa6b5638eaebbfe8b000453a145ffa6a19deb0fd999c15d000906b8", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/65552a26bee609df1122bb670e228a1e8eabd6c0186827209024b56ecad1df0706228c763ce99ecaa2f4d713a2fc74ab8e90130cc52cd8b40d8e91b27ec7c716", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/92c93c9f375a7fd99f9e305575cdfd39f67d102033fda595c71976a1ff30174b4cf9b3137613c67ed9db0e186ddd848acdb2380054619800fb120c6c7043e881"} build_url:https://queue.taskcluster.net/v1/task/QjwniFnmR7exVsWnUkO_3Q/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011500 build_url: 'https://queue.taskcluster.net/v1/task/QjwniFnmR7exVsWnUkO_3Q/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/21f0d4fc77bf41be357a2ed04569f6f1dd397f3a21d3847196ae9b8052fe86247cf65ac2c5e4875d80a32dc9fa84637c6c16608f9ac7c04e5466b68c4bfacce3", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/51f5859faad5150a2c1de66613eab413c6ff8c0374a4e517e89acba1d7766d1343e5a1ccdaa6b5638eaebbfe8b000453a145ffa6a19deb0fd999c15d000906b8", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/65552a26bee609df1122bb670e228a1e8eabd6c0186827209024b56ecad1df0706228c763ce99ecaa2f4d713a2fc74ab8e90130cc52cd8b40d8e91b27ec7c716", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/92c93c9f375a7fd99f9e305575cdfd39f67d102033fda595c71976a1ff30174b4cf9b3137613c67ed9db0e186ddd848acdb2380054619800fb120c6c7043e881"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-01 02:49:24.587082) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 02:49:24.587467) ========= 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=ea65d483da93c827905c2090000001f5-1504257792.242887-1504466522 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004508 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 02:49:24.669905) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-01 02:49:24.670282) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-01 02:49:24.670715) ========= ========= Total master_lag: 0.20 =========