builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-068 starttime: 1506056822.21 results: success (0) revision: 97282b0c985aa1778ced171514d1ae61945c634c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-21 22:07:02.208875) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-21 22:07:02.209392) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-21 22:07:02.209710) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-21 22:07:02.229424) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 22:07:02.229947) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1506052468.613065-334524023 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-21 22:07:02-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 45.5M=0s 2017-09-21 22:07:02 (45.5 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.082137 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 22:07:02.331270) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 22:07:02.331595) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1506052468.613065-334524023 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018902 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 22:07:02.382218) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-21 22:07:02.382566) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 97282b0c985aa1778ced171514d1ae61945c634c --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 97282b0c985aa1778ced171514d1ae61945c634c --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=97facb8ba2ac28944cc45e6b000001d4-1506052468.613065-334524023 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-21 22:07:02,438 truncating revision to first 12 chars 2017-09-21 22:07:02,438 Setting DEBUG logging. 2017-09-21 22:07:02,438 attempt 1/10 2017-09-21 22:07:02,438 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/97282b0c985a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-21 22:07:03,412 unpacking tar archive at: mozilla-central-97282b0c985a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.285462 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-21 22:07:03.685181) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-21 22:07:03.685539) ========= script_repo_revision: 97282b0c985aa1778ced171514d1ae61945c634c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-21 22:07:03.685981) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-21 22:07:03.686246) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-21 22:07:03.699477) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2017-09-21 22:07:03.699798) ========= /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=97facb8ba2ac28944cc45e6b000001d4-1506052468.613065-334524023 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:07:03 INFO - MultiFileLogger online at 20170921 22:07:03 in /builds/slave/test 22:07:03 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 22:07:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:07:03 INFO - {'append_to_log': False, 22:07:03 INFO - 'base_work_dir': '/builds/slave/test', 22:07:03 INFO - 'blob_upload_branch': 'Firefox', 22:07:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:07:03 INFO - 'branch': 'Firefox', 22:07:03 INFO - 'buildbot_json_path': 'buildprops.json', 22:07:03 INFO - 'code_coverage': False, 22:07:03 INFO - 'config_files': ('talos/linux_config.py',), 22:07:03 INFO - 'default_actions': ('clobber', 22:07:03 INFO - 'read-buildbot-config', 22:07:03 INFO - 'download-and-extract', 22:07:03 INFO - 'populate-webroot', 22:07:03 INFO - 'create-virtualenv', 22:07:03 INFO - 'install', 22:07:03 INFO - 'setup-mitmproxy', 22:07:03 INFO - 'run-tests'), 22:07:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:07:03 INFO - 'disable_ccov_upload': False, 22:07:03 INFO - 'disable_stylo': False, 22:07:03 INFO - 'download_minidump_stackwalk': True, 22:07:03 INFO - 'download_symbols': 'ondemand', 22:07:03 INFO - 'enable_stylo': False, 22:07:03 INFO - 'enable_webrender': False, 22:07:03 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 22:07:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:07:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:07:03 INFO - 'gecko_profile': False, 22:07:03 INFO - 'gecko_profile_interval': 0, 22:07:03 INFO - 'installer_path': 'installer.exe', 22:07:03 INFO - 'log_level': 'info', 22:07:03 INFO - 'log_name': 'talos', 22:07:03 INFO - 'log_to_console': True, 22:07:03 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:07:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:07:03 INFO - 'opt_config_files': (), 22:07:03 INFO - 'pip_index': False, 22:07:03 INFO - 'suite': 'dromaeojs-e10s', 22:07:03 INFO - 'system_bits': '32', 22:07:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:07:03 INFO - 'title': 'talos-linux64-ix-068', 22:07:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:07:03 INFO - 'use_talos_json': True, 22:07:03 INFO - 'verify': 'False', 22:07:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:07:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:07:03 INFO - 'work_dir': 'build'} 22:07:03 INFO - [mozharness: 2017-09-22 05:07:03.894757Z] Running clobber step. 22:07:03 INFO - Running pre-action listener: _resource_record_pre_action 22:07:03 INFO - Running main action method: clobber 22:07:03 INFO - rmtree: /builds/slave/test/build 22:07:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:07:04 INFO - Running post-action listener: _resource_record_post_action 22:07:04 INFO - [mozharness: 2017-09-22 05:07:04.891966Z] Finished clobber step (success) 22:07:04 INFO - [mozharness: 2017-09-22 05:07:04.892063Z] Running read-buildbot-config step. 22:07:04 INFO - Running pre-action listener: _resource_record_pre_action 22:07:04 INFO - Running main action method: read_buildbot_config 22:07:04 INFO - Using buildbot properties: 22:07:04 INFO - { 22:07:04 INFO - "project": "", 22:07:04 INFO - "product": "firefox", 22:07:04 INFO - "who": "philringnalda@gmail.com", 22:07:04 INFO - "installer_path": "public/build/target.tar.bz2", 22:07:04 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 22:07:04 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 22:07:04 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 22:07:04 INFO - "stage_platform": "linux64-pgo", 22:07:04 INFO - "basedir": "/builds/slave/test", 22:07:04 INFO - "buildnumber": 199, 22:07:04 INFO - "platform": "ubuntu64_hw", 22:07:04 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 22:07:04 INFO - "slavebuilddir": "test", 22:07:04 INFO - "taskId": "C5QhEzf6SbGxImvTWS9v-w", 22:07:04 INFO - "branch": "mozilla-central", 22:07:04 INFO - "script_repo_revision": "production", 22:07:04 INFO - "revision": "97282b0c985aa1778ced171514d1ae61945c634c", 22:07:04 INFO - "slavename": "talos-linux64-ix-068", 22:07:04 INFO - "repo_path": "mozilla-central" 22:07:04 INFO - } 22:07:04 INFO - Finding installer, test and symbols from parent task. 22:07:04 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/C5QhEzf6SbGxImvTWS9v-w'}, attempt #1 22:07:05 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/C5QhEzf6SbGxImvTWS9v-w'}, attempt #1 22:07:05 INFO - Task dependencies: bNEWwPAlQDWVxLoQgV_2iA 22:07:05 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bNEWwPAlQDWVxLoQgV_2iA'}, attempt #1 22:07:06 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/bNEWwPAlQDWVxLoQgV_2iA/artifacts/public/build/target.tar.bz2 22:07:06 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/bNEWwPAlQDWVxLoQgV_2iA/artifacts/public/build/target.test_packages.json 22:07:06 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/bNEWwPAlQDWVxLoQgV_2iA/artifacts/public/build/target.crashreporter-symbols.zip 22:07:06 INFO - Running post-action listener: _resource_record_post_action 22:07:06 INFO - [mozharness: 2017-09-22 05:07:06.279541Z] Finished read-buildbot-config step (success) 22:07:06 INFO - [mozharness: 2017-09-22 05:07:06.279757Z] Running download-and-extract step. 22:07:06 INFO - Running pre-action listener: _resource_record_pre_action 22:07:06 INFO - Running main action method: download_and_extract 22:07:06 INFO - mkdir: /builds/slave/test/build/tests 22:07: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')]} 22:07:06 INFO - trying https://queue.taskcluster.net/v1/task/bNEWwPAlQDWVxLoQgV_2iA/artifacts/public/build/target.test_packages.json 22:07:06 INFO - Downloading https://queue.taskcluster.net/v1/task/bNEWwPAlQDWVxLoQgV_2iA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 22:07:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bNEWwPAlQDWVxLoQgV_2iA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 22:07:06 INFO - Downloaded 989 bytes. 22:07:06 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 22:07:06 INFO - Using the following test package requirements: 22:07:06 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 22:07:06 INFO - u'common': [u'target.common.tests.zip'], 22:07:06 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 22:07:06 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 22:07:06 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 22:07:06 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 22:07:06 INFO - u'mozbase': [u'target.common.tests.zip'], 22:07:06 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 22:07:06 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 22:07:06 INFO - u'web-platform': [u'target.common.tests.zip', 22:07:06 INFO - u'target.web-platform.tests.tar.gz'], 22:07:06 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 22:07:06 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 22:07:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bNEWwPAlQDWVxLoQgV_2iA/artifacts/public/build/target.common.tests.zip 22:07:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bNEWwPAlQDWVxLoQgV_2iA/artifacts/public/build/target.common.tests.zip'}, attempt #1 22:07:06 INFO - Fetch https://queue.taskcluster.net/v1/task/bNEWwPAlQDWVxLoQgV_2iA/artifacts/public/build/target.common.tests.zip into memory 22:07:07 INFO - Content-Length response header: 55276051 22:07:07 INFO - Bytes received: 55276051 22:07:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bNEWwPAlQDWVxLoQgV_2iA/artifacts/public/build/target.talos.tests.zip 22:07:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bNEWwPAlQDWVxLoQgV_2iA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 22:07:18 INFO - Fetch https://queue.taskcluster.net/v1/task/bNEWwPAlQDWVxLoQgV_2iA/artifacts/public/build/target.talos.tests.zip into memory 22:07:19 INFO - Content-Length response header: 14513414 22:07:19 INFO - Bytes received: 14513414 22:07: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')]} 22:07:19 INFO - trying https://queue.taskcluster.net/v1/task/bNEWwPAlQDWVxLoQgV_2iA/artifacts/public/build/target.tar.bz2 22:07:19 INFO - Downloading https://queue.taskcluster.net/v1/task/bNEWwPAlQDWVxLoQgV_2iA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 22:07:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bNEWwPAlQDWVxLoQgV_2iA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 22:07:20 INFO - Downloaded 62276291 bytes. 22:07:20 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bNEWwPAlQDWVxLoQgV_2iA/artifacts/public/build/target.tar.bz2 22:07:20 INFO - mkdir: /builds/slave/test/properties 22:07:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:07:20 INFO - Writing to file /builds/slave/test/properties/build_url 22:07:20 INFO - Contents: 22:07:20 INFO - build_url:https://queue.taskcluster.net/v1/task/bNEWwPAlQDWVxLoQgV_2iA/artifacts/public/build/target.tar.bz2 22:07:20 INFO - Running post-action listener: _resource_record_post_action 22:07:20 INFO - Running post-action listener: find_tests_for_verification 22:07:20 INFO - Running post-action listener: set_extra_try_arguments 22:07:20 INFO - [mozharness: 2017-09-22 05:07:20.662948Z] Finished download-and-extract step (success) 22:07:20 INFO - [mozharness: 2017-09-22 05:07:20.663046Z] Running populate-webroot step. 22:07:20 INFO - Running pre-action listener: _resource_record_pre_action 22:07:20 INFO - Running main action method: populate_webroot 22:07:20 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:07:20 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 22:07:20 INFO - 'tests': ['tresize', 22:07:20 INFO - 'tcanvasmark']}, 22:07:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:07:20 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 22:07:20 INFO - 'tests': ['dromaeo_css', 22:07:20 INFO - 'kraken']}, 22:07:20 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 22:07:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:07:20 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 22:07:20 INFO - 'talos_options': ['--disable-stylo'], 22:07:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:07:20 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 22:07:20 INFO - 'tests': ['damp', 'tps']}, 22:07:20 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 22:07:20 INFO - 'talos_options': ['--disable-stylo'], 22:07:20 INFO - 'tests': ['damp', 'tps']}, 22:07:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:07:20 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 22:07:20 INFO - 'tests': ['dromaeo_dom']}, 22:07:20 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 22:07:20 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 22:07:20 INFO - 'tests': ['basic_compositor_video', 22:07:20 INFO - 'glvideo']}, 22:07:20 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 22:07:20 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 22:07:20 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 22:07:20 INFO - 'talos_options': ['--disable-stylo'], 22:07:20 INFO - 'tests': ['ts_paint_webext', 22:07:20 INFO - 'tp5o_webext']}, 22:07:20 INFO - 'other-e10s': {'tests': ['a11yr', 22:07:20 INFO - 'ts_paint', 22:07:20 INFO - 'tpaint', 22:07:20 INFO - 'sessionrestore', 22:07:20 INFO - 'sessionrestore_many_windows', 22:07:20 INFO - 'sessionrestore_no_auto_restore', 22:07:20 INFO - 'tabpaint']}, 22:07:20 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 22:07:20 INFO - 'tests': ['a11yr', 22:07:20 INFO - 'ts_paint', 22:07:20 INFO - 'tpaint', 22:07:20 INFO - 'sessionrestore', 22:07:20 INFO - 'sessionrestore_many_windows', 22:07:20 INFO - 'sessionrestore_no_auto_restore', 22:07:20 INFO - 'tabpaint']}, 22:07:20 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 22:07:20 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 22:07:20 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 22:07:20 INFO - 'tests': ['perf_reftest_singletons']}, 22:07:20 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 22:07:20 INFO - 'tests': ['perf_reftest']}, 22:07:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:07:20 INFO - 'tsvgr_opacity', 22:07:20 INFO - 'tart', 22:07:20 INFO - 'tscrollx', 22:07:20 INFO - 'tsvg_static']}, 22:07:20 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 22:07:20 INFO - 'tests': ['tsvgx', 22:07:20 INFO - 'tsvgr_opacity', 22:07:20 INFO - 'tart', 22:07:20 INFO - 'tscrollx', 22:07:20 INFO - 'tsvg_static']}, 22:07:20 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 22:07:20 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 22:07:20 INFO - 'talos_options': ['--disable-stylo'], 22:07:20 INFO - 'tests': ['tp5o']}, 22:07:20 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 22:07:20 INFO - 'talos_options': ['--mitmproxy', 22:07:20 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 22:07:20 INFO - '--firstNonBlankPaint'], 22:07:20 INFO - 'tests': ['tp6_google', 22:07:20 INFO - 'tp6_youtube', 22:07:20 INFO - 'tp6_amazon', 22:07:20 INFO - 'tp6_facebook']}, 22:07:20 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 22:07:20 INFO - 'talos_options': ['--disable-stylo', 22:07:20 INFO - '--mitmproxy', 22:07:20 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 22:07:20 INFO - '--firstNonBlankPaint'], 22:07:20 INFO - 'tests': ['tp6_google', 22:07:20 INFO - 'tp6_youtube', 22:07:20 INFO - 'tp6_amazon', 22:07:20 INFO - 'tp6_facebook']}, 22:07:20 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 22:07:20 INFO - 'talos_options': ['--stylo-threads=1', 22:07:20 INFO - '--mitmproxy', 22:07:20 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 22:07:20 INFO - '--firstNonBlankPaint'], 22:07:20 INFO - 'tests': ['tp6_google', 22:07:20 INFO - 'tp6_youtube', 22:07:20 INFO - 'tp6_amazon', 22:07:20 INFO - 'tp6_facebook']}, 22:07:20 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 22:07:20 INFO - 'talos_options': ['--xperf_path', 22:07:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:07:20 INFO - 'tests': ['tp5n']}, 22:07:20 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 22:07:20 INFO - 'talos_options': ['--disable-stylo', 22:07:20 INFO - '--xperf_path', 22:07:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:07:20 INFO - 'tests': ['tp5n']}}} 22:07:20 INFO - Running post-action listener: _resource_record_post_action 22:07:20 INFO - [mozharness: 2017-09-22 05:07:20.677858Z] Finished populate-webroot step (success) 22:07:20 INFO - [mozharness: 2017-09-22 05:07:20.677946Z] Running create-virtualenv step. 22:07:20 INFO - Running pre-action listener: _resource_record_pre_action 22:07:20 INFO - Running main action method: create_virtualenv 22:07:20 INFO - Creating virtualenv /builds/slave/test/build/venv 22:07:20 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 22:07:20 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 22:07:20 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 22:07:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:07:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:07:21 INFO - Installing distribute.............................................................................................................................................................................................done. 22:07:21 INFO - Installing pip...............done. 22:07:21 INFO - Return code: 0 22:07:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 22:07:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 22:07:21 INFO - Reading from file tmpfile_stdout 22:07:21 INFO - Output received: 22:07:21 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 22:07:21 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:07:21 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')]} 22:07:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:07:21 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')]} 22:07:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:07:21 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 0x1e5d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2026a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21c9a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21cd090>, '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 0x21cdd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21c7f90>, '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': '97facb8ba2ac28944cc45e6b000001d4-1506052468.613065-334524023', '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 22:07:21 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 22:07:21 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 22:07:21 INFO - Using env: {'DISPLAY': ':0', 22:07:21 INFO - 'HOME': '/home/cltbld', 22:07:21 INFO - 'LANG': 'en_US.UTF-8', 22:07:21 INFO - 'LANGUAGE': 'en_US:en', 22:07:21 INFO - 'LOGNAME': 'cltbld', 22:07:21 INFO - 'MAIL': '/var/mail/cltbld', 22:07:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:07:21 INFO - 'MOZ_NO_REMOTE': '1', 22:07:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:07:21 INFO - 'NO_EM_RESTART': '1', 22:07:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:07:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:07:21 INFO - 'PWD': '/builds/slave/test', 22:07:21 INFO - 'SHELL': '/bin/bash', 22:07:21 INFO - 'SHLVL': '1', 22:07:21 INFO - 'TERM': 'linux', 22:07:21 INFO - 'TMOUT': '86400', 22:07:21 INFO - 'USER': 'cltbld', 22:07:21 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1506052468.613065-334524023', 22:07:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:07:21 INFO - '_': '/tools/buildbot/bin/python'} 22:07:23 INFO - Ignoring indexes: http://pypi.python.org/simple/ 22:07:23 INFO - Downloading/unpacking pip>=1.5 22:07:23 INFO - Running setup.py egg_info for package pip 22:07:23 INFO - warning: no files found matching 'pip/cacert.pem' 22:07:23 INFO - warning: no files found matching '*.html' under directory 'docs' 22:07:23 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 22:07:23 INFO - no previously-included directories found matching 'docs/_build/_sources' 22:07:23 INFO - Installing collected packages: pip 22:07:23 INFO - Found existing installation: pip 1.1 22:07:23 INFO - Uninstalling pip: 22:07:23 INFO - Successfully uninstalled pip 22:07:23 INFO - Running setup.py install for pip 22:07:23 INFO - warning: no files found matching 'pip/cacert.pem' 22:07:23 INFO - warning: no files found matching '*.html' under directory 'docs' 22:07:23 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 22:07:23 INFO - no previously-included directories found matching 'docs/_build/_sources' 22:07:23 INFO - Installing pip script to /builds/slave/test/build/venv/bin 22:07:23 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 22:07:23 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 22:07:23 INFO - Successfully installed pip 22:07:23 INFO - Cleaning up... 22:07:23 INFO - Return code: 0 22:07:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:07:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:07:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:07:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:07:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:07:23 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 0x1e5d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2026a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21c9a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21cd090>, '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 0x21cdd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21c7f90>, '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': '97facb8ba2ac28944cc45e6b000001d4-1506052468.613065-334524023', '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 22:07:23 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 22:07:23 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 22:07:23 INFO - Using env: {'DISPLAY': ':0', 22:07:23 INFO - 'HOME': '/home/cltbld', 22:07:23 INFO - 'LANG': 'en_US.UTF-8', 22:07:23 INFO - 'LANGUAGE': 'en_US:en', 22:07:23 INFO - 'LOGNAME': 'cltbld', 22:07:23 INFO - 'MAIL': '/var/mail/cltbld', 22:07:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:07:23 INFO - 'MOZ_NO_REMOTE': '1', 22:07:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:07:23 INFO - 'NO_EM_RESTART': '1', 22:07:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:07:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:07:23 INFO - 'PWD': '/builds/slave/test', 22:07:23 INFO - 'SHELL': '/bin/bash', 22:07:23 INFO - 'SHLVL': '1', 22:07:23 INFO - 'TERM': 'linux', 22:07:23 INFO - 'TMOUT': '86400', 22:07:23 INFO - 'USER': 'cltbld', 22:07:23 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1506052468.613065-334524023', 22:07:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:07:23 INFO - '_': '/tools/buildbot/bin/python'} 22:07:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:07:23 INFO - Downloading/unpacking psutil>=3.1.1 22:07:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:07:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:07:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:07:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:07:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:07:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:07:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:07:24 INFO - Installing collected packages: psutil 22:07:24 INFO - Running setup.py install for psutil 22:07:24 INFO - building 'psutil._psutil_linux' extension 22:07:24 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 22:07:24 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 22:07:24 INFO - building 'psutil._psutil_posix' extension 22:07:24 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 22:07:24 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 22:07:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:07:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:07:24 INFO - Successfully installed psutil 22:07:24 INFO - Cleaning up... 22:07:25 INFO - Return code: 0 22:07:25 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:07:25 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')]} 22:07:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:07:25 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')]} 22:07:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:07:25 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 0x1e5d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2026a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21c9a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21cd090>, '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 0x21cdd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21c7f90>, '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': '97facb8ba2ac28944cc45e6b000001d4-1506052468.613065-334524023', '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 22:07:25 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 22:07:25 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 22:07:25 INFO - Using env: {'DISPLAY': ':0', 22:07:25 INFO - 'HOME': '/home/cltbld', 22:07:25 INFO - 'LANG': 'en_US.UTF-8', 22:07:25 INFO - 'LANGUAGE': 'en_US:en', 22:07:25 INFO - 'LOGNAME': 'cltbld', 22:07:25 INFO - 'MAIL': '/var/mail/cltbld', 22:07:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:07:25 INFO - 'MOZ_NO_REMOTE': '1', 22:07:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:07:25 INFO - 'NO_EM_RESTART': '1', 22:07:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:07:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:07:25 INFO - 'PWD': '/builds/slave/test', 22:07:25 INFO - 'SHELL': '/bin/bash', 22:07:25 INFO - 'SHLVL': '1', 22:07:25 INFO - 'TERM': 'linux', 22:07:25 INFO - 'TMOUT': '86400', 22:07:25 INFO - 'USER': 'cltbld', 22:07:25 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1506052468.613065-334524023', 22:07:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:07:25 INFO - '_': '/tools/buildbot/bin/python'} 22:07:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:07:25 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:07:25 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 22:07:25 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 22:07:25 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 22:07:25 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 22:07:26 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:07:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:07:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:07:26 INFO - Installing collected packages: mozsystemmonitor 22:07:26 INFO - Running setup.py install for mozsystemmonitor 22:07:26 INFO - Successfully installed mozsystemmonitor 22:07:26 INFO - Cleaning up... 22:07:27 INFO - Return code: 0 22:07:27 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:07:27 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')]} 22:07:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:07:27 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')]} 22:07:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:07:27 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 0x1e5d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2026a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21c9a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21cd090>, '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 0x21cdd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21c7f90>, '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': '97facb8ba2ac28944cc45e6b000001d4-1506052468.613065-334524023', '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 22:07:27 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 22:07:27 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 22:07:27 INFO - Using env: {'DISPLAY': ':0', 22:07:27 INFO - 'HOME': '/home/cltbld', 22:07:27 INFO - 'LANG': 'en_US.UTF-8', 22:07:27 INFO - 'LANGUAGE': 'en_US:en', 22:07:27 INFO - 'LOGNAME': 'cltbld', 22:07:27 INFO - 'MAIL': '/var/mail/cltbld', 22:07:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:07:27 INFO - 'MOZ_NO_REMOTE': '1', 22:07:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:07:27 INFO - 'NO_EM_RESTART': '1', 22:07:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:07:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:07:27 INFO - 'PWD': '/builds/slave/test', 22:07:27 INFO - 'SHELL': '/bin/bash', 22:07:27 INFO - 'SHLVL': '1', 22:07:27 INFO - 'TERM': 'linux', 22:07:27 INFO - 'TMOUT': '86400', 22:07:27 INFO - 'USER': 'cltbld', 22:07:27 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1506052468.613065-334524023', 22:07:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:07:27 INFO - '_': '/tools/buildbot/bin/python'} 22:07:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:07:27 INFO - Downloading/unpacking jsonschema==2.5.1 22:07:27 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 22:07:27 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 22:07:27 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 22:07:27 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 22:07:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:07:29 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:07:29 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 22:07:29 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 22:07:29 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 22:07:29 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 22:07:29 INFO - Downloading functools32-3.2.3-2.tar.gz 22:07:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:07:29 INFO - warning: no files found matching '*.txt' 22:07:29 INFO - no previously-included directories found matching 'build' 22:07:29 INFO - no previously-included directories found matching 'dist' 22:07:29 INFO - no previously-included directories found matching '.git*' 22:07:29 INFO - Installing collected packages: jsonschema, functools32 22:07:30 INFO - Running setup.py install for functools32 22:07:30 INFO - warning: no files found matching '*.txt' 22:07:30 INFO - no previously-included directories found matching 'build' 22:07:30 INFO - no previously-included directories found matching 'dist' 22:07:30 INFO - no previously-included directories found matching '.git*' 22:07:30 INFO - Successfully installed jsonschema functools32 22:07:30 INFO - Cleaning up... 22:07:30 INFO - Return code: 0 22:07:30 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:07:30 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')]} 22:07:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:07:30 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')]} 22:07:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:07:30 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 0x1e5d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2026a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21c9a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21cd090>, '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 0x21cdd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21c7f90>, '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': '97facb8ba2ac28944cc45e6b000001d4-1506052468.613065-334524023', '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 22:07:30 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 22:07:30 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 22:07:30 INFO - Using env: {'DISPLAY': ':0', 22:07:30 INFO - 'HOME': '/home/cltbld', 22:07:30 INFO - 'LANG': 'en_US.UTF-8', 22:07:30 INFO - 'LANGUAGE': 'en_US:en', 22:07:30 INFO - 'LOGNAME': 'cltbld', 22:07:30 INFO - 'MAIL': '/var/mail/cltbld', 22:07:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:07:30 INFO - 'MOZ_NO_REMOTE': '1', 22:07:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:07:30 INFO - 'NO_EM_RESTART': '1', 22:07:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:07:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:07:30 INFO - 'PWD': '/builds/slave/test', 22:07:30 INFO - 'SHELL': '/bin/bash', 22:07:30 INFO - 'SHLVL': '1', 22:07:30 INFO - 'TERM': 'linux', 22:07:30 INFO - 'TMOUT': '86400', 22:07:30 INFO - 'USER': 'cltbld', 22:07:30 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1506052468.613065-334524023', 22:07:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:07:30 INFO - '_': '/tools/buildbot/bin/python'} 22:07:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:07:30 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:07:30 INFO - Cleaning up... 22:07:30 INFO - Return code: 0 22:07:30 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:07:30 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')]} 22:07:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:07:30 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')]} 22:07:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:07:30 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 0x1e5d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2026a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21c9a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21cd090>, '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 0x21cdd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21c7f90>, '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': '97facb8ba2ac28944cc45e6b000001d4-1506052468.613065-334524023', '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 22:07:30 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 22:07:30 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 22:07:30 INFO - Using env: {'DISPLAY': ':0', 22:07:30 INFO - 'HOME': '/home/cltbld', 22:07:30 INFO - 'LANG': 'en_US.UTF-8', 22:07:30 INFO - 'LANGUAGE': 'en_US:en', 22:07:30 INFO - 'LOGNAME': 'cltbld', 22:07:30 INFO - 'MAIL': '/var/mail/cltbld', 22:07:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:07:30 INFO - 'MOZ_NO_REMOTE': '1', 22:07:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:07:30 INFO - 'NO_EM_RESTART': '1', 22:07:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:07:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:07:30 INFO - 'PWD': '/builds/slave/test', 22:07:30 INFO - 'SHELL': '/bin/bash', 22:07:30 INFO - 'SHLVL': '1', 22:07:30 INFO - 'TERM': 'linux', 22:07:30 INFO - 'TMOUT': '86400', 22:07:30 INFO - 'USER': 'cltbld', 22:07:30 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1506052468.613065-334524023', 22:07:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:07:30 INFO - '_': '/tools/buildbot/bin/python'} 22:07:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:07:31 INFO - Downloading/unpacking blobuploader==1.2.4 22:07:31 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 22:07:31 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 22:07:31 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 22:07:31 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 22:07:32 INFO - Downloading blobuploader-1.2.4.tar.gz 22:07:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:07:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:07:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:07:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:07:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:07:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:07:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:07:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:07:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:07:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:07:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:07:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:07:32 INFO - Downloading docopt-0.6.1.tar.gz 22:07:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:07:33 INFO - Installing collected packages: blobuploader, requests, docopt 22:07:33 INFO - Running setup.py install for blobuploader 22:07:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:07:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:07:33 INFO - Running setup.py install for requests 22:07:33 INFO - Running setup.py install for docopt 22:07:33 INFO - Successfully installed blobuploader requests docopt 22:07:33 INFO - Cleaning up... 22:07:33 INFO - Return code: 0 22:07:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:07:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:07:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:07:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:07:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:07:33 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 0x1e5d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2026a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21c9a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21cd090>, '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 0x21cdd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21c7f90>, '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': '97facb8ba2ac28944cc45e6b000001d4-1506052468.613065-334524023', '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 22:07:33 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 22:07:33 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 22:07:33 INFO - Using env: {'DISPLAY': ':0', 22:07:33 INFO - 'HOME': '/home/cltbld', 22:07:33 INFO - 'LANG': 'en_US.UTF-8', 22:07:33 INFO - 'LANGUAGE': 'en_US:en', 22:07:33 INFO - 'LOGNAME': 'cltbld', 22:07:33 INFO - 'MAIL': '/var/mail/cltbld', 22:07:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:07:33 INFO - 'MOZ_NO_REMOTE': '1', 22:07:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:07:33 INFO - 'NO_EM_RESTART': '1', 22:07:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:07:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:07:33 INFO - 'PWD': '/builds/slave/test', 22:07:33 INFO - 'SHELL': '/bin/bash', 22:07:33 INFO - 'SHLVL': '1', 22:07:33 INFO - 'TERM': 'linux', 22:07:33 INFO - 'TMOUT': '86400', 22:07:33 INFO - 'USER': 'cltbld', 22:07:33 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1506052468.613065-334524023', 22:07:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:07:33 INFO - '_': '/tools/buildbot/bin/python'} 22:07:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:07:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:07:33 INFO - Running setup.py (path:/tmp/pip-0HlpUI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:07:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:07:33 INFO - Running setup.py (path:/tmp/pip-b11RAN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:07:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:07:33 INFO - Running setup.py (path:/tmp/pip-0ihZkr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:07:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:07:34 INFO - Running setup.py (path:/tmp/pip-fu1Yz8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:07:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:07:34 INFO - Running setup.py (path:/tmp/pip-_prEpG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:07:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:07:34 INFO - Running setup.py (path:/tmp/pip-OVYoSh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:07:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:07:34 INFO - Running setup.py (path:/tmp/pip-58VnmQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:07:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:07:34 INFO - Running setup.py (path:/tmp/pip-Go_5Zj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:07:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:07:34 INFO - Running setup.py (path:/tmp/pip-2_DPy8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:07:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:07:34 INFO - Running setup.py (path:/tmp/pip-9nlIwD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:07:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:07:34 INFO - Running setup.py (path:/tmp/pip-V_vw3B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:07:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:07:34 INFO - Running setup.py (path:/tmp/pip-li7YQ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:07:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:07:34 INFO - Running setup.py (path:/tmp/pip-N0CBpv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:07:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:07:34 INFO - Running setup.py (path:/tmp/pip-jQyfnb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:07:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:07:34 INFO - Running setup.py (path:/tmp/pip-wCdOXZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:07:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:07:34 INFO - Running setup.py (path:/tmp/pip-NUVFAr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:07:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:07:34 INFO - Running setup.py (path:/tmp/pip-17vvyQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:07:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:07:35 INFO - Running setup.py install for manifestparser 22:07:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:07:35 INFO - Running setup.py install for mozcrash 22:07:35 INFO - Running setup.py install for mozdebug 22:07:35 INFO - Running setup.py install for mozdevice 22:07:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:07:35 INFO - Running setup.py install for mozfile 22:07:35 INFO - Running setup.py install for mozhttpd 22:07:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:07:35 INFO - Running setup.py install for mozinfo 22:07:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:07:35 INFO - Running setup.py install for mozInstall 22:07:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:07:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:07:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:07:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:07:35 INFO - Running setup.py install for mozleak 22:07:35 INFO - Running setup.py install for mozlog 22:07:36 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:07:36 INFO - Running setup.py install for moznetwork 22:07:36 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:07:36 INFO - Running setup.py install for mozprocess 22:07:36 INFO - Running setup.py install for mozprofile 22:07:36 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:07:36 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:07:36 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:07:36 INFO - Running setup.py install for mozrunner 22:07:36 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:07:36 INFO - Running setup.py install for mozscreenshot 22:07:36 INFO - Running setup.py install for moztest 22:07:36 INFO - Running setup.py install for mozversion 22:07:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:07:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:07:36 INFO - Cleaning up... 22:07:36 INFO - Return code: 0 22:07:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:07:36 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')]} 22:07:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:07:36 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')]} 22:07:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:07:36 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 0x1e5d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2026a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21c9a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21cd090>, '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 0x21cdd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21c7f90>, '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': '97facb8ba2ac28944cc45e6b000001d4-1506052468.613065-334524023', '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 22:07:36 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 22:07:36 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 22:07:36 INFO - Using env: {'DISPLAY': ':0', 22:07:36 INFO - 'HOME': '/home/cltbld', 22:07:36 INFO - 'LANG': 'en_US.UTF-8', 22:07:36 INFO - 'LANGUAGE': 'en_US:en', 22:07:36 INFO - 'LOGNAME': 'cltbld', 22:07:36 INFO - 'MAIL': '/var/mail/cltbld', 22:07:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:07:36 INFO - 'MOZ_NO_REMOTE': '1', 22:07:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:07:36 INFO - 'NO_EM_RESTART': '1', 22:07:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:07:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:07:36 INFO - 'PWD': '/builds/slave/test', 22:07:36 INFO - 'SHELL': '/bin/bash', 22:07:36 INFO - 'SHLVL': '1', 22:07:36 INFO - 'TERM': 'linux', 22:07:36 INFO - 'TMOUT': '86400', 22:07:36 INFO - 'USER': 'cltbld', 22:07:36 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1506052468.613065-334524023', 22:07:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:07:36 INFO - '_': '/tools/buildbot/bin/python'} 22:07:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:07:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:07:37 INFO - Running setup.py (path:/tmp/pip-rIMTr6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:07:37 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)) 22:07:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:07:37 INFO - Running setup.py (path:/tmp/pip-x7aDsw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:07:37 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)) 22:07:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:07:37 INFO - Running setup.py (path:/tmp/pip-cUpDaB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:07:37 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)) 22:07:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:07:37 INFO - Running setup.py (path:/tmp/pip-2QUUIa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:07:37 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)) 22:07:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:07:37 INFO - Running setup.py (path:/tmp/pip-xcIqQw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:07:37 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)) 22:07:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:07:37 INFO - Running setup.py (path:/tmp/pip-P42kDv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:07:37 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)) 22:07:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:07:37 INFO - Running setup.py (path:/tmp/pip-ecAbdK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:07:37 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)) 22:07:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:07:37 INFO - Running setup.py (path:/tmp/pip-h6yAU3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:07:37 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)) 22:07:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:07:37 INFO - Running setup.py (path:/tmp/pip-VnHghW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:07:37 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)) 22:07:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:07:37 INFO - Running setup.py (path:/tmp/pip-vBmpwq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:07:37 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)) 22:07:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:07:37 INFO - Running setup.py (path:/tmp/pip-JWn2ON-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:07:37 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)) 22:07:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:07:37 INFO - Running setup.py (path:/tmp/pip-GfwKOF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:07:37 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)) 22:07:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:07:37 INFO - Running setup.py (path:/tmp/pip-vOAbzX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:07:37 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)) 22:07:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:07:37 INFO - Running setup.py (path:/tmp/pip-cUVYeK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:07:38 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)) 22:07:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:07:38 INFO - Running setup.py (path:/tmp/pip-nTjjng-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:07:38 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)) 22:07:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:07:38 INFO - Running setup.py (path:/tmp/pip-C9Hor5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:07:38 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)) 22:07:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:07:38 INFO - Running setup.py (path:/tmp/pip-mWfoP2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:07:38 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)) 22:07:38 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)) 22:07:38 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)) 22:07:38 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)) 22:07:38 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)) 22:07:38 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)) 22:07:38 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)) 22:07:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:07:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:07:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:07:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:07:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:07:39 INFO - Downloading blessings-1.6.tar.gz 22:07:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:07:39 INFO - Installing collected packages: blessings 22:07:39 INFO - Running setup.py install for blessings 22:07:39 INFO - Successfully installed blessings 22:07:39 INFO - Cleaning up... 22:07:39 INFO - Return code: 0 22:07:39 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:07:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:07:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:07:39 INFO - Reading from file tmpfile_stdout 22:07:39 INFO - Current package versions: 22:07:39 INFO - argparse == 1.2.1 22:07:39 INFO - blessings == 1.6 22:07:39 INFO - blobuploader == 1.2.4 22:07:39 INFO - docopt == 0.6.1 22:07:39 INFO - functools32 == 3.2.3-2 22:07:39 INFO - jsonschema == 2.5.1 22:07:39 INFO - manifestparser == 1.1 22:07:39 INFO - mozInstall == 1.13 22:07:39 INFO - mozcrash == 1.0 22:07:39 INFO - mozdebug == 0.1 22:07:39 INFO - mozdevice == 0.51 22:07:39 INFO - mozfile == 1.2 22:07:39 INFO - mozhttpd == 0.7 22:07:39 INFO - mozinfo == 0.10 22:07:39 INFO - mozleak == 0.1 22:07:39 INFO - mozlog == 3.5 22:07:39 INFO - moznetwork == 0.27 22:07:39 INFO - mozprocess == 0.25 22:07:39 INFO - mozprofile == 0.28 22:07:39 INFO - mozrunner == 6.13 22:07:39 INFO - mozscreenshot == 0.1 22:07:39 INFO - mozsystemmonitor == 0.3 22:07:39 INFO - moztest == 0.8 22:07:39 INFO - mozversion == 1.4 22:07:39 INFO - psutil == 3.1.1 22:07:39 INFO - requests == 1.2.3 22:07:39 INFO - wsgiref == 0.1.2 22:07:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:07:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:07:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:07:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:07:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:07:39 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 0x1e5d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2026a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21c9a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21cd090>, '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 0x21cdd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21c7f90>, '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': '97facb8ba2ac28944cc45e6b000001d4-1506052468.613065-334524023', '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 22:07:39 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 22:07:39 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 22:07:39 INFO - Using env: {'DISPLAY': ':0', 22:07:39 INFO - 'HOME': '/home/cltbld', 22:07:39 INFO - 'LANG': 'en_US.UTF-8', 22:07:39 INFO - 'LANGUAGE': 'en_US:en', 22:07:39 INFO - 'LOGNAME': 'cltbld', 22:07:39 INFO - 'MAIL': '/var/mail/cltbld', 22:07:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:07:39 INFO - 'MOZ_NO_REMOTE': '1', 22:07:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:07:39 INFO - 'NO_EM_RESTART': '1', 22:07:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:07:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:07:39 INFO - 'PWD': '/builds/slave/test', 22:07:39 INFO - 'SHELL': '/bin/bash', 22:07:39 INFO - 'SHLVL': '1', 22:07:39 INFO - 'TERM': 'linux', 22:07:39 INFO - 'TMOUT': '86400', 22:07:39 INFO - 'USER': 'cltbld', 22:07:39 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1506052468.613065-334524023', 22:07:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:07:39 INFO - '_': '/tools/buildbot/bin/python'} 22:07:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:07:40 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)) 22:07:40 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)) 22:07:40 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)) 22:07:40 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)) 22:07:40 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)) 22:07:40 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)) 22:07:40 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)) 22:07:40 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)) 22:07:40 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)) 22:07:40 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 22:07:40 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 22:07:40 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 22:07:40 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 22:07:40 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 22:07:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 22:07:41 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)) 22:07:41 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)) 22:07:41 INFO - Installing collected packages: simplejson 22:07:41 INFO - Running setup.py install for simplejson 22:07:41 INFO - building 'simplejson._speedups' extension 22:07:41 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 22:07:42 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 22:07:42 INFO - Successfully installed simplejson 22:07:42 INFO - Cleaning up... 22:07:42 INFO - Return code: 0 22:07:42 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:07:42 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')]} 22:07:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:07:42 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')]} 22:07:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:07:42 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 0x1e5d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2026a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21c9a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21cd090>, '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 0x21cdd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21c7f90>, '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': '97facb8ba2ac28944cc45e6b000001d4-1506052468.613065-334524023', '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 22:07:42 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 22:07:42 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 22:07:42 INFO - Using env: {'DISPLAY': ':0', 22:07:42 INFO - 'HOME': '/home/cltbld', 22:07:42 INFO - 'LANG': 'en_US.UTF-8', 22:07:42 INFO - 'LANGUAGE': 'en_US:en', 22:07:42 INFO - 'LOGNAME': 'cltbld', 22:07:42 INFO - 'MAIL': '/var/mail/cltbld', 22:07:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:07:42 INFO - 'MOZ_NO_REMOTE': '1', 22:07:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:07:42 INFO - 'NO_EM_RESTART': '1', 22:07:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:07:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:07:42 INFO - 'PWD': '/builds/slave/test', 22:07:42 INFO - 'SHELL': '/bin/bash', 22:07:42 INFO - 'SHLVL': '1', 22:07:42 INFO - 'TERM': 'linux', 22:07:42 INFO - 'TMOUT': '86400', 22:07:42 INFO - 'USER': 'cltbld', 22:07:42 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1506052468.613065-334524023', 22:07:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:07:42 INFO - '_': '/tools/buildbot/bin/python'} 22:07:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:07:42 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:07:42 INFO - Cleaning up... 22:07:42 INFO - Return code: 0 22:07:42 INFO - Running post-action listener: _resource_record_post_action 22:07:42 INFO - Running post-action listener: _start_resource_monitoring 22:07:42 INFO - Starting resource monitoring. 22:07:42 INFO - [mozharness: 2017-09-22 05:07:42.541485Z] Finished create-virtualenv step (success) 22:07:42 INFO - [mozharness: 2017-09-22 05:07:42.542067Z] Running install step. 22:07:42 INFO - Running pre-action listener: _resource_record_pre_action 22:07:42 INFO - Running main action method: install 22:07:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:07:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:07:42 INFO - Reading from file tmpfile_stdout 22:07:42 INFO - Detecting whether we're running mozinstall >=1.0... 22:07:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:07:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:07:42 INFO - Reading from file tmpfile_stdout 22:07:42 INFO - Output received: 22:07:42 INFO - Usage: mozinstall [options] installer 22:07:42 INFO - Options: 22:07:42 INFO - -h, --help show this help message and exit 22:07:42 INFO - -d DEST, --destination=DEST 22:07:42 INFO - Directory to install application into. [default: 22:07:42 INFO - "/builds/slave/test"] 22:07:42 INFO - --app=APP Application being installed. [default: firefox] 22:07:42 INFO - mkdir: /builds/slave/test/build/application 22:07:42 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'] 22:07:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 22:08:00 INFO - Reading from file tmpfile_stdout 22:08:00 INFO - Output received: 22:08:00 INFO - /builds/slave/test/build/application/firefox/firefox 22:08:00 INFO - Running post-action listener: _resource_record_post_action 22:08:00 INFO - [mozharness: 2017-09-22 05:08:00.575472Z] Finished install step (success) 22:08:00 INFO - [mozharness: 2017-09-22 05:08:00.575722Z] Running setup-mitmproxy step. 22:08:00 INFO - Running pre-action listener: _resource_record_pre_action 22:08:00 INFO - Running main action method: setup_mitmproxy 22:08:00 INFO - Skipping: mitmproxy is not required 22:08:00 INFO - Running post-action listener: _resource_record_post_action 22:08:00 INFO - [mozharness: 2017-09-22 05:08:00.576979Z] Finished setup-mitmproxy step (success) 22:08:00 INFO - [mozharness: 2017-09-22 05:08:00.577199Z] Running run-tests step. 22:08:00 INFO - Running pre-action listener: _resource_record_pre_action 22:08:00 INFO - Running pre-action listener: _set_gcov_prefix 22:08:00 INFO - Running main action method: run_tests 22:08:00 WARNING - Try message not found. 22:08:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:08:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:08:00 INFO - Python 2.7.3 22:08:00 INFO - Return code: 0 22:08:00 INFO - grabbing minidump binary from tooltool 22:08: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')]} 22:08:00 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 0x21cd090>, '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 0x21cdd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21c7f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:08:00 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 22:08:00 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 22:08:00 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 22:08:00 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:08:00 INFO - Return code: 0 22:08:00 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:08:00 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:08:00 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:08:00 INFO - ENV: RUST_BACKTRACE is now full 22:08:00 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:08:00 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:08:00 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/bNEWwPAlQDWVxLoQgV_2iA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-068', '--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 22:08:00 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/bNEWwPAlQDWVxLoQgV_2iA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-068 --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 22:08:00 INFO - Using env: {'DISPLAY': ':0', 22:08:00 INFO - 'HOME': '/home/cltbld', 22:08:00 INFO - 'LANG': 'en_US.UTF-8', 22:08:00 INFO - 'LANGUAGE': 'en_US:en', 22:08:00 INFO - 'LOGNAME': 'cltbld', 22:08:00 INFO - 'MAIL': '/var/mail/cltbld', 22:08:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:08:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:08:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:08:00 INFO - 'MOZ_NO_REMOTE': '1', 22:08:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:08:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:08:00 INFO - 'NO_EM_RESTART': '1', 22:08:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:08:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:08:00 INFO - 'PWD': '/builds/slave/test', 22:08:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:08:00 INFO - 'RUST_BACKTRACE': 'full', 22:08:00 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 22:08:00 INFO - 'SHELL': '/bin/bash', 22:08:00 INFO - 'SHLVL': '1', 22:08:00 INFO - 'TERM': 'linux', 22:08:00 INFO - 'TMOUT': '86400', 22:08:00 INFO - 'USER': 'cltbld', 22:08:00 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1506052468.613065-334524023', 22:08:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:08:00 INFO - '_': '/tools/buildbot/bin/python'} 22:08:00 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/bNEWwPAlQDWVxLoQgV_2iA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-068', '--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 22:08:00 INFO - mozversion application_buildid: 20170922034016 22:08:00 INFO - mozversion application_changeset: 97282b0c985aa1778ced171514d1ae61945c634c 22:08:00 INFO - mozversion application_display_name: Nightly 22:08:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:08:00 INFO - mozversion application_name: Firefox 22:08:00 INFO - mozversion application_remotingname: firefox 22:08:00 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 22:08:00 INFO - mozversion application_vendor: Mozilla 22:08:00 INFO - mozversion application_version: 58.0a1 22:08:00 INFO - mozversion platform_buildid: 20170922034016 22:08:00 INFO - mozversion platform_changeset: 97282b0c985aa1778ced171514d1ae61945c634c 22:08:00 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 22:08:00 INFO - mozversion platform_version: 58.0a1 22:08:00 INFO - using testdate: 1506056880 22:08:00 INFO - actual date: 1506056880 22:08:00 INFO - starting webserver on 'localhost:44831' 22:08:00 INFO - SUITE-START | Running 2 tests 22:08:00 INFO - TEST-START | dromaeo_css 22:08:00 INFO - Initialising browser for dromaeo_css test... 22:08:00 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:44831/getInfo.html -profile /tmp/tmpunnyr4/profile 22:08:00 INFO - TEST-INFO | started process 31163 (/builds/slave/test/build/application/firefox/firefox http://localhost:44831/getInfo.html) 22:08:06 INFO - TEST-INFO | 31163: exit 0 22:08:06 INFO - Browser initialized. 22:08:06 INFO - Running cycle 1/1 for dromaeo_css test... 22:08:06 INFO - Using env: {'DISPLAY': ':0', 22:08:06 INFO - 'HOME': '/home/cltbld', 22:08:06 INFO - 'JSGC_DISABLE_POISONING': '1', 22:08:06 INFO - 'LANG': 'en_US.UTF-8', 22:08:06 INFO - 'LANGUAGE': 'en_US:en', 22:08:06 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 22:08:06 INFO - 'LOGNAME': 'cltbld', 22:08:06 INFO - 'MAIL': '/var/mail/cltbld', 22:08:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:08:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:08:06 INFO - 'MOZ_CRASHREPORTER': '1', 22:08:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:08:06 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 22:08:06 INFO - 'MOZ_NO_REMOTE': '1', 22:08:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:08:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:08:06 INFO - 'NO_EM_RESTART': '1', 22:08:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:08:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:08:06 INFO - 'PWD': '/builds/slave/test', 22:08:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:08:06 INFO - 'RUST_BACKTRACE': 'full', 22:08:06 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 22:08:06 INFO - 'SHELL': '/bin/bash', 22:08:06 INFO - 'SHLVL': '1', 22:08:06 INFO - 'TERM': 'linux', 22:08:06 INFO - 'TMOUT': '86400', 22:08:06 INFO - 'USER': 'cltbld', 22:08:06 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1506052468.613065-334524023', 22:08:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:08:06 INFO - '_': '/tools/buildbot/bin/python'} 22:08:06 INFO - TEST-INFO | started process 31403 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpunnyr4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 22:08:07 INFO - PID 31403 | 22:08:07 INFO - PID 31403 | (/builds/slave/test/build/application/firefox/firefox:31453): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 22:08:07 INFO - PID 31403 | 22:08:17 INFO - PID 31403 | RSS: Main: 147554304 22:08:17 INFO - PID 31403 | 22:11:15 INFO - PID 31403 | Cycle 1(1): loaded http://localhost:44831/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44831/tests/dromaeo/cssquery-ext.html) 22:11:16 INFO - PID 31403 | RSS: Main: 151109632 22:11:16 INFO - PID 31403 | 22:14:18 INFO - PID 31403 | Cycle 1(1): loaded http://localhost:44831/tests/dromaeo/cssquery-ext.html (next: http://localhost:44831/tests/dromaeo/cssquery-jquery.html) 22:14:18 INFO - PID 31403 | RSS: Main: 149495808 22:14:18 INFO - PID 31403 | 22:17:20 INFO - PID 31403 | Cycle 1(1): loaded http://localhost:44831/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44831/tests/dromaeo/cssquery-mootools.html) 22:17:21 INFO - PID 31403 | RSS: Main: 151478272 22:17:21 INFO - PID 31403 | 22:20:22 INFO - PID 31403 | Cycle 1(1): loaded http://localhost:44831/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44831/tests/dromaeo/cssquery-prototype.html) 22:20:23 INFO - PID 31403 | RSS: Main: 149745664 22:20:23 INFO - PID 31403 | 22:23:26 INFO - PID 31403 | Cycle 1(1): loaded http://localhost:44831/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44831/tests/dromaeo/cssquery-yui.html) 22:23:26 INFO - PID 31403 | RSS: Main: 151101440 22:23:26 INFO - PID 31403 | 22:26:28 INFO - PID 31403 | Cycle 1(1): loaded http://localhost:44831/tests/dromaeo/cssquery-yui.html (next: http://localhost:44831/tests/dromaeo/cssquery-dojo.html) 22:26:28 INFO - PID 31403 | RSS: Main: 153378816 22:26:28 INFO - PID 31403 | 22:26:28 INFO - PID 31403 | __start_tp_report 22:26:28 INFO - PID 31403 | _x_x_mozilla_page_load 22:26:28 INFO - PID 31403 | _x_x_mozilla_page_load_details 22:26:28 INFO - PID 31403 | |i|pagename|runs| 22:26:28 INFO - PID 31403 | |0;dojo.html;3831.8850434486967;3868.709846761492;3873.3608954522506;3878.6315300046494;3885.863994760184;6160.199174107371;6197.721102550385;6249.625022498648;6263.467605253553;6350.777722779703;6181.907271390922;6183.876322473559;6187.5978061426085;6200.1629779979685;6210.565260431772;5493.41695910798;5537.8061767838135;5554.1946417769395;5620.1288800235825;5695.231143795576;5654.038813401731;5675.035244008508;5692.260006199209;5699.601027928046;5708.029634962046;5445.210444485528;5670.206171136044;5729.226554415142;5760.654360738348;5772.913406298909;5558.166275058733;5558.277423934903;5570.108782594806;5652.321721393418;5654.9151762723595;5576.804811831607;5589.993801115797;5664.858378540528;5693.345265294462;5696.145578163268;6149.723262453201;6184.07238914162;6236.937630623681;6281.623102613812;6338.841528961812;6656.567323123982;6730.562513436611;6754;6776.661166941633;6779.661016949182;6831.487638427137;6879.415249703781;6899.517033807636;6902.792916212522;6921.48088893335;6302.243730752339;6303.842403939847;6339.619622822645;6417.871642567123;6429.389208025277;6894.793156205322;7015.649217539205;7020.017197592341;7021.473389495808;7040.155181378165;6642.966785166043;6707.228668703064;6774.830629234309;6783.152105986752;6800.387965083068;6712.42944349726;6731.024001519851;6779.694913728894;6798.864022719518;6807.897881531781;6824.146981627297;6829.6243706596615;6831.248562658104;6833.453323734089;6875.209350924608;6702.497312701566;6765.154355705537;6864.656767161622;6869.519133660596;6890;4904.239842824297;4912.612657471522;4964.180910149785;5009.699418034929;5012.899742005139;4490.348899409632;4515.0518391137575;4535.070310586322;4588.908221835612;4617.699849509771;6125.231619060963;6201.6899155042065;6214.098955651405;6236.469900058455;6279.623222606659;6610.933890661032;6674.23246326669;6715.294894036154;6778.864422711518;6788;6152.16945712332;6196.969015154986;6211.9689401552705;6274.529410294246;6331.2085989251345;5503.669779813224;5519.862003449946;5523.8066667666435;5558.110702287615;5559.722013899289;7027.332403421667;7032.3670869620955;7107.040549525851;7117.786466406016;7119.074520312326;6540.313267106885;6692.330766923269;6788.2193547742645;6789;6884.65576721162;6952.409045231212;6986.860262794818;7001.054857594159;7038.753643622448;7079.752208672878;6659.966700166468;6738.966305168443;6740.561863478858;6749.696263668048;6757.155355580552;6543.70553325111;6558.409743123142;6631.602103873783;6648.90026649591;6740.494462915399;6059.787907423219;6398.648074355955;6563.704633291418;6586.37429444202;6607.504437167136;6451.645159516084;6549.214094308523;6553.279139294769;6585.209774827052;6608;6496.058071579689;6497.772577959749;6533.542652014314;6577.440917522064;6578.243501997332;6591.901121483085;6613.503987200883;6671.666416679243;6692.866142677217;6694.966525167343;6373;6414.262359828493;6454.644994525346;6465.838354041187;6466.127072845105 22:26:28 INFO - PID 31403 | |1;ext.html;945.3288165402564;966.990330096699;998.9350692205008;1011.5144730529346;1017.6489111256618;44037;44634;44757.328640070424;44823.32765008527;44913;43229.35155972663;43330.35004474936;43560.3465948011;43768.343474847825;43804;7737.845243095135;7738.264864837832;7741.303282704555;7745.806354841117;7761.805954851117;7640.503367281137;7755.030621172354;7759.689612415496;7826.960865195666;7871.763847084597;8063.919360806375;8070.031596208463;8089.312408445289;8109.472884262504;8246.958765206165;7984;8028.277455029046;8079.27286544208;8092.473989190684;8136.186381361876;8231.42380033398;8435.114312997108;8448.915510844874;8461.323094152453;8467.66129354825;40634;40804.183916321505;40923.590764092274;41068.38397424041;41070.38394424086;3711.2391959648207;3777.7355585109053;3780.8109594520165;3783.8486460541544;3790.3556395412847;32686;32911.34177316464;33874.83062584696;33913.830430847935;33957.660423395944;5433.157860531584;5453.4273901240595;5465.726713664301;5468.535174510172;5473.781048758045;208007;210907;223516;224949;225615;54090.45909540933;54749.45250547523;54759;54760.72619636876;54856;56300.43699563034;56494.152587711214;56607.716961415754;56630;56678.716606417525;16385;16454.917725411295;16456.588585285223;16503.17484125789;16509.66980660404;13339.533116340881;13354.265515396746;13354.332283385791;13400.329983500786;13403.262820544964;14729.55811325662;14736.55790326292;14784.852151478348;14821.77767333491;14973.625659358604;5792.82621521355;5920.259967504062;5966.463018328372;5971.223740913653;6009.819705408845;1081.9296745711504;1088.580896354909;1103.4151899493281;1105.850710154135;1121.7980763462656;25397.746022539537;25554.361140971254;25622.359441013752;25685.357866053124;25789.3552661185;1197.4252358867793;1203.9759204815855;1207.643745095199;1215.7021529725098;1222.4254600337827;13446.114913670197;13563;13621.523246686318;13819.723605527834;13946.232957187249;274939;275177;277364;277404;278221;58042.419575804546;58050.709746451;58303.708481457325;58408.41591584115;58712.41287587155;1497.9176145312113;1551.3174203350716;1556.2685537797217;1557.9922100389651;1578.3213218316005;24838;24910.526841947707;24927.750722493267;25213.87393063023;25271.620925685762;41653.583464166186;41678.791606042985;41683.24850763446;41706.79146604247;41726;232519;235516;236580;236782;237674;37783.43324850075;38401;40473.595264046984;40488.59511404848;40874.38688419617;49737.75131124321;51549.48450515597;51691.74154129205;51814.740926295126;52333.7383313081;36781;37055.258894822495;37593.81203093967;37709;37768;36442;36637.633623664486;36818;37106.25787484181;37963.050923727125;43357.56642433661;43684.344734828366;43958.78020609877;44001.33997989968;44002.779986101144;35476;35484.46773298454;35579.46630800592;36351.63648363482;36683.44974825326;27049.729502704722;27275;27480.58779118275;27572.172834814988;27596.862015689792 22:26:28 INFO - PID 31403 | |2;jquery.html;597.4264705882352;600.1537831657358;614.6527212125148;632.1466020871823;644.2365796531111;23288.650670239917;23332;24171.274861754177;24187.75812241877;24262.87868560657;25052.373690657743;26072;26222.344441388926;26222.73777262227;26264.606030909552;6757.290484499131;6945.062416573774;6954.304569543056;6959.582425054487;6959.860802783941;6588.110605068327;6844.520883538129;6862.622555759432;6886.69009894556;6940.236573976858;6839.110915580967;6841.281665425134;6847;6856.177258728934;6856.17725872896;6820.11338525991;6826.761063362758;6827.180738311409;6831.658417079127;6844.562641845218;6197.380261973766;6394.072859435356;6404.423601875854;6424.229092508882;6431.292557818636;29407;29686.406271874443;29753;29764.85117574398;29919.70080299191;2262.5474905019064;2301.8849057547054;2302.7582103879017;2302.8848557572223;2303.424143964009;19501.317453889045;19704.21183152672;20085.79914200854;20387.286444974354;20651.5869682607;2493.127405408111;2497.2133777859917;2551.2091251712027;2562.8334158279654;2590.2617753940226;135161;140767.29616351955;148844;153096;154541.22729386392;15111.848881511263;16070.839291607168;16114.435994740363;16213.675726485404;16422.6861851054;25893.09374171895;25947.481050379265;25962.22113336603;26019;26040.73959260383;10450.947745261225;10465.738356540995;10556.155507559377;10564.418956957288;10725.83911241332;9556.283278754121;9614.03859614047;9710.951445242728;9722.368046077123;9812.754681132887;9615.711528654152;9627.759306017406;9806.411615303105;9834.459104749307;10008.849867251996;2734.8222365546176;2798.566222235557;2907.694692057346;2909.6072030276064;2932.8533573321247;1026.414943482208;1041.8801837788599;1046.6859942017363;1049.5014867937668;1049.637874933162;20531.178752850017;20630.896845515676;20720.95033908553;21100.261490847748;21374.465638359165;1096.3641088168843;1100.818364969771;1112.7551938573502;1118.3792994887915;1131.140333346646;11226.494807733672;11678;11748.31182323089;11771.529138834521;11794.52821887132;568774;583330;588675;590839;596841;128839.35580322226;129622;130519;133506.33246833703;134101;1114.3035602748282;1138.612871623652;1211.5759484180467;1251.3993283224104;1266.5503746170161;21446.391520636327;21879.56240875142;22117.77882221157;22142.88928555347;22168;58390;59910.40089599048;60666.393336066074;60906;62572.687136565844;80128.19871801208;80443;80533.59733201297;80885;82645.58677206576;15523.689526209639;15782.684346313239;15892.364305427653;15909.443169489012;15937.601559961095;23845.284641460785;23980;23981.760182397953;24052.15817446381;24139.396515087265;19258.8074119257;19468.415947521597;19519.219231230592;19610.90194549075;19814.207431703147;15810.130442825755;16553.66892662164;16635.91682041582;17560.648787024442;18076.690504389986;19280;19694;19800.702989455458;20179.596408071462;20415.8997064825;15393.384264629289;15418.306176221873;15593.844061559694;15620.68758624844;15733.292001860173;17632.73550896712;17774.822251777317;19175.712364314266;19565.8043419564;19730.60538789245 22:26:28 INFO - PID 31403 | |3;mootools.html;1422.2533170085705;1470.4926800253916;1482.9555113346603;1492.7536956402193;1503.4136686692193;16995.15024248787;17556.122193890318;17565.47303580893;17566.736498952527;17646;17216;17241.655166896653;17327.48017559472;17491.125443727826;17501.562460938483;2797.63599758204;2819.957700634487;2874.597556342114;2875.2380619135947;2875.43928933858;2741.5065288248206;2768.4739899419046;2789.27478855497;2791.3858951030843;2800.2999250187454;2702.567589185731;2790.14900455361;2792.45547118312;2806.859657017151;2809.7471227589513;2829.4906916754976;2836.3901761121356;2871.3252385689348;2873.267316834215;2876.8849246030136;2665;2692.3942113024414;2706.539888218988;2708.4853877763358;2713.226730381851;16602.66794664112;17090.401835935805;17131;17155.485335439833;17201.397951071776;8134.959325203396;8397;8417;8434.915650843473;8440.28257598105;5553.278073850373;5739.971300143473;5840.269966254218;5869.50109240715;5914.172015917775;2159;2162.351294611626;2162.8810415427147;2174.5324755177553;2205.4155648753094;31019.84490077535;31091.844540777605;31266;31375.37249255002;31415.37169256602;7133.3936615388275;7268.418526517866;7289.70841166358;7316.524425912298;7322.231165727522;19744.11151498186;19843.30548430798;19872.70190947137;19944.102515386843;19970;8108.513489190667;8730.17063378978;8973.910260897308;8984.191422771983;8990.235829954396;7811.296983271419;7918.287354138155;7942.364610831235;8056.7180148694515;8056.751203563457;5594.049011668027;5819.912701309484;5828.12578113278;5946.494547963385;5961.076033214858;1680.3110724602855;1681.9992104697685;1706.1128213329;1718.613312004789;1722.896626202432;983.6508039645965;999.7500624843789;1011.206203130539;1011.8026984737905;1028.665683652816;8796.516191609522;8806.603702833389;8864.822703546022;8867.955660221658;9043.864342034874;722.3931897206267;736.1901907901266;751.5603372027425;764.1059959846992;769.0002996105042;5866.6480011199465;5917.2011428800015;5930.55520835939;5967.104934259809;6041.6375017499095;23510.7648923512;24420.877895610764;25090.749092509206;25126.623100653505;25127.62308565373;23332.416689582897;24382.634260486106;24384.878075609508;24426.38934026628;24721.5055698885;12590.622281331574;12713.809292860615;12884.677883052998;12905.35473226335;12932.676683083186;9254.259659227111;9355.578998944928;9418.576164072496;9512.048795120432;9523.380980236263;20964;21195.8940205298;21318;21700.023498942268;22094.33716988493;21767.455813604785;21968.89015554912;22013.339599812032;22040.44898877541;22049;12529.812052819032;12898.742025159256;12899.226046437243;12912.548060817826;12924.353782311035;15839.208039597743;15861;15905.761413579037;16030.038197708174;16087.436939707053;12781.233126012468;12903.803666328207;13011.609651710463;13311;13431.261280629658;12518.311492867979;12720.109592328818;12761.680957976125;12763.617091487271;12981.350932453151;10462.319949203278;10502.212334075128;10562.894371056498;10601.893981060091;10703.250772445854;7929.563873986986;8008.919910800817;8050;8053.557054362066;8128.87806682912;7984.680612775656;8057.5165490070785;8068.757937261891;8074.919250807417;8185.181481851767 22:26:28 INFO - PID 31403 | |4;prototype.html;558.4220331956426;560.5739637875215;583.3116922032002;586.1003359842642;591.6686655472936;6269.655168965705;6282.874342513147;6324.2727086385075;6388.904166437507;6391.296957334701;6126.5602583392865;6279.340669229722;6305.022721478167;6329.968350158254;6353.9334825711585;5072.315237442954;5135.409427915782;5159.587233021368;5166.225066240057;5180.197069454241;4919.950800491985;5037.0859073552;5060.493950604929;5066.822661206858;5088.397154896196;5001.8249361272365;5036.169032109698;5049.747512624373;5051.242313652945;5074.517920797518;4981.302617633534;5004.174311238641;5017.648764586462;5018.7992480300745;5049.141645920203;4487.685861989662;4523.389342438762;4539.477960034572;4559.475660299075;4597.54024597541;6176.320604733476;6250.43746062852;6273.341299163613;6283.434490895841;6316.178896743394;5906.468417842415;5921;5980.970095149496;6017.759289628453;6030.939690603125;6680.131582894242;6703.899441508382;6707.6981535831;6739.898901516482;6743.629100399476;4916.70499770015;5092.6180536459915;5107.821226257063;5148.609875333676;5171.327727395452;6874.484413668944;6892.241853396123;6943.79168624942;7055.929440705579;7122.893156602655;5727.513161381329;5730.255066841284;5753.165790960371;5774.249347584871;5796.449337312949;5796.594238403356;5812.186293918855;5836.591438599342;5842.620229685057;5865;6133.233345831771;6151.876962460815;6179.969100154561;6233.594816336924;6242.406971337807;6697.631630260383;6783.93216067833;6801.251862295144;6808.421284190899;6816.386525212655;1972.6745087060474;2002.859799813999;2014.5769388428594;2037.4498885301182;2041.7851378429812;1462.429652435551;1478.4160256698758;1494.4769330734157;1502.30142983511;1505.8795296376484;6185.226846644169;6203.689815509297;6204.193454850841;6264.812055638338;6270.2789179244055;6706.631135287508;6729.831754206086;6799.7280108796085;6807.931920680829;6829.965850170816;6139.526513636784;6146.508279337642;6187.143856842929;6201.751929922752;6237.5945563539135;3588.784672919633;3607.7113830893463;3617.6382361763613;3619.7285203609836;3636.381815091441;6986.7205311787975;6991.3358230969;7041.964790176016;7109.680064397115;7148.17795953472;6964.93035069653;6978.337057979484;6996.475264355194;7000.824979375557;7013.790655943463;7117.75087871922;7130.500864939404;7133.144022717203;7148.963400306822;7170.3905168060255;6802.693878775368;6834.589924604539;6861.931380686129;6870.27862074475;6882.483813713891;6805.9659701701175;6811.2167100784045;6826.010228516937;6841.863162736817;6868.278830722702;6903.6203008835;7068.540544864567;7117.288271172842;7118.964405178148;7124.287571242834;5709.25779648643;5744.885102297847;5750.993576124245;5752.942470575241;5773.769049237983;6487.448566871869;6795.572929684821;6819.352161544645;6884.65576721172;6891.4486841053595;6382.266039405342;6530.8367290818105;6601.3728695773825;6678.293547081416;6741.561798483083;6517.217933847968;6593.736100630219;6615.206175259;6615.5038372123245;6708.6981085850275;6451.2903580607035;6499.870002600016;6503.967480162758;6527.801377325171;6648.933510665025;6639.344262295121;6666.699998499982;6709.832254193684;6714.462842972453;6717.630530320879;6506.072267479559;6518.348165183634;6541.836454088686;6555.475561955128;6596.439302659328 22:26:28 INFO - PID 31403 | |5;yui.html;944.8110377924417;968.6464440479225;974.4056125763283;976.5800705696552;979.9951000244997;20966.790332096694;21494.032768525416;21628.35114946554;21630.24294149705;21655.242066527673;19170.233190672356;19209.32767353143;19566;19614.607707845833;19661.803381966176;1022.161827301613;1040.0743338428804;1040.791841631673;1041.3231399590252;1042.4318746283257;1026.743314171457;1067.386252904583;1068.845017472466;1070.9946450267737;1072.4262519552042;1116.7319843237606;1126.4761885723137;1133.982990255147;1134.784390965718;1134.801409753294;1127.689885281546;1128.2215271462665;1129.824877144044;1130.4743294368118;1132.6715252576744;776.7902666280129;785.8310463250428;792.2631952284373;793.3137835772073;823.625250511016;21464.141434342608;21601.67597486039;21648;21665.133394664193;21666.566668666695;22711.772882271132;22800.429989250235;23579.52840943189;23739.287821365386;23744;18685.813141868544;19105.42683719477;19244.80755192444;19248.518787030436;19362.225510979544;3977.840886364542;4004.0990777075217;4004.3993400989793;4006.6994975376965;4009.1781184857036;161394;164024;164837.17581412016;165805;166739;17719.29122835072;17819.821801781814;17860.374886879155;17909.10454477297;18374.540636484195;33246.68053111539;33924.83037584796;34019.48970765441;34698.13254668654;34903.82548087243;9281.628734850665;9339.579718912666;9404.764880878032;9405.482698451651;9408.43549387039;4634.073185362941;4739;4764.094821983812;4812.422509298906;4839.540243676845;6047.969760151171;6220.720067596969;6241.7191226394925;6262.2172228471445;6274;1070.0850772589479;1092.1754075672816;1102.9889701102886;1116.0513563471147;1119.5297974850582;738.5384134915678;747.614978286183;748.2517482517483;762.3634265388351;772.4901564967095;13355.198688078746;13356.198628082346;13536.932315338558;14352.06711563745;14447.133172009713;744.4677055905054;745.4744405194325;747.6934456872656;752.1275320628155;769.0886299734832;6294.150289710922;6308.337624549356;6329.113924050636;6335.873282534323;6347.460465860361;149614;149955;150698.24650876675;153497.23251383894;157087;104772.47613762035;106865;127804;128502;135273;103344.96655033504;111328.44335778269;111452;116044;118085.40957295158;13385.263810490127;14683.265836708335;14704.632384190481;14759.114453132846;14805;54706.726466367414;55200;55518.44481555133;55743;56943.14585281141;67598.68175041997;70510.64744676244;72298.09694542487;72569;73812.63093684497;18036.90981545084;18183.09084545794;18422.631547369245;18469.445916622524;18541.721874172166;22430.102795887982;22439.663405049265;22694.886525567268;22695.773042269368;22817.429564261027;20753.688694669894;20927.8953605231;21156.047977841343;21341.67987480158;21709;17915.910420447814;18029.368972085915;18225.362112326005;18269.634607308046;18295.908520457844;20482.79517204809;20697.379078627666;20998.58002839965;21016;21047.8947605261;14206.360713768112;14367.856321436651;14395.78406323885;14486.565403037926;14699.779503307673;13517.459301627825;13577.59267221985;13708.588742337746;13729.931350343584;13783.448662053406 22:26:28 INFO - PID 31403 | __end_tp_report 22:26:28 INFO - PID 31403 | __start_cc_report 22:26:28 INFO - PID 31403 | _x_x_mozilla_cycle_collect,285 22:26:28 INFO - PID 31403 | __end_cc_report 22:26:28 INFO - PID 31403 | __startTimestamp1506057988689__endTimestamp 22:26:28 INFO - PID 31403 | 22:26:28 INFO - PID 31403 | ------- Summary: start ------- 22:26:28 INFO - PID 31403 | Number of tests: 6 22:26:28 INFO - PID 31403 | 22:26:28 INFO - PID 31403 | [#0] dojo.html Cycles:165 Average:6238.78 Median:6466.13 stddev:733.96 (11.4%) stddev-sans-first:711.50 22:26:28 INFO - PID 31403 | Values: 3831.9 3868.7 3873.4 3878.6 3885.9 6160.2 6197.7 6249.6 6263.5 6350.8 6181.9 6183.9 6187.6 6200.2 6210.6 5493.4 5537.8 5554.2 5620.1 5695.2 5654.0 5675.0 5692.3 5699.6 5708.0 5445.2 5670.2 5729.2 5760.7 5772.9 5558.2 5558.3 5570.1 5652.3 5654.9 5576.8 5590.0 5664.9 5693.3 5696.1 6149.7 6184.1 6236.9 6281.6 6338.8 6656.6 6730.6 6754.0 6776.7 6779.7 6831.5 6879.4 6899.5 6902.8 6921.5 6302.2 6303.8 6339.6 6417.9 6429.4 6894.8 7015.6 7020.0 7021.5 7040.2 6643.0 6707.2 6774.8 6783.2 6800.4 6712.4 6731.0 6779.7 6798.9 6807.9 6824.1 6829.6 6831.2 6833.5 6875.2 6702.5 6765.2 6864.7 6869.5 6890.0 4904.2 4912.6 4964.2 5009.7 5012.9 4490.3 4515.1 4535.1 4588.9 4617.7 6125.2 6201.7 6214.1 6236.5 6279.6 6610.9 6674.2 6715.3 6778.9 6788.0 6152.2 6197.0 6212.0 6274.5 6331.2 5503.7 5519.9 5523.8 5558.1 5559.7 7027.3 7032.4 7107.0 7117.8 7119.1 6540.3 6692.3 6788.2 6789.0 6884.7 6952.4 6986.9 7001.1 7038.8 7079.8 6660.0 6739.0 6740.6 6749.7 6757.2 6543.7 6558.4 6631.6 6648.9 6740.5 6059.8 6398.6 6563.7 6586.4 6607.5 6451.6 6549.2 6553.3 6585.2 6608.0 6496.1 6497.8 6533.5 6577.4 6578.2 6591.9 6613.5 6671.7 6692.9 6695.0 6373.0 6414.3 6454.6 6465.8 6466.1 22:26:28 INFO - PID 31403 | 22:26:28 INFO - PID 31403 | [#1] ext.html Cycles:180 Average:42953.94 Median:3787.10 stddev:63651.01 (1680.7%) stddev-sans-first:63751.42 22:26:28 INFO - PID 31403 | Values: 945.3 967.0 998.9 1011.5 1017.6 44037.0 44634.0 44757.3 44823.3 44913.0 43229.4 43330.4 43560.3 43768.3 43804.0 7737.8 7738.3 7741.3 7745.8 7761.8 7640.5 7755.0 7759.7 7827.0 7871.8 8063.9 8070.0 8089.3 8109.5 8247.0 7984.0 8028.3 8079.3 8092.5 8136.2 8231.4 8435.1 8448.9 8461.3 8467.7 40634.0 40804.2 40923.6 41068.4 41070.4 3711.2 3777.7 3780.8 3783.8 3790.4 32686.0 32911.3 33874.8 33913.8 33957.7 5433.2 5453.4 5465.7 5468.5 5473.8 208007.0 210907.0 223516.0 224949.0 225615.0 54090.5 54749.5 54759.0 54760.7 54856.0 56300.4 56494.2 56607.7 56630.0 56678.7 16385.0 16454.9 16456.6 16503.2 16509.7 13339.5 13354.3 13354.3 13400.3 13403.3 14729.6 14736.6 14784.9 14821.8 14973.6 5792.8 5920.3 5966.5 5971.2 6009.8 1081.9 1088.6 1103.4 1105.9 1121.8 25397.7 25554.4 25622.4 25685.4 25789.4 1197.4 1204.0 1207.6 1215.7 1222.4 13446.1 13563.0 13621.5 13819.7 13946.2 274939.0 275177.0 277364.0 277404.0 278221.0 58042.4 58050.7 58303.7 58408.4 58712.4 1497.9 1551.3 1556.3 1558.0 1578.3 24838.0 24910.5 24927.8 25213.9 25271.6 41653.6 41678.8 41683.2 41706.8 41726.0 232519.0 235516.0 236580.0 236782.0 237674.0 37783.4 38401.0 40473.6 40488.6 40874.4 49737.8 51549.5 51691.7 51814.7 52333.7 36781.0 37055.3 37593.8 37709.0 37768.0 36442.0 36637.6 36818.0 37106.3 37963.1 43357.6 43684.3 43958.8 44001.3 44002.8 35476.0 35484.5 35579.5 36351.6 36683.4 27049.7 27275.0 27480.6 27572.2 27596.9 22:26:28 INFO - PID 31403 | 22:26:28 INFO - PID 31403 | [#2] jquery.html Cycles:180 Average:39079.30 Median:12796.04 stddev:98120.41 (766.8%) stddev-sans-first:98353.12 22:26:28 INFO - PID 31403 | Values: 597.4 600.2 614.7 632.1 644.2 23288.7 23332.0 24171.3 24187.8 24262.9 25052.4 26072.0 26222.3 26222.7 26264.6 6757.3 6945.1 6954.3 6959.6 6959.9 6588.1 6844.5 6862.6 6886.7 6940.2 6839.1 6841.3 6847.0 6856.2 6856.2 6820.1 6826.8 6827.2 6831.7 6844.6 6197.4 6394.1 6404.4 6424.2 6431.3 29407.0 29686.4 29753.0 29764.9 29919.7 2262.5 2301.9 2302.8 2302.9 2303.4 19501.3 19704.2 20085.8 20387.3 20651.6 2493.1 2497.2 2551.2 2562.8 2590.3 135161.0 140767.3 148844.0 153096.0 154541.2 15111.8 16070.8 16114.4 16213.7 16422.7 25893.1 25947.5 25962.2 26019.0 26040.7 10450.9 10465.7 10556.2 10564.4 10725.8 9556.3 9614.0 9711.0 9722.4 9812.8 9615.7 9627.8 9806.4 9834.5 10008.8 2734.8 2798.6 2907.7 2909.6 2932.9 1026.4 1041.9 1046.7 1049.5 1049.6 20531.2 20630.9 20721.0 21100.3 21374.5 1096.4 1100.8 1112.8 1118.4 1131.1 11226.5 11678.0 11748.3 11771.5 11794.5 568774.0 583330.0 588675.0 590839.0 596841.0 128839.4 129622.0 130519.0 133506.3 134101.0 1114.3 1138.6 1211.6 1251.4 1266.6 21446.4 21879.6 22117.8 22142.9 22168.0 58390.0 59910.4 60666.4 60906.0 62572.7 80128.2 80443.0 80533.6 80885.0 82645.6 15523.7 15782.7 15892.4 15909.4 15937.6 23845.3 23980.0 23981.8 24052.2 24139.4 19258.8 19468.4 19519.2 19610.9 19814.2 15810.1 16553.7 16635.9 17560.6 18076.7 19280.0 19694.0 19800.7 20179.6 20415.9 15393.4 15418.3 15593.8 15620.7 15733.3 17632.7 17774.8 19175.7 19565.8 19730.6 22:26:28 INFO - PID 31403 | 22:26:28 INFO - PID 31403 | [#3] mootools.html Cycles:180 Average:10517.97 Median:2704.55 stddev:7778.32 (287.6%) stddev-sans-first:7770.12 22:26:28 INFO - PID 31403 | Values: 1422.3 1470.5 1483.0 1492.8 1503.4 16995.2 17556.1 17565.5 17566.7 17646.0 17216.0 17241.7 17327.5 17491.1 17501.6 2797.6 2820.0 2874.6 2875.2 2875.4 2741.5 2768.5 2789.3 2791.4 2800.3 2702.6 2790.1 2792.5 2806.9 2809.7 2829.5 2836.4 2871.3 2873.3 2876.9 2665.0 2692.4 2706.5 2708.5 2713.2 16602.7 17090.4 17131.0 17155.5 17201.4 8135.0 8397.0 8417.0 8434.9 8440.3 5553.3 5740.0 5840.3 5869.5 5914.2 2159.0 2162.4 2162.9 2174.5 2205.4 31019.8 31091.8 31266.0 31375.4 31415.4 7133.4 7268.4 7289.7 7316.5 7322.2 19744.1 19843.3 19872.7 19944.1 19970.0 8108.5 8730.2 8973.9 8984.2 8990.2 7811.3 7918.3 7942.4 8056.7 8056.8 5594.0 5819.9 5828.1 5946.5 5961.1 1680.3 1682.0 1706.1 1718.6 1722.9 983.7 999.8 1011.2 1011.8 1028.7 8796.5 8806.6 8864.8 8868.0 9043.9 722.4 736.2 751.6 764.1 769.0 5866.6 5917.2 5930.6 5967.1 6041.6 23510.8 24420.9 25090.7 25126.6 25127.6 23332.4 24382.6 24384.9 24426.4 24721.5 12590.6 12713.8 12884.7 12905.4 12932.7 9254.3 9355.6 9418.6 9512.0 9523.4 20964.0 21195.9 21318.0 21700.0 22094.3 21767.5 21968.9 22013.3 22040.4 22049.0 12529.8 12898.7 12899.2 12912.5 12924.4 15839.2 15861.0 15905.8 16030.0 16087.4 12781.2 12903.8 13011.6 13311.0 13431.3 12518.3 12720.1 12761.7 12763.6 12981.4 10462.3 10502.2 10562.9 10601.9 10703.3 7929.6 8008.9 8050.0 8053.6 8128.9 7984.7 8057.5 8068.8 8074.9 8185.2 22:26:28 INFO - PID 31403 | 22:26:28 INFO - PID 31403 | [#4] prototype.html Cycles:180 Average:5756.75 Median:6281.11 stddev:1562.21 (24.9%) stddev-sans-first:1517.08 22:26:28 INFO - PID 31403 | Values: 558.4 560.6 583.3 586.1 591.7 6269.7 6282.9 6324.3 6388.9 6391.3 6126.6 6279.3 6305.0 6330.0 6353.9 5072.3 5135.4 5159.6 5166.2 5180.2 4920.0 5037.1 5060.5 5066.8 5088.4 5001.8 5036.2 5049.7 5051.2 5074.5 4981.3 5004.2 5017.6 5018.8 5049.1 4487.7 4523.4 4539.5 4559.5 4597.5 6176.3 6250.4 6273.3 6283.4 6316.2 5906.5 5921.0 5981.0 6017.8 6030.9 6680.1 6703.9 6707.7 6739.9 6743.6 4916.7 5092.6 5107.8 5148.6 5171.3 6874.5 6892.2 6943.8 7055.9 7122.9 5727.5 5730.3 5753.2 5774.2 5796.4 5796.6 5812.2 5836.6 5842.6 5865.0 6133.2 6151.9 6180.0 6233.6 6242.4 6697.6 6783.9 6801.3 6808.4 6816.4 1972.7 2002.9 2014.6 2037.4 2041.8 1462.4 1478.4 1494.5 1502.3 1505.9 6185.2 6203.7 6204.2 6264.8 6270.3 6706.6 6729.8 6799.7 6807.9 6830.0 6139.5 6146.5 6187.1 6201.8 6237.6 3588.8 3607.7 3617.6 3619.7 3636.4 6986.7 6991.3 7042.0 7109.7 7148.2 6964.9 6978.3 6996.5 7000.8 7013.8 7117.8 7130.5 7133.1 7149.0 7170.4 6802.7 6834.6 6861.9 6870.3 6882.5 6806.0 6811.2 6826.0 6841.9 6868.3 6903.6 7068.5 7117.3 7119.0 7124.3 5709.3 5744.9 5751.0 5752.9 5773.8 6487.4 6795.6 6819.4 6884.7 6891.4 6382.3 6530.8 6601.4 6678.3 6741.6 6517.2 6593.7 6615.2 6615.5 6708.7 6451.3 6499.9 6504.0 6527.8 6648.9 6639.3 6666.7 6709.8 6714.5 6717.6 6506.1 6518.3 6541.8 6555.5 6596.4 22:26:28 INFO - PID 31403 | 22:26:28 INFO - PID 31403 | [#5] yui.html Cycles:180 Average:28620.81 Median:20590.09 stddev:41953.92 (203.8%) stddev-sans-first:42020.15 22:26:28 INFO - PID 31403 | Values: 944.8 968.6 974.4 976.6 980.0 20966.8 21494.0 21628.4 21630.2 21655.2 19170.2 19209.3 19566.0 19614.6 19661.8 1022.2 1040.1 1040.8 1041.3 1042.4 1026.7 1067.4 1068.8 1071.0 1072.4 1116.7 1126.5 1134.0 1134.8 1134.8 1127.7 1128.2 1129.8 1130.5 1132.7 776.8 785.8 792.3 793.3 823.6 21464.1 21601.7 21648.0 21665.1 21666.6 22711.8 22800.4 23579.5 23739.3 23744.0 18685.8 19105.4 19244.8 19248.5 19362.2 3977.8 4004.1 4004.4 4006.7 4009.2 161394.0 164024.0 164837.2 165805.0 166739.0 17719.3 17819.8 17860.4 17909.1 18374.5 33246.7 33924.8 34019.5 34698.1 34903.8 9281.6 9339.6 9404.8 9405.5 9408.4 4634.1 4739.0 4764.1 4812.4 4839.5 6048.0 6220.7 6241.7 6262.2 6274.0 1070.1 1092.2 1103.0 1116.1 1119.5 738.5 747.6 748.3 762.4 772.5 13355.2 13356.2 13536.9 14352.1 14447.1 744.5 745.5 747.7 752.1 769.1 6294.2 6308.3 6329.1 6335.9 6347.5 149614.0 149955.0 150698.2 153497.2 157087.0 104772.5 106865.0 127804.0 128502.0 135273.0 103345.0 111328.4 111452.0 116044.0 118085.4 13385.3 14683.3 14704.6 14759.1 14805.0 54706.7 55200.0 55518.4 55743.0 56943.1 67598.7 70510.6 72298.1 72569.0 73812.6 18036.9 18183.1 18422.6 18469.4 18541.7 22430.1 22439.7 22694.9 22695.8 22817.4 20753.7 20927.9 21156.0 21341.7 21709.0 17915.9 18029.4 18225.4 18269.6 18295.9 20482.8 20697.4 20998.6 21016.0 21047.9 14206.4 14367.9 14395.8 14486.6 14699.8 13517.5 13577.6 13708.6 13729.9 13783.4 22:26:28 INFO - PID 31403 | -------- Summary: end -------- 22:26:28 INFO - PID 31403 | 22:26:29 INFO - TEST-INFO | 31403: exit 0 22:26:29 INFO - TEST-OK | dromaeo_css | took 1108495ms 22:26:29 INFO - TEST-START | kraken 22:26:29 INFO - Initialising browser for kraken test... 22:26:29 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:44831/getInfo.html -profile /tmp/tmpCZ0qBV/profile 22:26:29 INFO - TEST-INFO | started process 31780 (/builds/slave/test/build/application/firefox/firefox http://localhost:44831/getInfo.html) 22:26:34 INFO - TEST-INFO | 31780: exit 0 22:26:34 INFO - Browser initialized. 22:26:34 INFO - Running cycle 1/1 for kraken test... 22:26:34 INFO - Using env: {'DISPLAY': ':0', 22:26:34 INFO - 'HOME': '/home/cltbld', 22:26:34 INFO - 'JSGC_DISABLE_POISONING': '1', 22:26:34 INFO - 'LANG': 'en_US.UTF-8', 22:26:34 INFO - 'LANGUAGE': 'en_US:en', 22:26:34 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 22:26:34 INFO - 'LOGNAME': 'cltbld', 22:26:34 INFO - 'MAIL': '/var/mail/cltbld', 22:26:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:26:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:26:34 INFO - 'MOZ_CRASHREPORTER': '1', 22:26:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:26:34 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 22:26:34 INFO - 'MOZ_NO_REMOTE': '1', 22:26:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:26:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:26:34 INFO - 'NO_EM_RESTART': '1', 22:26:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:26:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:26:34 INFO - 'PWD': '/builds/slave/test', 22:26:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:26:34 INFO - 'RUST_BACKTRACE': 'full', 22:26:34 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 22:26:34 INFO - 'SHELL': '/bin/bash', 22:26:34 INFO - 'SHLVL': '1', 22:26:34 INFO - 'TERM': 'linux', 22:26:34 INFO - 'TMOUT': '86400', 22:26:34 INFO - 'USER': 'cltbld', 22:26:34 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1506052468.613065-334524023', 22:26:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:26:34 INFO - '_': '/tools/buildbot/bin/python'} 22:26:34 INFO - TEST-INFO | started process 32018 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCZ0qBV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 22:26:35 INFO - PID 32018 | 22:26:35 INFO - PID 32018 | (/builds/slave/test/build/application/firefox/firefox:32068): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 22:26:35 INFO - PID 32018 | 22:26:45 INFO - PID 32018 | RSS: Main: 148185088 22:26:45 INFO - PID 32018 | 22:26:48 INFO - PID 32018 | Cycle 1(1): loaded http://localhost:44831/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44831/tests/kraken/driver.html?testName=audio-beat-detection) 22:26:49 INFO - PID 32018 | RSS: Main: 154034176 22:26:49 INFO - PID 32018 | 22:26:51 INFO - PID 32018 | Cycle 1(1): loaded http://localhost:44831/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44831/tests/kraken/driver.html?testName=audio-dft) 22:26:52 INFO - PID 32018 | RSS: Main: 154472448 22:26:52 INFO - PID 32018 | 22:26:55 INFO - PID 32018 | Cycle 1(1): loaded http://localhost:44831/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44831/tests/kraken/driver.html?testName=audio-fft) 22:26:56 INFO - PID 32018 | RSS: Main: 151056384 22:26:56 INFO - PID 32018 | 22:26:58 INFO - PID 32018 | Cycle 1(1): loaded http://localhost:44831/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44831/tests/kraken/driver.html?testName=audio-oscillator) 22:26:58 INFO - PID 32018 | RSS: Main: 151842816 22:26:58 INFO - PID 32018 | 22:27:00 INFO - PID 32018 | Cycle 1(1): loaded http://localhost:44831/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44831/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:27:01 INFO - PID 32018 | RSS: Main: 151736320 22:27:01 INFO - PID 32018 | 22:27:04 INFO - PID 32018 | Cycle 1(1): loaded http://localhost:44831/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44831/tests/kraken/driver.html?testName=imaging-darkroom) 22:27:05 INFO - PID 32018 | RSS: Main: 152494080 22:27:05 INFO - PID 32018 | 22:27:08 INFO - PID 32018 | Cycle 1(1): loaded http://localhost:44831/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44831/tests/kraken/driver.html?testName=imaging-desaturate) 22:27:09 INFO - PID 32018 | RSS: Main: 153894912 22:27:09 INFO - PID 32018 | 22:27:12 INFO - PID 32018 | Cycle 1(1): loaded http://localhost:44831/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44831/tests/kraken/driver.html?testName=json-parse-financial) 22:27:12 INFO - PID 32018 | RSS: Main: 154537984 22:27:12 INFO - PID 32018 | 22:27:14 INFO - PID 32018 | Cycle 1(1): loaded http://localhost:44831/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44831/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:27:14 INFO - PID 32018 | RSS: Main: 153829376 22:27:14 INFO - PID 32018 | 22:27:16 INFO - PID 32018 | Cycle 1(1): loaded http://localhost:44831/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44831/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:27:17 INFO - PID 32018 | RSS: Main: 154599424 22:27:17 INFO - PID 32018 | 22:27:18 INFO - PID 32018 | Cycle 1(1): loaded http://localhost:44831/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44831/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:27:19 INFO - PID 32018 | RSS: Main: 150994944 22:27:19 INFO - PID 32018 | 22:27:22 INFO - PID 32018 | Cycle 1(1): loaded http://localhost:44831/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44831/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:27:22 INFO - PID 32018 | RSS: Main: 152170496 22:27:22 INFO - PID 32018 | 22:27:25 INFO - PID 32018 | Cycle 1(1): loaded http://localhost:44831/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44831/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:27:26 INFO - PID 32018 | RSS: Main: 151384064 22:27:26 INFO - PID 32018 | 22:27:27 INFO - PID 32018 | Cycle 1(1): loaded http://localhost:44831/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44831/tests/kraken/driver.html?testName=ai-astar) 22:27:28 INFO - PID 32018 | RSS: Main: 153432064 22:27:28 INFO - PID 32018 | 22:27:28 INFO - PID 32018 | __start_tp_report 22:27:28 INFO - PID 32018 | _x_x_mozilla_page_load 22:27:28 INFO - PID 32018 | _x_x_mozilla_page_load_details 22:27:28 INFO - PID 32018 | |i|pagename|runs| 22:27:28 INFO - PID 32018 | |0;ai-astar;105;106;105;103;105;105;106;106;104;106 22:27:28 INFO - PID 32018 | |1;audio-beat-detection;119;114;114;115;112;117;128;113;119;116 22:27:28 INFO - PID 32018 | |2;audio-dft;164;164;164;164;162;164;163;163;163;170 22:27:28 INFO - PID 32018 | |3;audio-fft;74;74;72;73;73;83;73;73;72;73 22:27:28 INFO - PID 32018 | |4;audio-oscillator;79;79;79;78;78;78;78;78;86;79 22:27:28 INFO - PID 32018 | |5;imaging-gaussian-blur;116;114;115;113;110;113;113;114;114;115 22:27:28 INFO - PID 32018 | |6;imaging-darkroom;150;150;150;150;149;149;151;150;149;153 22:27:28 INFO - PID 32018 | |7;imaging-desaturate;94;99;94;95;94;97;95;96;94;96 22:27:28 INFO - PID 32018 | |8;json-parse-financial;46;44;43;43;44;45;44;44;44;44 22:27:28 INFO - PID 32018 | |9;json-stringify-tinderbox;38;36;36;36;40;36;36;36;37;36 22:27:28 INFO - PID 32018 | |10;stanford-crypto-aes;74;74;70;72;71;70;73;72;72;71 22:27:28 INFO - PID 32018 | |11;stanford-crypto-ccm;106;109;118;109;106;107;110;104;112;105 22:27:28 INFO - PID 32018 | |12;stanford-crypto-pbkdf2;150;161;155;149;151;147;157;148;156;147 22:27:28 INFO - PID 32018 | |13;stanford-crypto-sha256-iterative;63;65;62;61;62;59;58;65;77;59 22:27:28 INFO - PID 32018 | __end_tp_report 22:27:28 INFO - PID 32018 | __start_cc_report 22:27:28 INFO - PID 32018 | _x_x_mozilla_cycle_collect,735 22:27:28 INFO - PID 32018 | __end_cc_report 22:27:28 INFO - PID 32018 | __startTimestamp1506058048001__endTimestamp 22:27:28 INFO - PID 32018 | 22:27:28 INFO - PID 32018 | ------- Summary: start ------- 22:27:28 INFO - PID 32018 | Number of tests: 14 22:27:28 INFO - PID 32018 | 22:27:28 INFO - PID 32018 | [#0] ai-astar Cycles:10 Average:105.10 Median:105.50 stddev:0.99 (0.9%) stddev-sans-first:1.05 22:27:28 INFO - PID 32018 | Values: 105.0 106.0 105.0 103.0 105.0 105.0 106.0 106.0 104.0 106.0 22:27:28 INFO - PID 32018 | 22:27:28 INFO - PID 32018 | [#1] audio-beat-detection Cycles:10 Average:116.70 Median:116.50 stddev:4.62 (4.0%) stddev-sans-first:4.82 22:27:28 INFO - PID 32018 | Values: 119.0 114.0 114.0 115.0 112.0 117.0 128.0 113.0 119.0 116.0 22:27:28 INFO - PID 32018 | 22:27:28 INFO - PID 32018 | [#2] audio-dft Cycles:10 Average:164.10 Median:164.00 stddev:2.18 (1.3%) stddev-sans-first:2.32 22:27:28 INFO - PID 32018 | Values: 164.0 164.0 164.0 164.0 162.0 164.0 163.0 163.0 163.0 170.0 22:27:28 INFO - PID 32018 | 22:27:28 INFO - PID 32018 | [#3] audio-fft Cycles:10 Average:74.00 Median:73.00 stddev:3.23 (4.4%) stddev-sans-first:3.43 22:27:28 INFO - PID 32018 | Values: 74.0 74.0 72.0 73.0 73.0 83.0 73.0 73.0 72.0 73.0 22:27:28 INFO - PID 32018 | 22:27:28 INFO - PID 32018 | [#4] audio-oscillator Cycles:10 Average:79.20 Median:79.00 stddev:2.44 (3.1%) stddev-sans-first:2.59 22:27:28 INFO - PID 32018 | Values: 79.0 79.0 79.0 78.0 78.0 78.0 78.0 78.0 86.0 79.0 22:27:28 INFO - PID 32018 | 22:27:28 INFO - PID 32018 | [#5] imaging-gaussian-blur Cycles:10 Average:113.70 Median:114.00 stddev:1.64 (1.4%) stddev-sans-first:1.51 22:27:28 INFO - PID 32018 | Values: 116.0 114.0 115.0 113.0 110.0 113.0 113.0 114.0 114.0 115.0 22:27:28 INFO - PID 32018 | 22:27:28 INFO - PID 32018 | [#6] imaging-darkroom Cycles:10 Average:150.10 Median:150.00 stddev:1.20 (0.8%) stddev-sans-first:1.27 22:27:28 INFO - PID 32018 | Values: 150.0 150.0 150.0 150.0 149.0 149.0 151.0 150.0 149.0 153.0 22:27:28 INFO - PID 32018 | 22:27:28 INFO - PID 32018 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.50 stddev:1.65 (1.7%) stddev-sans-first:1.67 22:27:28 INFO - PID 32018 | Values: 94.0 99.0 94.0 95.0 94.0 97.0 95.0 96.0 94.0 96.0 22:27:28 INFO - PID 32018 | 22:27:28 INFO - PID 32018 | [#8] json-parse-financial Cycles:10 Average:44.10 Median:44.00 stddev:0.88 (2.0%) stddev-sans-first:0.60 22:27:28 INFO - PID 32018 | Values: 46.0 44.0 43.0 43.0 44.0 45.0 44.0 44.0 44.0 44.0 22:27:28 INFO - PID 32018 | 22:27:28 INFO - PID 32018 | [#9] json-stringify-tinderbox Cycles:10 Average:36.70 Median:36.00 stddev:1.34 (3.7%) stddev-sans-first:1.33 22:27:28 INFO - PID 32018 | Values: 38.0 36.0 36.0 36.0 40.0 36.0 36.0 36.0 37.0 36.0 22:27:28 INFO - PID 32018 | 22:27:28 INFO - PID 32018 | [#10] stanford-crypto-aes Cycles:10 Average:71.90 Median:72.00 stddev:1.45 (2.0%) stddev-sans-first:1.32 22:27:28 INFO - PID 32018 | Values: 74.0 74.0 70.0 72.0 71.0 70.0 73.0 72.0 72.0 71.0 22:27:28 INFO - PID 32018 | 22:27:28 INFO - PID 32018 | [#11] stanford-crypto-ccm Cycles:10 Average:108.60 Median:109.00 stddev:4.12 (3.8%) stddev-sans-first:4.26 22:27:28 INFO - PID 32018 | Values: 106.0 109.0 118.0 109.0 106.0 107.0 110.0 104.0 112.0 105.0 22:27:28 INFO - PID 32018 | 22:27:28 INFO - PID 32018 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.10 Median:153.00 stddev:4.84 (3.2%) stddev-sans-first:5.07 22:27:28 INFO - PID 32018 | Values: 150.0 161.0 155.0 149.0 151.0 147.0 157.0 148.0 156.0 147.0 22:27:28 INFO - PID 32018 | 22:27:28 INFO - PID 32018 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.10 Median:62.50 stddev:5.45 (8.7%) stddev-sans-first:5.78 22:27:28 INFO - PID 32018 | Values: 63.0 65.0 62.0 61.0 62.0 59.0 58.0 65.0 77.0 59.0 22:27:28 INFO - PID 32018 | -------- Summary: end -------- 22:27:28 INFO - PID 32018 | 22:27:28 INFO - PID 32018 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 22:27:28 INFO - TEST-INFO | 32018: exit 0 22:27:28 INFO - TEST-OK | kraken | took 59242ms 22:27:28 INFO - SUITE-END | took 1167s 22:27:29 INFO - Completed test suite (00:19:28) 22:27:29 INFO - javascript benchmark 22:27:29 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9163.952166085688, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3831.8850434486967, 3868.709846761492, 3873.3608954522506, 3878.6315300046494, 3885.863994760184, 6160.199174107371, 6197.721102550385, 6249.625022498648, 6263.467605253553, 6350.777722779703, 6181.907271390922, 6183.876322473559, 6187.5978061426085, 6200.1629779979685, 6210.565260431772, 5493.41695910798, 5537.8061767838135, 5554.1946417769395, 5620.1288800235825, 5695.231143795576, 5654.038813401731, 5675.035244008508, 5692.260006199209, 5699.601027928046, 5708.029634962046, 5445.210444485528, 5670.206171136044, 5729.226554415142, 5760.654360738348, 5772.913406298909, 5558.166275058733, 5558.277423934903, 5570.108782594806, 5652.321721393418, 5654.9151762723595, 5576.804811831607, 5589.993801115797, 5664.858378540528, 5693.345265294462, 5696.145578163268, 6149.723262453201, 6184.07238914162, 6236.937630623681, 6281.623102613812, 6338.841528961812, 6656.567323123982, 6730.562513436611, 6754.0, 6776.661166941633, 6779.661016949182, 6831.487638427137, 6879.415249703781, 6899.517033807636, 6902.792916212522, 6921.48088893335, 6302.243730752339, 6303.842403939847, 6339.619622822645, 6417.871642567123, 6429.389208025277, 6894.793156205322, 7015.649217539205, 7020.017197592341, 7021.473389495808, 7040.155181378165, 6642.966785166043, 6707.228668703064, 6774.830629234309, 6783.152105986752, 6800.387965083068, 6712.42944349726, 6731.024001519851, 6779.694913728894, 6798.864022719518, 6807.897881531781, 6824.146981627297, 6829.6243706596615, 6831.248562658104, 6833.453323734089, 6875.209350924608, 6702.497312701566, 6765.154355705537, 6864.656767161622, 6869.519133660596, 6890.0, 4904.239842824297, 4912.612657471522, 4964.180910149785, 5009.699418034929, 5012.899742005139, 4490.348899409632, 4515.0518391137575, 4535.070310586322, 4588.908221835612, 4617.699849509771, 6125.231619060963, 6201.6899155042065, 6214.098955651405, 6236.469900058455, 6279.623222606659, 6610.933890661032, 6674.23246326669, 6715.294894036154, 6778.864422711518, 6788.0, 6152.16945712332, 6196.969015154986, 6211.9689401552705, 6274.529410294246, 6331.2085989251345, 5503.669779813224, 5519.862003449946, 5523.8066667666435, 5558.110702287615, 5559.722013899289, 7027.332403421667, 7032.3670869620955, 7107.040549525851, 7117.786466406016, 7119.074520312326, 6540.313267106885, 6692.330766923269, 6788.2193547742645, 6789.0, 6884.65576721162, 6952.409045231212, 6986.860262794818, 7001.054857594159, 7038.753643622448, 7079.752208672878, 6659.966700166468, 6738.966305168443, 6740.561863478858, 6749.696263668048, 6757.155355580552, 6543.70553325111, 6558.409743123142, 6631.602103873783, 6648.90026649591, 6740.494462915399, 6059.787907423219, 6398.648074355955, 6563.704633291418, 6586.37429444202, 6607.504437167136, 6451.645159516084, 6549.214094308523, 6553.279139294769, 6585.209774827052, 6608.0, 6496.058071579689, 6497.772577959749, 6533.542652014314, 6577.440917522064, 6578.243501997332, 6591.901121483085, 6613.503987200883, 6671.666416679243, 6692.866142677217, 6694.966525167343, 6373.0, 6414.262359828493, 6454.644994525346, 6465.838354041187, 6466.127072845105], "value": 6189.653976468411, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [945.3288165402564, 966.990330096699, 998.9350692205008, 1011.5144730529346, 1017.6489111256618, 44037.0, 44634.0, 44757.328640070424, 44823.32765008527, 44913.0, 43229.35155972663, 43330.35004474936, 43560.3465948011, 43768.343474847825, 43804.0, 7737.845243095135, 7738.264864837832, 7741.303282704555, 7745.806354841117, 7761.805954851117, 7640.503367281137, 7755.030621172354, 7759.689612415496, 7826.960865195666, 7871.763847084597, 8063.919360806375, 8070.031596208463, 8089.312408445289, 8109.472884262504, 8246.958765206165, 7984.0, 8028.277455029046, 8079.27286544208, 8092.473989190684, 8136.186381361876, 8231.42380033398, 8435.114312997108, 8448.915510844874, 8461.323094152453, 8467.66129354825, 40634.0, 40804.183916321505, 40923.590764092274, 41068.38397424041, 41070.38394424086, 3711.2391959648207, 3777.7355585109053, 3780.8109594520165, 3783.8486460541544, 3790.3556395412847, 32686.0, 32911.34177316464, 33874.83062584696, 33913.830430847935, 33957.660423395944, 5433.157860531584, 5453.4273901240595, 5465.726713664301, 5468.535174510172, 5473.781048758045, 208007.0, 210907.0, 223516.0, 224949.0, 225615.0, 54090.45909540933, 54749.45250547523, 54759.0, 54760.72619636876, 54856.0, 56300.43699563034, 56494.152587711214, 56607.716961415754, 56630.0, 56678.716606417525, 16385.0, 16454.917725411295, 16456.588585285223, 16503.17484125789, 16509.66980660404, 13339.533116340881, 13354.265515396746, 13354.332283385791, 13400.329983500786, 13403.262820544964, 14729.55811325662, 14736.55790326292, 14784.852151478348, 14821.77767333491, 14973.625659358604, 5792.82621521355, 5920.259967504062, 5966.463018328372, 5971.223740913653, 6009.819705408845, 1081.9296745711504, 1088.580896354909, 1103.4151899493281, 1105.850710154135, 1121.7980763462656, 25397.746022539537, 25554.361140971254, 25622.359441013752, 25685.357866053124, 25789.3552661185, 1197.4252358867793, 1203.9759204815855, 1207.643745095199, 1215.7021529725098, 1222.4254600337827, 13446.114913670197, 13563.0, 13621.523246686318, 13819.723605527834, 13946.232957187249, 274939.0, 275177.0, 277364.0, 277404.0, 278221.0, 58042.419575804546, 58050.709746451, 58303.708481457325, 58408.41591584115, 58712.41287587155, 1497.9176145312113, 1551.3174203350716, 1556.2685537797217, 1557.9922100389651, 1578.3213218316005, 24838.0, 24910.526841947707, 24927.750722493267, 25213.87393063023, 25271.620925685762, 41653.583464166186, 41678.791606042985, 41683.24850763446, 41706.79146604247, 41726.0, 232519.0, 235516.0, 236580.0, 236782.0, 237674.0, 37783.43324850075, 38401.0, 40473.595264046984, 40488.59511404848, 40874.38688419617, 49737.75131124321, 51549.48450515597, 51691.74154129205, 51814.740926295126, 52333.7383313081, 36781.0, 37055.258894822495, 37593.81203093967, 37709.0, 37768.0, 36442.0, 36637.633623664486, 36818.0, 37106.25787484181, 37963.050923727125, 43357.56642433661, 43684.344734828366, 43958.78020609877, 44001.33997989968, 44002.779986101144, 35476.0, 35484.46773298454, 35579.46630800592, 36351.63648363482, 36683.44974825326, 27049.729502704722, 27275.0, 27480.58779118275, 27572.172834814988, 27596.862015689792], "value": 18850.696513619198, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [597.4264705882352, 600.1537831657358, 614.6527212125148, 632.1466020871823, 644.2365796531111, 23288.650670239917, 23332.0, 24171.274861754177, 24187.75812241877, 24262.87868560657, 25052.373690657743, 26072.0, 26222.344441388926, 26222.73777262227, 26264.606030909552, 6757.290484499131, 6945.062416573774, 6954.304569543056, 6959.582425054487, 6959.860802783941, 6588.110605068327, 6844.520883538129, 6862.622555759432, 6886.69009894556, 6940.236573976858, 6839.110915580967, 6841.281665425134, 6847.0, 6856.177258728934, 6856.17725872896, 6820.11338525991, 6826.761063362758, 6827.180738311409, 6831.658417079127, 6844.562641845218, 6197.380261973766, 6394.072859435356, 6404.423601875854, 6424.229092508882, 6431.292557818636, 29407.0, 29686.406271874443, 29753.0, 29764.85117574398, 29919.70080299191, 2262.5474905019064, 2301.8849057547054, 2302.7582103879017, 2302.8848557572223, 2303.424143964009, 19501.317453889045, 19704.21183152672, 20085.79914200854, 20387.286444974354, 20651.5869682607, 2493.127405408111, 2497.2133777859917, 2551.2091251712027, 2562.8334158279654, 2590.2617753940226, 135161.0, 140767.29616351955, 148844.0, 153096.0, 154541.22729386392, 15111.848881511263, 16070.839291607168, 16114.435994740363, 16213.675726485404, 16422.6861851054, 25893.09374171895, 25947.481050379265, 25962.22113336603, 26019.0, 26040.73959260383, 10450.947745261225, 10465.738356540995, 10556.155507559377, 10564.418956957288, 10725.83911241332, 9556.283278754121, 9614.03859614047, 9710.951445242728, 9722.368046077123, 9812.754681132887, 9615.711528654152, 9627.759306017406, 9806.411615303105, 9834.459104749307, 10008.849867251996, 2734.8222365546176, 2798.566222235557, 2907.694692057346, 2909.6072030276064, 2932.8533573321247, 1026.414943482208, 1041.8801837788599, 1046.6859942017363, 1049.5014867937668, 1049.637874933162, 20531.178752850017, 20630.896845515676, 20720.95033908553, 21100.261490847748, 21374.465638359165, 1096.3641088168843, 1100.818364969771, 1112.7551938573502, 1118.3792994887915, 1131.140333346646, 11226.494807733672, 11678.0, 11748.31182323089, 11771.529138834521, 11794.52821887132, 568774.0, 583330.0, 588675.0, 590839.0, 596841.0, 128839.35580322226, 129622.0, 130519.0, 133506.33246833703, 134101.0, 1114.3035602748282, 1138.612871623652, 1211.5759484180467, 1251.3993283224104, 1266.5503746170161, 21446.391520636327, 21879.56240875142, 22117.77882221157, 22142.88928555347, 22168.0, 58390.0, 59910.40089599048, 60666.393336066074, 60906.0, 62572.687136565844, 80128.19871801208, 80443.0, 80533.59733201297, 80885.0, 82645.58677206576, 15523.689526209639, 15782.684346313239, 15892.364305427653, 15909.443169489012, 15937.601559961095, 23845.284641460785, 23980.0, 23981.760182397953, 24052.15817446381, 24139.396515087265, 19258.8074119257, 19468.415947521597, 19519.219231230592, 19610.90194549075, 19814.207431703147, 15810.130442825755, 16553.66892662164, 16635.91682041582, 17560.648787024442, 18076.690504389986, 19280.0, 19694.0, 19800.702989455458, 20179.596408071462, 20415.8997064825, 15393.384264629289, 15418.306176221873, 15593.844061559694, 15620.68758624844, 15733.292001860173, 17632.73550896712, 17774.822251777317, 19175.712364314266, 19565.8043419564, 19730.60538789245], "value": 12767.793159146302, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1422.2533170085705, 1470.4926800253916, 1482.9555113346603, 1492.7536956402193, 1503.4136686692193, 16995.15024248787, 17556.122193890318, 17565.47303580893, 17566.736498952527, 17646.0, 17216.0, 17241.655166896653, 17327.48017559472, 17491.125443727826, 17501.562460938483, 2797.63599758204, 2819.957700634487, 2874.597556342114, 2875.2380619135947, 2875.43928933858, 2741.5065288248206, 2768.4739899419046, 2789.27478855497, 2791.3858951030843, 2800.2999250187454, 2702.567589185731, 2790.14900455361, 2792.45547118312, 2806.859657017151, 2809.7471227589513, 2829.4906916754976, 2836.3901761121356, 2871.3252385689348, 2873.267316834215, 2876.8849246030136, 2665.0, 2692.3942113024414, 2706.539888218988, 2708.4853877763358, 2713.226730381851, 16602.66794664112, 17090.401835935805, 17131.0, 17155.485335439833, 17201.397951071776, 8134.959325203396, 8397.0, 8417.0, 8434.915650843473, 8440.28257598105, 5553.278073850373, 5739.971300143473, 5840.269966254218, 5869.50109240715, 5914.172015917775, 2159.0, 2162.351294611626, 2162.8810415427147, 2174.5324755177553, 2205.4155648753094, 31019.84490077535, 31091.844540777605, 31266.0, 31375.37249255002, 31415.37169256602, 7133.3936615388275, 7268.418526517866, 7289.70841166358, 7316.524425912298, 7322.231165727522, 19744.11151498186, 19843.30548430798, 19872.70190947137, 19944.102515386843, 19970.0, 8108.513489190667, 8730.17063378978, 8973.910260897308, 8984.191422771983, 8990.235829954396, 7811.296983271419, 7918.287354138155, 7942.364610831235, 8056.7180148694515, 8056.751203563457, 5594.049011668027, 5819.912701309484, 5828.12578113278, 5946.494547963385, 5961.076033214858, 1680.3110724602855, 1681.9992104697685, 1706.1128213329, 1718.613312004789, 1722.896626202432, 983.6508039645965, 999.7500624843789, 1011.206203130539, 1011.8026984737905, 1028.665683652816, 8796.516191609522, 8806.603702833389, 8864.822703546022, 8867.955660221658, 9043.864342034874, 722.3931897206267, 736.1901907901266, 751.5603372027425, 764.1059959846992, 769.0002996105042, 5866.6480011199465, 5917.2011428800015, 5930.55520835939, 5967.104934259809, 6041.6375017499095, 23510.7648923512, 24420.877895610764, 25090.749092509206, 25126.623100653505, 25127.62308565373, 23332.416689582897, 24382.634260486106, 24384.878075609508, 24426.38934026628, 24721.5055698885, 12590.622281331574, 12713.809292860615, 12884.677883052998, 12905.35473226335, 12932.676683083186, 9254.259659227111, 9355.578998944928, 9418.576164072496, 9512.048795120432, 9523.380980236263, 20964.0, 21195.8940205298, 21318.0, 21700.023498942268, 22094.33716988493, 21767.455813604785, 21968.89015554912, 22013.339599812032, 22040.44898877541, 22049.0, 12529.812052819032, 12898.742025159256, 12899.226046437243, 12912.548060817826, 12924.353782311035, 15839.208039597743, 15861.0, 15905.761413579037, 16030.038197708174, 16087.436939707053, 12781.233126012468, 12903.803666328207, 13011.609651710463, 13311.0, 13431.261280629658, 12518.311492867979, 12720.109592328818, 12761.680957976125, 12763.617091487271, 12981.350932453151, 10462.319949203278, 10502.212334075128, 10562.894371056498, 10601.893981060091, 10703.250772445854, 7929.563873986986, 8008.919910800817, 8050.0, 8053.557054362066, 8128.87806682912, 7984.680612775656, 8057.5165490070785, 8068.757937261891, 8074.919250807417, 8185.181481851767], "value": 7322.625589779913, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [558.4220331956426, 560.5739637875215, 583.3116922032002, 586.1003359842642, 591.6686655472936, 6269.655168965705, 6282.874342513147, 6324.2727086385075, 6388.904166437507, 6391.296957334701, 6126.5602583392865, 6279.340669229722, 6305.022721478167, 6329.968350158254, 6353.9334825711585, 5072.315237442954, 5135.409427915782, 5159.587233021368, 5166.225066240057, 5180.197069454241, 4919.950800491985, 5037.0859073552, 5060.493950604929, 5066.822661206858, 5088.397154896196, 5001.8249361272365, 5036.169032109698, 5049.747512624373, 5051.242313652945, 5074.517920797518, 4981.302617633534, 5004.174311238641, 5017.648764586462, 5018.7992480300745, 5049.141645920203, 4487.685861989662, 4523.389342438762, 4539.477960034572, 4559.475660299075, 4597.54024597541, 6176.320604733476, 6250.43746062852, 6273.341299163613, 6283.434490895841, 6316.178896743394, 5906.468417842415, 5921.0, 5980.970095149496, 6017.759289628453, 6030.939690603125, 6680.131582894242, 6703.899441508382, 6707.6981535831, 6739.898901516482, 6743.629100399476, 4916.70499770015, 5092.6180536459915, 5107.821226257063, 5148.609875333676, 5171.327727395452, 6874.484413668944, 6892.241853396123, 6943.79168624942, 7055.929440705579, 7122.893156602655, 5727.513161381329, 5730.255066841284, 5753.165790960371, 5774.249347584871, 5796.449337312949, 5796.594238403356, 5812.186293918855, 5836.591438599342, 5842.620229685057, 5865.0, 6133.233345831771, 6151.876962460815, 6179.969100154561, 6233.594816336924, 6242.406971337807, 6697.631630260383, 6783.93216067833, 6801.251862295144, 6808.421284190899, 6816.386525212655, 1972.6745087060474, 2002.859799813999, 2014.5769388428594, 2037.4498885301182, 2041.7851378429812, 1462.429652435551, 1478.4160256698758, 1494.4769330734157, 1502.30142983511, 1505.8795296376484, 6185.226846644169, 6203.689815509297, 6204.193454850841, 6264.812055638338, 6270.2789179244055, 6706.631135287508, 6729.831754206086, 6799.7280108796085, 6807.931920680829, 6829.965850170816, 6139.526513636784, 6146.508279337642, 6187.143856842929, 6201.751929922752, 6237.5945563539135, 3588.784672919633, 3607.7113830893463, 3617.6382361763613, 3619.7285203609836, 3636.381815091441, 6986.7205311787975, 6991.3358230969, 7041.964790176016, 7109.680064397115, 7148.17795953472, 6964.93035069653, 6978.337057979484, 6996.475264355194, 7000.824979375557, 7013.790655943463, 7117.75087871922, 7130.500864939404, 7133.144022717203, 7148.963400306822, 7170.3905168060255, 6802.693878775368, 6834.589924604539, 6861.931380686129, 6870.27862074475, 6882.483813713891, 6805.9659701701175, 6811.2167100784045, 6826.010228516937, 6841.863162736817, 6868.278830722702, 6903.6203008835, 7068.540544864567, 7117.288271172842, 7118.964405178148, 7124.287571242834, 5709.25779648643, 5744.885102297847, 5750.993576124245, 5752.942470575241, 5773.769049237983, 6487.448566871869, 6795.572929684821, 6819.352161544645, 6884.65576721172, 6891.4486841053595, 6382.266039405342, 6530.8367290818105, 6601.3728695773825, 6678.293547081416, 6741.561798483083, 6517.217933847968, 6593.736100630219, 6615.206175259, 6615.5038372123245, 6708.6981085850275, 6451.2903580607035, 6499.870002600016, 6503.967480162758, 6527.801377325171, 6648.933510665025, 6639.344262295121, 6666.699998499982, 6709.832254193684, 6714.462842972453, 6717.630530320879, 6506.072267479559, 6518.348165183634, 6541.836454088686, 6555.475561955128, 6596.439302659328], "value": 5320.666086604799, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [944.8110377924417, 968.6464440479225, 974.4056125763283, 976.5800705696552, 979.9951000244997, 20966.790332096694, 21494.032768525416, 21628.35114946554, 21630.24294149705, 21655.242066527673, 19170.233190672356, 19209.32767353143, 19566.0, 19614.607707845833, 19661.803381966176, 1022.161827301613, 1040.0743338428804, 1040.791841631673, 1041.3231399590252, 1042.4318746283257, 1026.743314171457, 1067.386252904583, 1068.845017472466, 1070.9946450267737, 1072.4262519552042, 1116.7319843237606, 1126.4761885723137, 1133.982990255147, 1134.784390965718, 1134.801409753294, 1127.689885281546, 1128.2215271462665, 1129.824877144044, 1130.4743294368118, 1132.6715252576744, 776.7902666280129, 785.8310463250428, 792.2631952284373, 793.3137835772073, 823.625250511016, 21464.141434342608, 21601.67597486039, 21648.0, 21665.133394664193, 21666.566668666695, 22711.772882271132, 22800.429989250235, 23579.52840943189, 23739.287821365386, 23744.0, 18685.813141868544, 19105.42683719477, 19244.80755192444, 19248.518787030436, 19362.225510979544, 3977.840886364542, 4004.0990777075217, 4004.3993400989793, 4006.6994975376965, 4009.1781184857036, 161394.0, 164024.0, 164837.17581412016, 165805.0, 166739.0, 17719.29122835072, 17819.821801781814, 17860.374886879155, 17909.10454477297, 18374.540636484195, 33246.68053111539, 33924.83037584796, 34019.48970765441, 34698.13254668654, 34903.82548087243, 9281.628734850665, 9339.579718912666, 9404.764880878032, 9405.482698451651, 9408.43549387039, 4634.073185362941, 4739.0, 4764.094821983812, 4812.422509298906, 4839.540243676845, 6047.969760151171, 6220.720067596969, 6241.7191226394925, 6262.2172228471445, 6274.0, 1070.0850772589479, 1092.1754075672816, 1102.9889701102886, 1116.0513563471147, 1119.5297974850582, 738.5384134915678, 747.614978286183, 748.2517482517483, 762.3634265388351, 772.4901564967095, 13355.198688078746, 13356.198628082346, 13536.932315338558, 14352.06711563745, 14447.133172009713, 744.4677055905054, 745.4744405194325, 747.6934456872656, 752.1275320628155, 769.0886299734832, 6294.150289710922, 6308.337624549356, 6329.113924050636, 6335.873282534323, 6347.460465860361, 149614.0, 149955.0, 150698.24650876675, 153497.23251383894, 157087.0, 104772.47613762035, 106865.0, 127804.0, 128502.0, 135273.0, 103344.96655033504, 111328.44335778269, 111452.0, 116044.0, 118085.40957295158, 13385.263810490127, 14683.265836708335, 14704.632384190481, 14759.114453132846, 14805.0, 54706.726466367414, 55200.0, 55518.44481555133, 55743.0, 56943.14585281141, 67598.68175041997, 70510.64744676244, 72298.09694542487, 72569.0, 73812.63093684497, 18036.90981545084, 18183.09084545794, 18422.631547369245, 18469.445916622524, 18541.721874172166, 22430.102795887982, 22439.663405049265, 22694.886525567268, 22695.773042269368, 22817.429564261027, 20753.688694669894, 20927.8953605231, 21156.047977841343, 21341.67987480158, 21709.0, 17915.910420447814, 18029.368972085915, 18225.362112326005, 18269.634607308046, 18295.908520457844, 20482.79517204809, 20697.379078627666, 20998.58002839965, 21016.0, 21047.8947605261, 14206.360713768112, 14367.856321436651, 14395.78406323885, 14486.565403037926, 14699.779503307673, 13517.459301627825, 13577.59267221985, 13708.588742337746, 13729.931350343584, 13783.448662053406], "value": 10203.004463447938, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1374.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 106.0, 105.0, 103.0, 105.0, 105.0, 106.0, 106.0, 104.0, 106.0], "value": 105.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 114.0, 114.0, 115.0, 112.0, 117.0, 128.0, 113.0, 119.0, 116.0], "value": 116.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 164.0, 164.0, 164.0, 162.0, 164.0, 163.0, 163.0, 163.0, 170.0], "value": 164.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 72.0, 73.0, 73.0, 83.0, 73.0, 73.0, 72.0, 73.0], "value": 74.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 79.0, 78.0, 78.0, 78.0, 78.0, 78.0, 86.0, 79.0], "value": 79.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 114.0, 115.0, 113.0, 110.0, 113.0, 113.0, 114.0, 114.0, 115.0], "value": 113.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 150.0, 150.0, 150.0, 149.0, 149.0, 151.0, 150.0, 149.0, 153.0], "value": 150.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 99.0, 94.0, 95.0, 94.0, 97.0, 95.0, 96.0, 94.0, 96.0], "value": 95.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [46.0, 44.0, 43.0, 43.0, 44.0, 45.0, 44.0, 44.0, 44.0, 44.0], "value": 44.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 36.0, 36.0, 36.0, 40.0, 36.0, 36.0, 36.0, 37.0, 36.0], "value": 36.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 70.0, 72.0, 71.0, 70.0, 73.0, 72.0, 72.0, 71.0], "value": 71.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 109.0, 118.0, 109.0, 106.0, 107.0, 110.0, 104.0, 112.0, 105.0], "value": 108.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 161.0, 155.0, 149.0, 151.0, 147.0, 157.0, 148.0, 156.0, 147.0], "value": 152.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 65.0, 62.0, 61.0, 62.0, 59.0, 58.0, 65.0, 77.0, 59.0], "value": 63.1, "unit": "score"}]}]} 22:27:29 INFO - Return code: 0 22:27:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:27:29 INFO - # TBPL SUCCESS # 22:27:29 INFO - Running post-action listener: _package_coverage_data 22:27:29 INFO - Running post-action listener: _resource_record_post_action 22:27:29 INFO - [mozharness: 2017-09-22 05:27:29.317404Z] Finished run-tests step (success) 22:27:29 INFO - Running post-run listener: _resource_record_post_run 22:27:29 INFO - Total resource usage - Wall time: 1186s; CPU: 13.0%; Read bytes: 8192; Write bytes: 330510336; Read time: 156; Write time: 199636 22:27:29 INFO - TinderboxPrint: CPU usage
13.1% 22:27:29 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 156 22:27:29 INFO - TinderboxPrint: I/O write bytes / time
330,510,336 / 199,636 22:27:29 INFO - TinderboxPrint: CPU idle
8,219.1 (86.9%) 22:27:29 INFO - TinderboxPrint: CPU user
1,187.5 (12.6%) 22:27:29 INFO - TinderboxPrint: Swap in / out
0 / 0 22:27:29 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 10342400; Read time: 0; Write time: 1736 22:27:29 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 22:27:29 INFO - run-tests - Wall time: 1169s; CPU: 13.0%; Read bytes: 4096; Write bytes: 319303680; Read time: 140; Write time: 197824 22:27:29 INFO - Running post-run listener: _upload_blobber_files 22:27:29 INFO - Blob upload gear active. 22:27:29 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:27:29 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 22:27:29 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'] 22:27:29 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 22:27:30 INFO - (blobuploader) - INFO - Open directory for files ... 22:27:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 22:27:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:27:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:27:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 22:27:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:27:31 INFO - (blobuploader) - INFO - Done attempting. 22:27:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 22:27:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:27:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:27:32 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 22:27:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:27:32 INFO - (blobuploader) - INFO - Done attempting. 22:27:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:27:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:27:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:27:35 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:27:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:27:35 INFO - (blobuploader) - INFO - Done attempting. 22:27:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 22:27:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:27:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:27:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 22:27:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:27:36 INFO - (blobuploader) - INFO - Done attempting. 22:27:36 INFO - (blobuploader) - INFO - Iteration through files over. 22:27:36 INFO - Return code: 0 22:27:36 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:27:36 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:27:36 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5546d18321c32edd2abd243c51a6445ad321867b2d2399d37172c8f685e0f835d6020bb952353e21891fcd6b194d4aab06d56dffa8e4c9900a7d2f266c128375", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/64da0a34b427767552346a78e82f0c641b3aeeea1e176ede626cd68ff1066e9720641620d97df2a83955e68d2d5892418979c95769581480188b730f708b3fe9", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ad364bde37a5959ad86aa433651540523283fad39b15ee080cc1a9adfd28e3bf1f576d46448bf2e045b7baa84d9ce44e1883e2d10feaf09f90ff35b1b1b5bccb", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/060b221c64829f6650ed1bd6573e007dfec8f8342ca771e2c227753a0b87b2e28f7272663b0205daa2e3122106ca80d4637b6b46d749cd57deacd8d28cd7c0bf"} 22:27:36 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:27:36 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:27:36 INFO - Contents: 22:27:36 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5546d18321c32edd2abd243c51a6445ad321867b2d2399d37172c8f685e0f835d6020bb952353e21891fcd6b194d4aab06d56dffa8e4c9900a7d2f266c128375", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/64da0a34b427767552346a78e82f0c641b3aeeea1e176ede626cd68ff1066e9720641620d97df2a83955e68d2d5892418979c95769581480188b730f708b3fe9", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ad364bde37a5959ad86aa433651540523283fad39b15ee080cc1a9adfd28e3bf1f576d46448bf2e045b7baa84d9ce44e1883e2d10feaf09f90ff35b1b1b5bccb", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/060b221c64829f6650ed1bd6573e007dfec8f8342ca771e2c227753a0b87b2e28f7272663b0205daa2e3122106ca80d4637b6b46d749cd57deacd8d28cd7c0bf"} 22:27:36 INFO - Running post-run listener: copy_logs_to_upload_dir 22:27:36 INFO - Copying logs to upload dir... 22:27:36 INFO - mkdir: /builds/slave/test/build/upload/logs 22:27:36 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1233.132670 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2017-09-21 22:27:36.866753) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-21 22:27:36.870311) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1506052468.613065-334524023 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5546d18321c32edd2abd243c51a6445ad321867b2d2399d37172c8f685e0f835d6020bb952353e21891fcd6b194d4aab06d56dffa8e4c9900a7d2f266c128375", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/64da0a34b427767552346a78e82f0c641b3aeeea1e176ede626cd68ff1066e9720641620d97df2a83955e68d2d5892418979c95769581480188b730f708b3fe9", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ad364bde37a5959ad86aa433651540523283fad39b15ee080cc1a9adfd28e3bf1f576d46448bf2e045b7baa84d9ce44e1883e2d10feaf09f90ff35b1b1b5bccb", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/060b221c64829f6650ed1bd6573e007dfec8f8342ca771e2c227753a0b87b2e28f7272663b0205daa2e3122106ca80d4637b6b46d749cd57deacd8d28cd7c0bf"} build_url:https://queue.taskcluster.net/v1/task/bNEWwPAlQDWVxLoQgV_2iA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011336 build_url: 'https://queue.taskcluster.net/v1/task/bNEWwPAlQDWVxLoQgV_2iA/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5546d18321c32edd2abd243c51a6445ad321867b2d2399d37172c8f685e0f835d6020bb952353e21891fcd6b194d4aab06d56dffa8e4c9900a7d2f266c128375", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/64da0a34b427767552346a78e82f0c641b3aeeea1e176ede626cd68ff1066e9720641620d97df2a83955e68d2d5892418979c95769581480188b730f708b3fe9", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ad364bde37a5959ad86aa433651540523283fad39b15ee080cc1a9adfd28e3bf1f576d46448bf2e045b7baa84d9ce44e1883e2d10feaf09f90ff35b1b1b5bccb", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/060b221c64829f6650ed1bd6573e007dfec8f8342ca771e2c227753a0b87b2e28f7272663b0205daa2e3122106ca80d4637b6b46d749cd57deacd8d28cd7c0bf"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-21 22:27:36.906244) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 22:27:36.906912) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1506052468.613065-334524023 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004527 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 22:27:36.989232) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-21 22:27:36.989588) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-21 22:27:36.989968) ========= ========= Total master_lag: 0.20 =========