builder: mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s slave: talos-linux64-ix-082 starttime: 1505413841.99 results: success (0) revision: 593158cd491002031b4527a95d9bfac79c0cdcef ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-14 11:30:41.988219) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-14 11:30:41.988667) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-14 11:30:41.988938) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-14 11:30:42.006584) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-14 11:30:42.006888) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=51fc86400edbde9650d2781a000001d0-1505413615.117019-1729712491 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-14 11:30:42-- 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% 39.9M=0s 2017-09-14 11:30:42 (39.9 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.112146 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-14 11:30:42.134703) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-14 11:30:42.135009) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=51fc86400edbde9650d2781a000001d0-1505413615.117019-1729712491 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019319 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-14 11:30:42.190397) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-14 11:30:42.190755) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 593158cd491002031b4527a95d9bfac79c0cdcef --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 593158cd491002031b4527a95d9bfac79c0cdcef --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=51fc86400edbde9650d2781a000001d0-1505413615.117019-1729712491 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-14 11:30:42,827 truncating revision to first 12 chars 2017-09-14 11:30:42,827 Setting DEBUG logging. 2017-09-14 11:30:42,827 attempt 1/10 2017-09-14 11:30:42,827 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/593158cd4910?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-14 11:30:43,372 unpacking tar archive at: mozilla-central-593158cd4910/testing/mozharness/ program finished with exit code 0 elapsedTime=0.854890 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-14 11:30:43.068461) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-14 11:30:43.068769) ========= script_repo_revision: 593158cd491002031b4527a95d9bfac79c0cdcef ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-14 11:30:43.069133) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-14 11:30:43.069389) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-14 11:30:43.082607) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 25 mins, 0 secs) (at 2017-09-14 11:30:43.082950) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=51fc86400edbde9650d2781a000001d0-1505413615.117019-1729712491 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:30:43 INFO - MultiFileLogger online at 20170914 11:30:43 in /builds/slave/test 11:30:43 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 11:30:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:30:43 INFO - {'append_to_log': False, 11:30:43 INFO - 'base_work_dir': '/builds/slave/test', 11:30:43 INFO - 'blob_upload_branch': 'Firefox', 11:30:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:30:43 INFO - 'branch': 'Firefox', 11:30:43 INFO - 'buildbot_json_path': 'buildprops.json', 11:30:43 INFO - 'code_coverage': False, 11:30:43 INFO - 'config_files': ('talos/linux_config.py',), 11:30:43 INFO - 'default_actions': ('clobber', 11:30:43 INFO - 'read-buildbot-config', 11:30:43 INFO - 'download-and-extract', 11:30:43 INFO - 'populate-webroot', 11:30:43 INFO - 'create-virtualenv', 11:30:43 INFO - 'install', 11:30:43 INFO - 'setup-mitmproxy', 11:30:43 INFO - 'run-tests'), 11:30:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:30:43 INFO - 'disable_ccov_upload': False, 11:30:43 INFO - 'disable_stylo': False, 11:30:43 INFO - 'download_minidump_stackwalk': True, 11:30:43 INFO - 'download_symbols': 'ondemand', 11:30:43 INFO - 'enable_stylo': False, 11:30:43 INFO - 'enable_webrender': False, 11:30:43 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 11:30:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:30:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:30:43 INFO - 'gecko_profile': False, 11:30:43 INFO - 'gecko_profile_interval': 0, 11:30:43 INFO - 'installer_path': 'installer.exe', 11:30:43 INFO - 'log_level': 'info', 11:30:43 INFO - 'log_name': 'talos', 11:30:43 INFO - 'log_to_console': True, 11:30:43 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:30:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:30:43 INFO - 'opt_config_files': (), 11:30:43 INFO - 'pip_index': False, 11:30:43 INFO - 'suite': 'dromaeojs-e10s', 11:30:43 INFO - 'system_bits': '32', 11:30:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:30:43 INFO - 'title': 'talos-linux64-ix-082', 11:30:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:30:43 INFO - 'use_talos_json': True, 11:30:43 INFO - 'verify': 'False', 11:30:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:30:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:30:43 INFO - 'work_dir': 'build'} 11:30:43 INFO - [mozharness: 2017-09-14 18:30:43.855906Z] Running clobber step. 11:30:43 INFO - Running pre-action listener: _resource_record_pre_action 11:30:43 INFO - Running main action method: clobber 11:30:43 INFO - rmtree: /builds/slave/test/build 11:30:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:30:45 INFO - Running post-action listener: _resource_record_post_action 11:30:45 INFO - [mozharness: 2017-09-14 18:30:45.369527Z] Finished clobber step (success) 11:30:45 INFO - [mozharness: 2017-09-14 18:30:45.369632Z] Running read-buildbot-config step. 11:30:45 INFO - Running pre-action listener: _resource_record_pre_action 11:30:45 INFO - Running main action method: read_buildbot_config 11:30:45 INFO - Using buildbot properties: 11:30:45 INFO - { 11:30:45 INFO - "project": "", 11:30:45 INFO - "product": "firefox", 11:30:45 INFO - "who": "ffxbld@noreply.mozilla.org", 11:30:45 INFO - "installer_path": "public/build/target.tar.bz2", 11:30:45 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s", 11:30:45 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 11:30:45 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos dromaeojs-e10s", 11:30:45 INFO - "stage_platform": "linux64-qr", 11:30:45 INFO - "basedir": "/builds/slave/test", 11:30:45 INFO - "buildnumber": 56, 11:30:45 INFO - "platform": "ubuntu64_hw_qr", 11:30:45 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 11:30:45 INFO - "slavebuilddir": "test", 11:30:45 INFO - "taskId": "YTvQfIKiQZ2vzfztUn7ptg", 11:30:45 INFO - "branch": "mozilla-central", 11:30:45 INFO - "script_repo_revision": "production", 11:30:45 INFO - "revision": "593158cd491002031b4527a95d9bfac79c0cdcef", 11:30:45 INFO - "slavename": "talos-linux64-ix-082", 11:30:45 INFO - "repo_path": "mozilla-central" 11:30:45 INFO - } 11:30:45 INFO - Finding installer, test and symbols from parent task. 11:30:45 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YTvQfIKiQZ2vzfztUn7ptg'}, attempt #1 11:30:45 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YTvQfIKiQZ2vzfztUn7ptg'}, attempt #1 11:30:46 INFO - Task dependencies: IDcefCo4SICyNXkppCNLZA 11:30:46 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IDcefCo4SICyNXkppCNLZA'}, attempt #1 11:30:46 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/IDcefCo4SICyNXkppCNLZA/artifacts/public/build/target.tar.bz2 11:30:46 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/IDcefCo4SICyNXkppCNLZA/artifacts/public/build/target.test_packages.json 11:30:46 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/IDcefCo4SICyNXkppCNLZA/artifacts/public/build/target.crashreporter-symbols.zip 11:30:46 INFO - Running post-action listener: _resource_record_post_action 11:30:46 INFO - [mozharness: 2017-09-14 18:30:46.854191Z] Finished read-buildbot-config step (success) 11:30:46 INFO - [mozharness: 2017-09-14 18:30:46.854406Z] Running download-and-extract step. 11:30:46 INFO - Running pre-action listener: _resource_record_pre_action 11:30:46 INFO - Running main action method: download_and_extract 11:30:46 INFO - mkdir: /builds/slave/test/build/tests 11:30:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:30:46 INFO - trying https://queue.taskcluster.net/v1/task/IDcefCo4SICyNXkppCNLZA/artifacts/public/build/target.test_packages.json 11:30:46 INFO - Downloading https://queue.taskcluster.net/v1/task/IDcefCo4SICyNXkppCNLZA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 11:30:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IDcefCo4SICyNXkppCNLZA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 11:30:47 INFO - Downloaded 989 bytes. 11:30:47 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 11:30:47 INFO - Using the following test package requirements: 11:30:47 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 11:30:47 INFO - u'common': [u'target.common.tests.zip'], 11:30:47 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 11:30:47 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 11:30:47 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 11:30:47 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 11:30:47 INFO - u'mozbase': [u'target.common.tests.zip'], 11:30:47 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 11:30:47 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 11:30:47 INFO - u'web-platform': [u'target.common.tests.zip', 11:30:47 INFO - u'target.web-platform.tests.tar.gz'], 11:30:47 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 11:30:47 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 11:30:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IDcefCo4SICyNXkppCNLZA/artifacts/public/build/target.common.tests.zip 11:30:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IDcefCo4SICyNXkppCNLZA/artifacts/public/build/target.common.tests.zip'}, attempt #1 11:30:47 INFO - Fetch https://queue.taskcluster.net/v1/task/IDcefCo4SICyNXkppCNLZA/artifacts/public/build/target.common.tests.zip into memory 11:30:48 INFO - Content-Length response header: 54593770 11:30:48 INFO - Bytes received: 54593770 11:30:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IDcefCo4SICyNXkppCNLZA/artifacts/public/build/target.talos.tests.zip 11:30:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IDcefCo4SICyNXkppCNLZA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 11:30:59 INFO - Fetch https://queue.taskcluster.net/v1/task/IDcefCo4SICyNXkppCNLZA/artifacts/public/build/target.talos.tests.zip into memory 11:30:59 INFO - Content-Length response header: 14500356 11:30:59 INFO - Bytes received: 14500356 11:31:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:31:00 INFO - trying https://queue.taskcluster.net/v1/task/IDcefCo4SICyNXkppCNLZA/artifacts/public/build/target.tar.bz2 11:31:00 INFO - Downloading https://queue.taskcluster.net/v1/task/IDcefCo4SICyNXkppCNLZA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 11:31:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IDcefCo4SICyNXkppCNLZA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 11:31:01 INFO - Downloaded 61304833 bytes. 11:31:01 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IDcefCo4SICyNXkppCNLZA/artifacts/public/build/target.tar.bz2 11:31:01 INFO - mkdir: /builds/slave/test/properties 11:31:01 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:31:01 INFO - Writing to file /builds/slave/test/properties/build_url 11:31:01 INFO - Contents: 11:31:01 INFO - build_url:https://queue.taskcluster.net/v1/task/IDcefCo4SICyNXkppCNLZA/artifacts/public/build/target.tar.bz2 11:31:01 INFO - Running post-action listener: _resource_record_post_action 11:31:01 INFO - Running post-action listener: find_tests_for_verification 11:31:01 INFO - Running post-action listener: set_extra_try_arguments 11:31:01 INFO - [mozharness: 2017-09-14 18:31:01.807012Z] Finished download-and-extract step (success) 11:31:01 INFO - [mozharness: 2017-09-14 18:31:01.807114Z] Running populate-webroot step. 11:31:01 INFO - Running pre-action listener: _resource_record_pre_action 11:31:01 INFO - Running main action method: populate_webroot 11:31:01 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:31:01 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:31:01 INFO - 'tests': ['tresize', 11:31:01 INFO - 'tcanvasmark']}, 11:31:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:31:01 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:31:01 INFO - 'tests': ['dromaeo_css', 11:31:01 INFO - 'kraken']}, 11:31:01 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:31:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:31:01 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:31:01 INFO - 'talos_options': ['--disable-stylo'], 11:31:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:31:01 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:31:01 INFO - 'tests': ['damp', 'tps']}, 11:31:01 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:31:01 INFO - 'talos_options': ['--disable-stylo'], 11:31:01 INFO - 'tests': ['damp', 'tps']}, 11:31:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:31:01 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:31:01 INFO - 'tests': ['dromaeo_dom']}, 11:31:01 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 11:31:01 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:31:01 INFO - 'tests': ['basic_compositor_video', 11:31:01 INFO - 'glvideo']}, 11:31:01 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 11:31:01 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 11:31:01 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:31:01 INFO - 'talos_options': ['--disable-stylo'], 11:31:01 INFO - 'tests': ['ts_paint_webext', 11:31:01 INFO - 'tp5o_webext']}, 11:31:01 INFO - 'other-e10s': {'tests': ['a11yr', 11:31:01 INFO - 'ts_paint', 11:31:01 INFO - 'tpaint', 11:31:01 INFO - 'sessionrestore', 11:31:01 INFO - 'sessionrestore_many_windows', 11:31:01 INFO - 'sessionrestore_no_auto_restore', 11:31:01 INFO - 'tabpaint']}, 11:31:01 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:31:01 INFO - 'tests': ['a11yr', 11:31:01 INFO - 'ts_paint', 11:31:01 INFO - 'tpaint', 11:31:01 INFO - 'sessionrestore', 11:31:01 INFO - 'sessionrestore_many_windows', 11:31:01 INFO - 'sessionrestore_no_auto_restore', 11:31:01 INFO - 'tabpaint']}, 11:31:01 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 11:31:01 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 11:31:01 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:31:01 INFO - 'tests': ['bloom_basic_singleton']}, 11:31:01 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:31:01 INFO - 'tests': ['bloom_basic']}, 11:31:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:31:01 INFO - 'tsvgr_opacity', 11:31:01 INFO - 'tart', 11:31:01 INFO - 'tscrollx', 11:31:01 INFO - 'tsvg_static']}, 11:31:01 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:31:01 INFO - 'tests': ['tsvgx', 11:31:01 INFO - 'tsvgr_opacity', 11:31:01 INFO - 'tart', 11:31:01 INFO - 'tscrollx', 11:31:01 INFO - 'tsvg_static']}, 11:31:01 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:31:01 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:31:01 INFO - 'talos_options': ['--disable-stylo'], 11:31:01 INFO - 'tests': ['tp5o']}, 11:31:01 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:31:01 INFO - 'talos_options': ['--mitmproxy', 11:31:01 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:31:01 INFO - '--firstNonBlankPaint'], 11:31:01 INFO - 'tests': ['tp6_google', 11:31:01 INFO - 'tp6_youtube', 11:31:01 INFO - 'tp6_amazon', 11:31:01 INFO - 'tp6_facebook']}, 11:31:01 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:31:01 INFO - 'talos_options': ['--disable-stylo', 11:31:01 INFO - '--mitmproxy', 11:31:01 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:31:01 INFO - '--firstNonBlankPaint'], 11:31:01 INFO - 'tests': ['tp6_google', 11:31:01 INFO - 'tp6_youtube', 11:31:01 INFO - 'tp6_amazon', 11:31:01 INFO - 'tp6_facebook']}, 11:31:01 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:31:01 INFO - 'talos_options': ['--stylo-threads=1', 11:31:01 INFO - '--mitmproxy', 11:31:01 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:31:01 INFO - '--firstNonBlankPaint'], 11:31:01 INFO - 'tests': ['tp6_google', 11:31:01 INFO - 'tp6_youtube', 11:31:01 INFO - 'tp6_amazon', 11:31:01 INFO - 'tp6_facebook']}, 11:31:01 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:31:01 INFO - 'talos_options': ['--xperf_path', 11:31:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:31:01 INFO - 'tests': ['tp5n']}, 11:31:01 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:31:01 INFO - 'talos_options': ['--disable-stylo', 11:31:01 INFO - '--xperf_path', 11:31:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:31:01 INFO - 'tests': ['tp5n']}}} 11:31:01 INFO - Running post-action listener: _resource_record_post_action 11:31:01 INFO - [mozharness: 2017-09-14 18:31:01.822486Z] Finished populate-webroot step (success) 11:31:01 INFO - [mozharness: 2017-09-14 18:31:01.822575Z] Running create-virtualenv step. 11:31:01 INFO - Running pre-action listener: _resource_record_pre_action 11:31:01 INFO - Running main action method: create_virtualenv 11:31:01 INFO - Creating virtualenv /builds/slave/test/build/venv 11:31:01 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 11:31:01 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 11:31:01 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 11:31:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:31:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:31:02 INFO - Installing distribute.............................................................................................................................................................................................done. 11:31:02 INFO - Installing pip...............done. 11:31:02 INFO - Return code: 0 11:31:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:31:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:31:02 INFO - Reading from file tmpfile_stdout 11:31:02 INFO - Output received: 11:31:02 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 11:31:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:31: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')]} 11:31:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:31: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')]} 11:31:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:31:02 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 0x16cb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1894a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a37a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a3b090>, '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 0x1a3bd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a35f90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1505413615.117019-1729712491', '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 11:31:02 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 11:31:02 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 11:31:02 INFO - Using env: {'DISPLAY': ':0', 11:31:02 INFO - 'HOME': '/home/cltbld', 11:31:02 INFO - 'LANG': 'en_US.UTF-8', 11:31:02 INFO - 'LANGUAGE': 'en_US:en', 11:31:02 INFO - 'LOGNAME': 'cltbld', 11:31:02 INFO - 'MAIL': '/var/mail/cltbld', 11:31:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:31:02 INFO - 'MOZ_NO_REMOTE': '1', 11:31:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:31:02 INFO - 'NO_EM_RESTART': '1', 11:31:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:31:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:31:02 INFO - 'PWD': '/builds/slave/test', 11:31:02 INFO - 'SHELL': '/bin/bash', 11:31:02 INFO - 'SHLVL': '1', 11:31:02 INFO - 'TERM': 'linux', 11:31:02 INFO - 'TMOUT': '86400', 11:31:02 INFO - 'USER': 'cltbld', 11:31:02 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1505413615.117019-1729712491', 11:31:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:31:02 INFO - '_': '/tools/buildbot/bin/python'} 11:31:04 INFO - Ignoring indexes: http://pypi.python.org/simple/ 11:31:04 INFO - Downloading/unpacking pip>=1.5 11:31:04 INFO - Running setup.py egg_info for package pip 11:31:04 INFO - warning: no files found matching 'pip/cacert.pem' 11:31:04 INFO - warning: no files found matching '*.html' under directory 'docs' 11:31:04 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:31:04 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:31:04 INFO - Installing collected packages: pip 11:31:04 INFO - Found existing installation: pip 1.1 11:31:04 INFO - Uninstalling pip: 11:31:04 INFO - Successfully uninstalled pip 11:31:04 INFO - Running setup.py install for pip 11:31:04 INFO - warning: no files found matching 'pip/cacert.pem' 11:31:04 INFO - warning: no files found matching '*.html' under directory 'docs' 11:31:04 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:31:04 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:31:04 INFO - Installing pip script to /builds/slave/test/build/venv/bin 11:31:04 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 11:31:04 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 11:31:04 INFO - Successfully installed pip 11:31:04 INFO - Cleaning up... 11:31:04 INFO - Return code: 0 11:31:04 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:31:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:31:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:31:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:31:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:31:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x16cb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1894a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a37a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a3b090>, '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 0x1a3bd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a35f90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1505413615.117019-1729712491', '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 11:31:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 11:31:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 11:31:04 INFO - Using env: {'DISPLAY': ':0', 11:31:04 INFO - 'HOME': '/home/cltbld', 11:31:04 INFO - 'LANG': 'en_US.UTF-8', 11:31:04 INFO - 'LANGUAGE': 'en_US:en', 11:31:04 INFO - 'LOGNAME': 'cltbld', 11:31:04 INFO - 'MAIL': '/var/mail/cltbld', 11:31:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:31:04 INFO - 'MOZ_NO_REMOTE': '1', 11:31:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:31:04 INFO - 'NO_EM_RESTART': '1', 11:31:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:31:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:31:04 INFO - 'PWD': '/builds/slave/test', 11:31:04 INFO - 'SHELL': '/bin/bash', 11:31:04 INFO - 'SHLVL': '1', 11:31:04 INFO - 'TERM': 'linux', 11:31:04 INFO - 'TMOUT': '86400', 11:31:04 INFO - 'USER': 'cltbld', 11:31:04 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1505413615.117019-1729712491', 11:31:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:31:04 INFO - '_': '/tools/buildbot/bin/python'} 11:31:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:31:04 INFO - Downloading/unpacking psutil>=3.1.1 11:31:04 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 11:31:04 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 11:31:04 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 11:31:04 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 11:31:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:31:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:31:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:31:05 INFO - Installing collected packages: psutil 11:31:05 INFO - Running setup.py install for psutil 11:31:05 INFO - building 'psutil._psutil_linux' extension 11:31:05 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 11:31:05 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 11:31:05 INFO - building 'psutil._psutil_posix' extension 11:31:05 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 11:31:06 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 11:31:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:31:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:31:06 INFO - Successfully installed psutil 11:31:06 INFO - Cleaning up... 11:31:06 INFO - Return code: 0 11:31:06 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:31:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:31:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:31:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:31:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:31:06 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 0x16cb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1894a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a37a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a3b090>, '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 0x1a3bd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a35f90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1505413615.117019-1729712491', '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 11:31:06 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 11:31:06 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 11:31:06 INFO - Using env: {'DISPLAY': ':0', 11:31:06 INFO - 'HOME': '/home/cltbld', 11:31:06 INFO - 'LANG': 'en_US.UTF-8', 11:31:06 INFO - 'LANGUAGE': 'en_US:en', 11:31:06 INFO - 'LOGNAME': 'cltbld', 11:31:06 INFO - 'MAIL': '/var/mail/cltbld', 11:31:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:31:06 INFO - 'MOZ_NO_REMOTE': '1', 11:31:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:31:06 INFO - 'NO_EM_RESTART': '1', 11:31:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:31:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:31:06 INFO - 'PWD': '/builds/slave/test', 11:31:06 INFO - 'SHELL': '/bin/bash', 11:31:06 INFO - 'SHLVL': '1', 11:31:06 INFO - 'TERM': 'linux', 11:31:06 INFO - 'TMOUT': '86400', 11:31:06 INFO - 'USER': 'cltbld', 11:31:06 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1505413615.117019-1729712491', 11:31:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:31:06 INFO - '_': '/tools/buildbot/bin/python'} 11:31:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:31:06 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:31: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 11:31: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 11:31: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 11:31: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 11:31:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:31:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:31:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:31:07 INFO - Installing collected packages: mozsystemmonitor 11:31:07 INFO - Running setup.py install for mozsystemmonitor 11:31:07 INFO - Successfully installed mozsystemmonitor 11:31:07 INFO - Cleaning up... 11:31:08 INFO - Return code: 0 11:31:08 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:31: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')]} 11:31:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:31: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')]} 11:31:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:31:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x16cb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1894a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a37a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a3b090>, '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 0x1a3bd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a35f90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1505413615.117019-1729712491', '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 11:31:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 11:31:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 11:31:08 INFO - Using env: {'DISPLAY': ':0', 11:31:08 INFO - 'HOME': '/home/cltbld', 11:31:08 INFO - 'LANG': 'en_US.UTF-8', 11:31:08 INFO - 'LANGUAGE': 'en_US:en', 11:31:08 INFO - 'LOGNAME': 'cltbld', 11:31:08 INFO - 'MAIL': '/var/mail/cltbld', 11:31:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:31:08 INFO - 'MOZ_NO_REMOTE': '1', 11:31:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:31:08 INFO - 'NO_EM_RESTART': '1', 11:31:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:31:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:31:08 INFO - 'PWD': '/builds/slave/test', 11:31:08 INFO - 'SHELL': '/bin/bash', 11:31:08 INFO - 'SHLVL': '1', 11:31:08 INFO - 'TERM': 'linux', 11:31:08 INFO - 'TMOUT': '86400', 11:31:08 INFO - 'USER': 'cltbld', 11:31:08 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1505413615.117019-1729712491', 11:31:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:31:08 INFO - '_': '/tools/buildbot/bin/python'} 11:31:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:31:09 INFO - Downloading/unpacking jsonschema==2.5.1 11:31:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:31:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:31:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:31:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:31:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:31:10 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:31:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:31:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:31:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:31:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:31:10 INFO - Downloading functools32-3.2.3-2.tar.gz 11:31:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:31:10 INFO - warning: no files found matching '*.txt' 11:31:10 INFO - no previously-included directories found matching 'build' 11:31:10 INFO - no previously-included directories found matching 'dist' 11:31:10 INFO - no previously-included directories found matching '.git*' 11:31:10 INFO - Installing collected packages: jsonschema, functools32 11:31:10 INFO - Running setup.py install for functools32 11:31:11 INFO - warning: no files found matching '*.txt' 11:31:11 INFO - no previously-included directories found matching 'build' 11:31:11 INFO - no previously-included directories found matching 'dist' 11:31:11 INFO - no previously-included directories found matching '.git*' 11:31:11 INFO - Successfully installed jsonschema functools32 11:31:11 INFO - Cleaning up... 11:31:11 INFO - Return code: 0 11:31:11 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:31:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:31:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:31:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:31:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:31:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x16cb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1894a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a37a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a3b090>, '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 0x1a3bd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a35f90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1505413615.117019-1729712491', '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 11:31:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 11:31:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 11:31:11 INFO - Using env: {'DISPLAY': ':0', 11:31:11 INFO - 'HOME': '/home/cltbld', 11:31:11 INFO - 'LANG': 'en_US.UTF-8', 11:31:11 INFO - 'LANGUAGE': 'en_US:en', 11:31:11 INFO - 'LOGNAME': 'cltbld', 11:31:11 INFO - 'MAIL': '/var/mail/cltbld', 11:31:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:31:11 INFO - 'MOZ_NO_REMOTE': '1', 11:31:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:31:11 INFO - 'NO_EM_RESTART': '1', 11:31:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:31:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:31:11 INFO - 'PWD': '/builds/slave/test', 11:31:11 INFO - 'SHELL': '/bin/bash', 11:31:11 INFO - 'SHLVL': '1', 11:31:11 INFO - 'TERM': 'linux', 11:31:11 INFO - 'TMOUT': '86400', 11:31:11 INFO - 'USER': 'cltbld', 11:31:11 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1505413615.117019-1729712491', 11:31:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:31:11 INFO - '_': '/tools/buildbot/bin/python'} 11:31:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:31:11 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:31:11 INFO - Cleaning up... 11:31:11 INFO - Return code: 0 11:31:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:31:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:31:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:31:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:31:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:31:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x16cb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1894a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a37a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a3b090>, '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 0x1a3bd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a35f90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1505413615.117019-1729712491', '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 11:31:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:31:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 11:31:11 INFO - Using env: {'DISPLAY': ':0', 11:31:11 INFO - 'HOME': '/home/cltbld', 11:31:11 INFO - 'LANG': 'en_US.UTF-8', 11:31:11 INFO - 'LANGUAGE': 'en_US:en', 11:31:11 INFO - 'LOGNAME': 'cltbld', 11:31:11 INFO - 'MAIL': '/var/mail/cltbld', 11:31:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:31:11 INFO - 'MOZ_NO_REMOTE': '1', 11:31:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:31:11 INFO - 'NO_EM_RESTART': '1', 11:31:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:31:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:31:11 INFO - 'PWD': '/builds/slave/test', 11:31:11 INFO - 'SHELL': '/bin/bash', 11:31:11 INFO - 'SHLVL': '1', 11:31:11 INFO - 'TERM': 'linux', 11:31:11 INFO - 'TMOUT': '86400', 11:31:11 INFO - 'USER': 'cltbld', 11:31:11 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1505413615.117019-1729712491', 11:31:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:31:11 INFO - '_': '/tools/buildbot/bin/python'} 11:31:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:31:11 INFO - Downloading/unpacking blobuploader==1.2.4 11:31:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:31:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:31:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:31:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:31:12 INFO - Downloading blobuploader-1.2.4.tar.gz 11:31:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:31:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:31:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:31:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:31:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:31:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:31:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:31:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:31:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:31:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:31:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:31:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:31:13 INFO - Downloading docopt-0.6.1.tar.gz 11:31:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:31:13 INFO - Installing collected packages: blobuploader, requests, docopt 11:31:13 INFO - Running setup.py install for blobuploader 11:31:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:31:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:31:13 INFO - Running setup.py install for requests 11:31:13 INFO - Running setup.py install for docopt 11:31:13 INFO - Successfully installed blobuploader requests docopt 11:31:13 INFO - Cleaning up... 11:31:13 INFO - Return code: 0 11:31:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:31:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:31:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:31:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:31:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:31:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16cb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1894a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a37a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a3b090>, '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 0x1a3bd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a35f90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1505413615.117019-1729712491', '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 11:31:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 11:31:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:31:13 INFO - Using env: {'DISPLAY': ':0', 11:31:13 INFO - 'HOME': '/home/cltbld', 11:31:13 INFO - 'LANG': 'en_US.UTF-8', 11:31:13 INFO - 'LANGUAGE': 'en_US:en', 11:31:13 INFO - 'LOGNAME': 'cltbld', 11:31:13 INFO - 'MAIL': '/var/mail/cltbld', 11:31:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:31:13 INFO - 'MOZ_NO_REMOTE': '1', 11:31:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:31:13 INFO - 'NO_EM_RESTART': '1', 11:31:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:31:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:31:13 INFO - 'PWD': '/builds/slave/test', 11:31:13 INFO - 'SHELL': '/bin/bash', 11:31:13 INFO - 'SHLVL': '1', 11:31:13 INFO - 'TERM': 'linux', 11:31:13 INFO - 'TMOUT': '86400', 11:31:13 INFO - 'USER': 'cltbld', 11:31:13 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1505413615.117019-1729712491', 11:31:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:31:13 INFO - '_': '/tools/buildbot/bin/python'} 11:31:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:31:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:31:14 INFO - Running setup.py (path:/tmp/pip-qxpeQY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:31:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:31:14 INFO - Running setup.py (path:/tmp/pip-GXpZhV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:31:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:31:14 INFO - Running setup.py (path:/tmp/pip-M3gv3A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:31:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:31:14 INFO - Running setup.py (path:/tmp/pip-llb5Qw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:31:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:31:14 INFO - Running setup.py (path:/tmp/pip-NIK4B2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:31:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:31:14 INFO - Running setup.py (path:/tmp/pip-vTht0m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:31:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:31:14 INFO - Running setup.py (path:/tmp/pip-Bd3YUV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:31:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:31:14 INFO - Running setup.py (path:/tmp/pip-qpT4cd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:31:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:31:14 INFO - Running setup.py (path:/tmp/pip-slZ8v0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:31:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:31:14 INFO - Running setup.py (path:/tmp/pip-KvwpLB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:31:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:31:14 INFO - Running setup.py (path:/tmp/pip-vvQ6Kb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:31:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:31:14 INFO - Running setup.py (path:/tmp/pip-TjyEZq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:31:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:31:14 INFO - Running setup.py (path:/tmp/pip-yU_EDH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:31:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:31:14 INFO - Running setup.py (path:/tmp/pip-0wowsD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:31:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:31:15 INFO - Running setup.py (path:/tmp/pip-ECBxDE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:31:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:31:15 INFO - Running setup.py (path:/tmp/pip-2hgRTq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:31:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:31:15 INFO - Running setup.py (path:/tmp/pip-vlYLe1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:31:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:31:15 INFO - Running setup.py install for manifestparser 11:31:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:31:15 INFO - Running setup.py install for mozcrash 11:31:15 INFO - Running setup.py install for mozdebug 11:31:15 INFO - Running setup.py install for mozdevice 11:31:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:31:15 INFO - Running setup.py install for mozfile 11:31:15 INFO - Running setup.py install for mozhttpd 11:31:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:31:15 INFO - Running setup.py install for mozinfo 11:31:15 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:31:15 INFO - Running setup.py install for mozInstall 11:31:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:31:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:31:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:31:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:31:16 INFO - Running setup.py install for mozleak 11:31:16 INFO - Running setup.py install for mozlog 11:31:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:31:16 INFO - Running setup.py install for moznetwork 11:31:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:31:16 INFO - Running setup.py install for mozprocess 11:31:16 INFO - Running setup.py install for mozprofile 11:31:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:31:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:31:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:31:16 INFO - Running setup.py install for mozrunner 11:31:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:31:16 INFO - Running setup.py install for mozscreenshot 11:31:16 INFO - Running setup.py install for moztest 11:31:16 INFO - Running setup.py install for mozversion 11:31:16 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:31:16 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:31:16 INFO - Cleaning up... 11:31:17 INFO - Return code: 0 11:31:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:31:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:31:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:31:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:31:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:31:17 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 0x16cb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1894a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a37a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a3b090>, '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 0x1a3bd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a35f90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1505413615.117019-1729712491', '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 11:31:17 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 11:31:17 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 11:31:17 INFO - Using env: {'DISPLAY': ':0', 11:31:17 INFO - 'HOME': '/home/cltbld', 11:31:17 INFO - 'LANG': 'en_US.UTF-8', 11:31:17 INFO - 'LANGUAGE': 'en_US:en', 11:31:17 INFO - 'LOGNAME': 'cltbld', 11:31:17 INFO - 'MAIL': '/var/mail/cltbld', 11:31:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:31:17 INFO - 'MOZ_NO_REMOTE': '1', 11:31:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:31:17 INFO - 'NO_EM_RESTART': '1', 11:31:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:31:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:31:17 INFO - 'PWD': '/builds/slave/test', 11:31:17 INFO - 'SHELL': '/bin/bash', 11:31:17 INFO - 'SHLVL': '1', 11:31:17 INFO - 'TERM': 'linux', 11:31:17 INFO - 'TMOUT': '86400', 11:31:17 INFO - 'USER': 'cltbld', 11:31:17 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1505413615.117019-1729712491', 11:31:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:31:17 INFO - '_': '/tools/buildbot/bin/python'} 11:31:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:31:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:31:17 INFO - Running setup.py (path:/tmp/pip-QXk1U7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:31:17 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)) 11:31:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:31:17 INFO - Running setup.py (path:/tmp/pip-PrcgzG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:31:17 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)) 11:31:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:31:17 INFO - Running setup.py (path:/tmp/pip-754cjT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:31:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:31:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:31:17 INFO - Running setup.py (path:/tmp/pip-Rf21HV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:31:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:31:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:31:17 INFO - Running setup.py (path:/tmp/pip-VdH11W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:31:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 11:31:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:31:17 INFO - Running setup.py (path:/tmp/pip-KPUz_j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:31:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 11:31:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:31:17 INFO - Running setup.py (path:/tmp/pip-Rx55ZD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:31:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 11:31:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:31:17 INFO - Running setup.py (path:/tmp/pip-a6lD0p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:31:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 11:31:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:31:17 INFO - Running setup.py (path:/tmp/pip-QapH7U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:31:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 11:31:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:31:17 INFO - Running setup.py (path:/tmp/pip-pBjv16-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:31:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:31:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:31:17 INFO - Running setup.py (path:/tmp/pip-VuZFGO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:31:18 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)) 11:31:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:31:18 INFO - Running setup.py (path:/tmp/pip-bfa7t1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:31:18 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)) 11:31:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:31:18 INFO - Running setup.py (path:/tmp/pip-T9NGLf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:31:18 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)) 11:31:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:31:18 INFO - Running setup.py (path:/tmp/pip-Qj68jl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:31:18 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)) 11:31:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:31:18 INFO - Running setup.py (path:/tmp/pip-AWuJft-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:31:18 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)) 11:31:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:31:18 INFO - Running setup.py (path:/tmp/pip-tALOr_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:31:18 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)) 11:31:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:31:18 INFO - Running setup.py (path:/tmp/pip-vlli4R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:31:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 11:31:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:31:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:31:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:31:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:31:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:31:18 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 11:31:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:31:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:31:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:31:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:31:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:31:19 INFO - Downloading blessings-1.6.tar.gz 11:31:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:31:19 INFO - Installing collected packages: blessings 11:31:19 INFO - Running setup.py install for blessings 11:31:19 INFO - Successfully installed blessings 11:31:19 INFO - Cleaning up... 11:31:19 INFO - Return code: 0 11:31:19 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:31:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:31:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:31:19 INFO - Reading from file tmpfile_stdout 11:31:19 INFO - Current package versions: 11:31:19 INFO - argparse == 1.2.1 11:31:19 INFO - blessings == 1.6 11:31:19 INFO - blobuploader == 1.2.4 11:31:19 INFO - docopt == 0.6.1 11:31:19 INFO - functools32 == 3.2.3-2 11:31:19 INFO - jsonschema == 2.5.1 11:31:19 INFO - manifestparser == 1.1 11:31:19 INFO - mozInstall == 1.13 11:31:19 INFO - mozcrash == 1.0 11:31:19 INFO - mozdebug == 0.1 11:31:19 INFO - mozdevice == 0.51 11:31:19 INFO - mozfile == 1.2 11:31:19 INFO - mozhttpd == 0.7 11:31:19 INFO - mozinfo == 0.10 11:31:19 INFO - mozleak == 0.1 11:31:19 INFO - mozlog == 3.5 11:31:19 INFO - moznetwork == 0.27 11:31:19 INFO - mozprocess == 0.25 11:31:19 INFO - mozprofile == 0.28 11:31:19 INFO - mozrunner == 6.13 11:31:19 INFO - mozscreenshot == 0.1 11:31:19 INFO - mozsystemmonitor == 0.3 11:31:19 INFO - moztest == 0.8 11:31:19 INFO - mozversion == 1.4 11:31:19 INFO - psutil == 3.1.1 11:31:19 INFO - requests == 1.2.3 11:31:19 INFO - wsgiref == 0.1.2 11:31:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:31:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:31:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:31:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:31:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:31:20 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 0x16cb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1894a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a37a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a3b090>, '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 0x1a3bd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a35f90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1505413615.117019-1729712491', '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 11:31:20 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 11:31:20 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 11:31:20 INFO - Using env: {'DISPLAY': ':0', 11:31:20 INFO - 'HOME': '/home/cltbld', 11:31:20 INFO - 'LANG': 'en_US.UTF-8', 11:31:20 INFO - 'LANGUAGE': 'en_US:en', 11:31:20 INFO - 'LOGNAME': 'cltbld', 11:31:20 INFO - 'MAIL': '/var/mail/cltbld', 11:31:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:31:20 INFO - 'MOZ_NO_REMOTE': '1', 11:31:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:31:20 INFO - 'NO_EM_RESTART': '1', 11:31:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:31:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:31:20 INFO - 'PWD': '/builds/slave/test', 11:31:20 INFO - 'SHELL': '/bin/bash', 11:31:20 INFO - 'SHLVL': '1', 11:31:20 INFO - 'TERM': 'linux', 11:31:20 INFO - 'TMOUT': '86400', 11:31:20 INFO - 'USER': 'cltbld', 11:31:20 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1505413615.117019-1729712491', 11:31:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:31:20 INFO - '_': '/tools/buildbot/bin/python'} 11:31:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:31:20 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)) 11:31:20 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)) 11:31:20 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)) 11:31:20 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)) 11:31:20 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)) 11:31:20 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)) 11:31:20 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)) 11:31:20 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)) 11:31:20 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)) 11:31:20 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 11:31:20 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 11:31:20 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 11:31:20 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 11:31:20 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 11:31:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 11:31:21 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)) 11:31:21 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)) 11:31:21 INFO - Installing collected packages: simplejson 11:31:21 INFO - Running setup.py install for simplejson 11:31:21 INFO - building 'simplejson._speedups' extension 11:31:21 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 11:31:22 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 11:31:22 INFO - Successfully installed simplejson 11:31:22 INFO - Cleaning up... 11:31:22 INFO - Return code: 0 11:31:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:31:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:31:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:31:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:31:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:31:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16cb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1894a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a37a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a3b090>, '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 0x1a3bd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a35f90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1505413615.117019-1729712491', '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 11:31:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 11:31:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 11:31:22 INFO - Using env: {'DISPLAY': ':0', 11:31:22 INFO - 'HOME': '/home/cltbld', 11:31:22 INFO - 'LANG': 'en_US.UTF-8', 11:31:22 INFO - 'LANGUAGE': 'en_US:en', 11:31:22 INFO - 'LOGNAME': 'cltbld', 11:31:22 INFO - 'MAIL': '/var/mail/cltbld', 11:31:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:31:22 INFO - 'MOZ_NO_REMOTE': '1', 11:31:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:31:22 INFO - 'NO_EM_RESTART': '1', 11:31:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:31:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:31:22 INFO - 'PWD': '/builds/slave/test', 11:31:22 INFO - 'SHELL': '/bin/bash', 11:31:22 INFO - 'SHLVL': '1', 11:31:22 INFO - 'TERM': 'linux', 11:31:22 INFO - 'TMOUT': '86400', 11:31:22 INFO - 'USER': 'cltbld', 11:31:22 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1505413615.117019-1729712491', 11:31:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:31:22 INFO - '_': '/tools/buildbot/bin/python'} 11:31:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:31:22 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:31:22 INFO - Cleaning up... 11:31:22 INFO - Return code: 0 11:31:22 INFO - Running post-action listener: _resource_record_post_action 11:31:22 INFO - Running post-action listener: _start_resource_monitoring 11:31:22 INFO - Starting resource monitoring. 11:31:22 INFO - [mozharness: 2017-09-14 18:31:22.690945Z] Finished create-virtualenv step (success) 11:31:22 INFO - [mozharness: 2017-09-14 18:31:22.691414Z] Running install step. 11:31:22 INFO - Running pre-action listener: _resource_record_pre_action 11:31:22 INFO - Running main action method: install 11:31:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:31:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:31:22 INFO - Reading from file tmpfile_stdout 11:31:22 INFO - Detecting whether we're running mozinstall >=1.0... 11:31:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:31:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:31:23 INFO - Reading from file tmpfile_stdout 11:31:23 INFO - Output received: 11:31:23 INFO - Usage: mozinstall [options] installer 11:31:23 INFO - Options: 11:31:23 INFO - -h, --help show this help message and exit 11:31:23 INFO - -d DEST, --destination=DEST 11:31:23 INFO - Directory to install application into. [default: 11:31:23 INFO - "/builds/slave/test"] 11:31:23 INFO - --app=APP Application being installed. [default: firefox] 11:31:23 INFO - mkdir: /builds/slave/test/build/application 11:31:23 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'] 11:31:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 11:31:41 INFO - Reading from file tmpfile_stdout 11:31:41 INFO - Output received: 11:31:41 INFO - /builds/slave/test/build/application/firefox/firefox 11:31:41 INFO - Running post-action listener: _resource_record_post_action 11:31:41 INFO - [mozharness: 2017-09-14 18:31:41.264037Z] Finished install step (success) 11:31:41 INFO - [mozharness: 2017-09-14 18:31:41.264287Z] Running setup-mitmproxy step. 11:31:41 INFO - Running pre-action listener: _resource_record_pre_action 11:31:41 INFO - Running main action method: setup_mitmproxy 11:31:41 INFO - Skipping: mitmproxy is not required 11:31:41 INFO - Running post-action listener: _resource_record_post_action 11:31:41 INFO - [mozharness: 2017-09-14 18:31:41.265416Z] Finished setup-mitmproxy step (success) 11:31:41 INFO - [mozharness: 2017-09-14 18:31:41.265637Z] Running run-tests step. 11:31:41 INFO - Running pre-action listener: _resource_record_pre_action 11:31:41 INFO - Running pre-action listener: _set_gcov_prefix 11:31:41 INFO - Running main action method: run_tests 11:31:41 WARNING - Try message not found. 11:31:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:31:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:31:41 INFO - Python 2.7.3 11:31:41 INFO - Return code: 0 11:31:41 INFO - grabbing minidump binary from tooltool 11:31:41 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')]} 11:31:41 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 0x1a3b090>, '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 0x1a3bd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a35f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:31:41 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 11:31:41 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 11:31:41 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 11:31:41 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:31:41 INFO - Return code: 0 11:31:41 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:31:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:31:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:31:41 INFO - ENV: RUST_BACKTRACE is now full 11:31:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:31:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:31:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IDcefCo4SICyNXkppCNLZA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-082', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 11:31:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/IDcefCo4SICyNXkppCNLZA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-082 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 11:31:41 INFO - Using env: {'DISPLAY': ':0', 11:31:41 INFO - 'HOME': '/home/cltbld', 11:31:41 INFO - 'LANG': 'en_US.UTF-8', 11:31:41 INFO - 'LANGUAGE': 'en_US:en', 11:31:41 INFO - 'LOGNAME': 'cltbld', 11:31:41 INFO - 'MAIL': '/var/mail/cltbld', 11:31:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:31:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:31:41 INFO - 'MOZ_ACCELERATED': '1', 11:31:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:31:41 INFO - 'MOZ_NO_REMOTE': '1', 11:31:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:31:41 INFO - 'MOZ_WEBRENDER': '1', 11:31:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:31:41 INFO - 'NO_EM_RESTART': '1', 11:31:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:31:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:31:41 INFO - 'PWD': '/builds/slave/test', 11:31:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:31:41 INFO - 'RUST_BACKTRACE': 'full', 11:31:41 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:31:41 INFO - 'SHELL': '/bin/bash', 11:31:41 INFO - 'SHLVL': '1', 11:31:41 INFO - 'TERM': 'linux', 11:31:41 INFO - 'TMOUT': '86400', 11:31:41 INFO - 'USER': 'cltbld', 11:31:41 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1505413615.117019-1729712491', 11:31:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:31:41 INFO - '_': '/tools/buildbot/bin/python'} 11:31:41 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IDcefCo4SICyNXkppCNLZA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-082', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 11:31:41 INFO - mozversion application_buildid: 20170914174242 11:31:41 INFO - mozversion application_changeset: 593158cd491002031b4527a95d9bfac79c0cdcef 11:31:41 INFO - mozversion application_display_name: Nightly 11:31:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:31:41 INFO - mozversion application_name: Firefox 11:31:41 INFO - mozversion application_remotingname: firefox 11:31:41 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 11:31:41 INFO - mozversion application_vendor: Mozilla 11:31:41 INFO - mozversion application_version: 57.0a1 11:31:41 INFO - mozversion platform_buildid: 20170914174242 11:31:41 INFO - mozversion platform_changeset: 593158cd491002031b4527a95d9bfac79c0cdcef 11:31:41 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 11:31:41 INFO - mozversion platform_version: 57.0a1 11:31:41 INFO - using testdate: 1505413901 11:31:41 INFO - actual date: 1505413901 11:31:41 INFO - starting webserver on 'localhost:58225' 11:31:41 INFO - SUITE-START | Running 2 tests 11:31:41 INFO - TEST-START | dromaeo_css 11:31:41 INFO - Initialising browser for dromaeo_css test... 11:31:41 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:58225/getInfo.html -profile /tmp/tmpn4GQIQ/profile 11:31:41 INFO - TEST-INFO | started process 13246 (/builds/slave/test/build/application/firefox/firefox http://localhost:58225/getInfo.html) 11:31:50 INFO - TEST-INFO | 13246: exit 0 11:31:50 INFO - Browser initialized. 11:31:50 INFO - Running cycle 1/1 for dromaeo_css test... 11:31:50 INFO - Using env: {'DISPLAY': ':0', 11:31:50 INFO - 'HOME': '/home/cltbld', 11:31:50 INFO - 'JSGC_DISABLE_POISONING': '1', 11:31:50 INFO - 'LANG': 'en_US.UTF-8', 11:31:50 INFO - 'LANGUAGE': 'en_US:en', 11:31:50 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 11:31:50 INFO - 'LOGNAME': 'cltbld', 11:31:50 INFO - 'MAIL': '/var/mail/cltbld', 11:31:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:31:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:31:50 INFO - 'MOZ_ACCELERATED': '1', 11:31:50 INFO - 'MOZ_CRASHREPORTER': '1', 11:31:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:31:50 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 11:31:50 INFO - 'MOZ_NO_REMOTE': '1', 11:31:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:31:50 INFO - 'MOZ_WEBRENDER': '1', 11:31:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:31:50 INFO - 'NO_EM_RESTART': '1', 11:31:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:31:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:31:50 INFO - 'PWD': '/builds/slave/test', 11:31:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:31:50 INFO - 'RUST_BACKTRACE': 'full', 11:31:50 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:31:50 INFO - 'SHELL': '/bin/bash', 11:31:50 INFO - 'SHLVL': '1', 11:31:50 INFO - 'TERM': 'linux', 11:31:50 INFO - 'TMOUT': '86400', 11:31:50 INFO - 'USER': 'cltbld', 11:31:50 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1505413615.117019-1729712491', 11:31:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:31:50 INFO - '_': '/tools/buildbot/bin/python'} 11:31:50 INFO - TEST-INFO | started process 13487 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpn4GQIQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 11:31:51 INFO - PID 13487 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 11:31:51 INFO - PID 13487 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 11:31:51 INFO - PID 13487 | 11:31:51 INFO - PID 13487 | (/builds/slave/test/build/application/firefox/firefox:13547): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:31:51 INFO - PID 13487 | 11:32:01 INFO - PID 13487 | RSS: Main: 215805952 11:32:01 INFO - PID 13487 | 11:34:59 INFO - PID 13487 | Cycle 1(1): loaded http://localhost:58225/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58225/tests/dromaeo/cssquery-ext.html) 11:35:01 INFO - PID 13487 | RSS: Main: 224370688 11:35:01 INFO - PID 13487 | 11:38:03 INFO - PID 13487 | Cycle 1(1): loaded http://localhost:58225/tests/dromaeo/cssquery-ext.html (next: http://localhost:58225/tests/dromaeo/cssquery-jquery.html) 11:38:04 INFO - PID 13487 | RSS: Main: 220999680 11:38:04 INFO - PID 13487 | 11:41:06 INFO - PID 13487 | Cycle 1(1): loaded http://localhost:58225/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58225/tests/dromaeo/cssquery-mootools.html) 11:41:06 INFO - PID 13487 | RSS: Main: 226615296 11:41:06 INFO - PID 13487 | 11:44:08 INFO - PID 13487 | Cycle 1(1): loaded http://localhost:58225/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58225/tests/dromaeo/cssquery-prototype.html) 11:44:09 INFO - PID 13487 | RSS: Main: 223588352 11:44:09 INFO - PID 13487 | 11:47:13 INFO - PID 13487 | Cycle 1(1): loaded http://localhost:58225/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58225/tests/dromaeo/cssquery-yui.html) 11:47:13 INFO - PID 13487 | RSS: Main: 226275328 11:47:13 INFO - PID 13487 | 11:50:15 INFO - PID 13487 | Cycle 1(1): loaded http://localhost:58225/tests/dromaeo/cssquery-yui.html (next: http://localhost:58225/tests/dromaeo/cssquery-dojo.html) 11:50:15 INFO - PID 13487 | RSS: Main: 229081088 11:50:15 INFO - PID 13487 | 11:50:15 INFO - PID 13487 | __start_tp_report 11:50:15 INFO - PID 13487 | _x_x_mozilla_page_load 11:50:15 INFO - PID 13487 | _x_x_mozilla_page_load_details 11:50:15 INFO - PID 13487 | |i|pagename|runs| 11:50:15 INFO - PID 13487 | |0;dojo.html;3076.9846150769235;3105.596272484577;3178.6344570374413;3182.124915648195;3185.8407079646013;6112.388761123885;6225.7509699611965;6243.032329988853;6267.467265282451;6292.150559674443;6213.968930155354;6236.282827474842;6325.652089135096;6344;6352.968235158818;5808.651480911138;5872.471477567018;5895.263092113486;5895.734691938873;5917.378675239102;5630.465105814961;5822.1266809978415;5842.21130147431;5895.9705201473935;5930.911036334458;5636.351819540764;5678.403767604404;5685.090385538294;5712.714364281811;5796.275465566804;5726.856828579277;5768.278965129359;5771.8456308738005;5772.3361813391575;5776.133579962997;5652.5195358394585;5765.077587585966;5795.507381872546;5798.246227990334;5798.768049278024;6132.141500189977;6234.407731265523;6266.811995640138;6275.121482992385;6317.431431171217;6602.900956485657;6630.237522684904;6630.535862489628;6641.767538136142;6646;6623.4038936495945;6643.103181070542;6696.665166741644;6722;6809.285025072346;6120;6157.107219453199;6286.277078135981;6289.779857704958;6332.05019247112;6814.931850681529;6973.163220413481;6983.2318444971015;6986.790396288119;6995.685194166274;6595.0766892635065;6644.202695676453;6703.698333575001;6706.362895525015;6730.427913627299;6475.838104047437;6679;6715.127033485615;6756.358145976124;6807.2512023677355;6532;6595.901061484082;6599.70301336441;6619.768308109193;6642.933570664231;6641.933580664131;6657.40083392497;6669.966650166815;6679.265280819106;6695.397414232742;5022.799365582929;5133.512316329943;5217.217417387422;5237.790488380422;5256.816011439581;4547.47704014036;4607.9078418431445;4626.814927402934;4671.182543054952;4679.759863636139;5779.566532510079;5788.710564471759;5861;5877.089051197071;5881.852953676107;6535.215774107138;6601.8349541260895;6612.966935165294;6620.635865027373;6738.932610673928;5750.482456578928;5759.424057594207;5776.104703770922;5777.62445441045;5780.277465316835;5720.885582288331;5745.712714364265;5758.740856661377;5829.300483941955;5868.23712917318;6542.1168142300185;6604.96697516519;6634.104395906491;6642.1033160523675;6659.833504162435;6693.096431981718;6734.22556406;6753.696083676345;6803.741307857963;6827.1807383112355;6294.402031806971;6449.3873082057125;6472.255690595642;6488.318726533835;6618.238902526271;6425.1968503937005;6512.772052978123;6545.21457425112;6652;6682.599044057372;6716.529842910949;6768.357006084414;6798.388145066968;6813.659317034029;6836.829079273058;6475.902861457176;6538.313477084837;6539.182602174728;6608.339166083353;6613.437857782143;6310.368963103653;6447.0651755494255;6459.903101453576;6479.611223326616;6510.544261901811;6407.263164736115;6408.775692850728;6418.711157997808;6424.743010279536;6433.710483028369;6346.048092786119;6441.516886233458;6459.224893012869;6498.480121590167;6515.934840651723;6410.134631824644;6417.1657684500715;6425.1004859320665;6433.13152724395;6458.22501299847 11:50:15 INFO - PID 13487 | |1;ext.html;830.8629076202427;838.0822998816294;838.748375487354;844.9028361738398;856.7258477287271;37607.81196094019;40812.59187408125;40915.59084409155;41192.17615647689;41228.38157427641;38427;38428.23143537128;38890.222195556074;38954.220915581674;38967.41548876769;6923.653817309127;7025.578465292073;7026.6486675666265;7036.964815175917;7579.810504737398;7528.774136775905;7563.6218189090605;7610.086789585258;7628.275313845176;7631;7561.092668879742;7567.356774674132;7600.847983040336;7607.80980475487;7616.581088040156;7534.585597792119;7545.320921117071;7616.809579760495;7622.580758058304;7623.275788800055;7518.135414427356;7595.392368610554;7606.125295590967;7612.124605670363;7623.428242881806;37945.81027094847;38022.80988595067;38142.42786358207;38350.42474362887;38487.23025539474;3395.0833275015607;3424.332255210235;3424.7260219182403;3428.879989200366;3460.048486666187;30857.691423085707;31102.688973110206;31148;31156.53265201024;31160.53259201091;5015.5736762375245;5052.898942021139;5092.8217512387255;5103.795848166069;5132.8460146195675;179848;180027;180308;181749;182774;41184.588154118675;41345.586544134174;46446.767766161625;46734.53265467302;47081.5291847084;49254;50018.74990625024;50033.499665002884;50095.24857127146;50313.245301319774;16303;16428.835711642732;16453.424130155625;16459.670806583803;16485.58786030359;13346.532871349651;13441.46234150623;13470.461181552628;13558.525451609146;13564.118332308566;14062.15627062359;14097.224652644203;14150.716985660434;14249.572512824632;14306.141631502143;5562.527185189304;5658.688772117491;5681.943180568224;5809.651420914759;5842.912356314659;1001.5643195210107;1009.5759780892043;1019.5768755966191;1021.8416145497459;1033.2508931025097;22249;22274.44313892133;22359.66460503094;22366;22588;1053.4303887208005;1085.3325204998869;1089.3028461784463;1093.6281664234198;1096.5010920031366;11901.285922844658;12056.276623402624;12098.153129280865;12111.212771169845;12123.333216673167;214190;214206;214628;215871;216494;50978.74510627423;51137;51193.48806511887;51470.485295147315;51865.48134518682;1362.4754469529303;1407.7691697554137;1443.1196969848595;1448.391675496294;1455.4833034272892;22026.779732202474;23142.42143946415;23175.536489269783;23178.420539486648;23519.647205292276;36490.8175459121;36546.26907461783;36620.63379366172;36637.45043824398;36654.26691466209;186373;187185;187751;188281;188834;29288.121356359345;32400.675993239765;33014;34345;34743.82628086843;41632.583674162874;42947.35578966381;43084;43943.56056439395;44073.338899915885;31008.844955775076;31503;31742.365152696355;31976;32059.87790427324;31501;31519.750457394468;31613.89351372691;31660.525092123175;31880.68119318777;36010.819945900104;36012;36153;36385.45421818622;36406;30641.387172255985;31050.53424198684;31419.214519637193;31541.36917261596;31954.840225799653;22941.426464338525;23010.19464318741;23042.308730738103;23047.769522304563;23189.76810231876 11:50:15 INFO - PID 13487 | |2;jquery.html;552.6960171905451;562.1595714406961;564.9435056494353;574.3825387708215;575.1889835332181;21570.352889413323;22297.108115675375;22406.32781016568;22428.102875884946;22440.102395904145;23296.767032329673;23959.5208095838;24027.27918162458;24108.276751697435;24259.514809703796;6372.681365931697;6633.767818126366;6664.233613134503;6669.666516674171;6670.7331706731675;6527.477801775846;6623.73505059797;6623.9006414903815;6627.668616569176;6634.203895532519;6229.190205273331;6541.443977261938;6569.671516424183;6589.011648252753;6598.802035938929;6436.098946147543;6465.12720782693;6477.9676101619425;6540.084388185658;6542.116814230066;5991.340952495222;6022.337542870281;6028.035514317732;6039.698015099228;6041.033434650479;26803.46393072147;27181.1845644629;27869.303267418272;27888.884444622192;27915;1997.5605366819423;2056.4653190170516;2062.391594479617;2069.3481553310658;2081.26115229093;18932.62134757297;19626.116824742778;19627.90186049075;19677.508062298413;19699;2336.3224664847176;2354.682117914077;2365.2549446924163;2394.760523947609;2462.839915405511;121117;127791.36104319512;127972.36013819871;131090;141438.29280853632;13968.301584920713;14150.716985660229;14325.35535900887;14499.560888485361;14656.706865862836;22443.32670019902;22479.213227537286;22507.212247571257;22554.548909021727;22817.429564260696;9660.565274562661;9920.80158396828;9966.501674916226;10016.198704103654;10024.54889529973;9158.221551168226;9335.906640933505;9372.578233979351;9412;9426.245900327956;8751.343649226334;8852.468851868909;8961.955190224007;8987.865182022144;9058.909410905939;2652.9469410611678;2696.8786404611837;2735.9317710824357;2809.6258661431057;2816.8873245070376;926.28174735864;958.3004406782948;958.755517343073;961.1637875048752;975.8292298847673;18386.81613183878;18391.540211494554;18450.26198952054;19081.904590477236;19095.522611934535;997.1773287038222;998.4558415663481;1018.5671089786811;1027.173125633857;1032.2258306270298;9975.301728879054;10194.898051019394;10240.436775977392;10619.787604247871;10677.305975111592;451461;467923;468345;471395;471991;111255;112715;114571;114955.42522287503;116044;1142.1548054439654;1158.7566611011614;1159.2233203753506;1165.114512970132;1166.976660466786;16656.916715416344;17159.914200428917;17219.22513486871;17353;17409.216585253947;52530;53489.73255133855;55565.72217138889;55730.72134639301;56320.436795633155;63893;65986;66173;66848.33151668421;67033.32966670465;13729.45082196701;13774.104683195561;13897;13914.51299204523;14068;17805.46583602494;18905.716414253522;21767;22148.557028859654;22306.553868922856;17829.643407131527;17876.642467150323;17908.55228619295;17935.641287173923;18130.818691812914;15821.604459888595;15891.602709931882;16011.119388433748;16026.118563478654;16876.324947002475;17621.383251586132;17725.822741772416;17803;17963.371282005068;18613.162407691416;11587.826182607436;13878;13888.583342499742;13890.444382225001;13896.027278090436;14924.776128357866;16860.66278674444;17828.19773110187;17842.375516856846;17973.37093201732 11:50:15 INFO - PID 13487 | |3;mootools.html;1310.0829419406416;1346.491929039476;1348.6830594810217;1361.6731984323765;1382.4746596293407;14154.433822647095;14608;14608.269586520684;14772.778408323884;14801.185934773583;14253.429862805475;14387.496437624684;14433.566992990227;14499.855001449982;14521.20133392663;2668.4529671417313;2699.9595006074924;2738.835669859805;2763.2539214412195;2765.308672831792;2678.450917561895;2716.4567086582665;2732.931676708078;2733.221032005879;2735.0564055400955;2211.635080211763;2269.0810221860174;2704.8918043278245;2719.809613327068;2725.7819374449996;2701.594760785888;2773.0433000614757;2776.8889244430197;2777.6805667348312;2778.111004478568;2590.313566904772;2599.766021058095;2617.332580192058;2652.4297276085736;2657.0965871603553;14632.41470341185;14786;14858.257087145708;14858.702825943421;14859.851401486063;7855.921440785576;8129.593520323961;8138.715144969898;8192.385571082134;8197.46716463428;4751.311059896296;5094.235864620299;5209.843704688866;5243.86890327741;5496.725163741837;1844.7325137854937;1857.3963461874944;1889.7448844405967;1903.7525121734086;1904.0122014218566;26198;26204.344891377677;26237.606435903475;26245.73754262452;26551.73448265512;7110.466714996396;7149.714011439484;7213.098362704662;7304.347826086957;7417.888731669029;19798.90100549517;19830.603387932162;19839;19851.801481984996;19861.900690496743;8070.919290807017;8217.876731849026;8317.168283171755;8358.916410835935;8374.70688525911;7341.155767086747;7348.1182258129365;7381.520201186906;7485.1017877855;7565.810854728566;5067.923981140286;5158.200478925697;5246.816361427332;5248.737563121905;5284.497972692588;1575.8266590675016;1580.85772280493;1603.5670369000304;1605.903645781257;1606.5501659535348;879.3668558637899;912.9041450647719;926.416357694648;929.1869614087673;938.5401153434768;7573.848523029619;7590.962045189739;7622.885656715153;7714.459987800912;7735.574543400167;705.5660768627359;706.9434445244368;711.5659447736876;724.4313214126885;726.963651817407;4699.929501057487;4702.247640377524;4732.645051621143;4735;4777.235642297285;20952.58094838125;21463.678044829026;21823.563528729654;21880.89059554692;22045.889770551366;20467.18131274762;21229.150833966778;21358.252461163785;21419.57160856774;21423.571528569653;10702.357858528514;11282.492287846902;11311.321320720948;11493.42532873353;11591;8563.785905352415;8572.39993200495;8624.913750862412;8625.91374086251;8830.690925817566;17920.820791792437;18165.455036348932;18370.35703750362;18634;18669.15988780481;18468.907655461637;18598.53503662419;18706.532336691147;18800.52998675044;18909.243630254638;11008.614698485515;11065.336079835235;11106;11180.664580062612;11181.105511559219;13824.101433406797;13902.582922512342;14019.439222430989;14070.577882663536;14204.147751134966;10488.737781555523;10585.153187744809;10699.625513107005;10804.891951080703;10911.345319280868;10661.466926653791;10948.45257737126;11029;11091.556337746722;11179.105671546095;9993.800123997626;10080.344777589433;10108.595656173671;10120.190384769054;10149.137323327599;6963.40811031068;7015.438764898898;7026;7066.116735408074;7099.82250443743;7220.3140701633265;7374.483786134919;7420.369268612229;7429.44279179053;7453.962730186315 11:50:15 INFO - PID 13487 | |4;prototype.html;502.6607040247832;504.9166887463566;527.4303751947897;531.5986430245164;532.9520343169114;6273.404026617475;6276.05859121132;6278.246610406752;6311.936880631192;6329.683515824214;6108.816735497931;6219.346968568292;6239.438450539451;6241;6267.780627678032;5138.8715282118055;5152.484751524837;5153.330067091291;5183.818566350178;5212.791488340462;5132.4610915853855;5166.715830629314;5175.870603234912;5178.326817513718;5226.451222621627;4733.810647574093;4893.632977526682;4932.580730637882;4950.1584730595705;4978;4912.936566642019;4921.655484116113;4946.629002824802;4975.527324904128;4981.911215046676;4358.891027724332;4362.672799540047;4372.672049596261;4384.714993525411;4421.955780442187;5973.223480947492;6024.518038556905;6079.422454866781;6080.0847732963975;6109.877802443971;5620.494155525981;5635.422081817057;5671.9716401418145;5695.857603559903;5703.771849126071;6370.662160946207;6441.774537891151;6491.5780474269495;6533.575317604341;6559.573627714183;4573.359729637853;4777.235642297251;4799.9760001199775;4801.726463447534;4825.227963525819;6590.176227971504;6708.9075663845015;6736.58531708337;6776.491763117786;6871.553349032297;5391.460853914656;5400.243965844785;5417.5395091416885;5461.672299662033;5477.835664930059;5476.945230547723;5530.86172845674;5533.45461087896;5537.501624853782;5550.7779688812;5769.711514424262;5851.255196674132;5998.070299103646;6006.4594186522545;6015.045110339157;6398.456131228898;6577.177852768404;6701.0953521274005;6707.597544147269;6717.193936727624;1870.036930980546;1887.537553299423;1912.5218695326168;1915.9521011974534;1939.1370839976357;1411.647088227943;1413.4204975960013;1426.108682073704;1431.5562175725559;1438.978415323771;5581.7209139543675;5589.888202235932;5611.551075913917;5671.943280567224;5694;6484.1894763154605;6539.803805885831;6549.284515149085;6578.210614726264;6618.6690665467495;5502.697351645698;5588.6646801192055;5599.936012157677;5633.436656334334;5715.1713001614535;3513.789172649649;3517.3995831896427;3521.454174602941;3542.574891013095;3568.7858728476376;6442.387973142544;6461.19235095613;6470.741170353133;6476.805695829133;6534.607923524603;6662.700178491979;6776.7628133015105;6792;6839.487038472134;6844.863102737917;6406.84676758188;6409.391107844747;6424.775132870327;6515.250746164157;6557.245916719447;6626.6686665667485;6641.701123449553;6652.101966234496;6673.599584024974;6701.631410272482;6366.93633063682;6560.901586476302;6610.603363798187;6635.402813746786;6665.566738162004;6138.785142519991;6213.751449941951;6306.842328941813;6341.365863413622;6446.4520515756685;5367.651102678314;5388.946110538845;5394.2448057272795;5412.918806217831;5446.618736688394;6572.375624315682;6635.137432133792;6660.966695166493;6691.5315927884585;6692.732290708317;6407.679616019273;6420.486361091008;6421.775237866652;6442.677866106769;6447.903281450688;6415.358464153547;6471.288158302489;6485.805425837232;6490.95534906503;6493.675316234075;6244.812655620339;6306.400891915261;6343.397377249154;6345.17512723343;6470.623489222385;6337.809865704036;6425.293217745951;6429.196350456193;6485.675716214265;6526.738930442919;6217.160683307696;6235.407636274728;6267.091271765659;6269.49844012487;6276.874462510816 11:50:15 INFO - PID 13487 | |5;yui.html;890.8530092534733;906.8186362727454;926.532101288849;928.6285485805681;941.2234906202385;18339.633207335846;19039.61920761584;19159.425217243504;19162;19223.519412014706;18365.54086147847;18480.2607895684;18905.33831315904;18951.810481895176;19032.5241868953;972.6158167523823;990.9058639429243;993.5727637116037;993.9751506212331;999.9500024998757;970.3935040599625;975.7121649113493;976.3360914578084;983.365729104727;984.7439665686937;1019.525920446992;1036.3885307668475;1038.7143535527714;1041.0994489766374;1042.8748550173989;1026.0252759878147;1026.8613737145465;1028.3213079367656;1032.8760548734124;1034.0228484082545;752.6086435053796;760.3385055002184;760.8554374668795;762.5081822224635;763.713607397226;18942.337018204296;19505.609887802308;19694.507637309038;19715.309964151187;19741.703874441893;20941.685874711893;21310.235719047527;21599.784002159933;21879.12483500658;22075.227367042236;16296.348146074142;16677.916610416993;16978.49064528066;17171.96968181913;17289.913550432168;3753.6246375362516;3778.943315850237;3786.6024067472795;3789.0337963819325;3820.5033345665165;138081;141722;142946;143555;144523.99999999895;14992.925035374754;15244.542663720105;15615.843841561438;15655.295511702001;15973.520794376187;31252.218694532818;31442.528362074587;31447.213819654233;31470.685293146777;32054.519182212287;8350.749477515685;8421.410501264852;8471.661133554711;8482.279006284534;8511.404201705946;4200.369944508348;4214.704970652025;4271.316589345752;4272.722273052242;4273.786310684453;5218.191180367049;5365.678059316453;5413.539849112791;5427.240186373911;5440.374356949002;982.8427451607708;989.2679417231197;1001.3591301567003;1010.0505524806805;1010.0757806606872;676.2054585861592;695.3255342317943;699.2692636195188;714.4855703893188;715.2704241673567;10806.189535784848;11178.496967636476;11269.703984041776;11563.76872462546;11701.531938722526;694.2363400259775;697.9581225126509;700.3556727810427;706.4242642246553;719.5790462579345;5733.254676891977;5802.767889284381;5819;5859.794907178229;5875.059990401515;112563.43718281356;114100.42949785198;117316.41341793236;117926.41036794761;120875;101273.49363253285;104888;104972;105297.47351263194;105316;88619.55690221637;91796.54101729448;93293.0670693298;93764.53117734367;94104.52947735217;10698.197635177237;10707.304025238334;10719.196060295664;10726.51730672106;10906.836397454203;40006;41117.58882411138;41976.58023419727;42543.1491370177;42944.78527607342;52724.47275527349;53911;54648.72675636596;54774.726126370704;55405.168922467004;15015.699686006437;15064.32210550506;15072.54782356531;15133.319000645217;15290.388384464497;17480.388186413413;17820.91089544544;17918.372856949944;17958.371456998942;18078.45764627063;16340.918295408446;16406.26171822247;16550.42073527421;16583.17084145812;16596.668066638358;13246.337683115613;13366.93316533411;13465.865341346462;13517;13578.86421135776;15856.365745370056;15898.920505397398;15988.440404586248;16109.919450402673;16340.67318653644;11814.586489472827;12221.633350999484;12271.877281227073;12479;12634.178778379375;11971.940140299243;11998.16012879125;12094;12340.876591233973;12389.132760706663 11:50:15 INFO - PID 13487 | __end_tp_report 11:50:15 INFO - PID 13487 | __start_cc_report 11:50:15 INFO - PID 13487 | _x_x_mozilla_cycle_collect,378 11:50:15 INFO - PID 13487 | __end_cc_report 11:50:15 INFO - PID 13487 | __startTimestamp1505415015982__endTimestamp 11:50:15 INFO - PID 13487 | 11:50:15 INFO - PID 13487 | ------- Summary: start ------- 11:50:15 INFO - PID 13487 | Number of tests: 6 11:50:15 INFO - PID 13487 | 11:50:15 INFO - PID 13487 | [#0] dojo.html Cycles:165 Average:6160.38 Median:6418.71 stddev:734.73 (11.4%) stddev-sans-first:696.03 11:50:15 INFO - PID 13487 | Values: 3077.0 3105.6 3178.6 3182.1 3185.8 6112.4 6225.8 6243.0 6267.5 6292.2 6214.0 6236.3 6325.7 6344.0 6353.0 5808.7 5872.5 5895.3 5895.7 5917.4 5630.5 5822.1 5842.2 5896.0 5930.9 5636.4 5678.4 5685.1 5712.7 5796.3 5726.9 5768.3 5771.8 5772.3 5776.1 5652.5 5765.1 5795.5 5798.2 5798.8 6132.1 6234.4 6266.8 6275.1 6317.4 6602.9 6630.2 6630.5 6641.8 6646.0 6623.4 6643.1 6696.7 6722.0 6809.3 6120.0 6157.1 6286.3 6289.8 6332.1 6814.9 6973.2 6983.2 6986.8 6995.7 6595.1 6644.2 6703.7 6706.4 6730.4 6475.8 6679.0 6715.1 6756.4 6807.3 6532.0 6595.9 6599.7 6619.8 6642.9 6641.9 6657.4 6670.0 6679.3 6695.4 5022.8 5133.5 5217.2 5237.8 5256.8 4547.5 4607.9 4626.8 4671.2 4679.8 5779.6 5788.7 5861.0 5877.1 5881.9 6535.2 6601.8 6613.0 6620.6 6738.9 5750.5 5759.4 5776.1 5777.6 5780.3 5720.9 5745.7 5758.7 5829.3 5868.2 6542.1 6605.0 6634.1 6642.1 6659.8 6693.1 6734.2 6753.7 6803.7 6827.2 6294.4 6449.4 6472.3 6488.3 6618.2 6425.2 6512.8 6545.2 6652.0 6682.6 6716.5 6768.4 6798.4 6813.7 6836.8 6475.9 6538.3 6539.2 6608.3 6613.4 6310.4 6447.1 6459.9 6479.6 6510.5 6407.3 6408.8 6418.7 6424.7 6433.7 6346.0 6441.5 6459.2 6498.5 6515.9 6410.1 6417.2 6425.1 6433.1 6458.2 11:50:15 INFO - PID 13487 | 11:50:15 INFO - PID 13487 | [#1] ext.html Cycles:180 Average:36161.30 Median:3426.80 stddev:50449.26 (1472.2%) stddev-sans-first:50521.03 11:50:15 INFO - PID 13487 | Values: 830.9 838.1 838.7 844.9 856.7 37607.8 40812.6 40915.6 41192.2 41228.4 38427.0 38428.2 38890.2 38954.2 38967.4 6923.7 7025.6 7026.6 7037.0 7579.8 7528.8 7563.6 7610.1 7628.3 7631.0 7561.1 7567.4 7600.8 7607.8 7616.6 7534.6 7545.3 7616.8 7622.6 7623.3 7518.1 7595.4 7606.1 7612.1 7623.4 37945.8 38022.8 38142.4 38350.4 38487.2 3395.1 3424.3 3424.7 3428.9 3460.0 30857.7 31102.7 31148.0 31156.5 31160.5 5015.6 5052.9 5092.8 5103.8 5132.8 179848.0 180027.0 180308.0 181749.0 182774.0 41184.6 41345.6 46446.8 46734.5 47081.5 49254.0 50018.7 50033.5 50095.2 50313.2 16303.0 16428.8 16453.4 16459.7 16485.6 13346.5 13441.5 13470.5 13558.5 13564.1 14062.2 14097.2 14150.7 14249.6 14306.1 5562.5 5658.7 5681.9 5809.7 5842.9 1001.6 1009.6 1019.6 1021.8 1033.3 22249.0 22274.4 22359.7 22366.0 22588.0 1053.4 1085.3 1089.3 1093.6 1096.5 11901.3 12056.3 12098.2 12111.2 12123.3 214190.0 214206.0 214628.0 215871.0 216494.0 50978.7 51137.0 51193.5 51470.5 51865.5 1362.5 1407.8 1443.1 1448.4 1455.5 22026.8 23142.4 23175.5 23178.4 23519.6 36490.8 36546.3 36620.6 36637.5 36654.3 186373.0 187185.0 187751.0 188281.0 188834.0 29288.1 32400.7 33014.0 34345.0 34743.8 41632.6 42947.4 43084.0 43943.6 44073.3 31008.8 31503.0 31742.4 31976.0 32059.9 31501.0 31519.8 31613.9 31660.5 31880.7 36010.8 36012.0 36153.0 36385.5 36406.0 30641.4 31050.5 31419.2 31541.4 31954.8 22941.4 23010.2 23042.3 23047.8 23189.8 11:50:15 INFO - PID 13487 | 11:50:15 INFO - PID 13487 | [#2] jquery.html Cycles:180 Average:33201.78 Median:22493.21 stddev:78595.10 (349.4%) stddev-sans-first:78777.35 11:50:16 INFO - PID 13487 | Values: 552.7 562.2 564.9 574.4 575.2 21570.4 22297.1 22406.3 22428.1 22440.1 23296.8 23959.5 24027.3 24108.3 24259.5 6372.7 6633.8 6664.2 6669.7 6670.7 6527.5 6623.7 6623.9 6627.7 6634.2 6229.2 6541.4 6569.7 6589.0 6598.8 6436.1 6465.1 6478.0 6540.1 6542.1 5991.3 6022.3 6028.0 6039.7 6041.0 26803.5 27181.2 27869.3 27888.9 27915.0 1997.6 2056.5 2062.4 2069.3 2081.3 18932.6 19626.1 19627.9 19677.5 19699.0 2336.3 2354.7 2365.3 2394.8 2462.8 121117.0 127791.4 127972.4 131090.0 141438.3 13968.3 14150.7 14325.4 14499.6 14656.7 22443.3 22479.2 22507.2 22554.5 22817.4 9660.6 9920.8 9966.5 10016.2 10024.5 9158.2 9335.9 9372.6 9412.0 9426.2 8751.3 8852.5 8962.0 8987.9 9058.9 2652.9 2696.9 2735.9 2809.6 2816.9 926.3 958.3 958.8 961.2 975.8 18386.8 18391.5 18450.3 19081.9 19095.5 997.2 998.5 1018.6 1027.2 1032.2 9975.3 10194.9 10240.4 10619.8 10677.3 451461.0 467923.0 468345.0 471395.0 471991.0 111255.0 112715.0 114571.0 114955.4 116044.0 1142.2 1158.8 1159.2 1165.1 1167.0 16656.9 17159.9 17219.2 17353.0 17409.2 52530.0 53489.7 55565.7 55730.7 56320.4 63893.0 65986.0 66173.0 66848.3 67033.3 13729.5 13774.1 13897.0 13914.5 14068.0 17805.5 18905.7 21767.0 22148.6 22306.6 17829.6 17876.6 17908.6 17935.6 18130.8 15821.6 15891.6 16011.1 16026.1 16876.3 17621.4 17725.8 17803.0 17963.4 18613.2 11587.8 13878.0 13888.6 13890.4 13896.0 14924.8 16860.7 17828.2 17842.4 17973.4 11:50:16 INFO - PID 13487 | 11:50:16 INFO - PID 13487 | [#3] mootools.html Cycles:180 Average:9294.30 Median:26220.98 stddev:6688.29 (25.5%) stddev-sans-first:6680.15 11:50:16 INFO - PID 13487 | Values: 1310.1 1346.5 1348.7 1361.7 1382.5 14154.4 14608.0 14608.3 14772.8 14801.2 14253.4 14387.5 14433.6 14499.9 14521.2 2668.5 2700.0 2738.8 2763.3 2765.3 2678.5 2716.5 2732.9 2733.2 2735.1 2211.6 2269.1 2704.9 2719.8 2725.8 2701.6 2773.0 2776.9 2777.7 2778.1 2590.3 2599.8 2617.3 2652.4 2657.1 14632.4 14786.0 14858.3 14858.7 14859.9 7855.9 8129.6 8138.7 8192.4 8197.5 4751.3 5094.2 5209.8 5243.9 5496.7 1844.7 1857.4 1889.7 1903.8 1904.0 26198.0 26204.3 26237.6 26245.7 26551.7 7110.5 7149.7 7213.1 7304.3 7417.9 19798.9 19830.6 19839.0 19851.8 19861.9 8070.9 8217.9 8317.2 8358.9 8374.7 7341.2 7348.1 7381.5 7485.1 7565.8 5067.9 5158.2 5246.8 5248.7 5284.5 1575.8 1580.9 1603.6 1605.9 1606.6 879.4 912.9 926.4 929.2 938.5 7573.8 7591.0 7622.9 7714.5 7735.6 705.6 706.9 711.6 724.4 727.0 4699.9 4702.2 4732.6 4735.0 4777.2 20952.6 21463.7 21823.6 21880.9 22045.9 20467.2 21229.2 21358.3 21419.6 21423.6 10702.4 11282.5 11311.3 11493.4 11591.0 8563.8 8572.4 8624.9 8625.9 8830.7 17920.8 18165.5 18370.4 18634.0 18669.2 18468.9 18598.5 18706.5 18800.5 18909.2 11008.6 11065.3 11106.0 11180.7 11181.1 13824.1 13902.6 14019.4 14070.6 14204.1 10488.7 10585.2 10699.6 10804.9 10911.3 10661.5 10948.5 11029.0 11091.6 11179.1 9993.8 10080.3 10108.6 10120.2 10149.1 6963.4 7015.4 7026.0 7066.1 7099.8 7220.3 7374.5 7420.4 7429.4 7454.0 11:50:16 INFO - PID 13487 | 11:50:16 INFO - PID 13487 | [#4] prototype.html Cycles:180 Average:5518.76 Median:6124.33 stddev:1490.09 (24.3%) stddev-sans-first:1445.93 11:50:16 INFO - PID 13487 | Values: 502.7 504.9 527.4 531.6 533.0 6273.4 6276.1 6278.2 6311.9 6329.7 6108.8 6219.3 6239.4 6241.0 6267.8 5138.9 5152.5 5153.3 5183.8 5212.8 5132.5 5166.7 5175.9 5178.3 5226.5 4733.8 4893.6 4932.6 4950.2 4978.0 4912.9 4921.7 4946.6 4975.5 4981.9 4358.9 4362.7 4372.7 4384.7 4422.0 5973.2 6024.5 6079.4 6080.1 6109.9 5620.5 5635.4 5672.0 5695.9 5703.8 6370.7 6441.8 6491.6 6533.6 6559.6 4573.4 4777.2 4800.0 4801.7 4825.2 6590.2 6708.9 6736.6 6776.5 6871.6 5391.5 5400.2 5417.5 5461.7 5477.8 5476.9 5530.9 5533.5 5537.5 5550.8 5769.7 5851.3 5998.1 6006.5 6015.0 6398.5 6577.2 6701.1 6707.6 6717.2 1870.0 1887.5 1912.5 1916.0 1939.1 1411.6 1413.4 1426.1 1431.6 1439.0 5581.7 5589.9 5611.6 5671.9 5694.0 6484.2 6539.8 6549.3 6578.2 6618.7 5502.7 5588.7 5599.9 5633.4 5715.2 3513.8 3517.4 3521.5 3542.6 3568.8 6442.4 6461.2 6470.7 6476.8 6534.6 6662.7 6776.8 6792.0 6839.5 6844.9 6406.8 6409.4 6424.8 6515.3 6557.2 6626.7 6641.7 6652.1 6673.6 6701.6 6366.9 6560.9 6610.6 6635.4 6665.6 6138.8 6213.8 6306.8 6341.4 6446.5 5367.7 5388.9 5394.2 5412.9 5446.6 6572.4 6635.1 6661.0 6691.5 6692.7 6407.7 6420.5 6421.8 6442.7 6447.9 6415.4 6471.3 6485.8 6491.0 6493.7 6244.8 6306.4 6343.4 6345.2 6470.6 6337.8 6425.3 6429.2 6485.7 6526.7 6217.2 6235.4 6267.1 6269.5 6276.9 11:50:16 INFO - PID 13487 | 11:50:16 INFO - PID 13487 | [#5] yui.html Cycles:180 Average:23759.54 Median:19099.52 stddev:34514.32 (180.7%) stddev-sans-first:34568.42 11:50:16 INFO - PID 13487 | Values: 890.9 906.8 926.5 928.6 941.2 18339.6 19039.6 19159.4 19162.0 19223.5 18365.5 18480.3 18905.3 18951.8 19032.5 972.6 990.9 993.6 994.0 1000.0 970.4 975.7 976.3 983.4 984.7 1019.5 1036.4 1038.7 1041.1 1042.9 1026.0 1026.9 1028.3 1032.9 1034.0 752.6 760.3 760.9 762.5 763.7 18942.3 19505.6 19694.5 19715.3 19741.7 20941.7 21310.2 21599.8 21879.1 22075.2 16296.3 16677.9 16978.5 17172.0 17289.9 3753.6 3778.9 3786.6 3789.0 3820.5 138081.0 141722.0 142946.0 143555.0 144524.0 14992.9 15244.5 15615.8 15655.3 15973.5 31252.2 31442.5 31447.2 31470.7 32054.5 8350.7 8421.4 8471.7 8482.3 8511.4 4200.4 4214.7 4271.3 4272.7 4273.8 5218.2 5365.7 5413.5 5427.2 5440.4 982.8 989.3 1001.4 1010.1 1010.1 676.2 695.3 699.3 714.5 715.3 10806.2 11178.5 11269.7 11563.8 11701.5 694.2 698.0 700.4 706.4 719.6 5733.3 5802.8 5819.0 5859.8 5875.1 112563.4 114100.4 117316.4 117926.4 120875.0 101273.5 104888.0 104972.0 105297.5 105316.0 88619.6 91796.5 93293.1 93764.5 94104.5 10698.2 10707.3 10719.2 10726.5 10906.8 40006.0 41117.6 41976.6 42543.1 42944.8 52724.5 53911.0 54648.7 54774.7 55405.2 15015.7 15064.3 15072.5 15133.3 15290.4 17480.4 17820.9 17918.4 17958.4 18078.5 16340.9 16406.3 16550.4 16583.2 16596.7 13246.3 13366.9 13465.9 13517.0 13578.9 15856.4 15898.9 15988.4 16109.9 16340.7 11814.6 12221.6 12271.9 12479.0 12634.2 11971.9 11998.2 12094.0 12340.9 12389.1 11:50:16 INFO - PID 13487 | -------- Summary: end -------- 11:50:16 INFO - PID 13487 | 11:50:17 INFO - TEST-INFO | 13487: exit 0 11:50:17 INFO - TEST-OK | dromaeo_css | took 1115409ms 11:50:17 INFO - TEST-START | kraken 11:50:17 INFO - Initialising browser for kraken test... 11:50:17 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:58225/getInfo.html -profile /tmp/tmpY4DwdD/profile 11:50:17 INFO - TEST-INFO | started process 13863 (/builds/slave/test/build/application/firefox/firefox http://localhost:58225/getInfo.html) 11:50:22 INFO - TEST-INFO | 13863: exit 0 11:50:22 INFO - Browser initialized. 11:50:22 INFO - Running cycle 1/1 for kraken test... 11:50:22 INFO - Using env: {'DISPLAY': ':0', 11:50:22 INFO - 'HOME': '/home/cltbld', 11:50:22 INFO - 'JSGC_DISABLE_POISONING': '1', 11:50:22 INFO - 'LANG': 'en_US.UTF-8', 11:50:22 INFO - 'LANGUAGE': 'en_US:en', 11:50:22 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 11:50:22 INFO - 'LOGNAME': 'cltbld', 11:50:22 INFO - 'MAIL': '/var/mail/cltbld', 11:50:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:50:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:50:22 INFO - 'MOZ_ACCELERATED': '1', 11:50:22 INFO - 'MOZ_CRASHREPORTER': '1', 11:50:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:50:22 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 11:50:22 INFO - 'MOZ_NO_REMOTE': '1', 11:50:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:50:22 INFO - 'MOZ_WEBRENDER': '1', 11:50:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:50:22 INFO - 'NO_EM_RESTART': '1', 11:50:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:50:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:50:22 INFO - 'PWD': '/builds/slave/test', 11:50:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:50:22 INFO - 'RUST_BACKTRACE': 'full', 11:50:22 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:50:22 INFO - 'SHELL': '/bin/bash', 11:50:22 INFO - 'SHLVL': '1', 11:50:22 INFO - 'TERM': 'linux', 11:50:22 INFO - 'TMOUT': '86400', 11:50:22 INFO - 'USER': 'cltbld', 11:50:22 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1505413615.117019-1729712491', 11:50:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:50:22 INFO - '_': '/tools/buildbot/bin/python'} 11:50:22 INFO - TEST-INFO | started process 14103 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpY4DwdD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 11:50:23 INFO - PID 14103 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 11:50:23 INFO - PID 14103 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 11:50:24 INFO - PID 14103 | 11:50:24 INFO - PID 14103 | (/builds/slave/test/build/application/firefox/firefox:14163): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:50:24 INFO - PID 14103 | 11:50:34 INFO - PID 14103 | RSS: Main: 217976832 11:50:34 INFO - PID 14103 | 11:50:37 INFO - PID 14103 | Cycle 1(1): loaded http://localhost:58225/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58225/tests/kraken/driver.html?testName=audio-beat-detection) 11:50:38 INFO - PID 14103 | RSS: Main: 224288768 11:50:38 INFO - PID 14103 | 11:50:40 INFO - PID 14103 | Cycle 1(1): loaded http://localhost:58225/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58225/tests/kraken/driver.html?testName=audio-dft) 11:50:41 INFO - PID 14103 | RSS: Main: 224063488 11:50:41 INFO - PID 14103 | 11:50:44 INFO - PID 14103 | Cycle 1(1): loaded http://localhost:58225/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58225/tests/kraken/driver.html?testName=audio-fft) 11:50:45 INFO - PID 14103 | RSS: Main: 219697152 11:50:45 INFO - PID 14103 | 11:50:47 INFO - PID 14103 | Cycle 1(1): loaded http://localhost:58225/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58225/tests/kraken/driver.html?testName=audio-oscillator) 11:50:47 INFO - PID 14103 | RSS: Main: 221081600 11:50:47 INFO - PID 14103 | 11:50:50 INFO - PID 14103 | Cycle 1(1): loaded http://localhost:58225/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58225/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:50:50 INFO - PID 14103 | RSS: Main: 220958720 11:50:50 INFO - PID 14103 | 11:50:54 INFO - PID 14103 | Cycle 1(1): loaded http://localhost:58225/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58225/tests/kraken/driver.html?testName=imaging-darkroom) 11:50:54 INFO - PID 14103 | RSS: Main: 224321536 11:50:54 INFO - PID 14103 | 11:50:58 INFO - PID 14103 | Cycle 1(1): loaded http://localhost:58225/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58225/tests/kraken/driver.html?testName=imaging-desaturate) 11:50:59 INFO - PID 14103 | RSS: Main: 222072832 11:50:59 INFO - PID 14103 | 11:51:02 INFO - PID 14103 | Cycle 1(1): loaded http://localhost:58225/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58225/tests/kraken/driver.html?testName=json-parse-financial) 11:51:02 INFO - PID 14103 | RSS: Main: 223084544 11:51:02 INFO - PID 14103 | 11:51:04 INFO - PID 14103 | Cycle 1(1): loaded http://localhost:58225/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58225/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:51:04 INFO - PID 14103 | RSS: Main: 223219712 11:51:04 INFO - PID 14103 | 11:51:06 INFO - PID 14103 | Cycle 1(1): loaded http://localhost:58225/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58225/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:51:07 INFO - PID 14103 | RSS: Main: 221052928 11:51:07 INFO - PID 14103 | 11:51:09 INFO - PID 14103 | Cycle 1(1): loaded http://localhost:58225/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58225/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:51:10 INFO - PID 14103 | RSS: Main: 219992064 11:51:10 INFO - PID 14103 | 11:51:12 INFO - PID 14103 | Cycle 1(1): loaded http://localhost:58225/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58225/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:51:13 INFO - PID 14103 | RSS: Main: 221368320 11:51:13 INFO - PID 14103 | 11:51:16 INFO - PID 14103 | Cycle 1(1): loaded http://localhost:58225/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58225/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:51:17 INFO - PID 14103 | RSS: Main: 220856320 11:51:17 INFO - PID 14103 | 11:51:18 INFO - PID 14103 | Cycle 1(1): loaded http://localhost:58225/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58225/tests/kraken/driver.html?testName=ai-astar) 11:51:19 INFO - PID 14103 | RSS: Main: 222924800 11:51:19 INFO - PID 14103 | 11:51:19 INFO - PID 14103 | __start_tp_report 11:51:19 INFO - PID 14103 | _x_x_mozilla_page_load 11:51:19 INFO - PID 14103 | _x_x_mozilla_page_load_details 11:51:19 INFO - PID 14103 | |i|pagename|runs| 11:51:19 INFO - PID 14103 | |0;ai-astar;110;114;108;108;110;112;120;116;109;116 11:51:19 INFO - PID 14103 | |1;audio-beat-detection;117;128;118;144;118;137;145;117;129;124 11:51:19 INFO - PID 14103 | |2;audio-dft;166;163;162;173;171;176;170;164;170;164 11:51:19 INFO - PID 14103 | |3;audio-fft;77;77;84;78;76;78;74;83;77;78 11:51:19 INFO - PID 14103 | |4;audio-oscillator;80;87;80;78;82;79;146;78;79;82 11:51:19 INFO - PID 14103 | |5;imaging-gaussian-blur;115;115;115;115;111;120;114;123;111;115 11:51:19 INFO - PID 14103 | |6;imaging-darkroom;150;151;152;158;151;155;152;151;151;151 11:51:19 INFO - PID 14103 | |7;imaging-desaturate;103;94;94;94;94;100;95;97;95;96 11:51:19 INFO - PID 14103 | |8;json-parse-financial;55;55;51;55;53;54;53;55;53;56 11:51:19 INFO - PID 14103 | |9;json-stringify-tinderbox;42;50;48;42;40;39;39;39;38;45 11:51:19 INFO - PID 14103 | |10;stanford-crypto-aes;81;83;77;74;80;79;82;77;75;80 11:51:19 INFO - PID 14103 | |11;stanford-crypto-ccm;119;113;109;115;120;122;116;113;119;112 11:51:19 INFO - PID 14103 | |12;stanford-crypto-pbkdf2;168;171;173;169;165;175;166;169;173;180 11:51:19 INFO - PID 14103 | |13;stanford-crypto-sha256-iterative;70;64;64;66;66;65;64;67;79;65 11:51:19 INFO - PID 14103 | __end_tp_report 11:51:19 INFO - PID 14103 | __start_cc_report 11:51:19 INFO - PID 14103 | _x_x_mozilla_cycle_collect,942 11:51:19 INFO - PID 14103 | __end_cc_report 11:51:19 INFO - PID 14103 | __startTimestamp1505415079176__endTimestamp 11:51:19 INFO - PID 14103 | 11:51:19 INFO - PID 14103 | ------- Summary: start ------- 11:51:19 INFO - PID 14103 | Number of tests: 14 11:51:19 INFO - PID 14103 | 11:51:19 INFO - PID 14103 | [#0] ai-astar Cycles:10 Average:112.30 Median:113.00 stddev:4.06 (3.6%) stddev-sans-first:4.22 11:51:19 INFO - PID 14103 | Values: 110.0 114.0 108.0 108.0 110.0 112.0 120.0 116.0 109.0 116.0 11:51:19 INFO - PID 14103 | 11:51:19 INFO - PID 14103 | [#1] audio-beat-detection Cycles:10 Average:127.70 Median:128.50 stddev:10.98 (8.5%) stddev-sans-first:10.94 11:51:19 INFO - PID 14103 | Values: 117.0 128.0 118.0 144.0 118.0 137.0 145.0 117.0 129.0 124.0 11:51:19 INFO - PID 14103 | 11:51:19 INFO - PID 14103 | [#2] audio-dft Cycles:10 Average:167.90 Median:170.00 stddev:4.75 (2.8%) stddev-sans-first:4.99 11:51:19 INFO - PID 14103 | Values: 166.0 163.0 162.0 173.0 171.0 176.0 170.0 164.0 170.0 164.0 11:51:19 INFO - PID 14103 | 11:51:19 INFO - PID 14103 | [#3] audio-fft Cycles:10 Average:78.20 Median:78.00 stddev:3.05 (3.9%) stddev-sans-first:3.20 11:51:19 INFO - PID 14103 | Values: 77.0 77.0 84.0 78.0 76.0 78.0 74.0 83.0 77.0 78.0 11:51:19 INFO - PID 14103 | 11:51:19 INFO - PID 14103 | [#4] audio-oscillator Cycles:10 Average:87.10 Median:80.00 stddev:20.87 (26.1%) stddev-sans-first:21.97 11:51:19 INFO - PID 14103 | Values: 80.0 87.0 80.0 78.0 82.0 79.0 146.0 78.0 79.0 82.0 11:51:19 INFO - PID 14103 | 11:51:19 INFO - PID 14103 | [#5] imaging-gaussian-blur Cycles:10 Average:115.40 Median:115.00 stddev:3.66 (3.2%) stddev-sans-first:3.88 11:51:19 INFO - PID 14103 | Values: 115.0 115.0 115.0 115.0 111.0 120.0 114.0 123.0 111.0 115.0 11:51:19 INFO - PID 14103 | 11:51:19 INFO - PID 14103 | [#6] imaging-darkroom Cycles:10 Average:152.20 Median:151.50 stddev:2.44 (1.6%) stddev-sans-first:2.46 11:51:19 INFO - PID 14103 | Values: 150.0 151.0 152.0 158.0 151.0 155.0 152.0 151.0 151.0 151.0 11:51:19 INFO - PID 14103 | 11:51:19 INFO - PID 14103 | [#7] imaging-desaturate Cycles:10 Average:96.20 Median:94.50 stddev:3.05 (3.2%) stddev-sans-first:2.01 11:51:19 INFO - PID 14103 | Values: 103.0 94.0 94.0 94.0 94.0 100.0 95.0 97.0 95.0 96.0 11:51:19 INFO - PID 14103 | 11:51:19 INFO - PID 14103 | [#8] json-parse-financial Cycles:10 Average:54.00 Median:55.00 stddev:1.49 (2.7%) stddev-sans-first:1.54 11:51:19 INFO - PID 14103 | Values: 55.0 55.0 51.0 55.0 53.0 54.0 53.0 55.0 53.0 56.0 11:51:19 INFO - PID 14103 | 11:51:19 INFO - PID 14103 | [#9] json-stringify-tinderbox Cycles:10 Average:42.20 Median:42.00 stddev:4.16 (9.9%) stddev-sans-first:4.41 11:51:19 INFO - PID 14103 | Values: 42.0 50.0 48.0 42.0 40.0 39.0 39.0 39.0 38.0 45.0 11:51:19 INFO - PID 14103 | 11:51:19 INFO - PID 14103 | [#10] stanford-crypto-aes Cycles:10 Average:78.80 Median:80.00 stddev:2.97 (3.7%) stddev-sans-first:3.05 11:51:19 INFO - PID 14103 | Values: 81.0 83.0 77.0 74.0 80.0 79.0 82.0 77.0 75.0 80.0 11:51:19 INFO - PID 14103 | 11:51:19 INFO - PID 14103 | [#11] stanford-crypto-ccm Cycles:10 Average:115.80 Median:117.50 stddev:4.13 (3.5%) stddev-sans-first:4.22 11:51:19 INFO - PID 14103 | Values: 119.0 113.0 109.0 115.0 120.0 122.0 116.0 113.0 119.0 112.0 11:51:19 INFO - PID 14103 | 11:51:19 INFO - PID 14103 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.90 Median:172.00 stddev:4.51 (2.6%) stddev-sans-first:4.66 11:51:19 INFO - PID 14103 | Values: 168.0 171.0 173.0 169.0 165.0 175.0 166.0 169.0 173.0 180.0 11:51:19 INFO - PID 14103 | 11:51:19 INFO - PID 14103 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.00 Median:66.00 stddev:4.59 (7.0%) stddev-sans-first:4.74 11:51:19 INFO - PID 14103 | Values: 70.0 64.0 64.0 66.0 66.0 65.0 64.0 67.0 79.0 65.0 11:51:19 INFO - PID 14103 | -------- Summary: end -------- 11:51:19 INFO - PID 14103 | 11:51:19 INFO - PID 14103 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 11:51:20 INFO - TEST-INFO | 14103: exit 0 11:51:20 INFO - TEST-OK | kraken | took 63066ms 11:51:20 INFO - SUITE-END | took 1178s 11:51:20 INFO - Completed test suite (00:19:38) 11:51:20 INFO - javascript benchmark 11:51:20 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8405.559234353741, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3076.9846150769235, 3105.596272484577, 3178.6344570374413, 3182.124915648195, 3185.8407079646013, 6112.388761123885, 6225.7509699611965, 6243.032329988853, 6267.467265282451, 6292.150559674443, 6213.968930155354, 6236.282827474842, 6325.652089135096, 6344.0, 6352.968235158818, 5808.651480911138, 5872.471477567018, 5895.263092113486, 5895.734691938873, 5917.378675239102, 5630.465105814961, 5822.1266809978415, 5842.21130147431, 5895.9705201473935, 5930.911036334458, 5636.351819540764, 5678.403767604404, 5685.090385538294, 5712.714364281811, 5796.275465566804, 5726.856828579277, 5768.278965129359, 5771.8456308738005, 5772.3361813391575, 5776.133579962997, 5652.5195358394585, 5765.077587585966, 5795.507381872546, 5798.246227990334, 5798.768049278024, 6132.141500189977, 6234.407731265523, 6266.811995640138, 6275.121482992385, 6317.431431171217, 6602.900956485657, 6630.237522684904, 6630.535862489628, 6641.767538136142, 6646.0, 6623.4038936495945, 6643.103181070542, 6696.665166741644, 6722.0, 6809.285025072346, 6120.0, 6157.107219453199, 6286.277078135981, 6289.779857704958, 6332.05019247112, 6814.931850681529, 6973.163220413481, 6983.2318444971015, 6986.790396288119, 6995.685194166274, 6595.0766892635065, 6644.202695676453, 6703.698333575001, 6706.362895525015, 6730.427913627299, 6475.838104047437, 6679.0, 6715.127033485615, 6756.358145976124, 6807.2512023677355, 6532.0, 6595.901061484082, 6599.70301336441, 6619.768308109193, 6642.933570664231, 6641.933580664131, 6657.40083392497, 6669.966650166815, 6679.265280819106, 6695.397414232742, 5022.799365582929, 5133.512316329943, 5217.217417387422, 5237.790488380422, 5256.816011439581, 4547.47704014036, 4607.9078418431445, 4626.814927402934, 4671.182543054952, 4679.759863636139, 5779.566532510079, 5788.710564471759, 5861.0, 5877.089051197071, 5881.852953676107, 6535.215774107138, 6601.8349541260895, 6612.966935165294, 6620.635865027373, 6738.932610673928, 5750.482456578928, 5759.424057594207, 5776.104703770922, 5777.62445441045, 5780.277465316835, 5720.885582288331, 5745.712714364265, 5758.740856661377, 5829.300483941955, 5868.23712917318, 6542.1168142300185, 6604.96697516519, 6634.104395906491, 6642.1033160523675, 6659.833504162435, 6693.096431981718, 6734.22556406, 6753.696083676345, 6803.741307857963, 6827.1807383112355, 6294.402031806971, 6449.3873082057125, 6472.255690595642, 6488.318726533835, 6618.238902526271, 6425.1968503937005, 6512.772052978123, 6545.21457425112, 6652.0, 6682.599044057372, 6716.529842910949, 6768.357006084414, 6798.388145066968, 6813.659317034029, 6836.829079273058, 6475.902861457176, 6538.313477084837, 6539.182602174728, 6608.339166083353, 6613.437857782143, 6310.368963103653, 6447.0651755494255, 6459.903101453576, 6479.611223326616, 6510.544261901811, 6407.263164736115, 6408.775692850728, 6418.711157997808, 6424.743010279536, 6433.710483028369, 6346.048092786119, 6441.516886233458, 6459.224893012869, 6498.480121590167, 6515.934840651723, 6410.134631824644, 6417.1657684500715, 6425.1004859320665, 6433.13152724395, 6458.22501299847], "value": 6104.577003545728, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [830.8629076202427, 838.0822998816294, 838.748375487354, 844.9028361738398, 856.7258477287271, 37607.81196094019, 40812.59187408125, 40915.59084409155, 41192.17615647689, 41228.38157427641, 38427.0, 38428.23143537128, 38890.222195556074, 38954.220915581674, 38967.41548876769, 6923.653817309127, 7025.578465292073, 7026.6486675666265, 7036.964815175917, 7579.810504737398, 7528.774136775905, 7563.6218189090605, 7610.086789585258, 7628.275313845176, 7631.0, 7561.092668879742, 7567.356774674132, 7600.847983040336, 7607.80980475487, 7616.581088040156, 7534.585597792119, 7545.320921117071, 7616.809579760495, 7622.580758058304, 7623.275788800055, 7518.135414427356, 7595.392368610554, 7606.125295590967, 7612.124605670363, 7623.428242881806, 37945.81027094847, 38022.80988595067, 38142.42786358207, 38350.42474362887, 38487.23025539474, 3395.0833275015607, 3424.332255210235, 3424.7260219182403, 3428.879989200366, 3460.048486666187, 30857.691423085707, 31102.688973110206, 31148.0, 31156.53265201024, 31160.53259201091, 5015.5736762375245, 5052.898942021139, 5092.8217512387255, 5103.795848166069, 5132.8460146195675, 179848.0, 180027.0, 180308.0, 181749.0, 182774.0, 41184.588154118675, 41345.586544134174, 46446.767766161625, 46734.53265467302, 47081.5291847084, 49254.0, 50018.74990625024, 50033.499665002884, 50095.24857127146, 50313.245301319774, 16303.0, 16428.835711642732, 16453.424130155625, 16459.670806583803, 16485.58786030359, 13346.532871349651, 13441.46234150623, 13470.461181552628, 13558.525451609146, 13564.118332308566, 14062.15627062359, 14097.224652644203, 14150.716985660434, 14249.572512824632, 14306.141631502143, 5562.527185189304, 5658.688772117491, 5681.943180568224, 5809.651420914759, 5842.912356314659, 1001.5643195210107, 1009.5759780892043, 1019.5768755966191, 1021.8416145497459, 1033.2508931025097, 22249.0, 22274.44313892133, 22359.66460503094, 22366.0, 22588.0, 1053.4303887208005, 1085.3325204998869, 1089.3028461784463, 1093.6281664234198, 1096.5010920031366, 11901.285922844658, 12056.276623402624, 12098.153129280865, 12111.212771169845, 12123.333216673167, 214190.0, 214206.0, 214628.0, 215871.0, 216494.0, 50978.74510627423, 51137.0, 51193.48806511887, 51470.485295147315, 51865.48134518682, 1362.4754469529303, 1407.7691697554137, 1443.1196969848595, 1448.391675496294, 1455.4833034272892, 22026.779732202474, 23142.42143946415, 23175.536489269783, 23178.420539486648, 23519.647205292276, 36490.8175459121, 36546.26907461783, 36620.63379366172, 36637.45043824398, 36654.26691466209, 186373.0, 187185.0, 187751.0, 188281.0, 188834.0, 29288.121356359345, 32400.675993239765, 33014.0, 34345.0, 34743.82628086843, 41632.583674162874, 42947.35578966381, 43084.0, 43943.56056439395, 44073.338899915885, 31008.844955775076, 31503.0, 31742.365152696355, 31976.0, 32059.87790427324, 31501.0, 31519.750457394468, 31613.89351372691, 31660.525092123175, 31880.68119318777, 36010.819945900104, 36012.0, 36153.0, 36385.45421818622, 36406.0, 30641.387172255985, 31050.53424198684, 31419.214519637193, 31541.36917261596, 31954.840225799653, 22941.426464338525, 23010.19464318741, 23042.308730738103, 23047.769522304563, 23189.76810231876], "value": 16792.129240091155, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [552.6960171905451, 562.1595714406961, 564.9435056494353, 574.3825387708215, 575.1889835332181, 21570.352889413323, 22297.108115675375, 22406.32781016568, 22428.102875884946, 22440.102395904145, 23296.767032329673, 23959.5208095838, 24027.27918162458, 24108.276751697435, 24259.514809703796, 6372.681365931697, 6633.767818126366, 6664.233613134503, 6669.666516674171, 6670.7331706731675, 6527.477801775846, 6623.73505059797, 6623.9006414903815, 6627.668616569176, 6634.203895532519, 6229.190205273331, 6541.443977261938, 6569.671516424183, 6589.011648252753, 6598.802035938929, 6436.098946147543, 6465.12720782693, 6477.9676101619425, 6540.084388185658, 6542.116814230066, 5991.340952495222, 6022.337542870281, 6028.035514317732, 6039.698015099228, 6041.033434650479, 26803.46393072147, 27181.1845644629, 27869.303267418272, 27888.884444622192, 27915.0, 1997.5605366819423, 2056.4653190170516, 2062.391594479617, 2069.3481553310658, 2081.26115229093, 18932.62134757297, 19626.116824742778, 19627.90186049075, 19677.508062298413, 19699.0, 2336.3224664847176, 2354.682117914077, 2365.2549446924163, 2394.760523947609, 2462.839915405511, 121117.0, 127791.36104319512, 127972.36013819871, 131090.0, 141438.29280853632, 13968.301584920713, 14150.716985660229, 14325.35535900887, 14499.560888485361, 14656.706865862836, 22443.32670019902, 22479.213227537286, 22507.212247571257, 22554.548909021727, 22817.429564260696, 9660.565274562661, 9920.80158396828, 9966.501674916226, 10016.198704103654, 10024.54889529973, 9158.221551168226, 9335.906640933505, 9372.578233979351, 9412.0, 9426.245900327956, 8751.343649226334, 8852.468851868909, 8961.955190224007, 8987.865182022144, 9058.909410905939, 2652.9469410611678, 2696.8786404611837, 2735.9317710824357, 2809.6258661431057, 2816.8873245070376, 926.28174735864, 958.3004406782948, 958.755517343073, 961.1637875048752, 975.8292298847673, 18386.81613183878, 18391.540211494554, 18450.26198952054, 19081.904590477236, 19095.522611934535, 997.1773287038222, 998.4558415663481, 1018.5671089786811, 1027.173125633857, 1032.2258306270298, 9975.301728879054, 10194.898051019394, 10240.436775977392, 10619.787604247871, 10677.305975111592, 451461.0, 467923.0, 468345.0, 471395.0, 471991.0, 111255.0, 112715.0, 114571.0, 114955.42522287503, 116044.0, 1142.1548054439654, 1158.7566611011614, 1159.2233203753506, 1165.114512970132, 1166.976660466786, 16656.916715416344, 17159.914200428917, 17219.22513486871, 17353.0, 17409.216585253947, 52530.0, 53489.73255133855, 55565.72217138889, 55730.72134639301, 56320.436795633155, 63893.0, 65986.0, 66173.0, 66848.33151668421, 67033.32966670465, 13729.45082196701, 13774.104683195561, 13897.0, 13914.51299204523, 14068.0, 17805.46583602494, 18905.716414253522, 21767.0, 22148.557028859654, 22306.553868922856, 17829.643407131527, 17876.642467150323, 17908.55228619295, 17935.641287173923, 18130.818691812914, 15821.604459888595, 15891.602709931882, 16011.119388433748, 16026.118563478654, 16876.324947002475, 17621.383251586132, 17725.822741772416, 17803.0, 17963.371282005068, 18613.162407691416, 11587.826182607436, 13878.0, 13888.583342499742, 13890.444382225001, 13896.027278090436, 14924.776128357866, 16860.66278674444, 17828.19773110187, 17842.375516856846, 17973.37093201732], "value": 11592.02816938026, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1310.0829419406416, 1346.491929039476, 1348.6830594810217, 1361.6731984323765, 1382.4746596293407, 14154.433822647095, 14608.0, 14608.269586520684, 14772.778408323884, 14801.185934773583, 14253.429862805475, 14387.496437624684, 14433.566992990227, 14499.855001449982, 14521.20133392663, 2668.4529671417313, 2699.9595006074924, 2738.835669859805, 2763.2539214412195, 2765.308672831792, 2678.450917561895, 2716.4567086582665, 2732.931676708078, 2733.221032005879, 2735.0564055400955, 2211.635080211763, 2269.0810221860174, 2704.8918043278245, 2719.809613327068, 2725.7819374449996, 2701.594760785888, 2773.0433000614757, 2776.8889244430197, 2777.6805667348312, 2778.111004478568, 2590.313566904772, 2599.766021058095, 2617.332580192058, 2652.4297276085736, 2657.0965871603553, 14632.41470341185, 14786.0, 14858.257087145708, 14858.702825943421, 14859.851401486063, 7855.921440785576, 8129.593520323961, 8138.715144969898, 8192.385571082134, 8197.46716463428, 4751.311059896296, 5094.235864620299, 5209.843704688866, 5243.86890327741, 5496.725163741837, 1844.7325137854937, 1857.3963461874944, 1889.7448844405967, 1903.7525121734086, 1904.0122014218566, 26198.0, 26204.344891377677, 26237.606435903475, 26245.73754262452, 26551.73448265512, 7110.466714996396, 7149.714011439484, 7213.098362704662, 7304.347826086957, 7417.888731669029, 19798.90100549517, 19830.603387932162, 19839.0, 19851.801481984996, 19861.900690496743, 8070.919290807017, 8217.876731849026, 8317.168283171755, 8358.916410835935, 8374.70688525911, 7341.155767086747, 7348.1182258129365, 7381.520201186906, 7485.1017877855, 7565.810854728566, 5067.923981140286, 5158.200478925697, 5246.816361427332, 5248.737563121905, 5284.497972692588, 1575.8266590675016, 1580.85772280493, 1603.5670369000304, 1605.903645781257, 1606.5501659535348, 879.3668558637899, 912.9041450647719, 926.416357694648, 929.1869614087673, 938.5401153434768, 7573.848523029619, 7590.962045189739, 7622.885656715153, 7714.459987800912, 7735.574543400167, 705.5660768627359, 706.9434445244368, 711.5659447736876, 724.4313214126885, 726.963651817407, 4699.929501057487, 4702.247640377524, 4732.645051621143, 4735.0, 4777.235642297285, 20952.58094838125, 21463.678044829026, 21823.563528729654, 21880.89059554692, 22045.889770551366, 20467.18131274762, 21229.150833966778, 21358.252461163785, 21419.57160856774, 21423.571528569653, 10702.357858528514, 11282.492287846902, 11311.321320720948, 11493.42532873353, 11591.0, 8563.785905352415, 8572.39993200495, 8624.913750862412, 8625.91374086251, 8830.690925817566, 17920.820791792437, 18165.455036348932, 18370.35703750362, 18634.0, 18669.15988780481, 18468.907655461637, 18598.53503662419, 18706.532336691147, 18800.52998675044, 18909.243630254638, 11008.614698485515, 11065.336079835235, 11106.0, 11180.664580062612, 11181.105511559219, 13824.101433406797, 13902.582922512342, 14019.439222430989, 14070.577882663536, 14204.147751134966, 10488.737781555523, 10585.153187744809, 10699.625513107005, 10804.891951080703, 10911.345319280868, 10661.466926653791, 10948.45257737126, 11029.0, 11091.556337746722, 11179.105671546095, 9993.800123997626, 10080.344777589433, 10108.595656173671, 10120.190384769054, 10149.137323327599, 6963.40811031068, 7015.438764898898, 7026.0, 7066.116735408074, 7099.82250443743, 7220.3140701633265, 7374.483786134919, 7420.369268612229, 7429.44279179053, 7453.962730186315], "value": 6589.320411577293, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [502.6607040247832, 504.9166887463566, 527.4303751947897, 531.5986430245164, 532.9520343169114, 6273.404026617475, 6276.05859121132, 6278.246610406752, 6311.936880631192, 6329.683515824214, 6108.816735497931, 6219.346968568292, 6239.438450539451, 6241.0, 6267.780627678032, 5138.8715282118055, 5152.484751524837, 5153.330067091291, 5183.818566350178, 5212.791488340462, 5132.4610915853855, 5166.715830629314, 5175.870603234912, 5178.326817513718, 5226.451222621627, 4733.810647574093, 4893.632977526682, 4932.580730637882, 4950.1584730595705, 4978.0, 4912.936566642019, 4921.655484116113, 4946.629002824802, 4975.527324904128, 4981.911215046676, 4358.891027724332, 4362.672799540047, 4372.672049596261, 4384.714993525411, 4421.955780442187, 5973.223480947492, 6024.518038556905, 6079.422454866781, 6080.0847732963975, 6109.877802443971, 5620.494155525981, 5635.422081817057, 5671.9716401418145, 5695.857603559903, 5703.771849126071, 6370.662160946207, 6441.774537891151, 6491.5780474269495, 6533.575317604341, 6559.573627714183, 4573.359729637853, 4777.235642297251, 4799.9760001199775, 4801.726463447534, 4825.227963525819, 6590.176227971504, 6708.9075663845015, 6736.58531708337, 6776.491763117786, 6871.553349032297, 5391.460853914656, 5400.243965844785, 5417.5395091416885, 5461.672299662033, 5477.835664930059, 5476.945230547723, 5530.86172845674, 5533.45461087896, 5537.501624853782, 5550.7779688812, 5769.711514424262, 5851.255196674132, 5998.070299103646, 6006.4594186522545, 6015.045110339157, 6398.456131228898, 6577.177852768404, 6701.0953521274005, 6707.597544147269, 6717.193936727624, 1870.036930980546, 1887.537553299423, 1912.5218695326168, 1915.9521011974534, 1939.1370839976357, 1411.647088227943, 1413.4204975960013, 1426.108682073704, 1431.5562175725559, 1438.978415323771, 5581.7209139543675, 5589.888202235932, 5611.551075913917, 5671.943280567224, 5694.0, 6484.1894763154605, 6539.803805885831, 6549.284515149085, 6578.210614726264, 6618.6690665467495, 5502.697351645698, 5588.6646801192055, 5599.936012157677, 5633.436656334334, 5715.1713001614535, 3513.789172649649, 3517.3995831896427, 3521.454174602941, 3542.574891013095, 3568.7858728476376, 6442.387973142544, 6461.19235095613, 6470.741170353133, 6476.805695829133, 6534.607923524603, 6662.700178491979, 6776.7628133015105, 6792.0, 6839.487038472134, 6844.863102737917, 6406.84676758188, 6409.391107844747, 6424.775132870327, 6515.250746164157, 6557.245916719447, 6626.6686665667485, 6641.701123449553, 6652.101966234496, 6673.599584024974, 6701.631410272482, 6366.93633063682, 6560.901586476302, 6610.603363798187, 6635.402813746786, 6665.566738162004, 6138.785142519991, 6213.751449941951, 6306.842328941813, 6341.365863413622, 6446.4520515756685, 5367.651102678314, 5388.946110538845, 5394.2448057272795, 5412.918806217831, 5446.618736688394, 6572.375624315682, 6635.137432133792, 6660.966695166493, 6691.5315927884585, 6692.732290708317, 6407.679616019273, 6420.486361091008, 6421.775237866652, 6442.677866106769, 6447.903281450688, 6415.358464153547, 6471.288158302489, 6485.805425837232, 6490.95534906503, 6493.675316234075, 6244.812655620339, 6306.400891915261, 6343.397377249154, 6345.17512723343, 6470.623489222385, 6337.809865704036, 6425.293217745951, 6429.196350456193, 6485.675716214265, 6526.738930442919, 6217.160683307696, 6235.407636274728, 6267.091271765659, 6269.49844012487, 6276.874462510816], "value": 5095.14103396717, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [890.8530092534733, 906.8186362727454, 926.532101288849, 928.6285485805681, 941.2234906202385, 18339.633207335846, 19039.61920761584, 19159.425217243504, 19162.0, 19223.519412014706, 18365.54086147847, 18480.2607895684, 18905.33831315904, 18951.810481895176, 19032.5241868953, 972.6158167523823, 990.9058639429243, 993.5727637116037, 993.9751506212331, 999.9500024998757, 970.3935040599625, 975.7121649113493, 976.3360914578084, 983.365729104727, 984.7439665686937, 1019.525920446992, 1036.3885307668475, 1038.7143535527714, 1041.0994489766374, 1042.8748550173989, 1026.0252759878147, 1026.8613737145465, 1028.3213079367656, 1032.8760548734124, 1034.0228484082545, 752.6086435053796, 760.3385055002184, 760.8554374668795, 762.5081822224635, 763.713607397226, 18942.337018204296, 19505.609887802308, 19694.507637309038, 19715.309964151187, 19741.703874441893, 20941.685874711893, 21310.235719047527, 21599.784002159933, 21879.12483500658, 22075.227367042236, 16296.348146074142, 16677.916610416993, 16978.49064528066, 17171.96968181913, 17289.913550432168, 3753.6246375362516, 3778.943315850237, 3786.6024067472795, 3789.0337963819325, 3820.5033345665165, 138081.0, 141722.0, 142946.0, 143555.0, 144523.99999999895, 14992.925035374754, 15244.542663720105, 15615.843841561438, 15655.295511702001, 15973.520794376187, 31252.218694532818, 31442.528362074587, 31447.213819654233, 31470.685293146777, 32054.519182212287, 8350.749477515685, 8421.410501264852, 8471.661133554711, 8482.279006284534, 8511.404201705946, 4200.369944508348, 4214.704970652025, 4271.316589345752, 4272.722273052242, 4273.786310684453, 5218.191180367049, 5365.678059316453, 5413.539849112791, 5427.240186373911, 5440.374356949002, 982.8427451607708, 989.2679417231197, 1001.3591301567003, 1010.0505524806805, 1010.0757806606872, 676.2054585861592, 695.3255342317943, 699.2692636195188, 714.4855703893188, 715.2704241673567, 10806.189535784848, 11178.496967636476, 11269.703984041776, 11563.76872462546, 11701.531938722526, 694.2363400259775, 697.9581225126509, 700.3556727810427, 706.4242642246553, 719.5790462579345, 5733.254676891977, 5802.767889284381, 5819.0, 5859.794907178229, 5875.059990401515, 112563.43718281356, 114100.42949785198, 117316.41341793236, 117926.41036794761, 120875.0, 101273.49363253285, 104888.0, 104972.0, 105297.47351263194, 105316.0, 88619.55690221637, 91796.54101729448, 93293.0670693298, 93764.53117734367, 94104.52947735217, 10698.197635177237, 10707.304025238334, 10719.196060295664, 10726.51730672106, 10906.836397454203, 40006.0, 41117.58882411138, 41976.58023419727, 42543.1491370177, 42944.78527607342, 52724.47275527349, 53911.0, 54648.72675636596, 54774.726126370704, 55405.168922467004, 15015.699686006437, 15064.32210550506, 15072.54782356531, 15133.319000645217, 15290.388384464497, 17480.388186413413, 17820.91089544544, 17918.372856949944, 17958.371456998942, 18078.45764627063, 16340.918295408446, 16406.26171822247, 16550.42073527421, 16583.17084145812, 16596.668066638358, 13246.337683115613, 13366.93316533411, 13465.865341346462, 13517.0, 13578.86421135776, 15856.365745370056, 15898.920505397398, 15988.440404586248, 16109.919450402673, 16340.67318653644, 11814.586489472827, 12221.633350999484, 12271.877281227073, 12479.0, 12634.178778379375, 11971.940140299243, 11998.16012879125, 12094.0, 12340.876591233973, 12389.132760706663], "value": 8840.103240444952, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1465.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 114.0, 108.0, 108.0, 110.0, 112.0, 120.0, 116.0, 109.0, 116.0], "value": 112.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 128.0, 118.0, 144.0, 118.0, 137.0, 145.0, 117.0, 129.0, 124.0], "value": 127.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 163.0, 162.0, 173.0, 171.0, 176.0, 170.0, 164.0, 170.0, 164.0], "value": 167.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 77.0, 84.0, 78.0, 76.0, 78.0, 74.0, 83.0, 77.0, 78.0], "value": 78.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 87.0, 80.0, 78.0, 82.0, 79.0, 146.0, 78.0, 79.0, 82.0], "value": 87.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 115.0, 115.0, 115.0, 111.0, 120.0, 114.0, 123.0, 111.0, 115.0], "value": 115.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 151.0, 152.0, 158.0, 151.0, 155.0, 152.0, 151.0, 151.0, 151.0], "value": 152.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 94.0, 94.0, 94.0, 94.0, 100.0, 95.0, 97.0, 95.0, 96.0], "value": 96.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 51.0, 55.0, 53.0, 54.0, 53.0, 55.0, 53.0, 56.0], "value": 54.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [42.0, 50.0, 48.0, 42.0, 40.0, 39.0, 39.0, 39.0, 38.0, 45.0], "value": 42.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 83.0, 77.0, 74.0, 80.0, 79.0, 82.0, 77.0, 75.0, 80.0], "value": 78.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 113.0, 109.0, 115.0, 120.0, 122.0, 116.0, 113.0, 119.0, 112.0], "value": 115.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 171.0, 173.0, 169.0, 165.0, 175.0, 166.0, 169.0, 173.0, 180.0], "value": 170.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 64.0, 64.0, 66.0, 66.0, 65.0, 64.0, 67.0, 79.0, 65.0], "value": 67.0, "unit": "score"}]}]} 11:51:20 INFO - Return code: 0 11:51:20 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:51:20 INFO - # TBPL SUCCESS # 11:51:20 INFO - Running post-action listener: _package_coverage_data 11:51:20 INFO - Running post-action listener: _resource_record_post_action 11:51:20 INFO - [mozharness: 2017-09-14 18:51:20.443382Z] Finished run-tests step (success) 11:51:20 INFO - Running post-run listener: _resource_record_post_run 11:51:20 INFO - Total resource usage - Wall time: 1197s; CPU: 13.0%; Read bytes: 10784768; Write bytes: 337289216; Read time: 8676; Write time: 202324 11:51:20 INFO - TinderboxPrint: CPU usage
13.3% 11:51:20 INFO - TinderboxPrint: I/O read bytes / time
10,784,768 / 8,676 11:51:20 INFO - TinderboxPrint: I/O write bytes / time
337,289,216 / 202,324 11:51:20 INFO - TinderboxPrint: CPU idle
8,271.7 (86.7%) 11:51:20 INFO - TinderboxPrint: CPU user
1,204.2 (12.6%) 11:51:20 INFO - TinderboxPrint: Swap in / out
0 / 0 11:51:20 INFO - install - Wall time: 19s; CPU: 13.0%; Read bytes: 0; Write bytes: 13357056; Read time: 0; Write time: 2100 11:51:20 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 11:51:20 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 10784768; Write bytes: 323932160; Read time: 8676; Write time: 200224 11:51:21 INFO - Running post-run listener: _upload_blobber_files 11:51:21 INFO - Blob upload gear active. 11:51:21 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:51:21 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 11:51:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:51:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:51:57 INFO - (blobuploader) - INFO - Open directory for files ... 11:51:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:51:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:51:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:53:03 INFO - (blobuploader) - CRITICAL - Something went wrong on blobber! 11:53:03 INFO - (blobuploader) - INFO - Upload failed. Trying again ... 11:53:03 INFO - (blobuploader) - INFO - Done attempting. 11:53:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:53:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:53:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:54:03 INFO - (blobuploader) - CRITICAL - Something went wrong on blobber! 11:54:03 INFO - (blobuploader) - INFO - Upload failed. Trying again ... 11:54:03 INFO - (blobuploader) - INFO - Done attempting. 11:54:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:54:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:54:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:54:55 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:54:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:54:55 INFO - (blobuploader) - INFO - Done attempting. 11:54:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:54:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:54:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:55:43 INFO - (blobuploader) - CRITICAL - Failed uploading to S3 11:55:43 INFO - (blobuploader) - INFO - Upload failed. Trying again ... 11:55:43 INFO - (blobuploader) - INFO - Done attempting. 11:55:43 INFO - (blobuploader) - INFO - Iteration through files over. 11:55:43 INFO - Return code: 0 11:55:43 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:55:43 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:55:43 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": null, "resource-usage.json": null, "dromaeojs-e10s_raw.log": null, "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b3cc1bac4d467c717716e98fa09168c4ed4e631b2a3e55e5a1324ae43c634b905a90c7e806787f51b71e4a10006cae62e084cd0a141106b46f9dcc605f2caf5d"} 11:55:43 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:55:43 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:55:43 INFO - Contents: 11:55:43 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": null, "resource-usage.json": null, "dromaeojs-e10s_raw.log": null, "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b3cc1bac4d467c717716e98fa09168c4ed4e631b2a3e55e5a1324ae43c634b905a90c7e806787f51b71e4a10006cae62e084cd0a141106b46f9dcc605f2caf5d"} 11:55:43 INFO - Running post-run listener: copy_logs_to_upload_dir 11:55:43 INFO - Copying logs to upload dir... 11:55:43 INFO - mkdir: /builds/slave/test/build/upload/logs 11:55:43 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1499.548777 ========= master_lag: 0.46 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 25 mins, 0 secs) (at 2017-09-14 11:55:43.087574) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-14 11:55:43.091989) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=51fc86400edbde9650d2781a000001d0-1505413615.117019-1729712491 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": null, "resource-usage.json": null, "dromaeojs-e10s_raw.log": null, "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b3cc1bac4d467c717716e98fa09168c4ed4e631b2a3e55e5a1324ae43c634b905a90c7e806787f51b71e4a10006cae62e084cd0a141106b46f9dcc605f2caf5d"} build_url:https://queue.taskcluster.net/v1/task/IDcefCo4SICyNXkppCNLZA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011324 build_url: 'https://queue.taskcluster.net/v1/task/IDcefCo4SICyNXkppCNLZA/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": null, "resource-usage.json": null, "dromaeojs-e10s_raw.log": null, "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b3cc1bac4d467c717716e98fa09168c4ed4e631b2a3e55e5a1324ae43c634b905a90c7e806787f51b71e4a10006cae62e084cd0a141106b46f9dcc605f2caf5d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-14 11:55:43.123468) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-14 11:55:43.123803) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=51fc86400edbde9650d2781a000001d0-1505413615.117019-1729712491 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004618 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-14 11:55:43.205739) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-14 11:55:43.206053) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-14 11:55:43.206363) ========= ========= Total master_lag: 0.63 =========