builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-059 starttime: 1506379050.35 results: success (0) revision: 6660f111fa007f01b0ba44caadae4b39bb1bfa56 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-25 15:37:30.346847) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-25 15:37:30.347260) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-25 15:37:30.347527) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-25 15:37:30.365289) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-25 15:37:30.365627) ========= 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=3a3620fb28aea1604ab609ef000001d2-1506368044.582502-831625406 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-25 15:37:30-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 45.9M=0s 2017-09-25 15:37:30 (45.9 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.082282 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-25 15:37:30.465666) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-25 15:37:30.466105) ========= 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=3a3620fb28aea1604ab609ef000001d2-1506368044.582502-831625406 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021628 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-25 15:37:30.515960) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-25 15:37:30.516289) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 6660f111fa007f01b0ba44caadae4b39bb1bfa56 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 6660f111fa007f01b0ba44caadae4b39bb1bfa56 --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=3a3620fb28aea1604ab609ef000001d2-1506368044.582502-831625406 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-25 15:37:30,563 truncating revision to first 12 chars 2017-09-25 15:37:30,563 Setting DEBUG logging. 2017-09-25 15:37:30,563 attempt 1/10 2017-09-25 15:37:30,564 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/6660f111fa00?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-25 15:37:30,957 unpacking tar archive at: mozilla-beta-6660f111fa00/testing/mozharness/ program finished with exit code 0 elapsedTime=0.673899 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-25 15:37:31.208843) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-25 15:37:31.209329) ========= script_repo_revision: 6660f111fa007f01b0ba44caadae4b39bb1bfa56 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-25 15:37:31.209914) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-25 15:37:31.210312) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-25 15:37:31.225117) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 29 secs) (at 2017-09-25 15:37:31.225620) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta-Non-PGO 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', 'Mozilla-Beta-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta-Non-PGO'] 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=3a3620fb28aea1604ab609ef000001d2-1506368044.582502-831625406 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:37:31 INFO - MultiFileLogger online at 20170925 15:37:31 in /builds/slave/test 15:37:31 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta-Non-PGO 15:37:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:37:31 INFO - {'append_to_log': False, 15:37:31 INFO - 'base_work_dir': '/builds/slave/test', 15:37:31 INFO - 'blob_upload_branch': 'Mozilla-Beta-Non-PGO', 15:37:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:37:31 INFO - 'branch': 'Mozilla-Beta-Non-PGO', 15:37:31 INFO - 'buildbot_json_path': 'buildprops.json', 15:37:31 INFO - 'code_coverage': False, 15:37:31 INFO - 'config_files': ('talos/linux_config.py',), 15:37:31 INFO - 'default_actions': ('clobber', 15:37:31 INFO - 'read-buildbot-config', 15:37:31 INFO - 'download-and-extract', 15:37:31 INFO - 'populate-webroot', 15:37:31 INFO - 'create-virtualenv', 15:37:31 INFO - 'install', 15:37:31 INFO - 'setup-mitmproxy', 15:37:31 INFO - 'run-tests'), 15:37:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:37:31 INFO - 'disable_ccov_upload': False, 15:37:31 INFO - 'disable_stylo': False, 15:37:31 INFO - 'download_minidump_stackwalk': True, 15:37:31 INFO - 'download_symbols': 'ondemand', 15:37:31 INFO - 'enable_stylo': False, 15:37:31 INFO - 'enable_webrender': False, 15:37:31 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 15:37:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:37:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:37:31 INFO - 'gecko_profile': False, 15:37:31 INFO - 'gecko_profile_interval': 0, 15:37:31 INFO - 'installer_path': 'installer.exe', 15:37:31 INFO - 'log_level': 'info', 15:37:31 INFO - 'log_name': 'talos', 15:37:31 INFO - 'log_to_console': True, 15:37:31 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:37:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:37:31 INFO - 'opt_config_files': (), 15:37:31 INFO - 'pip_index': False, 15:37:31 INFO - 'suite': 'dromaeojs-e10s', 15:37:31 INFO - 'system_bits': '32', 15:37:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:37:31 INFO - 'title': 'talos-linux64-ix-059', 15:37:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:37:31 INFO - 'use_talos_json': True, 15:37:31 INFO - 'verify': 'False', 15:37:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:37:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:37:31 INFO - 'work_dir': 'build'} 15:37:31 INFO - [mozharness: 2017-09-25 22:37:31.419329Z] Running clobber step. 15:37:31 INFO - Running pre-action listener: _resource_record_pre_action 15:37:31 INFO - Running main action method: clobber 15:37:31 INFO - rmtree: /builds/slave/test/build 15:37:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:37:32 INFO - Running post-action listener: _resource_record_post_action 15:37:32 INFO - [mozharness: 2017-09-25 22:37:32.403097Z] Finished clobber step (success) 15:37:32 INFO - [mozharness: 2017-09-25 22:37:32.403194Z] Running read-buildbot-config step. 15:37:32 INFO - Running pre-action listener: _resource_record_pre_action 15:37:32 INFO - Running main action method: read_buildbot_config 15:37:32 INFO - Using buildbot properties: 15:37:32 INFO - { 15:37:32 INFO - "project": "", 15:37:32 INFO - "product": "firefox", 15:37:32 INFO - "who": "ryanvm@gmail.com", 15:37:32 INFO - "installer_path": "public/build/target.tar.bz2", 15:37:32 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s", 15:37:32 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta", 15:37:32 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta talos dromaeojs-e10s", 15:37:32 INFO - "stage_platform": "linux64", 15:37:32 INFO - "basedir": "/builds/slave/test", 15:37:32 INFO - "buildnumber": 117, 15:37:32 INFO - "platform": "ubuntu64_hw", 15:37:32 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 15:37:32 INFO - "slavebuilddir": "test", 15:37:32 INFO - "taskId": "Ai1tesNOR02ujCwm-JMl0A", 15:37:32 INFO - "branch": "mozilla-beta", 15:37:32 INFO - "script_repo_revision": "production", 15:37:32 INFO - "revision": "6660f111fa007f01b0ba44caadae4b39bb1bfa56", 15:37:32 INFO - "slavename": "talos-linux64-ix-059", 15:37:32 INFO - "repo_path": "releases/mozilla-beta" 15:37:32 INFO - } 15:37:32 INFO - Finding installer, test and symbols from parent task. 15:37:32 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ai1tesNOR02ujCwm-JMl0A'}, attempt #1 15:37:32 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ai1tesNOR02ujCwm-JMl0A'}, attempt #1 15:37:33 INFO - Task dependencies: ekCv5nYHQveClEcM63kBZA 15:37:33 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ekCv5nYHQveClEcM63kBZA'}, attempt #1 15:37:33 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/ekCv5nYHQveClEcM63kBZA/artifacts/public/build/target.tar.bz2 15:37:33 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/ekCv5nYHQveClEcM63kBZA/artifacts/public/build/target.test_packages.json 15:37:33 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/ekCv5nYHQveClEcM63kBZA/artifacts/public/build/target.crashreporter-symbols.zip 15:37:33 INFO - Running post-action listener: _resource_record_post_action 15:37:33 INFO - [mozharness: 2017-09-25 22:37:33.817608Z] Finished read-buildbot-config step (success) 15:37:33 INFO - [mozharness: 2017-09-25 22:37:33.817825Z] Running download-and-extract step. 15:37:33 INFO - Running pre-action listener: _resource_record_pre_action 15:37:33 INFO - Running main action method: download_and_extract 15:37:33 INFO - mkdir: /builds/slave/test/build/tests 15:37:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:37:33 INFO - trying https://queue.taskcluster.net/v1/task/ekCv5nYHQveClEcM63kBZA/artifacts/public/build/target.test_packages.json 15:37:33 INFO - Downloading https://queue.taskcluster.net/v1/task/ekCv5nYHQveClEcM63kBZA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 15:37:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ekCv5nYHQveClEcM63kBZA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 15:37:34 INFO - Downloaded 989 bytes. 15:37:34 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 15:37:34 INFO - Using the following test package requirements: 15:37:34 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 15:37:34 INFO - u'common': [u'target.common.tests.zip'], 15:37:34 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 15:37:34 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 15:37:34 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 15:37:34 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 15:37:34 INFO - u'mozbase': [u'target.common.tests.zip'], 15:37:34 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 15:37:34 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 15:37:34 INFO - u'web-platform': [u'target.common.tests.zip', 15:37:34 INFO - u'target.web-platform.tests.tar.gz'], 15:37:34 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 15:37:34 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 15:37:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ekCv5nYHQveClEcM63kBZA/artifacts/public/build/target.common.tests.zip 15:37:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ekCv5nYHQveClEcM63kBZA/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:37:34 INFO - Fetch https://queue.taskcluster.net/v1/task/ekCv5nYHQveClEcM63kBZA/artifacts/public/build/target.common.tests.zip into memory 15:37:36 INFO - Content-Length response header: 55183285 15:37:36 INFO - Bytes received: 55183285 15:37:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ekCv5nYHQveClEcM63kBZA/artifacts/public/build/target.talos.tests.zip 15:37:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ekCv5nYHQveClEcM63kBZA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 15:37:47 INFO - Fetch https://queue.taskcluster.net/v1/task/ekCv5nYHQveClEcM63kBZA/artifacts/public/build/target.talos.tests.zip into memory 15:37:49 INFO - Content-Length response header: 14509184 15:37:49 INFO - Bytes received: 14509184 15:37:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:37:49 INFO - trying https://queue.taskcluster.net/v1/task/ekCv5nYHQveClEcM63kBZA/artifacts/public/build/target.tar.bz2 15:37:49 INFO - Downloading https://queue.taskcluster.net/v1/task/ekCv5nYHQveClEcM63kBZA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 15:37:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ekCv5nYHQveClEcM63kBZA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 15:37:51 INFO - Downloaded 53492134 bytes. 15:37:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ekCv5nYHQveClEcM63kBZA/artifacts/public/build/target.tar.bz2 15:37:51 INFO - mkdir: /builds/slave/test/properties 15:37:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:37:51 INFO - Writing to file /builds/slave/test/properties/build_url 15:37:51 INFO - Contents: 15:37:51 INFO - build_url:https://queue.taskcluster.net/v1/task/ekCv5nYHQveClEcM63kBZA/artifacts/public/build/target.tar.bz2 15:37:51 INFO - Running post-action listener: _resource_record_post_action 15:37:51 INFO - Running post-action listener: find_tests_for_verification 15:37:51 INFO - Running post-action listener: set_extra_try_arguments 15:37:51 INFO - [mozharness: 2017-09-25 22:37:51.258086Z] Finished download-and-extract step (success) 15:37:51 INFO - [mozharness: 2017-09-25 22:37:51.258236Z] Running populate-webroot step. 15:37:51 INFO - Running pre-action listener: _resource_record_pre_action 15:37:51 INFO - Running main action method: populate_webroot 15:37:51 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:37:51 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:37:51 INFO - 'tests': ['tresize', 15:37:51 INFO - 'tcanvasmark']}, 15:37:51 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:37:51 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:37:51 INFO - 'tests': ['dromaeo_css', 15:37:51 INFO - 'kraken']}, 15:37:51 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:37:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:37:51 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:37:51 INFO - 'talos_options': ['--disable-stylo'], 15:37:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:37:51 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:37:51 INFO - 'tests': ['damp', 'tps']}, 15:37:51 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:37:51 INFO - 'talos_options': ['--disable-stylo'], 15:37:51 INFO - 'tests': ['damp', 'tps']}, 15:37:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:37:51 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:37:51 INFO - 'tests': ['dromaeo_dom']}, 15:37:51 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 15:37:51 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:37:51 INFO - 'tests': ['basic_compositor_video', 15:37:51 INFO - 'glvideo']}, 15:37:51 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 15:37:51 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 15:37:51 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:37:51 INFO - 'talos_options': ['--disable-stylo'], 15:37:51 INFO - 'tests': ['ts_paint_webext', 15:37:51 INFO - 'tp5o_webext']}, 15:37:51 INFO - 'other-e10s': {'tests': ['a11yr', 15:37:51 INFO - 'ts_paint', 15:37:51 INFO - 'tpaint', 15:37:51 INFO - 'sessionrestore', 15:37:51 INFO - 'sessionrestore_many_windows', 15:37:51 INFO - 'sessionrestore_no_auto_restore', 15:37:51 INFO - 'tabpaint']}, 15:37:51 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:37:51 INFO - 'tests': ['a11yr', 15:37:51 INFO - 'ts_paint', 15:37:51 INFO - 'tpaint', 15:37:51 INFO - 'sessionrestore', 15:37:51 INFO - 'sessionrestore_many_windows', 15:37:51 INFO - 'sessionrestore_no_auto_restore', 15:37:51 INFO - 'tabpaint']}, 15:37:51 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 15:37:51 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 15:37:51 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:37:51 INFO - 'tests': ['perf_reftest_singletons']}, 15:37:51 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:37:51 INFO - 'tests': ['bloom_basic']}, 15:37:51 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:37:51 INFO - 'tsvgr_opacity', 15:37:51 INFO - 'tart', 15:37:51 INFO - 'tscrollx', 15:37:51 INFO - 'tsvg_static']}, 15:37:51 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:37:51 INFO - 'tests': ['tsvgx', 15:37:51 INFO - 'tsvgr_opacity', 15:37:51 INFO - 'tart', 15:37:51 INFO - 'tscrollx', 15:37:51 INFO - 'tsvg_static']}, 15:37:51 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:37:51 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:37:51 INFO - 'talos_options': ['--disable-stylo'], 15:37:51 INFO - 'tests': ['tp5o']}, 15:37:51 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:37:51 INFO - 'talos_options': ['--mitmproxy', 15:37:51 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:37:51 INFO - '--firstNonBlankPaint'], 15:37:51 INFO - 'tests': ['tp6_google', 15:37:51 INFO - 'tp6_youtube', 15:37:51 INFO - 'tp6_amazon', 15:37:51 INFO - 'tp6_facebook']}, 15:37:51 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:37:51 INFO - 'talos_options': ['--disable-stylo', 15:37:51 INFO - '--mitmproxy', 15:37:51 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:37:51 INFO - '--firstNonBlankPaint'], 15:37:51 INFO - 'tests': ['tp6_google', 15:37:51 INFO - 'tp6_youtube', 15:37:51 INFO - 'tp6_amazon', 15:37:51 INFO - 'tp6_facebook']}, 15:37:51 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:37:51 INFO - 'talos_options': ['--stylo-threads=1', 15:37:51 INFO - '--mitmproxy', 15:37:51 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:37:51 INFO - '--firstNonBlankPaint'], 15:37:51 INFO - 'tests': ['tp6_google', 15:37:51 INFO - 'tp6_youtube', 15:37:51 INFO - 'tp6_amazon', 15:37:51 INFO - 'tp6_facebook']}, 15:37:51 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:37:51 INFO - 'talos_options': ['--xperf_path', 15:37:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:37:51 INFO - 'tests': ['tp5n']}, 15:37:51 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:37:51 INFO - 'talos_options': ['--disable-stylo', 15:37:51 INFO - '--xperf_path', 15:37:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:37:51 INFO - 'tests': ['tp5n']}}} 15:37:51 INFO - Running post-action listener: _resource_record_post_action 15:37:51 INFO - [mozharness: 2017-09-25 22:37:51.280393Z] Finished populate-webroot step (success) 15:37:51 INFO - [mozharness: 2017-09-25 22:37:51.280499Z] Running create-virtualenv step. 15:37:51 INFO - Running pre-action listener: _resource_record_pre_action 15:37:51 INFO - Running main action method: create_virtualenv 15:37:51 INFO - Creating virtualenv /builds/slave/test/build/venv 15:37:51 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:37:51 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 15:37:51 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 15:37:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:37:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:37:51 INFO - Installing distribute.............................................................................................................................................................................................done. 15:37:52 INFO - Installing pip...............done. 15:37:52 INFO - Return code: 0 15:37:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:37:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:37:52 INFO - Reading from file tmpfile_stdout 15:37:52 INFO - Output received: 15:37:52 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 15:37:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:37:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:37:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:37:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:37:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:37:52 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 0xa4d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc15a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdb8a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdbc090>, '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 0xdbcd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdb6f90>, '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': '3a3620fb28aea1604ab609ef000001d2-1506368044.582502-831625406', '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 15:37:52 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 15:37:52 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 15:37:52 INFO - Using env: {'DISPLAY': ':0', 15:37:52 INFO - 'HOME': '/home/cltbld', 15:37:52 INFO - 'LANG': 'en_US.UTF-8', 15:37:52 INFO - 'LANGUAGE': 'en_US:en', 15:37:52 INFO - 'LOGNAME': 'cltbld', 15:37:52 INFO - 'MAIL': '/var/mail/cltbld', 15:37:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:37:52 INFO - 'MOZ_NO_REMOTE': '1', 15:37:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:37:52 INFO - 'NO_EM_RESTART': '1', 15:37:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:37:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:37:52 INFO - 'PWD': '/builds/slave/test', 15:37:52 INFO - 'SHELL': '/bin/bash', 15:37:52 INFO - 'SHLVL': '1', 15:37:52 INFO - 'TERM': 'linux', 15:37:52 INFO - 'TMOUT': '86400', 15:37:52 INFO - 'USER': 'cltbld', 15:37:52 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1506368044.582502-831625406', 15:37:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:37:52 INFO - '_': '/tools/buildbot/bin/python'} 15:37:53 INFO - Ignoring indexes: http://pypi.python.org/simple/ 15:37:53 INFO - Downloading/unpacking pip>=1.5 15:37:53 INFO - Running setup.py egg_info for package pip 15:37:53 INFO - warning: no files found matching 'pip/cacert.pem' 15:37:53 INFO - warning: no files found matching '*.html' under directory 'docs' 15:37:53 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:37:53 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:37:53 INFO - Installing collected packages: pip 15:37:53 INFO - Found existing installation: pip 1.1 15:37:53 INFO - Uninstalling pip: 15:37:53 INFO - Successfully uninstalled pip 15:37:53 INFO - Running setup.py install for pip 15:37:53 INFO - warning: no files found matching 'pip/cacert.pem' 15:37:53 INFO - warning: no files found matching '*.html' under directory 'docs' 15:37:53 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:37:53 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:37:53 INFO - Installing pip script to /builds/slave/test/build/venv/bin 15:37:53 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 15:37:53 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 15:37:53 INFO - Successfully installed pip 15:37:53 INFO - Cleaning up... 15:37:53 INFO - Return code: 0 15:37:53 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:37:53 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')]} 15:37:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:37:53 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')]} 15:37:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:37:53 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 0xa4d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc15a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdb8a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdbc090>, '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 0xdbcd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdb6f90>, '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': '3a3620fb28aea1604ab609ef000001d2-1506368044.582502-831625406', '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 15:37:53 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 15:37:53 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 15:37:53 INFO - Using env: {'DISPLAY': ':0', 15:37:53 INFO - 'HOME': '/home/cltbld', 15:37:53 INFO - 'LANG': 'en_US.UTF-8', 15:37:53 INFO - 'LANGUAGE': 'en_US:en', 15:37:53 INFO - 'LOGNAME': 'cltbld', 15:37:53 INFO - 'MAIL': '/var/mail/cltbld', 15:37:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:37:53 INFO - 'MOZ_NO_REMOTE': '1', 15:37:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:37:53 INFO - 'NO_EM_RESTART': '1', 15:37:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:37:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:37:53 INFO - 'PWD': '/builds/slave/test', 15:37:53 INFO - 'SHELL': '/bin/bash', 15:37:53 INFO - 'SHLVL': '1', 15:37:53 INFO - 'TERM': 'linux', 15:37:53 INFO - 'TMOUT': '86400', 15:37:53 INFO - 'USER': 'cltbld', 15:37:53 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1506368044.582502-831625406', 15:37:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:37:53 INFO - '_': '/tools/buildbot/bin/python'} 15:37:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:37:53 INFO - Downloading/unpacking psutil>=3.1.1 15:37:53 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 15:37:53 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 15:37:53 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 15:37:53 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 15:37:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:37:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:37:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:37:55 INFO - Installing collected packages: psutil 15:37:55 INFO - Running setup.py install for psutil 15:37:55 INFO - building 'psutil._psutil_linux' extension 15:37:55 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 15:37:55 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 15:37:55 INFO - building 'psutil._psutil_posix' extension 15:37:55 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 15:37:55 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 15:37:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:37:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:37:55 INFO - Successfully installed psutil 15:37:55 INFO - Cleaning up... 15:37:55 INFO - Return code: 0 15:37:55 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:37:55 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')]} 15:37:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:37:55 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')]} 15:37:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:37:55 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 0xa4d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc15a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdb8a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdbc090>, '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 0xdbcd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdb6f90>, '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': '3a3620fb28aea1604ab609ef000001d2-1506368044.582502-831625406', '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 15:37:55 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 15:37:55 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 15:37:55 INFO - Using env: {'DISPLAY': ':0', 15:37:55 INFO - 'HOME': '/home/cltbld', 15:37:55 INFO - 'LANG': 'en_US.UTF-8', 15:37:55 INFO - 'LANGUAGE': 'en_US:en', 15:37:55 INFO - 'LOGNAME': 'cltbld', 15:37:55 INFO - 'MAIL': '/var/mail/cltbld', 15:37:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:37:55 INFO - 'MOZ_NO_REMOTE': '1', 15:37:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:37:55 INFO - 'NO_EM_RESTART': '1', 15:37:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:37:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:37:55 INFO - 'PWD': '/builds/slave/test', 15:37:55 INFO - 'SHELL': '/bin/bash', 15:37:55 INFO - 'SHLVL': '1', 15:37:55 INFO - 'TERM': 'linux', 15:37:55 INFO - 'TMOUT': '86400', 15:37:55 INFO - 'USER': 'cltbld', 15:37:55 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1506368044.582502-831625406', 15:37:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:37:55 INFO - '_': '/tools/buildbot/bin/python'} 15:37:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:37:55 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:37:55 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 15:37:55 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 15:37:55 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 15:37:55 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 15:37:57 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:37:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:37:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:37:57 INFO - Installing collected packages: mozsystemmonitor 15:37:57 INFO - Running setup.py install for mozsystemmonitor 15:37:57 INFO - Successfully installed mozsystemmonitor 15:37:57 INFO - Cleaning up... 15:37:57 INFO - Return code: 0 15:37:57 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:37:57 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')]} 15:37:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:37:57 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')]} 15:37:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:37:57 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 0xa4d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc15a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdb8a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdbc090>, '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 0xdbcd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdb6f90>, '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': '3a3620fb28aea1604ab609ef000001d2-1506368044.582502-831625406', '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 15:37:57 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 15:37:57 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 15:37:57 INFO - Using env: {'DISPLAY': ':0', 15:37:57 INFO - 'HOME': '/home/cltbld', 15:37:57 INFO - 'LANG': 'en_US.UTF-8', 15:37:57 INFO - 'LANGUAGE': 'en_US:en', 15:37:57 INFO - 'LOGNAME': 'cltbld', 15:37:57 INFO - 'MAIL': '/var/mail/cltbld', 15:37:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:37:57 INFO - 'MOZ_NO_REMOTE': '1', 15:37:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:37:57 INFO - 'NO_EM_RESTART': '1', 15:37:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:37:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:37:57 INFO - 'PWD': '/builds/slave/test', 15:37:57 INFO - 'SHELL': '/bin/bash', 15:37:57 INFO - 'SHLVL': '1', 15:37:57 INFO - 'TERM': 'linux', 15:37:57 INFO - 'TMOUT': '86400', 15:37:57 INFO - 'USER': 'cltbld', 15:37:57 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1506368044.582502-831625406', 15:37:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:37:57 INFO - '_': '/tools/buildbot/bin/python'} 15:37:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:37:57 INFO - Downloading/unpacking jsonschema==2.5.1 15:37:57 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 15:37:57 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 15:37:57 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 15:37:57 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 15:37:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:37:59 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:37:59 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 15:37:59 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 15:37:59 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 15:37:59 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 15:37:59 INFO - Downloading functools32-3.2.3-2.tar.gz 15:37:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:37:59 INFO - warning: no files found matching '*.txt' 15:37:59 INFO - no previously-included directories found matching 'build' 15:37:59 INFO - no previously-included directories found matching 'dist' 15:37:59 INFO - no previously-included directories found matching '.git*' 15:37:59 INFO - Installing collected packages: jsonschema, functools32 15:37:59 INFO - Running setup.py install for functools32 15:37:59 INFO - warning: no files found matching '*.txt' 15:37:59 INFO - no previously-included directories found matching 'build' 15:37:59 INFO - no previously-included directories found matching 'dist' 15:37:59 INFO - no previously-included directories found matching '.git*' 15:37:59 INFO - Successfully installed jsonschema functools32 15:37:59 INFO - Cleaning up... 15:37:59 INFO - Return code: 0 15:37:59 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:37:59 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')]} 15:37:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:37:59 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')]} 15:37:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:37:59 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 0xa4d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc15a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdb8a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdbc090>, '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 0xdbcd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdb6f90>, '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': '3a3620fb28aea1604ab609ef000001d2-1506368044.582502-831625406', '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 15:37:59 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 15:37:59 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 15:37:59 INFO - Using env: {'DISPLAY': ':0', 15:37:59 INFO - 'HOME': '/home/cltbld', 15:37:59 INFO - 'LANG': 'en_US.UTF-8', 15:37:59 INFO - 'LANGUAGE': 'en_US:en', 15:37:59 INFO - 'LOGNAME': 'cltbld', 15:37:59 INFO - 'MAIL': '/var/mail/cltbld', 15:37:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:37:59 INFO - 'MOZ_NO_REMOTE': '1', 15:37:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:37:59 INFO - 'NO_EM_RESTART': '1', 15:37:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:37:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:37:59 INFO - 'PWD': '/builds/slave/test', 15:37:59 INFO - 'SHELL': '/bin/bash', 15:37:59 INFO - 'SHLVL': '1', 15:37:59 INFO - 'TERM': 'linux', 15:37:59 INFO - 'TMOUT': '86400', 15:37:59 INFO - 'USER': 'cltbld', 15:37:59 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1506368044.582502-831625406', 15:37:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:37:59 INFO - '_': '/tools/buildbot/bin/python'} 15:37:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:37:59 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:37:59 INFO - Cleaning up... 15:37:59 INFO - Return code: 0 15:37:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:37:59 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')]} 15:37:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:37:59 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')]} 15:37:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:37:59 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 0xa4d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc15a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdb8a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdbc090>, '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 0xdbcd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdb6f90>, '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': '3a3620fb28aea1604ab609ef000001d2-1506368044.582502-831625406', '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 15:37:59 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 15:37:59 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 15:37:59 INFO - Using env: {'DISPLAY': ':0', 15:37:59 INFO - 'HOME': '/home/cltbld', 15:37:59 INFO - 'LANG': 'en_US.UTF-8', 15:37:59 INFO - 'LANGUAGE': 'en_US:en', 15:37:59 INFO - 'LOGNAME': 'cltbld', 15:37:59 INFO - 'MAIL': '/var/mail/cltbld', 15:37:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:37:59 INFO - 'MOZ_NO_REMOTE': '1', 15:37:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:37:59 INFO - 'NO_EM_RESTART': '1', 15:37:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:37:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:37:59 INFO - 'PWD': '/builds/slave/test', 15:37:59 INFO - 'SHELL': '/bin/bash', 15:37:59 INFO - 'SHLVL': '1', 15:37:59 INFO - 'TERM': 'linux', 15:37:59 INFO - 'TMOUT': '86400', 15:37:59 INFO - 'USER': 'cltbld', 15:37:59 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1506368044.582502-831625406', 15:37:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:37:59 INFO - '_': '/tools/buildbot/bin/python'} 15:37:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:37:59 INFO - Downloading/unpacking blobuploader==1.2.4 15:37:59 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 15:37:59 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 15:37:59 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 15:37:59 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 15:38:00 INFO - Downloading blobuploader-1.2.4.tar.gz 15:38:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:38:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:38:01 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 15:38:01 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 15:38:01 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 15:38:01 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 15:38:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:38:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:38:01 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 15:38:01 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 15:38:01 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 15:38:01 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 15:38:01 INFO - Downloading docopt-0.6.1.tar.gz 15:38:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:38:01 INFO - Installing collected packages: blobuploader, requests, docopt 15:38:01 INFO - Running setup.py install for blobuploader 15:38:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:38:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:38:01 INFO - Running setup.py install for requests 15:38:02 INFO - Running setup.py install for docopt 15:38:02 INFO - Successfully installed blobuploader requests docopt 15:38:02 INFO - Cleaning up... 15:38:02 INFO - Return code: 0 15:38:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:38:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:38:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:38:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:38:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:38:02 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 0xa4d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc15a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdb8a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdbc090>, '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 0xdbcd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdb6f90>, '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': '3a3620fb28aea1604ab609ef000001d2-1506368044.582502-831625406', '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 15:38:02 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 15:38:02 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 15:38:02 INFO - Using env: {'DISPLAY': ':0', 15:38:02 INFO - 'HOME': '/home/cltbld', 15:38:02 INFO - 'LANG': 'en_US.UTF-8', 15:38:02 INFO - 'LANGUAGE': 'en_US:en', 15:38:02 INFO - 'LOGNAME': 'cltbld', 15:38:02 INFO - 'MAIL': '/var/mail/cltbld', 15:38:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:38:02 INFO - 'MOZ_NO_REMOTE': '1', 15:38:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:38:02 INFO - 'NO_EM_RESTART': '1', 15:38:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:38:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:38:02 INFO - 'PWD': '/builds/slave/test', 15:38:02 INFO - 'SHELL': '/bin/bash', 15:38:02 INFO - 'SHLVL': '1', 15:38:02 INFO - 'TERM': 'linux', 15:38:02 INFO - 'TMOUT': '86400', 15:38:02 INFO - 'USER': 'cltbld', 15:38:02 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1506368044.582502-831625406', 15:38:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:38:02 INFO - '_': '/tools/buildbot/bin/python'} 15:38:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:38:02 INFO - Running setup.py (path:/tmp/pip-xLUsaa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:38:02 INFO - Running setup.py (path:/tmp/pip-v3vFZJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:38:02 INFO - Running setup.py (path:/tmp/pip-RGbekw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:38:02 INFO - Running setup.py (path:/tmp/pip-T3CzFI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:38:02 INFO - Running setup.py (path:/tmp/pip-TBofoe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:38:02 INFO - Running setup.py (path:/tmp/pip-GFs8g2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:38:02 INFO - Running setup.py (path:/tmp/pip-kGwegA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:38:02 INFO - Running setup.py (path:/tmp/pip-jMvZB0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:38:02 INFO - Running setup.py (path:/tmp/pip-6LvJ1C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:38:03 INFO - Running setup.py (path:/tmp/pip-BS1mHK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:38:03 INFO - Running setup.py (path:/tmp/pip-otQN39-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:38:03 INFO - Running setup.py (path:/tmp/pip-IPY6UF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:38:03 INFO - Running setup.py (path:/tmp/pip-UfsZFV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:38:03 INFO - Running setup.py (path:/tmp/pip-CGRRXk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:38:03 INFO - Running setup.py (path:/tmp/pip-aU0_te-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:38:03 INFO - Running setup.py (path:/tmp/pip-6cFcId-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:38:03 INFO - Running setup.py (path:/tmp/pip-I49DtT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:38:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:38:03 INFO - Running setup.py install for manifestparser 15:38:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:38:03 INFO - Running setup.py install for mozcrash 15:38:03 INFO - Running setup.py install for mozdebug 15:38:03 INFO - Running setup.py install for mozdevice 15:38:03 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:38:03 INFO - Running setup.py install for mozfile 15:38:04 INFO - Running setup.py install for mozhttpd 15:38:04 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:38:04 INFO - Running setup.py install for mozinfo 15:38:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:38:04 INFO - Running setup.py install for mozInstall 15:38:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:38:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:38:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:38:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:38:04 INFO - Running setup.py install for mozleak 15:38:04 INFO - Running setup.py install for mozlog 15:38:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:38:04 INFO - Running setup.py install for moznetwork 15:38:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:38:04 INFO - Running setup.py install for mozprocess 15:38:04 INFO - Running setup.py install for mozprofile 15:38:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:38:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:38:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:38:04 INFO - Running setup.py install for mozrunner 15:38:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:38:05 INFO - Running setup.py install for mozscreenshot 15:38:05 INFO - Running setup.py install for moztest 15:38:05 INFO - Running setup.py install for mozversion 15:38:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:38:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:38:05 INFO - Cleaning up... 15:38:05 INFO - Return code: 0 15:38:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:38:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:38:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:38:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:38:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:38:05 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 0xa4d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc15a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdb8a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdbc090>, '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 0xdbcd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdb6f90>, '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': '3a3620fb28aea1604ab609ef000001d2-1506368044.582502-831625406', '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 15:38:05 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 15:38:05 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 15:38:05 INFO - Using env: {'DISPLAY': ':0', 15:38:05 INFO - 'HOME': '/home/cltbld', 15:38:05 INFO - 'LANG': 'en_US.UTF-8', 15:38:05 INFO - 'LANGUAGE': 'en_US:en', 15:38:05 INFO - 'LOGNAME': 'cltbld', 15:38:05 INFO - 'MAIL': '/var/mail/cltbld', 15:38:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:38:05 INFO - 'MOZ_NO_REMOTE': '1', 15:38:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:38:05 INFO - 'NO_EM_RESTART': '1', 15:38:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:38:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:38:05 INFO - 'PWD': '/builds/slave/test', 15:38:05 INFO - 'SHELL': '/bin/bash', 15:38:05 INFO - 'SHLVL': '1', 15:38:05 INFO - 'TERM': 'linux', 15:38:05 INFO - 'TMOUT': '86400', 15:38:05 INFO - 'USER': 'cltbld', 15:38:05 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1506368044.582502-831625406', 15:38:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:38:05 INFO - '_': '/tools/buildbot/bin/python'} 15:38:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:38:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:38:05 INFO - Running setup.py (path:/tmp/pip-98JAFQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:38:05 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)) 15:38:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:38:05 INFO - Running setup.py (path:/tmp/pip-b03hlA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:38:05 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)) 15:38:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:38:05 INFO - Running setup.py (path:/tmp/pip-VzTHlD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:38:05 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)) 15:38:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:38:05 INFO - Running setup.py (path:/tmp/pip-ep7Ol5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:38:05 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)) 15:38:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:38:05 INFO - Running setup.py (path:/tmp/pip-mp2iIh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:38:05 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)) 15:38:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:38:05 INFO - Running setup.py (path:/tmp/pip-Buduz0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:38:06 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)) 15:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:38:06 INFO - Running setup.py (path:/tmp/pip-LoeT7v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:38:06 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)) 15:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:38:06 INFO - Running setup.py (path:/tmp/pip-WvZVj8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:38:06 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)) 15:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:38:06 INFO - Running setup.py (path:/tmp/pip-iwknUg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:38:06 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)) 15:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:38:06 INFO - Running setup.py (path:/tmp/pip-f0lFYT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:38:06 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)) 15:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:38:06 INFO - Running setup.py (path:/tmp/pip-Bsev5z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:38:06 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)) 15:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:38:06 INFO - Running setup.py (path:/tmp/pip-OkTDsb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:38:06 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)) 15:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:38:06 INFO - Running setup.py (path:/tmp/pip-qG0Ds8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:38:06 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)) 15:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:38:06 INFO - Running setup.py (path:/tmp/pip-Ecsfic-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:38:06 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)) 15:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:38:06 INFO - Running setup.py (path:/tmp/pip-LimWxG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:38:06 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)) 15:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:38:06 INFO - Running setup.py (path:/tmp/pip-5sS1yT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:38:06 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)) 15:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:38:06 INFO - Running setup.py (path:/tmp/pip-zLDVzW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:38:06 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)) 15:38:06 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)) 15:38:06 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)) 15:38:06 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)) 15:38:06 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)) 15:38:06 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)) 15:38:06 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)) 15:38:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:38:06 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 15:38:06 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 15:38:06 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 15:38:06 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 15:38:07 INFO - Downloading blessings-1.6.tar.gz 15:38:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:38:08 INFO - Installing collected packages: blessings 15:38:08 INFO - Running setup.py install for blessings 15:38:08 INFO - Successfully installed blessings 15:38:08 INFO - Cleaning up... 15:38:08 INFO - Return code: 0 15:38:08 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:38:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:38:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:38:08 INFO - Reading from file tmpfile_stdout 15:38:08 INFO - Current package versions: 15:38:08 INFO - argparse == 1.2.1 15:38:08 INFO - blessings == 1.6 15:38:08 INFO - blobuploader == 1.2.4 15:38:08 INFO - docopt == 0.6.1 15:38:08 INFO - functools32 == 3.2.3-2 15:38:08 INFO - jsonschema == 2.5.1 15:38:08 INFO - manifestparser == 1.1 15:38:08 INFO - mozInstall == 1.13 15:38:08 INFO - mozcrash == 1.0 15:38:08 INFO - mozdebug == 0.1 15:38:08 INFO - mozdevice == 0.51 15:38:08 INFO - mozfile == 1.2 15:38:08 INFO - mozhttpd == 0.7 15:38:08 INFO - mozinfo == 0.10 15:38:08 INFO - mozleak == 0.1 15:38:08 INFO - mozlog == 3.5 15:38:08 INFO - moznetwork == 0.27 15:38:08 INFO - mozprocess == 0.25 15:38:08 INFO - mozprofile == 0.28 15:38:08 INFO - mozrunner == 6.13 15:38:08 INFO - mozscreenshot == 0.1 15:38:08 INFO - mozsystemmonitor == 0.3 15:38:08 INFO - moztest == 0.8 15:38:08 INFO - mozversion == 1.4 15:38:08 INFO - psutil == 3.1.1 15:38:08 INFO - requests == 1.2.3 15:38:08 INFO - wsgiref == 0.1.2 15:38:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:38: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')]} 15:38:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:38: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')]} 15:38:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:38:08 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 0xa4d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc15a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdb8a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdbc090>, '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 0xdbcd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdb6f90>, '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': '3a3620fb28aea1604ab609ef000001d2-1506368044.582502-831625406', '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 15:38:08 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 15:38:08 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 15:38:08 INFO - Using env: {'DISPLAY': ':0', 15:38:08 INFO - 'HOME': '/home/cltbld', 15:38:08 INFO - 'LANG': 'en_US.UTF-8', 15:38:08 INFO - 'LANGUAGE': 'en_US:en', 15:38:08 INFO - 'LOGNAME': 'cltbld', 15:38:08 INFO - 'MAIL': '/var/mail/cltbld', 15:38:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:38:08 INFO - 'MOZ_NO_REMOTE': '1', 15:38:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:38:08 INFO - 'NO_EM_RESTART': '1', 15:38:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:38:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:38:08 INFO - 'PWD': '/builds/slave/test', 15:38:08 INFO - 'SHELL': '/bin/bash', 15:38:08 INFO - 'SHLVL': '1', 15:38:08 INFO - 'TERM': 'linux', 15:38:08 INFO - 'TMOUT': '86400', 15:38:08 INFO - 'USER': 'cltbld', 15:38:08 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1506368044.582502-831625406', 15:38:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:38:08 INFO - '_': '/tools/buildbot/bin/python'} 15:38:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:38:08 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)) 15:38:08 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)) 15:38:08 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)) 15:38:08 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)) 15:38:08 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)) 15:38:08 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)) 15:38:08 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)) 15:38:08 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)) 15:38:08 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)) 15:38:08 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 15:38:08 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 15:38:08 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 15:38:08 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 15:38:08 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 15:38:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 15:38:09 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)) 15:38:09 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)) 15:38:09 INFO - Installing collected packages: simplejson 15:38:09 INFO - Running setup.py install for simplejson 15:38:09 INFO - building 'simplejson._speedups' extension 15:38:09 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 15:38:10 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 15:38:10 INFO - Successfully installed simplejson 15:38:10 INFO - Cleaning up... 15:38:10 INFO - Return code: 0 15:38:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:38:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:38:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:38:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:38:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:38:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xa4d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc15a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdb8a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdbc090>, '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 0xdbcd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdb6f90>, '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': '3a3620fb28aea1604ab609ef000001d2-1506368044.582502-831625406', '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 15:38:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:38:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:38:10 INFO - Using env: {'DISPLAY': ':0', 15:38:10 INFO - 'HOME': '/home/cltbld', 15:38:10 INFO - 'LANG': 'en_US.UTF-8', 15:38:10 INFO - 'LANGUAGE': 'en_US:en', 15:38:10 INFO - 'LOGNAME': 'cltbld', 15:38:10 INFO - 'MAIL': '/var/mail/cltbld', 15:38:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:38:10 INFO - 'MOZ_NO_REMOTE': '1', 15:38:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:38:10 INFO - 'NO_EM_RESTART': '1', 15:38:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:38:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:38:10 INFO - 'PWD': '/builds/slave/test', 15:38:10 INFO - 'SHELL': '/bin/bash', 15:38:10 INFO - 'SHLVL': '1', 15:38:10 INFO - 'TERM': 'linux', 15:38:10 INFO - 'TMOUT': '86400', 15:38:10 INFO - 'USER': 'cltbld', 15:38:10 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1506368044.582502-831625406', 15:38:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:38:10 INFO - '_': '/tools/buildbot/bin/python'} 15:38:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:38:11 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:38:11 INFO - Cleaning up... 15:38:11 INFO - Return code: 0 15:38:11 INFO - Running post-action listener: _resource_record_post_action 15:38:11 INFO - Running post-action listener: _start_resource_monitoring 15:38:11 INFO - Starting resource monitoring. 15:38:11 INFO - [mozharness: 2017-09-25 22:38:11.116550Z] Finished create-virtualenv step (success) 15:38:11 INFO - [mozharness: 2017-09-25 22:38:11.117065Z] Running install step. 15:38:11 INFO - Running pre-action listener: _resource_record_pre_action 15:38:11 INFO - Running main action method: install 15:38:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:38:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:38:11 INFO - Reading from file tmpfile_stdout 15:38:11 INFO - Detecting whether we're running mozinstall >=1.0... 15:38:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:38:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:38:11 INFO - Reading from file tmpfile_stdout 15:38:11 INFO - Output received: 15:38:11 INFO - Usage: mozinstall [options] installer 15:38:11 INFO - Options: 15:38:11 INFO - -h, --help show this help message and exit 15:38:11 INFO - -d DEST, --destination=DEST 15:38:11 INFO - Directory to install application into. [default: 15:38:11 INFO - "/builds/slave/test"] 15:38:11 INFO - --app=APP Application being installed. [default: firefox] 15:38:11 INFO - mkdir: /builds/slave/test/build/application 15:38:11 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'] 15:38:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 15:38:26 INFO - Reading from file tmpfile_stdout 15:38:26 INFO - Output received: 15:38:26 INFO - /builds/slave/test/build/application/firefox/firefox 15:38:26 INFO - Running post-action listener: _resource_record_post_action 15:38:26 INFO - [mozharness: 2017-09-25 22:38:26.037438Z] Finished install step (success) 15:38:26 INFO - [mozharness: 2017-09-25 22:38:26.037691Z] Running setup-mitmproxy step. 15:38:26 INFO - Running pre-action listener: _resource_record_pre_action 15:38:26 INFO - Running main action method: setup_mitmproxy 15:38:26 INFO - Skipping: mitmproxy is not required 15:38:26 INFO - Running post-action listener: _resource_record_post_action 15:38:26 INFO - [mozharness: 2017-09-25 22:38:26.038809Z] Finished setup-mitmproxy step (success) 15:38:26 INFO - [mozharness: 2017-09-25 22:38:26.039028Z] Running run-tests step. 15:38:26 INFO - Running pre-action listener: _resource_record_pre_action 15:38:26 INFO - Running pre-action listener: _set_gcov_prefix 15:38:26 INFO - Running main action method: run_tests 15:38:26 WARNING - Try message not found. 15:38:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:38:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:38:26 INFO - Python 2.7.3 15:38:26 INFO - Return code: 0 15:38:26 INFO - grabbing minidump binary from tooltool 15:38: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')]} 15:38:26 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 0xdbc090>, '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 0xdbcd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdb6f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:38:26 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 15:38:26 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 15:38:26 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 15:38:26 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:38:26 INFO - Return code: 0 15:38:26 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:38:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:38:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:38:26 INFO - ENV: RUST_BACKTRACE is now full 15:38:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:38:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:38:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ekCv5nYHQveClEcM63kBZA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-059', '--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 15:38:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ekCv5nYHQveClEcM63kBZA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-059 --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 15:38:26 INFO - Using env: {'DISPLAY': ':0', 15:38:26 INFO - 'HOME': '/home/cltbld', 15:38:26 INFO - 'LANG': 'en_US.UTF-8', 15:38:26 INFO - 'LANGUAGE': 'en_US:en', 15:38:26 INFO - 'LOGNAME': 'cltbld', 15:38:26 INFO - 'MAIL': '/var/mail/cltbld', 15:38:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:38:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:38:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:38:26 INFO - 'MOZ_NO_REMOTE': '1', 15:38:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:38:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:38:26 INFO - 'NO_EM_RESTART': '1', 15:38:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:38:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:38:26 INFO - 'PWD': '/builds/slave/test', 15:38:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:38:26 INFO - 'RUST_BACKTRACE': 'full', 15:38:26 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:38:26 INFO - 'SHELL': '/bin/bash', 15:38:26 INFO - 'SHLVL': '1', 15:38:26 INFO - 'TERM': 'linux', 15:38:26 INFO - 'TMOUT': '86400', 15:38:26 INFO - 'USER': 'cltbld', 15:38:26 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1506368044.582502-831625406', 15:38:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:38:26 INFO - '_': '/tools/buildbot/bin/python'} 15:38:26 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ekCv5nYHQveClEcM63kBZA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-059', '--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 15:38:26 INFO - mozversion application_buildid: 20170925211542 15:38:26 INFO - mozversion application_changeset: 6660f111fa007f01b0ba44caadae4b39bb1bfa56 15:38:26 INFO - mozversion application_display_name: Firefox 15:38:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:38:26 INFO - mozversion application_name: Firefox 15:38:26 INFO - mozversion application_remotingname: firefox 15:38:26 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 15:38:26 INFO - mozversion application_vendor: Mozilla 15:38:26 INFO - mozversion application_version: 57.0 15:38:26 INFO - mozversion platform_buildid: 20170925211542 15:38:26 INFO - mozversion platform_changeset: 6660f111fa007f01b0ba44caadae4b39bb1bfa56 15:38:26 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 15:38:26 INFO - mozversion platform_version: 57.0 15:38:26 INFO - using testdate: 1506379106 15:38:26 INFO - actual date: 1506379106 15:38:26 INFO - starting webserver on 'localhost:52658' 15:38:26 INFO - SUITE-START | Running 2 tests 15:38:26 INFO - TEST-START | dromaeo_css 15:38:26 INFO - Initialising browser for dromaeo_css test... 15:38:26 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:52658/getInfo.html -profile /tmp/tmpRyN5Y7/profile 15:38:26 INFO - TEST-INFO | started process 7639 (/builds/slave/test/build/application/firefox/firefox http://localhost:52658/getInfo.html) 15:38:31 INFO - TEST-INFO | 7639: exit 0 15:38:31 INFO - Browser initialized. 15:38:31 INFO - Running cycle 1/1 for dromaeo_css test... 15:38:31 INFO - Using env: {'DISPLAY': ':0', 15:38:31 INFO - 'HOME': '/home/cltbld', 15:38:31 INFO - 'JSGC_DISABLE_POISONING': '1', 15:38:31 INFO - 'LANG': 'en_US.UTF-8', 15:38:31 INFO - 'LANGUAGE': 'en_US:en', 15:38:31 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 15:38:31 INFO - 'LOGNAME': 'cltbld', 15:38:31 INFO - 'MAIL': '/var/mail/cltbld', 15:38:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:38:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:38:31 INFO - 'MOZ_CRASHREPORTER': '1', 15:38:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:38:31 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 15:38:31 INFO - 'MOZ_NO_REMOTE': '1', 15:38:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:38:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:38:31 INFO - 'NO_EM_RESTART': '1', 15:38:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:38:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:38:31 INFO - 'PWD': '/builds/slave/test', 15:38:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:38:31 INFO - 'RUST_BACKTRACE': 'full', 15:38:31 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:38:31 INFO - 'SHELL': '/bin/bash', 15:38:31 INFO - 'SHLVL': '1', 15:38:31 INFO - 'TERM': 'linux', 15:38:31 INFO - 'TMOUT': '86400', 15:38:31 INFO - 'USER': 'cltbld', 15:38:31 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1506368044.582502-831625406', 15:38:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:38:31 INFO - '_': '/tools/buildbot/bin/python'} 15:38:31 INFO - TEST-INFO | started process 7825 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRyN5Y7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 15:38:31 INFO - PID 7825 | 15:38:31 INFO - PID 7825 | (/builds/slave/test/build/application/firefox/firefox:7875): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 15:38:31 INFO - PID 7825 | 15:38:42 INFO - PID 7825 | RSS: Main: 143212544 15:38:42 INFO - PID 7825 | 15:41:39 INFO - PID 7825 | Cycle 1(1): loaded http://localhost:52658/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52658/tests/dromaeo/cssquery-ext.html) 15:41:40 INFO - PID 7825 | RSS: Main: 146231296 15:41:40 INFO - PID 7825 | 15:44:41 INFO - PID 7825 | Cycle 1(1): loaded http://localhost:52658/tests/dromaeo/cssquery-ext.html (next: http://localhost:52658/tests/dromaeo/cssquery-jquery.html) 15:44:42 INFO - PID 7825 | RSS: Main: 143790080 15:44:42 INFO - PID 7825 | 15:47:44 INFO - PID 7825 | Cycle 1(1): loaded http://localhost:52658/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52658/tests/dromaeo/cssquery-mootools.html) 15:47:44 INFO - PID 7825 | RSS: Main: 145965056 15:47:44 INFO - PID 7825 | 15:50:46 INFO - PID 7825 | Cycle 1(1): loaded http://localhost:52658/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52658/tests/dromaeo/cssquery-prototype.html) 15:50:46 INFO - PID 7825 | RSS: Main: 145977344 15:50:46 INFO - PID 7825 | 15:53:49 INFO - PID 7825 | Cycle 1(1): loaded http://localhost:52658/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52658/tests/dromaeo/cssquery-yui.html) 15:53:50 INFO - PID 7825 | RSS: Main: 146751488 15:53:50 INFO - PID 7825 | 15:56:51 INFO - PID 7825 | Cycle 1(1): loaded http://localhost:52658/tests/dromaeo/cssquery-yui.html (next: http://localhost:52658/tests/dromaeo/cssquery-dojo.html) 15:56:51 INFO - PID 7825 | RSS: Main: 149794816 15:56:51 INFO - PID 7825 | 15:56:51 INFO - PID 7825 | __start_tp_report 15:56:51 INFO - PID 7825 | _x_x_mozilla_page_load 15:56:51 INFO - PID 7825 | _x_x_mozilla_page_load_details 15:56:51 INFO - PID 7825 | |i|pagename|runs| 15:56:51 INFO - PID 7825 | |0;dojo.html;3909.609039096091;3926.7251292409524;3990.660793832526;3996.5803590622995;4002.4396584478195;6581.407673309402;6620.04009418634;6642.302558231377;6656.434203092743;6734.158230221223;6589.60462372258;6607.372299631527;6617.8014659560295;6702.02820591014;6747.628880411576;5932.970335148319;5941.079132734411;5998.4901283390745;6027.39726027396;6028.939710602893;5912.17229587858;5973.342932277467;6056.576039677225;6097.57316987811;6125.17310163129;6048.123022161784;6099.542534309922;6126.663033533154;6134.171886795292;6140.969295153518;5967;6016.2479690038745;6050.2437195350585;6130.386961303856;6163.044728067157;5881.85295367615;5902.734376953004;5949;5951.58338916276;5989.071693887455;6519.054737063099;6533.640649764261;6564.638944858031;6584.11114499546;6628.767993120217;7192.532485388433;7195.8201044973775;7201.675924583406;7307.707691692326;7335.449841261873;7248.311410416002;7250.601216933067;7310.488265821395;7322.157951835554;7329.853402931964;6723.193216814014;6749.493787965824;6778.22050464198;6816.420604248645;6818.761343352959;7331.667346999812;7370;7388.335049845432;7391.48259621832;7434.145073316529;7154.35610795031;7164.713411463483;7177.820554486075;7192.496525243288;7194.748183813542;7236.457265705093;7328.377087947584;7356;7360.668769905367;7394.038774959221;7134.2509036551455;7156.606386648784;7158.2125966143685;7176.425885929113;7252.274772522811;7113.253108423541;7123.7506687265695;7223.169335526377;7229.024081749002;7298.598577078312;5347.732613369316;5372.409035006146;5375.919361209585;5380.838574842761;5406.162044883049;4841.379927513527;4852;4870.806652370192;4910.656254062182;4930.950690493121;6639.966800166065;6661.600303981777;6669.099671544376;6732.091167692397;6746.797596072126;7175.784726458214;7212.855742885218;7260.382867456326;7268.781936541912;7277.345038946521;6682.966585167043;6719.227288861844;6749.190097188369;6763.222229443579;6813.284605116491;6067;6140.846478838065;6234.2830574484515;6235.095911092957;6236.501079913596;7354.448416368794;7390.150132734697;7465.328120469184;7479.962600186965;7554.924450755422;7221.350078492961;7239.239879812754;7254.528455650471;7255.09311336083;7297.635118244173;7335.559866408033;7359.521631093961;7364.705411783469;7430.96284518574;7438.888416673863;6995.475339349671;7043.823904402431;7095.148582170173;7120.643967801486;7206.7117315306805;7264.782056538313;7270.418366530771;7325.523840950321;7346.485745997729;7350.632468376667;6813.931860681329;6899.275576064641;6985.545939513915;6996.615186164606;7015.543989640657;6825.180978282638;6881.862362752817;6891.448684105159;6903.654817259217;6988.615626140408;6810.489213308922;6822.965885170741;6911.274316196928;6926.515143939876;6963.338482844122;6808.863822723617;6913.930860691329;6935.098437203132;6968.895466567904;7016.36852683261;6769.695363708546;6771.187457505131;6771.796846094625;6857.828554286183;6865.897011544731 15:56:51 INFO - PID 7825 | |1;ext.html;966.4008314844793;967.5017366056478;980.8038392321536;1018.7554986803165;1020.5356562763941;43065;43495;43684.781576092115;43774.124517509714;43776;41688;42004;42021.159576808444;42021.57978420215;42050.57949420505;8064.193580641918;8209.343252539813;8214.13751556087;8222.301104406133;8223.05434874988;8065.556394398306;8156.265936065753;8159.184081591853;8163.38774591905;8164.346852251806;8737.344699147588;8952.060033696465;8957.686480973167;8960.551972401387;8993.415427997192;8867.246284065863;8880.245179159747;8930.19628233459;8947.418417802823;8958.23854972328;8660.740177794676;8706.825863482758;8750.606222719993;8760.780980475476;8769.254613357873;40731.38902916459;40806.59193408058;40912.59087409118;40949.385759213634;41174;3855.363864962278;3889.3971434427426;3891.610838916086;3895.1235971906376;3915.6280153385383;33602.6639733602;33648.32703345944;33651.15872103217;33689.32621347559;33720.15699607505;5489.505944465017;5643.971780141073;5706.4864162225595;5713.771449142029;5716.8856622867725;232406;232570;233033;233645.83177084176;233741;52638.73680631649;52950.73524632352;52952.73523632357;53114.203286950724;53116.28186027018;54781.45218547843;54827.72586137044;55032;55143.172852407246;55184.72407638017;17577.121143943008;17803.910980445275;17851.73222401665;17858.732119018227;17954.5511362217;14685.926570367079;14692.338844752012;14696;14696.26518674062;14729.705405891822;15024.399024038916;15305.693886122215;15307;15386.999845010263;15552.066875987477;5899.233099697098;5962.672053037038;6131.294901086343;6152.8769424611255;6173.382661733791;1090.6837017265063;1100.2518287564533;1112.6439539347493;1112.9610463633735;1129.5594718059963;25211;25251.62122568163;25401.491970160863;25510;25703.61444578333;1214.6173955204083;1231.907606929466;1234.3457967277357;1248.3134276148082;1260.4769020856243;13114.213147211198;13222.338883056002;13239.73520529584;13353.73292534144;13360.2651854147;268608;269265;273089.9898079501;275451;276244;46824;47437.288440673416;50348.748256258485;51321;57131;1558.8986715863432;1562.4687606213943;1563.9061656300657;1583.588267050552;1589.2212815720445;29871.402571948875;30168;30177.849110754305;30220.24449388783;30550;39981;39983;40066.1986760269;40068.39897401483;40101.39847902225;244653;245008;246479;246840;248136;35084.64915350916;35194;35403.64596354003;35556.466653000745;35725.64274357327;43335.13329733325;44442;44948.325775112746;45112.77443612892;45241;33439;33719.66280337263;33763.83118084394;34036.659633403346;34143;34234.65765342315;34328.48507272343;34365.656343437244;34553.136171595914;35131.824340878135;40200.39699404453;40345;40567;40703.38944915888;40822.59177408188;33635.15912102217;33943;33989.49015764716;34208.486872697424;34360.48459273163;25537.61693574635;25644.358891027874;25896.22311330663;26154.215373538824;26205.082822101136 15:56:51 INFO - PID 7825 | |2;jquery.html;632.6223542589039;633.4750148019427;640.6792368322402;642.0530060198575;644.9301325689718;23198.304050878458;23909.76090239097;23947.52104957904;23972.400689982736;24017.279481615536;25492.107776227836;25856.48287034258;25997.09010184644;26000.47999040018;26054.609180862302;6566.34336566342;6722.932770672292;6776.2546119926765;6792.083068785725;6804.727810887559;6776.661166941658;6875.071865298196;6875.862482750342;6884.414824739903;6884.586924784504;6757.932420675804;6840.486963477759;6850.7602233921825;6853.760118395857;6854.280300568418;6987.1266091738535;7033.859322813566;7036.331548502884;7038.08504894363;7041.823954401129;6574.704138313788;6587.044878492639;6591.7363305467725;6594.142761440982;6607.372299631527;30791.692083079106;31680.524792128137;32027.679723202702;32089.197770055704;32121;2350.4476448034698;2405.338531903723;2415.7221919479307;2418.7944024757917;2423.127674037345;21078.473038174016;21588.784112158835;21607;21619.67570486444;21667.241646542294;2570.730073342291;2601.9609705854427;2630.973690263092;2655.0574546036264;2695.8517281549507;150768;157597;166436;172743.27256727396;172959;17178.16560267567;17222.483325500005;17273.222704978307;17449.12754362302;17627.206775695126;26944.730552694222;27114.72885271122;27237.319067023087;27261.318467038087;27269.318267043484;10816.351018938889;10993.340399576051;11009.834852477059;11263.887361126448;11266.929641684026;9850.113489785954;9867.851982220272;9915.107640312262;9955.502224888727;10158.273093573962;10052.899471005343;10152.340097893613;10228.437435940947;10448;10650.414227217423;2898.855057247129;2914.7231013053724;3020.788544801843;3085.2595377109783;3104.2549788050706;1011.9797604047877;1016.2835201183149;1025.9692209233735;1041.7656027393775;1048.8688913885765;19303.034848257532;20819.58360832775;20994.055267513;21247.78752212489;21350.35948921535;1105.2870898270571;1107.7839821234782;1108.8281316395971;1118.032901540162;1119.6081371520065;11010.50452729629;11093.5562577496;11121.165912556591;11248.493817778219;11357.318560886373;633337;640431;640638;654548;674187;129296;138158.3092084533;138635.30682346725;140005;140658;1109.0628418985943;1200.363807182195;1212.678640160358;1250.9499620015097;1258.930758808256;21618.567628647026;21702.2404215858;21837.454063648536;21958.45103872352;22007;61893.69053154706;62302.37697622966;62520.68739656454;62999.37000629935;63139.68430158003;77570;78061;79272.20727792649;82699.5865020695;83204.58397707973;15941.282642280894;16295.429659961845;16311.26599303058;16317.67364652724;16342;24166.63750043786;24396.63405048961;24651.630225546272;24669.753302466746;24727;20032.69950950766;20063;20228.393148205578;20339.389818305473;20356.18575256973;17206.741898871278;17983.460496185136;17994.5501362467;18086.366977155736;18092.819071809114;20188.798112019278;20696.586068278248;20809.583808324052;20870.37388878336;20912;15890.126043067743;15920.8407915924;15986.920065399598;16039.518814435585;16082.035077895363;17875.46373608742;18311;18330;18345.174467149278;19966.500837478598 15:56:51 INFO - PID 7825 | |3;mootools.html;1473.3443617590174;1512.115412483697;1534.5549790560738;1539.6689711711979;1557.5015994881628;17351.739723904153;18029.459116226535;18041.368552100677;18063.09684515774;18081.186346614402;17215.96704197749;17248.22382992765;17315.82684173161;17372.305107795673;17400;2932.7800414968856;2933.5746316784052;2990.5364668476423;2999.0852789899072;3006.669266380696;2870.2680816391894;2907.4766542022535;2918.6789453160136;2920.4159168166348;2920.45971495273;2798.244473992021;2918.708129187075;2958.1421387797513;2981.791274610778;3015.2914065194664;3051.2829485070993;3075.154332558542;3079;3079.13784140441;3080.938381232374;2703.540398132322;2729.604207389937;2867.2545138263995;2922.649282086142;2956.2609347663083;17359.392421265195;17477.300907963665;17518.773685841996;17522.561935951577;17522.912385438118;8503.659853605848;8771.386002979794;8781.209691127766;8786.780330491725;8805.559722013873;5373.5163835254625;5544.63959842609;5695.14572814077;5767.913481297784;5771.307443106812;2137.764845866969;2159.287435146398;2165.566886622666;2183.366823621148;2213.6458166693415;31549.684503154906;31756.682433175603;31797.364052718814;31832.204194895083;31979;7075.292470752884;7121.465890058266;7127.643617819192;7276.308750668678;7296.124465064227;20561.89719051395;20638.690419643415;20668;20733.170673172903;20820.37538873806;8533.402661813738;8649.3512986525;8675.56622168889;8704.390692651581;8738.432001919855;7517.473776835569;7585.58279294633;7620.123685776096;7677.193894641094;7746.109197442366;5848.473637372657;5908.940910590925;5938.6436813791315;6028.125921741323;6124.785632502841;1641.967160656804;1667.2997341116761;1668.649583587436;1669.090345761563;1705.9061751603533;932.7574830544107;971.3491960386417;972.1882228339274;978.0512902484721;980.3382716666222;8347.248747612744;8409.789755256044;8457.112003239572;8457.365697572706;8488.278496327881;803.2489622203259;815.8898548695969;817.1134319263547;825.793551612097;829.6142293833396;5324.281222035053;5766.971165144148;5827.88344233113;5881.911771323348;6003.519718422603;26508.60237096445;26951.460970780474;27166.1850144496;27195.728042719715;27521;25740.871295643403;26823;27049.59425608578;27064.458710825675;27095.458090838467;12433.62699119046;13027.47890084386;13168.144070635379;13172.47310107604;13193.20840749558;9361.251099912128;9407.106324899123;9462.858057129011;9466.05339466048;9515.857262140935;21566.353009409744;21727.674084889055;21875.343739687836;22086;22606.547869042857;23339.53320933606;23380.347837382666;23765.16821911294;23774.762252377946;24792.25623231306;12907;13180.077394582288;13208.471661133448;13213.405396756976;13269;16290.348386064425;16339.509814705578;16355.100469473817;16580.170991450137;16585.336586536403;13008.544700935421;13070.150440221356;13081.345932703518;13190.80213796813;13453.529126480526;12855.550055748005;12929.547465839027;12962.481500739865;13001.2849293286;13027.739445210853;10377.221708371751;10493.9475302623;10709.946450268011;10712.303700259457;10735.409552474688;8240.13478584764;8370.414071014971;8400.41197116196;8404.789880252798;8500.957495212484;7855.60721963911;8347.582620869054;8349;8381.287590554628;8417.579121044046 15:56:51 INFO - PID 7825 | |4;prototype.html;557.7908284393352;576.6568891509555;593.5192494079796;601.9488343490805;608.1212647724041;6512.218533775947;6590.286525503368;6590.637514936677;6597.373249541298;6620.801375958729;6505.609663420198;6507.967460162693;6534.54258201926;6544.116544266522;6556.475481961444;5286.31277933868;5317.308749862522;5357.973210133944;5371.919421208685;5399.5680345572455;5067.518585734368;5276.630635855492;5288.68267903927;5307.708076055816;5375.220593014011;5167.4574169712205;5181.9740901295445;5185.662931909433;5189.2875351134035;5193.480651934795;5169.922451163236;5181.98396242911;5183.792648294064;5195.7402129893735;5200.603257992671;4693.6949098308505;4717.103750287434;4720.480747117815;4720.527947205252;4721.457032441244;6347.301796802348;6384.297727249998;6419.8271123769455;6438.871222575523;6465.418112369909;6142.907856382158;6158.784442544535;6166.198394208769;6166.414190651881;6204.503639708858;6928.307169283082;6950.714117888207;6962.1297337832775;6967.651617419108;6974.651267436608;5294.602904782157;5305.681659100466;5329.866753331159;5335.386430560497;5362.471695566784;7034.824129396755;7038.612876291853;7148.890232748771;7263.4915555910575;7279;5464.26232458621;5472.589555783332;5473.644213126134;5497.890042199134;5526.115821468546;5546.944530554642;5565.860853478614;5576.776928922797;5589.552835773128;5589.692566908859;6132.662703551258;6150.415710507495;6161.568690191733;6181.134641150243;6192.845178870564;6895.172579290517;6930.27232140618;6950.443964482829;6955.65221738911;6983;1846.8522518198513;1875.343629729611;1893.6686079935955;1912.7130930360556;1917.673995420782;1320.504810695989;1353.8357012968838;1360.3334366160707;1364.4746772492463;1390.0894913831457;6372.776952806629;6536.182977127859;6538.280789113193;6561.80314590563;6609.339066093352;6926.757563485254;7014.193367762773;7044.788656340318;7058.329458701415;7090.822729431702;6397.296297407282;6525.314841941623;6542.738290468423;6543.44380727634;6544.7382104716235;3701.6483434074257;3722.8696995605023;3727.4408838674417;3728.6576832340334;3736;7304.926950730531;7321.707131714672;7342.743003994835;7344.963275183697;7353.4484913631695;7135.4648401370105;7204.855902881913;7216.242294559101;7242.782716518407;7244.45666575009;7256.96371518139;7278.890816637755;7305.707771689179;7346.77959661211;7361.227071157451;7039.472039596948;7044.330788575282;7075;7077.82305442368;7123.323284287984;6938.895916561357;6982.895256571054;7010.544314619533;7038.401735852305;7084.504084714021;7094.290570942864;7203.279672032755;7224;7254.492185546961;7257.854842903218;5400.162974738922;5414.593905457185;5429.429909859408;5445.1832225166545;5463.399026107205;6807.9319206807295;6926.965365173343;6931;7021.122359705037;7057.7176912922905;6730.596164229966;6783.660816959034;6793.218779840382;6796.72813087471;6915.757948471779;6657.966710166418;6698.531102822755;6749.257581665915;6783.287754785676;6796.829345040832;6483.157189565326;6589.143411356493;6767.187937447341;6801.251862295045;6835.316468353125;6759.797206083826;6875;6919.169699636076;6974.407175389946;6994.440444764508;6642.568233064835;6715.496337774589;6725.8991115134295;6730.427913627396;6768.796936091925 15:56:51 INFO - PID 7825 | |5;yui.html;1000.824855650261;1032.1794173631963;1034.0848349210946;1035.3580779916454;1069.053887309731;21359.786402135975;21755.23856665017;21797;21799.455013624647;21805.127794888187;16388.098654573994;16511.504654860375;16886.746698799496;17706.20322085506;17877.7318340225;1067.1889364083295;1096.4956120184725;1097.30869552182;1098.0940723902772;1098.6154845803985;1096.7038899497131;1113.059464752283;1114.169943392174;1114.5931734916778;1115.2639258089662;1166.8833116688347;1176.9176157668967;1177.4407156600632;1177.640819550037;1177.6820258530192;1171.830084637727;1173.3312012153076;1174.8942595166477;1177.0936378988174;1177.3465726521777;852.910444403335;858.2876212743408;860.6600392844854;862.3446180902497;863.3611127765471;20785.896070519702;20975;21038.684419733716;21145;21226.36320910375;23680.171194008126;23683.763162368326;23847.403814904766;24077.87961060201;24104.276871693877;18997.43007709771;19021.809781902142;19166.329178478685;19189.136488858036;19271.22915083395;4021.7989100545146;4044.7573145611063;4066.3087275163293;4076.5515793262716;4079.8368065277355;164493.17753411276;166299;167046;168580;169020;17143.485695429157;17608.11959402025;17788.644227115386;17887.28450861977;17889.373871914682;35910.46134307988;36038.639613603526;36211.4568281476;36248.09379765474;36262.27475450529;9274.44353338802;9319.30105242096;9324.95337523308;9415.340926135104;9418.623255069857;4800.927986080142;4837.129316722953;4853.12643724126;4855.344528488679;4863.659543831898;6090.695465226721;6112.755489780359;6188.721507532082;6196.10156527301;6199;1176.9293842369486;1183.3668987091864;1200.7178313096415;1200.8498937632796;1201.2732296960387;774.1523032279645;778.0468925566137;788.8658927982257;789.3132974312385;795.5028107432854;13711.245881476416;14218.715625687428;14340.21128837903;14864;14901.180435075958;781.3905153979905;782.4992005116729;782.5695867272977;802.9237222463856;808.6199486241458;6381.936180638227;6549.967250163719;6571.31001244872;6604.240512341143;6621.569597976116;157623;161507;161675.19162404348;162235;166263;133604.08620904447;134848;136802;137697;139774;115214;116246;116704;117468;120518;12498.812517812423;12510.874891251335;12549.184303020274;12561.497540098659;12640.367981601066;58085;58461.4153858456;58858.41141588529;59055.11417328657;59388.40611593829;72435.63782181055;73857;74034;74071.62964185144;74736;17630.206640701457;18628.720569191744;18634.534136646693;18641.90679046596;18645.160967756754;19047.42857714271;19628.214871404984;19646.91941943207;19908;19942.20231190736;18696.252149914395;18711.064446777335;18932.526686832938;18958.336458223897;19056.523586909883;18951.241950321833;19039.52401189981;19095.713564296268;19317.613647726685;19380.12789424451;19082.141303641092;19411.611767764283;19519.60960780748;19647.508812279808;19739.013049347188;13087.280199589113;13338.599842004756;13437.924966002893;13530.391132398869;13536.729265414833;12301.815472768094;12412.937935310627;12517.436715347649;12649.747005059664;12672.493100275886 15:56:51 INFO - PID 7825 | __end_tp_report 15:56:51 INFO - PID 7825 | __start_cc_report 15:56:51 INFO - PID 7825 | _x_x_mozilla_cycle_collect,252 15:56:51 INFO - PID 7825 | __end_cc_report 15:56:51 INFO - PID 7825 | __startTimestamp1506380211889__endTimestamp 15:56:51 INFO - PID 7825 | 15:56:51 INFO - PID 7825 | ------- Summary: start ------- 15:56:51 INFO - PID 7825 | Number of tests: 6 15:56:51 INFO - PID 7825 | 15:56:51 INFO - PID 7825 | [#0] dojo.html Cycles:165 Average:6654.99 Median:6825.18 stddev:775.42 (11.4%) stddev-sans-first:747.29 15:56:51 INFO - PID 7825 | Values: 3909.6 3926.7 3990.7 3996.6 4002.4 6581.4 6620.0 6642.3 6656.4 6734.2 6589.6 6607.4 6617.8 6702.0 6747.6 5933.0 5941.1 5998.5 6027.4 6028.9 5912.2 5973.3 6056.6 6097.6 6125.2 6048.1 6099.5 6126.7 6134.2 6141.0 5967.0 6016.2 6050.2 6130.4 6163.0 5881.9 5902.7 5949.0 5951.6 5989.1 6519.1 6533.6 6564.6 6584.1 6628.8 7192.5 7195.8 7201.7 7307.7 7335.4 7248.3 7250.6 7310.5 7322.2 7329.9 6723.2 6749.5 6778.2 6816.4 6818.8 7331.7 7370.0 7388.3 7391.5 7434.1 7154.4 7164.7 7177.8 7192.5 7194.7 7236.5 7328.4 7356.0 7360.7 7394.0 7134.3 7156.6 7158.2 7176.4 7252.3 7113.3 7123.8 7223.2 7229.0 7298.6 5347.7 5372.4 5375.9 5380.8 5406.2 4841.4 4852.0 4870.8 4910.7 4931.0 6640.0 6661.6 6669.1 6732.1 6746.8 7175.8 7212.9 7260.4 7268.8 7277.3 6683.0 6719.2 6749.2 6763.2 6813.3 6067.0 6140.8 6234.3 6235.1 6236.5 7354.4 7390.2 7465.3 7480.0 7554.9 7221.4 7239.2 7254.5 7255.1 7297.6 7335.6 7359.5 7364.7 7431.0 7438.9 6995.5 7043.8 7095.1 7120.6 7206.7 7264.8 7270.4 7325.5 7346.5 7350.6 6813.9 6899.3 6985.5 6996.6 7015.5 6825.2 6881.9 6891.4 6903.7 6988.6 6810.5 6823.0 6911.3 6926.5 6963.3 6808.9 6913.9 6935.1 6968.9 7016.4 6769.7 6771.2 6771.8 6857.8 6865.9 15:56:51 INFO - PID 7825 | 15:56:51 INFO - PID 7825 | [#1] ext.html Cycles:180 Average:42761.94 Median:35641.05 stddev:65136.38 (182.8%) stddev-sans-first:65243.50 15:56:51 INFO - PID 7825 | Values: 966.4 967.5 980.8 1018.8 1020.5 43065.0 43495.0 43684.8 43774.1 43776.0 41688.0 42004.0 42021.2 42021.6 42050.6 8064.2 8209.3 8214.1 8222.3 8223.1 8065.6 8156.3 8159.2 8163.4 8164.3 8737.3 8952.1 8957.7 8960.6 8993.4 8867.2 8880.2 8930.2 8947.4 8958.2 8660.7 8706.8 8750.6 8760.8 8769.3 40731.4 40806.6 40912.6 40949.4 41174.0 3855.4 3889.4 3891.6 3895.1 3915.6 33602.7 33648.3 33651.2 33689.3 33720.2 5489.5 5644.0 5706.5 5713.8 5716.9 232406.0 232570.0 233033.0 233645.8 233741.0 52638.7 52950.7 52952.7 53114.2 53116.3 54781.5 54827.7 55032.0 55143.2 55184.7 17577.1 17803.9 17851.7 17858.7 17954.6 14685.9 14692.3 14696.0 14696.3 14729.7 15024.4 15305.7 15307.0 15387.0 15552.1 5899.2 5962.7 6131.3 6152.9 6173.4 1090.7 1100.3 1112.6 1113.0 1129.6 25211.0 25251.6 25401.5 25510.0 25703.6 1214.6 1231.9 1234.3 1248.3 1260.5 13114.2 13222.3 13239.7 13353.7 13360.3 268608.0 269265.0 273090.0 275451.0 276244.0 46824.0 47437.3 50348.7 51321.0 57131.0 1558.9 1562.5 1563.9 1583.6 1589.2 29871.4 30168.0 30177.8 30220.2 30550.0 39981.0 39983.0 40066.2 40068.4 40101.4 244653.0 245008.0 246479.0 246840.0 248136.0 35084.6 35194.0 35403.6 35556.5 35725.6 43335.1 44442.0 44948.3 45112.8 45241.0 33439.0 33719.7 33763.8 34036.7 34143.0 34234.7 34328.5 34365.7 34553.1 35131.8 40200.4 40345.0 40567.0 40703.4 40822.6 33635.2 33943.0 33989.5 34208.5 34360.5 25537.6 25644.4 25896.2 26154.2 26205.1 15:56:51 INFO - PID 7825 | 15:56:51 INFO - PID 7825 | [#2] jquery.html Cycles:180 Average:41818.96 Median:22602.65 stddev:108595.80 (480.5%) stddev-sans-first:108856.41 15:56:51 INFO - PID 7825 | Values: 632.6 633.5 640.7 642.1 644.9 23198.3 23909.8 23947.5 23972.4 24017.3 25492.1 25856.5 25997.1 26000.5 26054.6 6566.3 6722.9 6776.3 6792.1 6804.7 6776.7 6875.1 6875.9 6884.4 6884.6 6757.9 6840.5 6850.8 6853.8 6854.3 6987.1 7033.9 7036.3 7038.1 7041.8 6574.7 6587.0 6591.7 6594.1 6607.4 30791.7 31680.5 32027.7 32089.2 32121.0 2350.4 2405.3 2415.7 2418.8 2423.1 21078.5 21588.8 21607.0 21619.7 21667.2 2570.7 2602.0 2631.0 2655.1 2695.9 150768.0 157597.0 166436.0 172743.3 172959.0 17178.2 17222.5 17273.2 17449.1 17627.2 26944.7 27114.7 27237.3 27261.3 27269.3 10816.4 10993.3 11009.8 11263.9 11266.9 9850.1 9867.9 9915.1 9955.5 10158.3 10052.9 10152.3 10228.4 10448.0 10650.4 2898.9 2914.7 3020.8 3085.3 3104.3 1012.0 1016.3 1026.0 1041.8 1048.9 19303.0 20819.6 20994.1 21247.8 21350.4 1105.3 1107.8 1108.8 1118.0 1119.6 11010.5 11093.6 11121.2 11248.5 11357.3 633337.0 640431.0 640638.0 654548.0 674187.0 129296.0 138158.3 138635.3 140005.0 140658.0 1109.1 1200.4 1212.7 1250.9 1258.9 21618.6 21702.2 21837.5 21958.5 22007.0 61893.7 62302.4 62520.7 62999.4 63139.7 77570.0 78061.0 79272.2 82699.6 83204.6 15941.3 16295.4 16311.3 16317.7 16342.0 24166.6 24396.6 24651.6 24669.8 24727.0 20032.7 20063.0 20228.4 20339.4 20356.2 17206.7 17983.5 17994.6 18086.4 18092.8 20188.8 20696.6 20809.6 20870.4 20912.0 15890.1 15920.8 15986.9 16039.5 16082.0 17875.5 18311.0 18330.0 18345.2 19966.5 15:56:51 INFO - PID 7825 | 15:56:51 INFO - PID 7825 | [#3] mootools.html Cycles:180 Average:10834.63 Median:2913.08 stddev:8218.99 (282.1%) stddev-sans-first:8211.96 15:56:51 INFO - PID 7825 | Values: 1473.3 1512.1 1534.6 1539.7 1557.5 17351.7 18029.5 18041.4 18063.1 18081.2 17216.0 17248.2 17315.8 17372.3 17400.0 2932.8 2933.6 2990.5 2999.1 3006.7 2870.3 2907.5 2918.7 2920.4 2920.5 2798.2 2918.7 2958.1 2981.8 3015.3 3051.3 3075.2 3079.0 3079.1 3080.9 2703.5 2729.6 2867.3 2922.6 2956.3 17359.4 17477.3 17518.8 17522.6 17522.9 8503.7 8771.4 8781.2 8786.8 8805.6 5373.5 5544.6 5695.1 5767.9 5771.3 2137.8 2159.3 2165.6 2183.4 2213.6 31549.7 31756.7 31797.4 31832.2 31979.0 7075.3 7121.5 7127.6 7276.3 7296.1 20561.9 20638.7 20668.0 20733.2 20820.4 8533.4 8649.4 8675.6 8704.4 8738.4 7517.5 7585.6 7620.1 7677.2 7746.1 5848.5 5908.9 5938.6 6028.1 6124.8 1642.0 1667.3 1668.6 1669.1 1705.9 932.8 971.3 972.2 978.1 980.3 8347.2 8409.8 8457.1 8457.4 8488.3 803.2 815.9 817.1 825.8 829.6 5324.3 5767.0 5827.9 5881.9 6003.5 26508.6 26951.5 27166.2 27195.7 27521.0 25740.9 26823.0 27049.6 27064.5 27095.5 12433.6 13027.5 13168.1 13172.5 13193.2 9361.3 9407.1 9462.9 9466.1 9515.9 21566.4 21727.7 21875.3 22086.0 22606.5 23339.5 23380.3 23765.2 23774.8 24792.3 12907.0 13180.1 13208.5 13213.4 13269.0 16290.3 16339.5 16355.1 16580.2 16585.3 13008.5 13070.2 13081.3 13190.8 13453.5 12855.6 12929.5 12962.5 13001.3 13027.7 10377.2 10493.9 10709.9 10712.3 10735.4 8240.1 8370.4 8400.4 8404.8 8501.0 7855.6 8347.6 8349.0 8381.3 8417.6 15:56:51 INFO - PID 7825 | 15:56:51 INFO - PID 7825 | [#4] prototype.html Cycles:180 Average:5888.51 Median:6543.09 stddev:1635.88 (25.0%) stddev-sans-first:1590.79 15:56:51 INFO - PID 7825 | Values: 557.8 576.7 593.5 601.9 608.1 6512.2 6590.3 6590.6 6597.4 6620.8 6505.6 6508.0 6534.5 6544.1 6556.5 5286.3 5317.3 5358.0 5371.9 5399.6 5067.5 5276.6 5288.7 5307.7 5375.2 5167.5 5182.0 5185.7 5189.3 5193.5 5169.9 5182.0 5183.8 5195.7 5200.6 4693.7 4717.1 4720.5 4720.5 4721.5 6347.3 6384.3 6419.8 6438.9 6465.4 6142.9 6158.8 6166.2 6166.4 6204.5 6928.3 6950.7 6962.1 6967.7 6974.7 5294.6 5305.7 5329.9 5335.4 5362.5 7034.8 7038.6 7148.9 7263.5 7279.0 5464.3 5472.6 5473.6 5497.9 5526.1 5546.9 5565.9 5576.8 5589.6 5589.7 6132.7 6150.4 6161.6 6181.1 6192.8 6895.2 6930.3 6950.4 6955.7 6983.0 1846.9 1875.3 1893.7 1912.7 1917.7 1320.5 1353.8 1360.3 1364.5 1390.1 6372.8 6536.2 6538.3 6561.8 6609.3 6926.8 7014.2 7044.8 7058.3 7090.8 6397.3 6525.3 6542.7 6543.4 6544.7 3701.6 3722.9 3727.4 3728.7 3736.0 7304.9 7321.7 7342.7 7345.0 7353.4 7135.5 7204.9 7216.2 7242.8 7244.5 7257.0 7278.9 7305.7 7346.8 7361.2 7039.5 7044.3 7075.0 7077.8 7123.3 6938.9 6982.9 7010.5 7038.4 7084.5 7094.3 7203.3 7224.0 7254.5 7257.9 5400.2 5414.6 5429.4 5445.2 5463.4 6807.9 6927.0 6931.0 7021.1 7057.7 6730.6 6783.7 6793.2 6796.7 6915.8 6658.0 6698.5 6749.3 6783.3 6796.8 6483.2 6589.1 6767.2 6801.3 6835.3 6759.8 6875.0 6919.2 6974.4 6994.4 6642.6 6715.5 6725.9 6730.4 6768.8 15:56:51 INFO - PID 7825 | 15:56:51 INFO - PID 7825 | [#5] yui.html Cycles:180 Average:29459.47 Median:19009.62 stddev:44436.66 (233.8%) stddev-sans-first:44509.94 15:56:51 INFO - PID 7825 | Values: 1000.8 1032.2 1034.1 1035.4 1069.1 21359.8 21755.2 21797.0 21799.5 21805.1 16388.1 16511.5 16886.7 17706.2 17877.7 1067.2 1096.5 1097.3 1098.1 1098.6 1096.7 1113.1 1114.2 1114.6 1115.3 1166.9 1176.9 1177.4 1177.6 1177.7 1171.8 1173.3 1174.9 1177.1 1177.3 852.9 858.3 860.7 862.3 863.4 20785.9 20975.0 21038.7 21145.0 21226.4 23680.2 23683.8 23847.4 24077.9 24104.3 18997.4 19021.8 19166.3 19189.1 19271.2 4021.8 4044.8 4066.3 4076.6 4079.8 164493.2 166299.0 167046.0 168580.0 169020.0 17143.5 17608.1 17788.6 17887.3 17889.4 35910.5 36038.6 36211.5 36248.1 36262.3 9274.4 9319.3 9325.0 9415.3 9418.6 4800.9 4837.1 4853.1 4855.3 4863.7 6090.7 6112.8 6188.7 6196.1 6199.0 1176.9 1183.4 1200.7 1200.8 1201.3 774.2 778.0 788.9 789.3 795.5 13711.2 14218.7 14340.2 14864.0 14901.2 781.4 782.5 782.6 802.9 808.6 6381.9 6550.0 6571.3 6604.2 6621.6 157623.0 161507.0 161675.2 162235.0 166263.0 133604.1 134848.0 136802.0 137697.0 139774.0 115214.0 116246.0 116704.0 117468.0 120518.0 12498.8 12510.9 12549.2 12561.5 12640.4 58085.0 58461.4 58858.4 59055.1 59388.4 72435.6 73857.0 74034.0 74071.6 74736.0 17630.2 18628.7 18634.5 18641.9 18645.2 19047.4 19628.2 19646.9 19908.0 19942.2 18696.3 18711.1 18932.5 18958.3 19056.5 18951.2 19039.5 19095.7 19317.6 19380.1 19082.1 19411.6 19519.6 19647.5 19739.0 13087.3 13338.6 13437.9 13530.4 13536.7 12301.8 12412.9 12517.4 12649.7 12672.5 15:56:51 INFO - PID 7825 | -------- Summary: end -------- 15:56:51 INFO - PID 7825 | 15:56:52 INFO - TEST-INFO | 7825: exit 0 15:56:52 INFO - TEST-OK | dromaeo_css | took 1106136ms 15:56:52 INFO - TEST-START | kraken 15:56:52 INFO - Initialising browser for kraken test... 15:56:52 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:52658/getInfo.html -profile /tmp/tmpR_Y1ta/profile 15:56:52 INFO - TEST-INFO | started process 8192 (/builds/slave/test/build/application/firefox/firefox http://localhost:52658/getInfo.html) 15:56:57 INFO - TEST-INFO | 8192: exit 0 15:56:57 INFO - Browser initialized. 15:56:57 INFO - Running cycle 1/1 for kraken test... 15:56:57 INFO - Using env: {'DISPLAY': ':0', 15:56:57 INFO - 'HOME': '/home/cltbld', 15:56:57 INFO - 'JSGC_DISABLE_POISONING': '1', 15:56:57 INFO - 'LANG': 'en_US.UTF-8', 15:56:57 INFO - 'LANGUAGE': 'en_US:en', 15:56:57 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 15:56:57 INFO - 'LOGNAME': 'cltbld', 15:56:57 INFO - 'MAIL': '/var/mail/cltbld', 15:56:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:56:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:56:57 INFO - 'MOZ_CRASHREPORTER': '1', 15:56:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:56:57 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 15:56:57 INFO - 'MOZ_NO_REMOTE': '1', 15:56:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:56:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:56:57 INFO - 'NO_EM_RESTART': '1', 15:56:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:56:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:56:57 INFO - 'PWD': '/builds/slave/test', 15:56:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:56:57 INFO - 'RUST_BACKTRACE': 'full', 15:56:57 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:56:57 INFO - 'SHELL': '/bin/bash', 15:56:57 INFO - 'SHLVL': '1', 15:56:57 INFO - 'TERM': 'linux', 15:56:57 INFO - 'TMOUT': '86400', 15:56:57 INFO - 'USER': 'cltbld', 15:56:57 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1506368044.582502-831625406', 15:56:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:56:57 INFO - '_': '/tools/buildbot/bin/python'} 15:56:57 INFO - TEST-INFO | started process 8378 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpR_Y1ta/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 15:56:57 INFO - PID 8378 | 15:56:57 INFO - PID 8378 | (/builds/slave/test/build/application/firefox/firefox:8429): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 15:56:57 INFO - PID 8378 | 15:57:08 INFO - PID 8378 | RSS: Main: 143695872 15:57:08 INFO - PID 8378 | 15:57:10 INFO - PID 8378 | Cycle 1(1): loaded http://localhost:52658/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52658/tests/kraken/driver.html?testName=audio-beat-detection) 15:57:11 INFO - PID 8378 | RSS: Main: 150327296 15:57:11 INFO - PID 8378 | 15:57:14 INFO - PID 8378 | Cycle 1(1): loaded http://localhost:52658/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52658/tests/kraken/driver.html?testName=audio-dft) 15:57:14 INFO - PID 8378 | RSS: Main: 151465984 15:57:14 INFO - PID 8378 | 15:57:17 INFO - PID 8378 | Cycle 1(1): loaded http://localhost:52658/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52658/tests/kraken/driver.html?testName=audio-fft) 15:57:18 INFO - PID 8378 | RSS: Main: 152784896 15:57:18 INFO - PID 8378 | 15:57:20 INFO - PID 8378 | Cycle 1(1): loaded http://localhost:52658/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52658/tests/kraken/driver.html?testName=audio-oscillator) 15:57:20 INFO - PID 8378 | RSS: Main: 152473600 15:57:20 INFO - PID 8378 | 15:57:22 INFO - PID 8378 | Cycle 1(1): loaded http://localhost:52658/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52658/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:57:23 INFO - PID 8378 | RSS: Main: 152166400 15:57:23 INFO - PID 8378 | 15:57:26 INFO - PID 8378 | Cycle 1(1): loaded http://localhost:52658/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52658/tests/kraken/driver.html?testName=imaging-darkroom) 15:57:27 INFO - PID 8378 | RSS: Main: 152522752 15:57:27 INFO - PID 8378 | 15:57:30 INFO - PID 8378 | Cycle 1(1): loaded http://localhost:52658/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52658/tests/kraken/driver.html?testName=imaging-desaturate) 15:57:31 INFO - PID 8378 | RSS: Main: 151871488 15:57:31 INFO - PID 8378 | 15:57:34 INFO - PID 8378 | Cycle 1(1): loaded http://localhost:52658/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52658/tests/kraken/driver.html?testName=json-parse-financial) 15:57:34 INFO - PID 8378 | RSS: Main: 153251840 15:57:34 INFO - PID 8378 | 15:57:36 INFO - PID 8378 | Cycle 1(1): loaded http://localhost:52658/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52658/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:57:36 INFO - PID 8378 | RSS: Main: 153255936 15:57:36 INFO - PID 8378 | 15:57:38 INFO - PID 8378 | Cycle 1(1): loaded http://localhost:52658/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52658/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:57:39 INFO - PID 8378 | RSS: Main: 153239552 15:57:39 INFO - PID 8378 | 15:57:40 INFO - PID 8378 | Cycle 1(1): loaded http://localhost:52658/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52658/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:57:41 INFO - PID 8378 | RSS: Main: 150257664 15:57:41 INFO - PID 8378 | 15:57:44 INFO - PID 8378 | Cycle 1(1): loaded http://localhost:52658/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52658/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:57:44 INFO - PID 8378 | RSS: Main: 150274048 15:57:44 INFO - PID 8378 | 15:57:47 INFO - PID 8378 | Cycle 1(1): loaded http://localhost:52658/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52658/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:57:47 INFO - PID 8378 | RSS: Main: 151244800 15:57:47 INFO - PID 8378 | 15:57:49 INFO - PID 8378 | Cycle 1(1): loaded http://localhost:52658/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52658/tests/kraken/driver.html?testName=ai-astar) 15:57:49 INFO - PID 8378 | RSS: Main: 152576000 15:57:49 INFO - PID 8378 | 15:57:49 INFO - PID 8378 | __start_tp_report 15:57:49 INFO - PID 8378 | _x_x_mozilla_page_load 15:57:49 INFO - PID 8378 | _x_x_mozilla_page_load_details 15:57:49 INFO - PID 8378 | |i|pagename|runs| 15:57:49 INFO - PID 8378 | |0;ai-astar;104;106;105;106;105;105;105;106;104;108 15:57:49 INFO - PID 8378 | |1;audio-beat-detection;120;114;113;138;113;113;112;117;117;139 15:57:49 INFO - PID 8378 | |2;audio-dft;164;164;164;163;161;162;163;162;162;160 15:57:49 INFO - PID 8378 | |3;audio-fft;73;72;78;73;74;75;73;75;74;77 15:57:49 INFO - PID 8378 | |4;audio-oscillator;78;78;78;79;78;77;129;81;83;81 15:57:49 INFO - PID 8378 | |5;imaging-gaussian-blur;114;114;114;114;115;114;113;116;116;113 15:57:49 INFO - PID 8378 | |6;imaging-darkroom;149;150;148;149;149;150;148;149;151;150 15:57:49 INFO - PID 8378 | |7;imaging-desaturate;94;94;94;94;95;94;94;95;94;95 15:57:49 INFO - PID 8378 | |8;json-parse-financial;43;42;43;44;44;43;43;43;44;42 15:57:49 INFO - PID 8378 | |9;json-stringify-tinderbox;39;39;39;38;39;38;38;38;38;38 15:57:49 INFO - PID 8378 | |10;stanford-crypto-aes;75;81;70;69;71;70;70;74;72;70 15:57:49 INFO - PID 8378 | |11;stanford-crypto-ccm;100;105;106;102;101;104;102;108;105;103 15:57:49 INFO - PID 8378 | |12;stanford-crypto-pbkdf2;157;160;155;148;147;150;147;148;152;149 15:57:49 INFO - PID 8378 | |13;stanford-crypto-sha256-iterative;60;59;58;59;57;58;56;57;59;63 15:57:49 INFO - PID 8378 | __end_tp_report 15:57:49 INFO - PID 8378 | __start_cc_report 15:57:49 INFO - PID 8378 | _x_x_mozilla_cycle_collect,683 15:57:49 INFO - PID 8378 | __end_cc_report 15:57:49 INFO - PID 8378 | __startTimestamp1506380269820__endTimestamp 15:57:49 INFO - PID 8378 | 15:57:49 INFO - PID 8378 | ------- Summary: start ------- 15:57:49 INFO - PID 8378 | Number of tests: 14 15:57:49 INFO - PID 8378 | 15:57:49 INFO - PID 8378 | [#0] ai-astar Cycles:10 Average:105.40 Median:105.50 stddev:1.17 (1.1%) stddev-sans-first:1.13 15:57:49 INFO - PID 8378 | Values: 104.0 106.0 105.0 106.0 105.0 105.0 105.0 106.0 104.0 108.0 15:57:49 INFO - PID 8378 | 15:57:49 INFO - PID 8378 | [#1] audio-beat-detection Cycles:10 Average:119.60 Median:117.00 stddev:10.27 (8.8%) stddev-sans-first:10.89 15:57:49 INFO - PID 8378 | Values: 120.0 114.0 113.0 138.0 113.0 113.0 112.0 117.0 117.0 139.0 15:57:49 INFO - PID 8378 | 15:57:49 INFO - PID 8378 | [#2] audio-dft Cycles:10 Average:162.50 Median:163.00 stddev:1.35 (0.8%) stddev-sans-first:1.32 15:57:49 INFO - PID 8378 | Values: 164.0 164.0 164.0 163.0 161.0 162.0 163.0 162.0 162.0 160.0 15:57:49 INFO - PID 8378 | 15:57:49 INFO - PID 8378 | [#3] audio-fft Cycles:10 Average:74.40 Median:74.50 stddev:1.90 (2.5%) stddev-sans-first:1.94 15:57:49 INFO - PID 8378 | Values: 73.0 72.0 78.0 73.0 74.0 75.0 73.0 75.0 74.0 77.0 15:57:49 INFO - PID 8378 | 15:57:49 INFO - PID 8378 | [#4] audio-oscillator Cycles:10 Average:84.20 Median:78.50 stddev:15.85 (20.2%) stddev-sans-first:16.65 15:57:49 INFO - PID 8378 | Values: 78.0 78.0 78.0 79.0 78.0 77.0 129.0 81.0 83.0 81.0 15:57:49 INFO - PID 8378 | 15:57:49 INFO - PID 8378 | [#5] imaging-gaussian-blur Cycles:10 Average:114.30 Median:114.00 stddev:1.06 (0.9%) stddev-sans-first:1.12 15:57:49 INFO - PID 8378 | Values: 114.0 114.0 114.0 114.0 115.0 114.0 113.0 116.0 116.0 113.0 15:57:49 INFO - PID 8378 | 15:57:49 INFO - PID 8378 | [#6] imaging-darkroom Cycles:10 Average:149.30 Median:149.50 stddev:0.95 (0.6%) stddev-sans-first:1.00 15:57:49 INFO - PID 8378 | Values: 149.0 150.0 148.0 149.0 149.0 150.0 148.0 149.0 151.0 150.0 15:57:49 INFO - PID 8378 | 15:57:49 INFO - PID 8378 | [#7] imaging-desaturate Cycles:10 Average:94.30 Median:94.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 15:57:49 INFO - PID 8378 | Values: 94.0 94.0 94.0 94.0 95.0 94.0 94.0 95.0 94.0 95.0 15:57:49 INFO - PID 8378 | 15:57:49 INFO - PID 8378 | [#8] json-parse-financial Cycles:10 Average:43.10 Median:43.00 stddev:0.74 (1.7%) stddev-sans-first:0.78 15:57:49 INFO - PID 8378 | Values: 43.0 42.0 43.0 44.0 44.0 43.0 43.0 43.0 44.0 42.0 15:57:49 INFO - PID 8378 | 15:57:49 INFO - PID 8378 | [#9] json-stringify-tinderbox Cycles:10 Average:38.40 Median:38.50 stddev:0.52 (1.3%) stddev-sans-first:0.50 15:57:49 INFO - PID 8378 | Values: 39.0 39.0 39.0 38.0 39.0 38.0 38.0 38.0 38.0 38.0 15:57:49 INFO - PID 8378 | 15:57:49 INFO - PID 8378 | [#10] stanford-crypto-aes Cycles:10 Average:72.20 Median:71.50 stddev:3.65 (5.1%) stddev-sans-first:3.72 15:57:49 INFO - PID 8378 | Values: 75.0 81.0 70.0 69.0 71.0 70.0 70.0 74.0 72.0 70.0 15:57:49 INFO - PID 8378 | 15:57:49 INFO - PID 8378 | [#11] stanford-crypto-ccm Cycles:10 Average:103.60 Median:104.50 stddev:2.46 (2.4%) stddev-sans-first:2.24 15:57:49 INFO - PID 8378 | Values: 100.0 105.0 106.0 102.0 101.0 104.0 102.0 108.0 105.0 103.0 15:57:49 INFO - PID 8378 | 15:57:49 INFO - PID 8378 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:151.30 Median:151.00 stddev:4.57 (3.0%) stddev-sans-first:4.36 15:57:49 INFO - PID 8378 | Values: 157.0 160.0 155.0 148.0 147.0 150.0 147.0 148.0 152.0 149.0 15:57:49 INFO - PID 8378 | 15:57:49 INFO - PID 8378 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:58.60 Median:59.00 stddev:1.96 (3.3%) stddev-sans-first:2.01 15:57:49 INFO - PID 8378 | Values: 60.0 59.0 58.0 59.0 57.0 58.0 56.0 57.0 59.0 63.0 15:57:49 INFO - PID 8378 | -------- Summary: end -------- 15:57:49 INFO - PID 8378 | 15:57:50 INFO - PID 8378 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 15:57:50 INFO - TEST-INFO | 8378: exit 0 15:57:50 INFO - TEST-OK | kraken | took 57879ms 15:57:50 INFO - SUITE-END | took 1164s 15:57:50 INFO - Completed test suite (00:19:24) 15:57:50 INFO - javascript benchmark 15:57:50 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9380.922501071935, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3909.609039096091, 3926.7251292409524, 3990.660793832526, 3996.5803590622995, 4002.4396584478195, 6581.407673309402, 6620.04009418634, 6642.302558231377, 6656.434203092743, 6734.158230221223, 6589.60462372258, 6607.372299631527, 6617.8014659560295, 6702.02820591014, 6747.628880411576, 5932.970335148319, 5941.079132734411, 5998.4901283390745, 6027.39726027396, 6028.939710602893, 5912.17229587858, 5973.342932277467, 6056.576039677225, 6097.57316987811, 6125.17310163129, 6048.123022161784, 6099.542534309922, 6126.663033533154, 6134.171886795292, 6140.969295153518, 5967.0, 6016.2479690038745, 6050.2437195350585, 6130.386961303856, 6163.044728067157, 5881.85295367615, 5902.734376953004, 5949.0, 5951.58338916276, 5989.071693887455, 6519.054737063099, 6533.640649764261, 6564.638944858031, 6584.11114499546, 6628.767993120217, 7192.532485388433, 7195.8201044973775, 7201.675924583406, 7307.707691692326, 7335.449841261873, 7248.311410416002, 7250.601216933067, 7310.488265821395, 7322.157951835554, 7329.853402931964, 6723.193216814014, 6749.493787965824, 6778.22050464198, 6816.420604248645, 6818.761343352959, 7331.667346999812, 7370.0, 7388.335049845432, 7391.48259621832, 7434.145073316529, 7154.35610795031, 7164.713411463483, 7177.820554486075, 7192.496525243288, 7194.748183813542, 7236.457265705093, 7328.377087947584, 7356.0, 7360.668769905367, 7394.038774959221, 7134.2509036551455, 7156.606386648784, 7158.2125966143685, 7176.425885929113, 7252.274772522811, 7113.253108423541, 7123.7506687265695, 7223.169335526377, 7229.024081749002, 7298.598577078312, 5347.732613369316, 5372.409035006146, 5375.919361209585, 5380.838574842761, 5406.162044883049, 4841.379927513527, 4852.0, 4870.806652370192, 4910.656254062182, 4930.950690493121, 6639.966800166065, 6661.600303981777, 6669.099671544376, 6732.091167692397, 6746.797596072126, 7175.784726458214, 7212.855742885218, 7260.382867456326, 7268.781936541912, 7277.345038946521, 6682.966585167043, 6719.227288861844, 6749.190097188369, 6763.222229443579, 6813.284605116491, 6067.0, 6140.846478838065, 6234.2830574484515, 6235.095911092957, 6236.501079913596, 7354.448416368794, 7390.150132734697, 7465.328120469184, 7479.962600186965, 7554.924450755422, 7221.350078492961, 7239.239879812754, 7254.528455650471, 7255.09311336083, 7297.635118244173, 7335.559866408033, 7359.521631093961, 7364.705411783469, 7430.96284518574, 7438.888416673863, 6995.475339349671, 7043.823904402431, 7095.148582170173, 7120.643967801486, 7206.7117315306805, 7264.782056538313, 7270.418366530771, 7325.523840950321, 7346.485745997729, 7350.632468376667, 6813.931860681329, 6899.275576064641, 6985.545939513915, 6996.615186164606, 7015.543989640657, 6825.180978282638, 6881.862362752817, 6891.448684105159, 6903.654817259217, 6988.615626140408, 6810.489213308922, 6822.965885170741, 6911.274316196928, 6926.515143939876, 6963.338482844122, 6808.863822723617, 6913.930860691329, 6935.098437203132, 6968.895466567904, 7016.36852683261, 6769.695363708546, 6771.187457505131, 6771.796846094625, 6857.828554286183, 6865.897011544731], "value": 6602.28158640657, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [966.4008314844793, 967.5017366056478, 980.8038392321536, 1018.7554986803165, 1020.5356562763941, 43065.0, 43495.0, 43684.781576092115, 43774.124517509714, 43776.0, 41688.0, 42004.0, 42021.159576808444, 42021.57978420215, 42050.57949420505, 8064.193580641918, 8209.343252539813, 8214.13751556087, 8222.301104406133, 8223.05434874988, 8065.556394398306, 8156.265936065753, 8159.184081591853, 8163.38774591905, 8164.346852251806, 8737.344699147588, 8952.060033696465, 8957.686480973167, 8960.551972401387, 8993.415427997192, 8867.246284065863, 8880.245179159747, 8930.19628233459, 8947.418417802823, 8958.23854972328, 8660.740177794676, 8706.825863482758, 8750.606222719993, 8760.780980475476, 8769.254613357873, 40731.38902916459, 40806.59193408058, 40912.59087409118, 40949.385759213634, 41174.0, 3855.363864962278, 3889.3971434427426, 3891.610838916086, 3895.1235971906376, 3915.6280153385383, 33602.6639733602, 33648.32703345944, 33651.15872103217, 33689.32621347559, 33720.15699607505, 5489.505944465017, 5643.971780141073, 5706.4864162225595, 5713.771449142029, 5716.8856622867725, 232406.0, 232570.0, 233033.0, 233645.83177084176, 233741.0, 52638.73680631649, 52950.73524632352, 52952.73523632357, 53114.203286950724, 53116.28186027018, 54781.45218547843, 54827.72586137044, 55032.0, 55143.172852407246, 55184.72407638017, 17577.121143943008, 17803.910980445275, 17851.73222401665, 17858.732119018227, 17954.5511362217, 14685.926570367079, 14692.338844752012, 14696.0, 14696.26518674062, 14729.705405891822, 15024.399024038916, 15305.693886122215, 15307.0, 15386.999845010263, 15552.066875987477, 5899.233099697098, 5962.672053037038, 6131.294901086343, 6152.8769424611255, 6173.382661733791, 1090.6837017265063, 1100.2518287564533, 1112.6439539347493, 1112.9610463633735, 1129.5594718059963, 25211.0, 25251.62122568163, 25401.491970160863, 25510.0, 25703.61444578333, 1214.6173955204083, 1231.907606929466, 1234.3457967277357, 1248.3134276148082, 1260.4769020856243, 13114.213147211198, 13222.338883056002, 13239.73520529584, 13353.73292534144, 13360.2651854147, 268608.0, 269265.0, 273089.9898079501, 275451.0, 276244.0, 46824.0, 47437.288440673416, 50348.748256258485, 51321.0, 57131.0, 1558.8986715863432, 1562.4687606213943, 1563.9061656300657, 1583.588267050552, 1589.2212815720445, 29871.402571948875, 30168.0, 30177.849110754305, 30220.24449388783, 30550.0, 39981.0, 39983.0, 40066.1986760269, 40068.39897401483, 40101.39847902225, 244653.0, 245008.0, 246479.0, 246840.0, 248136.0, 35084.64915350916, 35194.0, 35403.64596354003, 35556.466653000745, 35725.64274357327, 43335.13329733325, 44442.0, 44948.325775112746, 45112.77443612892, 45241.0, 33439.0, 33719.66280337263, 33763.83118084394, 34036.659633403346, 34143.0, 34234.65765342315, 34328.48507272343, 34365.656343437244, 34553.136171595914, 35131.824340878135, 40200.39699404453, 40345.0, 40567.0, 40703.38944915888, 40822.59177408188, 33635.15912102217, 33943.0, 33989.49015764716, 34208.486872697424, 34360.48459273163, 25537.61693574635, 25644.358891027874, 25896.22311330663, 26154.215373538824, 26205.082822101136], "value": 18857.014485042626, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [632.6223542589039, 633.4750148019427, 640.6792368322402, 642.0530060198575, 644.9301325689718, 23198.304050878458, 23909.76090239097, 23947.52104957904, 23972.400689982736, 24017.279481615536, 25492.107776227836, 25856.48287034258, 25997.09010184644, 26000.47999040018, 26054.609180862302, 6566.34336566342, 6722.932770672292, 6776.2546119926765, 6792.083068785725, 6804.727810887559, 6776.661166941658, 6875.071865298196, 6875.862482750342, 6884.414824739903, 6884.586924784504, 6757.932420675804, 6840.486963477759, 6850.7602233921825, 6853.760118395857, 6854.280300568418, 6987.1266091738535, 7033.859322813566, 7036.331548502884, 7038.08504894363, 7041.823954401129, 6574.704138313788, 6587.044878492639, 6591.7363305467725, 6594.142761440982, 6607.372299631527, 30791.692083079106, 31680.524792128137, 32027.679723202702, 32089.197770055704, 32121.0, 2350.4476448034698, 2405.338531903723, 2415.7221919479307, 2418.7944024757917, 2423.127674037345, 21078.473038174016, 21588.784112158835, 21607.0, 21619.67570486444, 21667.241646542294, 2570.730073342291, 2601.9609705854427, 2630.973690263092, 2655.0574546036264, 2695.8517281549507, 150768.0, 157597.0, 166436.0, 172743.27256727396, 172959.0, 17178.16560267567, 17222.483325500005, 17273.222704978307, 17449.12754362302, 17627.206775695126, 26944.730552694222, 27114.72885271122, 27237.319067023087, 27261.318467038087, 27269.318267043484, 10816.351018938889, 10993.340399576051, 11009.834852477059, 11263.887361126448, 11266.929641684026, 9850.113489785954, 9867.851982220272, 9915.107640312262, 9955.502224888727, 10158.273093573962, 10052.899471005343, 10152.340097893613, 10228.437435940947, 10448.0, 10650.414227217423, 2898.855057247129, 2914.7231013053724, 3020.788544801843, 3085.2595377109783, 3104.2549788050706, 1011.9797604047877, 1016.2835201183149, 1025.9692209233735, 1041.7656027393775, 1048.8688913885765, 19303.034848257532, 20819.58360832775, 20994.055267513, 21247.78752212489, 21350.35948921535, 1105.2870898270571, 1107.7839821234782, 1108.8281316395971, 1118.032901540162, 1119.6081371520065, 11010.50452729629, 11093.5562577496, 11121.165912556591, 11248.493817778219, 11357.318560886373, 633337.0, 640431.0, 640638.0, 654548.0, 674187.0, 129296.0, 138158.3092084533, 138635.30682346725, 140005.0, 140658.0, 1109.0628418985943, 1200.363807182195, 1212.678640160358, 1250.9499620015097, 1258.930758808256, 21618.567628647026, 21702.2404215858, 21837.454063648536, 21958.45103872352, 22007.0, 61893.69053154706, 62302.37697622966, 62520.68739656454, 62999.37000629935, 63139.68430158003, 77570.0, 78061.0, 79272.20727792649, 82699.5865020695, 83204.58397707973, 15941.282642280894, 16295.429659961845, 16311.26599303058, 16317.67364652724, 16342.0, 24166.63750043786, 24396.63405048961, 24651.630225546272, 24669.753302466746, 24727.0, 20032.69950950766, 20063.0, 20228.393148205578, 20339.389818305473, 20356.18575256973, 17206.741898871278, 17983.460496185136, 17994.5501362467, 18086.366977155736, 18092.819071809114, 20188.798112019278, 20696.586068278248, 20809.583808324052, 20870.37388878336, 20912.0, 15890.126043067743, 15920.8407915924, 15986.920065399598, 16039.518814435585, 16082.035077895363, 17875.46373608742, 18311.0, 18330.0, 18345.174467149278, 19966.500837478598], "value": 13130.462252863483, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1473.3443617590174, 1512.115412483697, 1534.5549790560738, 1539.6689711711979, 1557.5015994881628, 17351.739723904153, 18029.459116226535, 18041.368552100677, 18063.09684515774, 18081.186346614402, 17215.96704197749, 17248.22382992765, 17315.82684173161, 17372.305107795673, 17400.0, 2932.7800414968856, 2933.5746316784052, 2990.5364668476423, 2999.0852789899072, 3006.669266380696, 2870.2680816391894, 2907.4766542022535, 2918.6789453160136, 2920.4159168166348, 2920.45971495273, 2798.244473992021, 2918.708129187075, 2958.1421387797513, 2981.791274610778, 3015.2914065194664, 3051.2829485070993, 3075.154332558542, 3079.0, 3079.13784140441, 3080.938381232374, 2703.540398132322, 2729.604207389937, 2867.2545138263995, 2922.649282086142, 2956.2609347663083, 17359.392421265195, 17477.300907963665, 17518.773685841996, 17522.561935951577, 17522.912385438118, 8503.659853605848, 8771.386002979794, 8781.209691127766, 8786.780330491725, 8805.559722013873, 5373.5163835254625, 5544.63959842609, 5695.14572814077, 5767.913481297784, 5771.307443106812, 2137.764845866969, 2159.287435146398, 2165.566886622666, 2183.366823621148, 2213.6458166693415, 31549.684503154906, 31756.682433175603, 31797.364052718814, 31832.204194895083, 31979.0, 7075.292470752884, 7121.465890058266, 7127.643617819192, 7276.308750668678, 7296.124465064227, 20561.89719051395, 20638.690419643415, 20668.0, 20733.170673172903, 20820.37538873806, 8533.402661813738, 8649.3512986525, 8675.56622168889, 8704.390692651581, 8738.432001919855, 7517.473776835569, 7585.58279294633, 7620.123685776096, 7677.193894641094, 7746.109197442366, 5848.473637372657, 5908.940910590925, 5938.6436813791315, 6028.125921741323, 6124.785632502841, 1641.967160656804, 1667.2997341116761, 1668.649583587436, 1669.090345761563, 1705.9061751603533, 932.7574830544107, 971.3491960386417, 972.1882228339274, 978.0512902484721, 980.3382716666222, 8347.248747612744, 8409.789755256044, 8457.112003239572, 8457.365697572706, 8488.278496327881, 803.2489622203259, 815.8898548695969, 817.1134319263547, 825.793551612097, 829.6142293833396, 5324.281222035053, 5766.971165144148, 5827.88344233113, 5881.911771323348, 6003.519718422603, 26508.60237096445, 26951.460970780474, 27166.1850144496, 27195.728042719715, 27521.0, 25740.871295643403, 26823.0, 27049.59425608578, 27064.458710825675, 27095.458090838467, 12433.62699119046, 13027.47890084386, 13168.144070635379, 13172.47310107604, 13193.20840749558, 9361.251099912128, 9407.106324899123, 9462.858057129011, 9466.05339466048, 9515.857262140935, 21566.353009409744, 21727.674084889055, 21875.343739687836, 22086.0, 22606.547869042857, 23339.53320933606, 23380.347837382666, 23765.16821911294, 23774.762252377946, 24792.25623231306, 12907.0, 13180.077394582288, 13208.471661133448, 13213.405396756976, 13269.0, 16290.348386064425, 16339.509814705578, 16355.100469473817, 16580.170991450137, 16585.336586536403, 13008.544700935421, 13070.150440221356, 13081.345932703518, 13190.80213796813, 13453.529126480526, 12855.550055748005, 12929.547465839027, 12962.481500739865, 13001.2849293286, 13027.739445210853, 10377.221708371751, 10493.9475302623, 10709.946450268011, 10712.303700259457, 10735.409552474688, 8240.13478584764, 8370.414071014971, 8400.41197116196, 8404.789880252798, 8500.957495212484, 7855.60721963911, 8347.582620869054, 8349.0, 8381.287590554628, 8417.579121044046], "value": 7484.1151336205885, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [557.7908284393352, 576.6568891509555, 593.5192494079796, 601.9488343490805, 608.1212647724041, 6512.218533775947, 6590.286525503368, 6590.637514936677, 6597.373249541298, 6620.801375958729, 6505.609663420198, 6507.967460162693, 6534.54258201926, 6544.116544266522, 6556.475481961444, 5286.31277933868, 5317.308749862522, 5357.973210133944, 5371.919421208685, 5399.5680345572455, 5067.518585734368, 5276.630635855492, 5288.68267903927, 5307.708076055816, 5375.220593014011, 5167.4574169712205, 5181.9740901295445, 5185.662931909433, 5189.2875351134035, 5193.480651934795, 5169.922451163236, 5181.98396242911, 5183.792648294064, 5195.7402129893735, 5200.603257992671, 4693.6949098308505, 4717.103750287434, 4720.480747117815, 4720.527947205252, 4721.457032441244, 6347.301796802348, 6384.297727249998, 6419.8271123769455, 6438.871222575523, 6465.418112369909, 6142.907856382158, 6158.784442544535, 6166.198394208769, 6166.414190651881, 6204.503639708858, 6928.307169283082, 6950.714117888207, 6962.1297337832775, 6967.651617419108, 6974.651267436608, 5294.602904782157, 5305.681659100466, 5329.866753331159, 5335.386430560497, 5362.471695566784, 7034.824129396755, 7038.612876291853, 7148.890232748771, 7263.4915555910575, 7279.0, 5464.26232458621, 5472.589555783332, 5473.644213126134, 5497.890042199134, 5526.115821468546, 5546.944530554642, 5565.860853478614, 5576.776928922797, 5589.552835773128, 5589.692566908859, 6132.662703551258, 6150.415710507495, 6161.568690191733, 6181.134641150243, 6192.845178870564, 6895.172579290517, 6930.27232140618, 6950.443964482829, 6955.65221738911, 6983.0, 1846.8522518198513, 1875.343629729611, 1893.6686079935955, 1912.7130930360556, 1917.673995420782, 1320.504810695989, 1353.8357012968838, 1360.3334366160707, 1364.4746772492463, 1390.0894913831457, 6372.776952806629, 6536.182977127859, 6538.280789113193, 6561.80314590563, 6609.339066093352, 6926.757563485254, 7014.193367762773, 7044.788656340318, 7058.329458701415, 7090.822729431702, 6397.296297407282, 6525.314841941623, 6542.738290468423, 6543.44380727634, 6544.7382104716235, 3701.6483434074257, 3722.8696995605023, 3727.4408838674417, 3728.6576832340334, 3736.0, 7304.926950730531, 7321.707131714672, 7342.743003994835, 7344.963275183697, 7353.4484913631695, 7135.4648401370105, 7204.855902881913, 7216.242294559101, 7242.782716518407, 7244.45666575009, 7256.96371518139, 7278.890816637755, 7305.707771689179, 7346.77959661211, 7361.227071157451, 7039.472039596948, 7044.330788575282, 7075.0, 7077.82305442368, 7123.323284287984, 6938.895916561357, 6982.895256571054, 7010.544314619533, 7038.401735852305, 7084.504084714021, 7094.290570942864, 7203.279672032755, 7224.0, 7254.492185546961, 7257.854842903218, 5400.162974738922, 5414.593905457185, 5429.429909859408, 5445.1832225166545, 5463.399026107205, 6807.9319206807295, 6926.965365173343, 6931.0, 7021.122359705037, 7057.7176912922905, 6730.596164229966, 6783.660816959034, 6793.218779840382, 6796.72813087471, 6915.757948471779, 6657.966710166418, 6698.531102822755, 6749.257581665915, 6783.287754785676, 6796.829345040832, 6483.157189565326, 6589.143411356493, 6767.187937447341, 6801.251862295045, 6835.316468353125, 6759.797206083826, 6875.0, 6919.169699636076, 6974.407175389946, 6994.440444764508, 6642.568233064835, 6715.496337774589, 6725.8991115134295, 6730.427913627396, 6768.796936091925], "value": 5417.828748656157, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1000.824855650261, 1032.1794173631963, 1034.0848349210946, 1035.3580779916454, 1069.053887309731, 21359.786402135975, 21755.23856665017, 21797.0, 21799.455013624647, 21805.127794888187, 16388.098654573994, 16511.504654860375, 16886.746698799496, 17706.20322085506, 17877.7318340225, 1067.1889364083295, 1096.4956120184725, 1097.30869552182, 1098.0940723902772, 1098.6154845803985, 1096.7038899497131, 1113.059464752283, 1114.169943392174, 1114.5931734916778, 1115.2639258089662, 1166.8833116688347, 1176.9176157668967, 1177.4407156600632, 1177.640819550037, 1177.6820258530192, 1171.830084637727, 1173.3312012153076, 1174.8942595166477, 1177.0936378988174, 1177.3465726521777, 852.910444403335, 858.2876212743408, 860.6600392844854, 862.3446180902497, 863.3611127765471, 20785.896070519702, 20975.0, 21038.684419733716, 21145.0, 21226.36320910375, 23680.171194008126, 23683.763162368326, 23847.403814904766, 24077.87961060201, 24104.276871693877, 18997.43007709771, 19021.809781902142, 19166.329178478685, 19189.136488858036, 19271.22915083395, 4021.7989100545146, 4044.7573145611063, 4066.3087275163293, 4076.5515793262716, 4079.8368065277355, 164493.17753411276, 166299.0, 167046.0, 168580.0, 169020.0, 17143.485695429157, 17608.11959402025, 17788.644227115386, 17887.28450861977, 17889.373871914682, 35910.46134307988, 36038.639613603526, 36211.4568281476, 36248.09379765474, 36262.27475450529, 9274.44353338802, 9319.30105242096, 9324.95337523308, 9415.340926135104, 9418.623255069857, 4800.927986080142, 4837.129316722953, 4853.12643724126, 4855.344528488679, 4863.659543831898, 6090.695465226721, 6112.755489780359, 6188.721507532082, 6196.10156527301, 6199.0, 1176.9293842369486, 1183.3668987091864, 1200.7178313096415, 1200.8498937632796, 1201.2732296960387, 774.1523032279645, 778.0468925566137, 788.8658927982257, 789.3132974312385, 795.5028107432854, 13711.245881476416, 14218.715625687428, 14340.21128837903, 14864.0, 14901.180435075958, 781.3905153979905, 782.4992005116729, 782.5695867272977, 802.9237222463856, 808.6199486241458, 6381.936180638227, 6549.967250163719, 6571.31001244872, 6604.240512341143, 6621.569597976116, 157623.0, 161507.0, 161675.19162404348, 162235.0, 166263.0, 133604.08620904447, 134848.0, 136802.0, 137697.0, 139774.0, 115214.0, 116246.0, 116704.0, 117468.0, 120518.0, 12498.812517812423, 12510.874891251335, 12549.184303020274, 12561.497540098659, 12640.367981601066, 58085.0, 58461.4153858456, 58858.41141588529, 59055.11417328657, 59388.40611593829, 72435.63782181055, 73857.0, 74034.0, 74071.62964185144, 74736.0, 17630.206640701457, 18628.720569191744, 18634.534136646693, 18641.90679046596, 18645.160967756754, 19047.42857714271, 19628.214871404984, 19646.91941943207, 19908.0, 19942.20231190736, 18696.252149914395, 18711.064446777335, 18932.526686832938, 18958.336458223897, 19056.523586909883, 18951.241950321833, 19039.52401189981, 19095.713564296268, 19317.613647726685, 19380.12789424451, 19082.141303641092, 19411.611767764283, 19519.60960780748, 19647.508812279808, 19739.013049347188, 13087.280199589113, 13338.599842004756, 13437.924966002893, 13530.391132398869, 13536.729265414833, 12301.815472768094, 12412.937935310627, 12517.436715347649, 12649.747005059664, 12672.493100275886], "value": 10281.015725815649, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1371.1999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 106.0, 105.0, 106.0, 105.0, 105.0, 105.0, 106.0, 104.0, 108.0], "value": 105.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 114.0, 113.0, 138.0, 113.0, 113.0, 112.0, 117.0, 117.0, 139.0], "value": 119.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 164.0, 164.0, 163.0, 161.0, 162.0, 163.0, 162.0, 162.0, 160.0], "value": 162.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 72.0, 78.0, 73.0, 74.0, 75.0, 73.0, 75.0, 74.0, 77.0], "value": 74.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 78.0, 78.0, 79.0, 78.0, 77.0, 129.0, 81.0, 83.0, 81.0], "value": 84.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 114.0, 114.0, 115.0, 114.0, 113.0, 116.0, 116.0, 113.0], "value": 114.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 150.0, 148.0, 149.0, 149.0, 150.0, 148.0, 149.0, 151.0, 150.0], "value": 149.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 95.0, 94.0, 94.0, 95.0, 94.0, 95.0], "value": 94.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [43.0, 42.0, 43.0, 44.0, 44.0, 43.0, 43.0, 43.0, 44.0, 42.0], "value": 43.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [39.0, 39.0, 39.0, 38.0, 39.0, 38.0, 38.0, 38.0, 38.0, 38.0], "value": 38.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 81.0, 70.0, 69.0, 71.0, 70.0, 70.0, 74.0, 72.0, 70.0], "value": 72.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 105.0, 106.0, 102.0, 101.0, 104.0, 102.0, 108.0, 105.0, 103.0], "value": 103.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 160.0, 155.0, 148.0, 147.0, 150.0, 147.0, 148.0, 152.0, 149.0], "value": 151.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 59.0, 58.0, 59.0, 57.0, 58.0, 56.0, 57.0, 59.0, 63.0], "value": 58.6, "unit": "score"}]}]} 15:57:50 INFO - Return code: 0 15:57:50 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:57:50 INFO - # TBPL SUCCESS # 15:57:50 INFO - Running post-action listener: _package_coverage_data 15:57:50 INFO - Running post-action listener: _resource_record_post_action 15:57:50 INFO - [mozharness: 2017-09-25 22:57:50.633893Z] Finished run-tests step (success) 15:57:50 INFO - Running post-run listener: _resource_record_post_run 15:57:50 INFO - Total resource usage - Wall time: 1179s; CPU: 13.0%; Read bytes: 425984; Write bytes: 293945344; Read time: 40; Write time: 187236 15:57:50 INFO - TinderboxPrint: CPU usage
13.1% 15:57:50 INFO - TinderboxPrint: I/O read bytes / time
425,984 / 40 15:57:50 INFO - TinderboxPrint: I/O write bytes / time
293,945,344 / 187,236 15:57:50 INFO - TinderboxPrint: CPU idle
8,173.2 (86.9%) 15:57:50 INFO - TinderboxPrint: CPU user
1,179.8 (12.5%) 15:57:50 INFO - TinderboxPrint: Swap in / out
0 / 0 15:57:50 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 0; Write bytes: 14872576; Read time: 0; Write time: 6700 15:57:50 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 15:57:50 INFO - run-tests - Wall time: 1165s; CPU: 13.0%; Read bytes: 425984; Write bytes: 279072768; Read time: 40; Write time: 180536 15:57:51 INFO - Running post-run listener: _upload_blobber_files 15:57:51 INFO - Blob upload gear active. 15:57:51 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:57:51 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 15:57:51 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', 'Mozilla-Beta-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:57:51 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 Mozilla-Beta-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:57:51 INFO - (blobuploader) - INFO - Open directory for files ... 15:57:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:57:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:57:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:57:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:57:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:57:52 INFO - (blobuploader) - INFO - Done attempting. 15:57:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:57:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:57:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:57:59 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:57:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:57:59 INFO - (blobuploader) - INFO - Done attempting. 15:57:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:57:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:57:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:58:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:58:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:58:00 INFO - (blobuploader) - INFO - Done attempting. 15:58:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 15:58:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:58:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:58:01 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 15:58:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:58:01 INFO - (blobuploader) - INFO - Done attempting. 15:58:01 INFO - (blobuploader) - INFO - Iteration through files over. 15:58:01 INFO - Return code: 0 15:58:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:58:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:58:01 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/21dee93a447caf1e9beeda1ad3171f6f3c3119180e42ded2c29ae70ebbedecdb481a5ec118744be5b8d1971604a8a71536005bd2563d6d4af1da1352abe48165", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/4b9ee1c1817b8188f72cdcce24cc5dc0a9381c6a0b0cc9b0bd26d2c075cc8f74593c655eff46e553602f562dd4ce4868f5bac880c97c16fc3abc0db9c9b283d4", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/999da3141655c022eae46e99e462783fde5588948eec1c5cd9267ccc680d3259f4d603b4c509cdd6477ab3c02027ac0f60919d0d7e2ba225bf9ce2c6ac2c71e0", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/4adb7e6ae3caeb052137c40f23d9f5d039b3d7b0410f12f23152c9604ad450b6a1222f1ce5066b78f73afb88152e623db5f5a8faeb00d960cc02fffa0628939d"} 15:58:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:58:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:58:01 INFO - Contents: 15:58:01 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/21dee93a447caf1e9beeda1ad3171f6f3c3119180e42ded2c29ae70ebbedecdb481a5ec118744be5b8d1971604a8a71536005bd2563d6d4af1da1352abe48165", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/4b9ee1c1817b8188f72cdcce24cc5dc0a9381c6a0b0cc9b0bd26d2c075cc8f74593c655eff46e553602f562dd4ce4868f5bac880c97c16fc3abc0db9c9b283d4", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/999da3141655c022eae46e99e462783fde5588948eec1c5cd9267ccc680d3259f4d603b4c509cdd6477ab3c02027ac0f60919d0d7e2ba225bf9ce2c6ac2c71e0", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/4adb7e6ae3caeb052137c40f23d9f5d039b3d7b0410f12f23152c9604ad450b6a1222f1ce5066b78f73afb88152e623db5f5a8faeb00d960cc02fffa0628939d"} 15:58:01 INFO - Running post-run listener: copy_logs_to_upload_dir 15:58:01 INFO - Copying logs to upload dir... 15:58:01 INFO - mkdir: /builds/slave/test/build/upload/logs 15:58:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1229.878340 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 29 secs) (at 2017-09-25 15:58:01.154323) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-25 15:58:01.158829) ========= 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=3a3620fb28aea1604ab609ef000001d2-1506368044.582502-831625406 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/21dee93a447caf1e9beeda1ad3171f6f3c3119180e42ded2c29ae70ebbedecdb481a5ec118744be5b8d1971604a8a71536005bd2563d6d4af1da1352abe48165", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/4b9ee1c1817b8188f72cdcce24cc5dc0a9381c6a0b0cc9b0bd26d2c075cc8f74593c655eff46e553602f562dd4ce4868f5bac880c97c16fc3abc0db9c9b283d4", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/999da3141655c022eae46e99e462783fde5588948eec1c5cd9267ccc680d3259f4d603b4c509cdd6477ab3c02027ac0f60919d0d7e2ba225bf9ce2c6ac2c71e0", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/4adb7e6ae3caeb052137c40f23d9f5d039b3d7b0410f12f23152c9604ad450b6a1222f1ce5066b78f73afb88152e623db5f5a8faeb00d960cc02fffa0628939d"} build_url:https://queue.taskcluster.net/v1/task/ekCv5nYHQveClEcM63kBZA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011034 build_url: 'https://queue.taskcluster.net/v1/task/ekCv5nYHQveClEcM63kBZA/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/21dee93a447caf1e9beeda1ad3171f6f3c3119180e42ded2c29ae70ebbedecdb481a5ec118744be5b8d1971604a8a71536005bd2563d6d4af1da1352abe48165", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/4b9ee1c1817b8188f72cdcce24cc5dc0a9381c6a0b0cc9b0bd26d2c075cc8f74593c655eff46e553602f562dd4ce4868f5bac880c97c16fc3abc0db9c9b283d4", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/999da3141655c022eae46e99e462783fde5588948eec1c5cd9267ccc680d3259f4d603b4c509cdd6477ab3c02027ac0f60919d0d7e2ba225bf9ce2c6ac2c71e0", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/4adb7e6ae3caeb052137c40f23d9f5d039b3d7b0410f12f23152c9604ad450b6a1222f1ce5066b78f73afb88152e623db5f5a8faeb00d960cc02fffa0628939d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-25 15:58:01.192063) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-25 15:58:01.192430) ========= 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=3a3620fb28aea1604ab609ef000001d2-1506368044.582502-831625406 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004609 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-25 15:58:01.280115) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-25 15:58:01.280779) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-25 15:58:01.281423) ========= ========= Total master_lag: 0.22 =========