builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-071 starttime: 1507821869.27 results: success (0) revision: f4ba99b411837e4b16b134cbab5bcc355ddd6b22 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-10-12 08:24:29.268393) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-10-12 08:24:29.268767) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-12 08:24:29.269028) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-12 08:24:29.316247) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-12 08:24:29.316518) ========= 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=dbb0bb31bf4a4c7db9ba1648000001b6-1507814394.778953-131491975 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-10-12 08:24:29-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 227M=0s 2017-10-12 08:24:29 (227 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.120535 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-12 08:24:29.473178) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-12 08:24:29.473453) ========= 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=dbb0bb31bf4a4c7db9ba1648000001b6-1507814394.778953-131491975 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020018 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-12 08:24:29.522080) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-12 08:24:29.522367) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev f4ba99b411837e4b16b134cbab5bcc355ddd6b22 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev f4ba99b411837e4b16b134cbab5bcc355ddd6b22 --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=dbb0bb31bf4a4c7db9ba1648000001b6-1507814394.778953-131491975 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-10-12 08:24:29,575 truncating revision to first 12 chars 2017-10-12 08:24:29,575 Setting DEBUG logging. 2017-10-12 08:24:29,575 attempt 1/10 2017-10-12 08:24:29,575 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/f4ba99b41183?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-10-12 08:24:30,152 unpacking tar archive at: mozilla-beta-f4ba99b41183/testing/mozharness/ program finished with exit code 0 elapsedTime=0.852674 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-12 08:24:30.391940) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-12 08:24:30.392257) ========= script_repo_revision: f4ba99b411837e4b16b134cbab5bcc355ddd6b22 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-12 08:24:30.392670) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-12 08:24:30.392938) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-12 08:24:30.407884) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2017-10-12 08:24:30.408241) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=dbb0bb31bf4a4c7db9ba1648000001b6-1507814394.778953-131491975 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:24:30 INFO - MultiFileLogger online at 20171012 08:24:30 in /builds/slave/test 08:24:30 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta-Non-PGO 08:24:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:24:30 INFO - {'append_to_log': False, 08:24:30 INFO - 'base_work_dir': '/builds/slave/test', 08:24:30 INFO - 'blob_upload_branch': 'Mozilla-Beta-Non-PGO', 08:24:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:24:30 INFO - 'branch': 'Mozilla-Beta-Non-PGO', 08:24:30 INFO - 'buildbot_json_path': 'buildprops.json', 08:24:30 INFO - 'code_coverage': False, 08:24:30 INFO - 'config_files': ('talos/linux_config.py',), 08:24:30 INFO - 'default_actions': ('clobber', 08:24:30 INFO - 'read-buildbot-config', 08:24:30 INFO - 'download-and-extract', 08:24:30 INFO - 'populate-webroot', 08:24:30 INFO - 'create-virtualenv', 08:24:30 INFO - 'install', 08:24:30 INFO - 'setup-mitmproxy', 08:24:30 INFO - 'run-tests'), 08:24:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:24:30 INFO - 'disable_ccov_upload': False, 08:24:30 INFO - 'disable_stylo': False, 08:24:30 INFO - 'download_minidump_stackwalk': True, 08:24:30 INFO - 'download_symbols': 'ondemand', 08:24:30 INFO - 'enable_stylo': False, 08:24:30 INFO - 'enable_webrender': False, 08:24:30 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 08:24:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:24:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:24:30 INFO - 'gecko_profile': False, 08:24:30 INFO - 'gecko_profile_interval': 0, 08:24:30 INFO - 'installer_path': 'installer.exe', 08:24:30 INFO - 'log_level': 'info', 08:24:30 INFO - 'log_name': 'talos', 08:24:30 INFO - 'log_to_console': True, 08:24:30 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:24:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:24:30 INFO - 'opt_config_files': (), 08:24:30 INFO - 'pip_index': False, 08:24:30 INFO - 'suite': 'dromaeojs-e10s', 08:24:30 INFO - 'system_bits': '32', 08:24:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:24:30 INFO - 'title': 'talos-linux64-ix-071', 08:24:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:24:30 INFO - 'use_talos_json': True, 08:24:30 INFO - 'verify': 'False', 08:24:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:24:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:24:30 INFO - 'work_dir': 'build'} 08:24:30 INFO - [mozharness: 2017-10-12 15:24:30.687674Z] Running clobber step. 08:24:30 INFO - Running pre-action listener: _resource_record_pre_action 08:24:30 INFO - Running main action method: clobber 08:24:30 INFO - rmtree: /builds/slave/test/build 08:24:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:24:31 INFO - Running post-action listener: _resource_record_post_action 08:24:31 INFO - [mozharness: 2017-10-12 15:24:31.666661Z] Finished clobber step (success) 08:24:31 INFO - [mozharness: 2017-10-12 15:24:31.666758Z] Running read-buildbot-config step. 08:24:31 INFO - Running pre-action listener: _resource_record_pre_action 08:24:31 INFO - Running main action method: read_buildbot_config 08:24:31 INFO - Using buildbot properties: 08:24:31 INFO - { 08:24:31 INFO - "project": "", 08:24:31 INFO - "product": "firefox", 08:24:31 INFO - "who": "ryanvm@gmail.com", 08:24:31 INFO - "installer_path": "public/build/target.tar.bz2", 08:24:31 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s", 08:24:31 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta", 08:24:31 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta talos dromaeojs-e10s", 08:24:31 INFO - "stage_platform": "linux64", 08:24:31 INFO - "basedir": "/builds/slave/test", 08:24:31 INFO - "buildnumber": 135, 08:24:31 INFO - "platform": "ubuntu64_hw", 08:24:31 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 08:24:31 INFO - "slavebuilddir": "test", 08:24:31 INFO - "taskId": "Ja7-bQRiTHaLOETSmdsIvg", 08:24:31 INFO - "branch": "mozilla-beta", 08:24:31 INFO - "script_repo_revision": "production", 08:24:31 INFO - "revision": "f4ba99b411837e4b16b134cbab5bcc355ddd6b22", 08:24:31 INFO - "slavename": "talos-linux64-ix-071", 08:24:31 INFO - "repo_path": "releases/mozilla-beta" 08:24:31 INFO - } 08:24:31 INFO - Finding installer, test and symbols from parent task. 08:24:31 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ja7-bQRiTHaLOETSmdsIvg'}, attempt #1 08:24:32 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ja7-bQRiTHaLOETSmdsIvg'}, attempt #1 08:24:32 INFO - Task dependencies: eUlwW_TISOGJjYS0gNJqdw 08:24:32 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eUlwW_TISOGJjYS0gNJqdw'}, attempt #1 08:24:33 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/eUlwW_TISOGJjYS0gNJqdw/artifacts/public/build/target.tar.bz2 08:24:33 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/eUlwW_TISOGJjYS0gNJqdw/artifacts/public/build/target.test_packages.json 08:24:33 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/eUlwW_TISOGJjYS0gNJqdw/artifacts/public/build/target.crashreporter-symbols.zip 08:24:33 INFO - Running post-action listener: _resource_record_post_action 08:24:33 INFO - [mozharness: 2017-10-12 15:24:33.156219Z] Finished read-buildbot-config step (success) 08:24:33 INFO - [mozharness: 2017-10-12 15:24:33.156437Z] Running download-and-extract step. 08:24:33 INFO - Running pre-action listener: _resource_record_pre_action 08:24:33 INFO - Running main action method: download_and_extract 08:24:33 INFO - mkdir: /builds/slave/test/build/tests 08:24: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')]} 08:24:33 INFO - trying https://queue.taskcluster.net/v1/task/eUlwW_TISOGJjYS0gNJqdw/artifacts/public/build/target.test_packages.json 08:24:33 INFO - Downloading https://queue.taskcluster.net/v1/task/eUlwW_TISOGJjYS0gNJqdw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 08:24:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eUlwW_TISOGJjYS0gNJqdw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 08:24:33 INFO - Downloaded 989 bytes. 08:24:33 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 08:24:33 INFO - Using the following test package requirements: 08:24:33 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 08:24:33 INFO - u'common': [u'target.common.tests.zip'], 08:24:33 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 08:24:33 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 08:24:33 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 08:24:33 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 08:24:33 INFO - u'mozbase': [u'target.common.tests.zip'], 08:24:33 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 08:24:33 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 08:24:33 INFO - u'web-platform': [u'target.common.tests.zip', 08:24:33 INFO - u'target.web-platform.tests.tar.gz'], 08:24:33 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 08:24:33 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 08:24:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eUlwW_TISOGJjYS0gNJqdw/artifacts/public/build/target.common.tests.zip 08:24:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eUlwW_TISOGJjYS0gNJqdw/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:24:33 INFO - Fetch https://queue.taskcluster.net/v1/task/eUlwW_TISOGJjYS0gNJqdw/artifacts/public/build/target.common.tests.zip into memory 08:24:36 INFO - Content-Length response header: 55176818 08:24:36 INFO - Bytes received: 55176818 08:24:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eUlwW_TISOGJjYS0gNJqdw/artifacts/public/build/target.talos.tests.zip 08:24:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eUlwW_TISOGJjYS0gNJqdw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 08:24:47 INFO - Fetch https://queue.taskcluster.net/v1/task/eUlwW_TISOGJjYS0gNJqdw/artifacts/public/build/target.talos.tests.zip into memory 08:24:48 INFO - Content-Length response header: 14509251 08:24:48 INFO - Bytes received: 14509251 08:24:48 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')]} 08:24:48 INFO - trying https://queue.taskcluster.net/v1/task/eUlwW_TISOGJjYS0gNJqdw/artifacts/public/build/target.tar.bz2 08:24:48 INFO - Downloading https://queue.taskcluster.net/v1/task/eUlwW_TISOGJjYS0gNJqdw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 08:24:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eUlwW_TISOGJjYS0gNJqdw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 08:24:50 INFO - Downloaded 53556150 bytes. 08:24:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eUlwW_TISOGJjYS0gNJqdw/artifacts/public/build/target.tar.bz2 08:24:51 INFO - mkdir: /builds/slave/test/properties 08:24:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:24:51 INFO - Writing to file /builds/slave/test/properties/build_url 08:24:51 INFO - Contents: 08:24:51 INFO - build_url:https://queue.taskcluster.net/v1/task/eUlwW_TISOGJjYS0gNJqdw/artifacts/public/build/target.tar.bz2 08:24:51 INFO - Running post-action listener: _resource_record_post_action 08:24:51 INFO - Running post-action listener: find_tests_for_verification 08:24:51 INFO - Running post-action listener: set_extra_try_arguments 08:24:51 INFO - [mozharness: 2017-10-12 15:24:51.243758Z] Finished download-and-extract step (success) 08:24:51 INFO - [mozharness: 2017-10-12 15:24:51.243889Z] Running populate-webroot step. 08:24:51 INFO - Running pre-action listener: _resource_record_pre_action 08:24:51 INFO - Running main action method: populate_webroot 08:24:51 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:24:51 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 08:24:51 INFO - 'tests': ['tresize', 08:24:51 INFO - 'tcanvasmark']}, 08:24:51 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:24:51 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 08:24:51 INFO - 'tests': ['dromaeo_css', 08:24:51 INFO - 'kraken']}, 08:24:51 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:24:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:24:51 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 08:24:51 INFO - 'talos_options': ['--disable-stylo'], 08:24:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:24:51 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:24:51 INFO - 'tests': ['damp', 'tps']}, 08:24:51 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 08:24:51 INFO - 'talos_options': ['--disable-stylo'], 08:24:51 INFO - 'tests': ['damp', 'tps']}, 08:24:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:24:51 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 08:24:51 INFO - 'tests': ['dromaeo_dom']}, 08:24:51 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 08:24:51 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 08:24:51 INFO - 'tests': ['basic_compositor_video', 08:24:51 INFO - 'glvideo']}, 08:24:51 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 08:24:51 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 08:24:51 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 08:24:51 INFO - 'talos_options': ['--disable-stylo'], 08:24:51 INFO - 'tests': ['ts_paint_webext', 08:24:51 INFO - 'tp5o_webext']}, 08:24:51 INFO - 'other-e10s': {'tests': ['a11yr', 08:24:51 INFO - 'ts_paint', 08:24:51 INFO - 'tpaint', 08:24:51 INFO - 'sessionrestore', 08:24:51 INFO - 'sessionrestore_many_windows', 08:24:51 INFO - 'sessionrestore_no_auto_restore', 08:24:51 INFO - 'tabpaint']}, 08:24:51 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 08:24:51 INFO - 'tests': ['a11yr', 08:24:51 INFO - 'ts_paint', 08:24:51 INFO - 'tpaint', 08:24:51 INFO - 'sessionrestore', 08:24:51 INFO - 'sessionrestore_many_windows', 08:24:51 INFO - 'sessionrestore_no_auto_restore', 08:24:51 INFO - 'tabpaint']}, 08:24:51 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 08:24:51 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 08:24:51 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 08:24:51 INFO - 'tests': ['perf_reftest_singletons']}, 08:24:51 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 08:24:51 INFO - 'tests': ['bloom_basic']}, 08:24:51 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:24:51 INFO - 'tsvgr_opacity', 08:24:51 INFO - 'tart', 08:24:51 INFO - 'tscrollx', 08:24:51 INFO - 'tsvg_static']}, 08:24:51 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 08:24:51 INFO - 'tests': ['tsvgx', 08:24:51 INFO - 'tsvgr_opacity', 08:24:51 INFO - 'tart', 08:24:51 INFO - 'tscrollx', 08:24:51 INFO - 'tsvg_static']}, 08:24:51 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:24:51 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 08:24:51 INFO - 'talos_options': ['--disable-stylo'], 08:24:51 INFO - 'tests': ['tp5o']}, 08:24:51 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 08:24:51 INFO - 'talos_options': ['--mitmproxy', 08:24:51 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 08:24:51 INFO - '--firstNonBlankPaint'], 08:24:51 INFO - 'tests': ['tp6_google', 08:24:51 INFO - 'tp6_youtube', 08:24:51 INFO - 'tp6_amazon', 08:24:51 INFO - 'tp6_facebook']}, 08:24:51 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 08:24:51 INFO - 'talos_options': ['--disable-stylo', 08:24:51 INFO - '--mitmproxy', 08:24:51 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 08:24:51 INFO - '--firstNonBlankPaint'], 08:24:51 INFO - 'tests': ['tp6_google', 08:24:51 INFO - 'tp6_youtube', 08:24:51 INFO - 'tp6_amazon', 08:24:51 INFO - 'tp6_facebook']}, 08:24:51 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 08:24:51 INFO - 'talos_options': ['--stylo-threads=1', 08:24:51 INFO - '--mitmproxy', 08:24:51 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 08:24:51 INFO - '--firstNonBlankPaint'], 08:24:51 INFO - 'tests': ['tp6_google', 08:24:51 INFO - 'tp6_youtube', 08:24:51 INFO - 'tp6_amazon', 08:24:51 INFO - 'tp6_facebook']}, 08:24:51 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:24:51 INFO - 'talos_options': ['--xperf_path', 08:24:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:24:51 INFO - 'tests': ['tp5n']}, 08:24:51 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 08:24:51 INFO - 'talos_options': ['--disable-stylo', 08:24:51 INFO - '--xperf_path', 08:24:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:24:51 INFO - 'tests': ['tp5n']}}} 08:24:51 INFO - Running post-action listener: _resource_record_post_action 08:24:51 INFO - [mozharness: 2017-10-12 15:24:51.267019Z] Finished populate-webroot step (success) 08:24:51 INFO - [mozharness: 2017-10-12 15:24:51.267108Z] Running create-virtualenv step. 08:24:51 INFO - Running pre-action listener: _resource_record_pre_action 08:24:51 INFO - Running main action method: create_virtualenv 08:24:51 INFO - Creating virtualenv /builds/slave/test/build/venv 08:24:51 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 08:24:51 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 08:24:51 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 08:24:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:24:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:24:52 INFO - Installing distribute.............................................................................................................................................................................................done. 08:24:52 INFO - Installing pip...............done. 08:24:52 INFO - Return code: 0 08:24:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:24:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:24:52 INFO - Reading from file tmpfile_stdout 08:24:52 INFO - Output received: 08:24:52 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 08:24:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:24:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:24:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:24:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:24:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:24:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c8e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e56a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ff9a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ffd090>, '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 0x1ffdd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ff7f90>, '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': 'dbb0bb31bf4a4c7db9ba1648000001b6-1507814394.778953-131491975', '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 08:24:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:24:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:24:52 INFO - Using env: {'DISPLAY': ':0', 08:24:52 INFO - 'HOME': '/home/cltbld', 08:24:52 INFO - 'LANG': 'en_US.UTF-8', 08:24:52 INFO - 'LANGUAGE': 'en_US:en', 08:24:52 INFO - 'LOGNAME': 'cltbld', 08:24:52 INFO - 'MAIL': '/var/mail/cltbld', 08:24:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:24:52 INFO - 'MOZ_NO_REMOTE': '1', 08:24:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:24:52 INFO - 'NO_EM_RESTART': '1', 08:24:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:24:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:24:52 INFO - 'PWD': '/builds/slave/test', 08:24:52 INFO - 'SHELL': '/bin/bash', 08:24:52 INFO - 'SHLVL': '1', 08:24:52 INFO - 'TERM': 'linux', 08:24:52 INFO - 'TMOUT': '86400', 08:24:52 INFO - 'USER': 'cltbld', 08:24:52 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1507814394.778953-131491975', 08:24:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:24:52 INFO - '_': '/tools/buildbot/bin/python'} 08:24:54 INFO - Ignoring indexes: http://pypi.python.org/simple/ 08:24:54 INFO - Downloading/unpacking pip>=1.5 08:24:54 INFO - Running setup.py egg_info for package pip 08:24:54 INFO - warning: no files found matching 'pip/cacert.pem' 08:24:54 INFO - warning: no files found matching '*.html' under directory 'docs' 08:24:54 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 08:24:54 INFO - no previously-included directories found matching 'docs/_build/_sources' 08:24:54 INFO - Installing collected packages: pip 08:24:54 INFO - Found existing installation: pip 1.1 08:24:54 INFO - Uninstalling pip: 08:24:54 INFO - Successfully uninstalled pip 08:24:54 INFO - Running setup.py install for pip 08:24:54 INFO - warning: no files found matching 'pip/cacert.pem' 08:24:54 INFO - warning: no files found matching '*.html' under directory 'docs' 08:24:54 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 08:24:54 INFO - no previously-included directories found matching 'docs/_build/_sources' 08:24:54 INFO - Installing pip script to /builds/slave/test/build/venv/bin 08:24:54 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 08:24:54 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 08:24:54 INFO - Successfully installed pip 08:24:54 INFO - Cleaning up... 08:24:54 INFO - Return code: 0 08:24:54 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:24:54 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')]} 08:24:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:24:54 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')]} 08:24:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:24:54 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 0x1c8e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e56a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ff9a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ffd090>, '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 0x1ffdd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ff7f90>, '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': 'dbb0bb31bf4a4c7db9ba1648000001b6-1507814394.778953-131491975', '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 08:24:54 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 08:24:54 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 08:24:54 INFO - Using env: {'DISPLAY': ':0', 08:24:54 INFO - 'HOME': '/home/cltbld', 08:24:54 INFO - 'LANG': 'en_US.UTF-8', 08:24:54 INFO - 'LANGUAGE': 'en_US:en', 08:24:54 INFO - 'LOGNAME': 'cltbld', 08:24:54 INFO - 'MAIL': '/var/mail/cltbld', 08:24:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:24:54 INFO - 'MOZ_NO_REMOTE': '1', 08:24:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:24:54 INFO - 'NO_EM_RESTART': '1', 08:24:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:24:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:24:54 INFO - 'PWD': '/builds/slave/test', 08:24:54 INFO - 'SHELL': '/bin/bash', 08:24:54 INFO - 'SHLVL': '1', 08:24:54 INFO - 'TERM': 'linux', 08:24:54 INFO - 'TMOUT': '86400', 08:24:54 INFO - 'USER': 'cltbld', 08:24:54 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1507814394.778953-131491975', 08:24:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:24:54 INFO - '_': '/tools/buildbot/bin/python'} 08:24:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:24:54 INFO - Downloading/unpacking psutil>=3.1.1 08:24:54 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 08:24:54 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 08:24:54 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 08:24:54 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 08:24:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:24:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:24:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:24:55 INFO - Installing collected packages: psutil 08:24:55 INFO - Running setup.py install for psutil 08:24:55 INFO - building 'psutil._psutil_linux' extension 08:24:55 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 08:24:57 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 08:24:57 INFO - building 'psutil._psutil_posix' extension 08:24:57 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 08:24:58 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 08:24:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:24:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:24:58 INFO - Successfully installed psutil 08:24:58 INFO - Cleaning up... 08:24:58 INFO - Return code: 0 08:24:58 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:24:58 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')]} 08:24:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:24:58 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')]} 08:24:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:24:58 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 0x1c8e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e56a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ff9a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ffd090>, '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 0x1ffdd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ff7f90>, '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': 'dbb0bb31bf4a4c7db9ba1648000001b6-1507814394.778953-131491975', '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 08:24:58 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 08:24:58 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 08:24:58 INFO - Using env: {'DISPLAY': ':0', 08:24:58 INFO - 'HOME': '/home/cltbld', 08:24:58 INFO - 'LANG': 'en_US.UTF-8', 08:24:58 INFO - 'LANGUAGE': 'en_US:en', 08:24:58 INFO - 'LOGNAME': 'cltbld', 08:24:58 INFO - 'MAIL': '/var/mail/cltbld', 08:24:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:24:58 INFO - 'MOZ_NO_REMOTE': '1', 08:24:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:24:58 INFO - 'NO_EM_RESTART': '1', 08:24:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:24:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:24:58 INFO - 'PWD': '/builds/slave/test', 08:24:58 INFO - 'SHELL': '/bin/bash', 08:24:58 INFO - 'SHLVL': '1', 08:24:58 INFO - 'TERM': 'linux', 08:24:58 INFO - 'TMOUT': '86400', 08:24:58 INFO - 'USER': 'cltbld', 08:24:58 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1507814394.778953-131491975', 08:24:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:24:58 INFO - '_': '/tools/buildbot/bin/python'} 08:24:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:24:58 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:24:58 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 08:24:58 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 08:24:58 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 08:24:58 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 08:24:59 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:24:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:24:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:24:59 INFO - Installing collected packages: mozsystemmonitor 08:24:59 INFO - Running setup.py install for mozsystemmonitor 08:24:59 INFO - Successfully installed mozsystemmonitor 08:24:59 INFO - Cleaning up... 08:24:59 INFO - Return code: 0 08:24:59 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:24:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:24:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:24:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:24:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:24:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1c8e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e56a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ff9a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ffd090>, '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 0x1ffdd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ff7f90>, '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': 'dbb0bb31bf4a4c7db9ba1648000001b6-1507814394.778953-131491975', '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 08:24:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:24:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:24:59 INFO - Using env: {'DISPLAY': ':0', 08:24:59 INFO - 'HOME': '/home/cltbld', 08:24:59 INFO - 'LANG': 'en_US.UTF-8', 08:24:59 INFO - 'LANGUAGE': 'en_US:en', 08:24:59 INFO - 'LOGNAME': 'cltbld', 08:24:59 INFO - 'MAIL': '/var/mail/cltbld', 08:24:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:24:59 INFO - 'MOZ_NO_REMOTE': '1', 08:24:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:24:59 INFO - 'NO_EM_RESTART': '1', 08:24:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:24:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:24:59 INFO - 'PWD': '/builds/slave/test', 08:24:59 INFO - 'SHELL': '/bin/bash', 08:24:59 INFO - 'SHLVL': '1', 08:24:59 INFO - 'TERM': 'linux', 08:24:59 INFO - 'TMOUT': '86400', 08:24:59 INFO - 'USER': 'cltbld', 08:24:59 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1507814394.778953-131491975', 08:24:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:24:59 INFO - '_': '/tools/buildbot/bin/python'} 08:24:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:24:59 INFO - Downloading/unpacking jsonschema==2.5.1 08:24:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:24:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:24:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:24:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:25:01 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:25:01 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:25:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:25:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:25:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:25:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:25:01 INFO - Downloading functools32-3.2.3-2.tar.gz 08:25:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:25:01 INFO - warning: no files found matching '*.txt' 08:25:01 INFO - no previously-included directories found matching 'build' 08:25:01 INFO - no previously-included directories found matching 'dist' 08:25:01 INFO - no previously-included directories found matching '.git*' 08:25:01 INFO - Installing collected packages: jsonschema, functools32 08:25:01 INFO - Running setup.py install for functools32 08:25:01 INFO - warning: no files found matching '*.txt' 08:25:01 INFO - no previously-included directories found matching 'build' 08:25:01 INFO - no previously-included directories found matching 'dist' 08:25:01 INFO - no previously-included directories found matching '.git*' 08:25:01 INFO - Successfully installed jsonschema functools32 08:25:01 INFO - Cleaning up... 08:25:01 INFO - Return code: 0 08:25:01 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:25:01 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')]} 08:25:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:25:01 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')]} 08:25:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:25:01 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 0x1c8e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e56a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ff9a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ffd090>, '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 0x1ffdd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ff7f90>, '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': 'dbb0bb31bf4a4c7db9ba1648000001b6-1507814394.778953-131491975', '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 08:25:01 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 08:25:01 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 08:25:01 INFO - Using env: {'DISPLAY': ':0', 08:25:01 INFO - 'HOME': '/home/cltbld', 08:25:01 INFO - 'LANG': 'en_US.UTF-8', 08:25:01 INFO - 'LANGUAGE': 'en_US:en', 08:25:01 INFO - 'LOGNAME': 'cltbld', 08:25:01 INFO - 'MAIL': '/var/mail/cltbld', 08:25:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:25:01 INFO - 'MOZ_NO_REMOTE': '1', 08:25:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:25:01 INFO - 'NO_EM_RESTART': '1', 08:25:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:25:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:25:01 INFO - 'PWD': '/builds/slave/test', 08:25:01 INFO - 'SHELL': '/bin/bash', 08:25:01 INFO - 'SHLVL': '1', 08:25:01 INFO - 'TERM': 'linux', 08:25:01 INFO - 'TMOUT': '86400', 08:25:01 INFO - 'USER': 'cltbld', 08:25:01 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1507814394.778953-131491975', 08:25:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:25:01 INFO - '_': '/tools/buildbot/bin/python'} 08:25:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:25:01 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:25:01 INFO - Cleaning up... 08:25:01 INFO - Return code: 0 08:25:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:25:01 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')]} 08:25:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:25:01 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')]} 08:25:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:25:01 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 0x1c8e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e56a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ff9a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ffd090>, '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 0x1ffdd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ff7f90>, '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': 'dbb0bb31bf4a4c7db9ba1648000001b6-1507814394.778953-131491975', '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 08:25:01 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 08:25:01 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 08:25:01 INFO - Using env: {'DISPLAY': ':0', 08:25:01 INFO - 'HOME': '/home/cltbld', 08:25:01 INFO - 'LANG': 'en_US.UTF-8', 08:25:01 INFO - 'LANGUAGE': 'en_US:en', 08:25:01 INFO - 'LOGNAME': 'cltbld', 08:25:01 INFO - 'MAIL': '/var/mail/cltbld', 08:25:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:25:01 INFO - 'MOZ_NO_REMOTE': '1', 08:25:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:25:01 INFO - 'NO_EM_RESTART': '1', 08:25:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:25:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:25:01 INFO - 'PWD': '/builds/slave/test', 08:25:01 INFO - 'SHELL': '/bin/bash', 08:25:01 INFO - 'SHLVL': '1', 08:25:01 INFO - 'TERM': 'linux', 08:25:01 INFO - 'TMOUT': '86400', 08:25:01 INFO - 'USER': 'cltbld', 08:25:01 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1507814394.778953-131491975', 08:25:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:25:01 INFO - '_': '/tools/buildbot/bin/python'} 08:25:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:25:01 INFO - Downloading/unpacking blobuploader==1.2.4 08:25:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:25:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:25:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:25:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:25:03 INFO - Downloading blobuploader-1.2.4.tar.gz 08:25:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:25:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:25:03 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 08:25:03 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 08:25:03 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 08:25:03 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 08:25:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:25:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:25:03 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 08:25:03 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 08:25:03 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 08:25:03 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 08:25:03 INFO - Downloading docopt-0.6.1.tar.gz 08:25:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:25:03 INFO - Installing collected packages: blobuploader, requests, docopt 08:25:03 INFO - Running setup.py install for blobuploader 08:25:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:25:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:25:03 INFO - Running setup.py install for requests 08:25:04 INFO - Running setup.py install for docopt 08:25:04 INFO - Successfully installed blobuploader requests docopt 08:25:04 INFO - Cleaning up... 08:25:04 INFO - Return code: 0 08:25:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:25:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:25:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:25:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:25:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:25:04 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 0x1c8e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e56a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ff9a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ffd090>, '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 0x1ffdd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ff7f90>, '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': 'dbb0bb31bf4a4c7db9ba1648000001b6-1507814394.778953-131491975', '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 08:25:04 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 08:25:04 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 08:25:04 INFO - Using env: {'DISPLAY': ':0', 08:25:04 INFO - 'HOME': '/home/cltbld', 08:25:04 INFO - 'LANG': 'en_US.UTF-8', 08:25:04 INFO - 'LANGUAGE': 'en_US:en', 08:25:04 INFO - 'LOGNAME': 'cltbld', 08:25:04 INFO - 'MAIL': '/var/mail/cltbld', 08:25:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:25:04 INFO - 'MOZ_NO_REMOTE': '1', 08:25:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:25:04 INFO - 'NO_EM_RESTART': '1', 08:25:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:25:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:25:04 INFO - 'PWD': '/builds/slave/test', 08:25:04 INFO - 'SHELL': '/bin/bash', 08:25:04 INFO - 'SHLVL': '1', 08:25:04 INFO - 'TERM': 'linux', 08:25:04 INFO - 'TMOUT': '86400', 08:25:04 INFO - 'USER': 'cltbld', 08:25:04 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1507814394.778953-131491975', 08:25:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:25:04 INFO - '_': '/tools/buildbot/bin/python'} 08:25:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:25:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:25:04 INFO - Running setup.py (path:/tmp/pip-jgeQzA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:25:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:25:04 INFO - Running setup.py (path:/tmp/pip-AgtIn1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:25:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:25:04 INFO - Running setup.py (path:/tmp/pip-Gs6i_d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:25:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:25:04 INFO - Running setup.py (path:/tmp/pip-jHydbn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:25:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:25:04 INFO - Running setup.py (path:/tmp/pip-f4oDBO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:25:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:25:04 INFO - Running setup.py (path:/tmp/pip-GbNZuT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:25:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:25:04 INFO - Running setup.py (path:/tmp/pip-m9N8_H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:25:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:25:04 INFO - Running setup.py (path:/tmp/pip-CfX1ps-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:25:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:25:04 INFO - Running setup.py (path:/tmp/pip-Mld_zE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:25:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:25:05 INFO - Running setup.py (path:/tmp/pip-0h_mED-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:25:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:25:05 INFO - Running setup.py (path:/tmp/pip-mDO6IS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:25:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:25:05 INFO - Running setup.py (path:/tmp/pip-VyUIy6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:25:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:25:05 INFO - Running setup.py (path:/tmp/pip-ox2sk8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:25:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:25:05 INFO - Running setup.py (path:/tmp/pip-ks9tw_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:25:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:25:05 INFO - Running setup.py (path:/tmp/pip-_sIaNC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:25:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:25:05 INFO - Running setup.py (path:/tmp/pip-oEtEfX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:25:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:25:05 INFO - Running setup.py (path:/tmp/pip-rB2WAB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:25:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:25:05 INFO - Running setup.py install for manifestparser 08:25:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:25:05 INFO - Running setup.py install for mozcrash 08:25:05 INFO - Running setup.py install for mozdebug 08:25:05 INFO - Running setup.py install for mozdevice 08:25:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:25:06 INFO - Running setup.py install for mozfile 08:25:06 INFO - Running setup.py install for mozhttpd 08:25:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:25:06 INFO - Running setup.py install for mozinfo 08:25:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:25:06 INFO - Running setup.py install for mozInstall 08:25:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:25:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:25:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:25:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:25:06 INFO - Running setup.py install for mozleak 08:25:06 INFO - Running setup.py install for mozlog 08:25:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:25:06 INFO - Running setup.py install for moznetwork 08:25:06 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:25:06 INFO - Running setup.py install for mozprocess 08:25:06 INFO - Running setup.py install for mozprofile 08:25:06 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:25:06 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:25:06 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:25:06 INFO - Running setup.py install for mozrunner 08:25:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:25:07 INFO - Running setup.py install for mozscreenshot 08:25:07 INFO - Running setup.py install for moztest 08:25:07 INFO - Running setup.py install for mozversion 08:25:07 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:25:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:25:07 INFO - Cleaning up... 08:25:07 INFO - Return code: 0 08:25:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:25:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:25:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:25:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:25:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:25:07 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 0x1c8e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e56a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ff9a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ffd090>, '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 0x1ffdd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ff7f90>, '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': 'dbb0bb31bf4a4c7db9ba1648000001b6-1507814394.778953-131491975', '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 08:25:07 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 08:25:07 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 08:25:07 INFO - Using env: {'DISPLAY': ':0', 08:25:07 INFO - 'HOME': '/home/cltbld', 08:25:07 INFO - 'LANG': 'en_US.UTF-8', 08:25:07 INFO - 'LANGUAGE': 'en_US:en', 08:25:07 INFO - 'LOGNAME': 'cltbld', 08:25:07 INFO - 'MAIL': '/var/mail/cltbld', 08:25:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:25:07 INFO - 'MOZ_NO_REMOTE': '1', 08:25:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:25:07 INFO - 'NO_EM_RESTART': '1', 08:25:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:25:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:25:07 INFO - 'PWD': '/builds/slave/test', 08:25:07 INFO - 'SHELL': '/bin/bash', 08:25:07 INFO - 'SHLVL': '1', 08:25:07 INFO - 'TERM': 'linux', 08:25:07 INFO - 'TMOUT': '86400', 08:25:07 INFO - 'USER': 'cltbld', 08:25:07 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1507814394.778953-131491975', 08:25:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:25:07 INFO - '_': '/tools/buildbot/bin/python'} 08:25:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:25:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:25:07 INFO - Running setup.py (path:/tmp/pip-vXN5WS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:25:07 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)) 08:25:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:25:07 INFO - Running setup.py (path:/tmp/pip-brdwT9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:25:07 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)) 08:25:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:25:07 INFO - Running setup.py (path:/tmp/pip-jYBP7h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:25:07 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)) 08:25:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:25:07 INFO - Running setup.py (path:/tmp/pip-OopDvn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:25:07 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)) 08:25:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:25:07 INFO - Running setup.py (path:/tmp/pip-9K7zVj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:25:07 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)) 08:25:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:25:07 INFO - Running setup.py (path:/tmp/pip-Yn2X3B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:25:08 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)) 08:25:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:25:08 INFO - Running setup.py (path:/tmp/pip-UK_Yn8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:25:08 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)) 08:25:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:25:08 INFO - Running setup.py (path:/tmp/pip-k7Dleh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:25:08 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)) 08:25:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:25:08 INFO - Running setup.py (path:/tmp/pip-UsCgMI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:25:08 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)) 08:25:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:25:08 INFO - Running setup.py (path:/tmp/pip-A0r0ws-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:25:08 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)) 08:25:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:25:08 INFO - Running setup.py (path:/tmp/pip-Buss8O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:25:08 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)) 08:25:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:25:08 INFO - Running setup.py (path:/tmp/pip-oNo7Iv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:25:08 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)) 08:25:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:25:08 INFO - Running setup.py (path:/tmp/pip-r8Vbrq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:25:08 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)) 08:25:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:25:08 INFO - Running setup.py (path:/tmp/pip-sF5jtW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:25:08 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)) 08:25:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:25:08 INFO - Running setup.py (path:/tmp/pip-W2fmvE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:25:08 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)) 08:25:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:25:08 INFO - Running setup.py (path:/tmp/pip-e45JjV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:25:08 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)) 08:25:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:25:08 INFO - Running setup.py (path:/tmp/pip-45KJO6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:25:08 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)) 08:25:08 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)) 08:25:08 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)) 08:25:08 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)) 08:25:08 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)) 08:25:08 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)) 08:25:08 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)) 08:25:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:25:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:25:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:25:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:25:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:25:09 INFO - Downloading blessings-1.6.tar.gz 08:25:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:25:10 INFO - Installing collected packages: blessings 08:25:10 INFO - Running setup.py install for blessings 08:25:10 INFO - Successfully installed blessings 08:25:10 INFO - Cleaning up... 08:25:10 INFO - Return code: 0 08:25:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:25:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:25:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:25:10 INFO - Reading from file tmpfile_stdout 08:25:10 INFO - Current package versions: 08:25:10 INFO - argparse == 1.2.1 08:25:10 INFO - blessings == 1.6 08:25:10 INFO - blobuploader == 1.2.4 08:25:10 INFO - docopt == 0.6.1 08:25:10 INFO - functools32 == 3.2.3-2 08:25:10 INFO - jsonschema == 2.5.1 08:25:10 INFO - manifestparser == 1.1 08:25:10 INFO - mozInstall == 1.13 08:25:10 INFO - mozcrash == 1.0 08:25:10 INFO - mozdebug == 0.1 08:25:10 INFO - mozdevice == 0.51 08:25:10 INFO - mozfile == 1.2 08:25:10 INFO - mozhttpd == 0.7 08:25:10 INFO - mozinfo == 0.10 08:25:10 INFO - mozleak == 0.1 08:25:10 INFO - mozlog == 3.5 08:25:10 INFO - moznetwork == 0.27 08:25:10 INFO - mozprocess == 0.25 08:25:10 INFO - mozprofile == 0.28 08:25:10 INFO - mozrunner == 6.13 08:25:10 INFO - mozscreenshot == 0.1 08:25:10 INFO - mozsystemmonitor == 0.3 08:25:10 INFO - moztest == 0.8 08:25:10 INFO - mozversion == 1.4 08:25:10 INFO - psutil == 3.1.1 08:25:10 INFO - requests == 1.2.3 08:25:10 INFO - wsgiref == 0.1.2 08:25:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:25:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:25:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:25:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:25:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:25:10 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 0x1c8e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e56a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ff9a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ffd090>, '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 0x1ffdd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ff7f90>, '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': 'dbb0bb31bf4a4c7db9ba1648000001b6-1507814394.778953-131491975', '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 08:25:10 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 08:25:10 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 08:25:10 INFO - Using env: {'DISPLAY': ':0', 08:25:10 INFO - 'HOME': '/home/cltbld', 08:25:10 INFO - 'LANG': 'en_US.UTF-8', 08:25:10 INFO - 'LANGUAGE': 'en_US:en', 08:25:10 INFO - 'LOGNAME': 'cltbld', 08:25:10 INFO - 'MAIL': '/var/mail/cltbld', 08:25:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:25:10 INFO - 'MOZ_NO_REMOTE': '1', 08:25:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:25:10 INFO - 'NO_EM_RESTART': '1', 08:25:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:25:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:25:10 INFO - 'PWD': '/builds/slave/test', 08:25:10 INFO - 'SHELL': '/bin/bash', 08:25:10 INFO - 'SHLVL': '1', 08:25:10 INFO - 'TERM': 'linux', 08:25:10 INFO - 'TMOUT': '86400', 08:25:10 INFO - 'USER': 'cltbld', 08:25:10 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1507814394.778953-131491975', 08:25:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:25:10 INFO - '_': '/tools/buildbot/bin/python'} 08:25:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:25:10 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)) 08:25:10 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)) 08:25:10 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)) 08:25:10 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)) 08:25:10 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)) 08:25:10 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)) 08:25:10 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)) 08:25:10 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)) 08:25:10 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)) 08:25:10 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 08:25:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:25:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:25:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:25:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:25:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 08:25:11 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)) 08:25:11 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)) 08:25:11 INFO - Installing collected packages: simplejson 08:25:11 INFO - Running setup.py install for simplejson 08:25:11 INFO - building 'simplejson._speedups' extension 08:25:11 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 08:25:12 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 08:25:12 INFO - Successfully installed simplejson 08:25:12 INFO - Cleaning up... 08:25:12 INFO - Return code: 0 08:25:12 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:25:12 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')]} 08:25:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:25:12 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')]} 08:25:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:25:12 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 0x1c8e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e56a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ff9a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ffd090>, '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 0x1ffdd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ff7f90>, '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': 'dbb0bb31bf4a4c7db9ba1648000001b6-1507814394.778953-131491975', '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 08:25:12 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 08:25:12 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 08:25:12 INFO - Using env: {'DISPLAY': ':0', 08:25:12 INFO - 'HOME': '/home/cltbld', 08:25:12 INFO - 'LANG': 'en_US.UTF-8', 08:25:12 INFO - 'LANGUAGE': 'en_US:en', 08:25:12 INFO - 'LOGNAME': 'cltbld', 08:25:12 INFO - 'MAIL': '/var/mail/cltbld', 08:25:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:25:12 INFO - 'MOZ_NO_REMOTE': '1', 08:25:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:25:12 INFO - 'NO_EM_RESTART': '1', 08:25:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:25:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:25:12 INFO - 'PWD': '/builds/slave/test', 08:25:12 INFO - 'SHELL': '/bin/bash', 08:25:12 INFO - 'SHLVL': '1', 08:25:12 INFO - 'TERM': 'linux', 08:25:12 INFO - 'TMOUT': '86400', 08:25:12 INFO - 'USER': 'cltbld', 08:25:12 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1507814394.778953-131491975', 08:25:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:25:12 INFO - '_': '/tools/buildbot/bin/python'} 08:25:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:25:13 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:25:13 INFO - Cleaning up... 08:25:13 INFO - Return code: 0 08:25:13 INFO - Running post-action listener: _resource_record_post_action 08:25:13 INFO - Running post-action listener: _start_resource_monitoring 08:25:13 INFO - Starting resource monitoring. 08:25:13 INFO - [mozharness: 2017-10-12 15:25:13.139521Z] Finished create-virtualenv step (success) 08:25:13 INFO - [mozharness: 2017-10-12 15:25:13.140592Z] Running install step. 08:25:13 INFO - Running pre-action listener: _resource_record_pre_action 08:25:13 INFO - Running main action method: install 08:25:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:25:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:25:13 INFO - Reading from file tmpfile_stdout 08:25:13 INFO - Detecting whether we're running mozinstall >=1.0... 08:25:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:25:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:25:13 INFO - Reading from file tmpfile_stdout 08:25:13 INFO - Output received: 08:25:13 INFO - Usage: mozinstall [options] installer 08:25:13 INFO - Options: 08:25:13 INFO - -h, --help show this help message and exit 08:25:13 INFO - -d DEST, --destination=DEST 08:25:13 INFO - Directory to install application into. [default: 08:25:13 INFO - "/builds/slave/test"] 08:25:13 INFO - --app=APP Application being installed. [default: firefox] 08:25:13 INFO - mkdir: /builds/slave/test/build/application 08:25:13 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'] 08:25:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 08:25:28 INFO - Reading from file tmpfile_stdout 08:25:28 INFO - Output received: 08:25:28 INFO - /builds/slave/test/build/application/firefox/firefox 08:25:28 INFO - Running post-action listener: _resource_record_post_action 08:25:28 INFO - [mozharness: 2017-10-12 15:25:28.325079Z] Finished install step (success) 08:25:28 INFO - [mozharness: 2017-10-12 15:25:28.325327Z] Running setup-mitmproxy step. 08:25:28 INFO - Running pre-action listener: _resource_record_pre_action 08:25:28 INFO - Running main action method: setup_mitmproxy 08:25:28 INFO - Skipping: mitmproxy is not required 08:25:28 INFO - Running post-action listener: _resource_record_post_action 08:25:28 INFO - [mozharness: 2017-10-12 15:25:28.326458Z] Finished setup-mitmproxy step (success) 08:25:28 INFO - [mozharness: 2017-10-12 15:25:28.326678Z] Running run-tests step. 08:25:28 INFO - Running pre-action listener: _resource_record_pre_action 08:25:28 INFO - Running pre-action listener: _set_gcov_prefix 08:25:28 INFO - Running main action method: run_tests 08:25:28 WARNING - Try message not found. 08:25:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:25:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:25:28 INFO - Python 2.7.3 08:25:28 INFO - Return code: 0 08:25:28 INFO - grabbing minidump binary from tooltool 08:25:28 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')]} 08:25:28 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 0x1ffd090>, '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 0x1ffdd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ff7f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:25:28 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 08:25:28 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 08:25:28 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 08:25:28 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:25:28 INFO - Return code: 0 08:25:28 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:25:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:25:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:25:28 INFO - ENV: RUST_BACKTRACE is now full 08:25:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:25:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:25:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eUlwW_TISOGJjYS0gNJqdw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-071', '--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 08:25:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/eUlwW_TISOGJjYS0gNJqdw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-071 --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 08:25:28 INFO - Using env: {'DISPLAY': ':0', 08:25:28 INFO - 'HOME': '/home/cltbld', 08:25:28 INFO - 'LANG': 'en_US.UTF-8', 08:25:28 INFO - 'LANGUAGE': 'en_US:en', 08:25:28 INFO - 'LOGNAME': 'cltbld', 08:25:28 INFO - 'MAIL': '/var/mail/cltbld', 08:25:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:25:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:25:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:25:28 INFO - 'MOZ_NO_REMOTE': '1', 08:25:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:25:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:25:28 INFO - 'NO_EM_RESTART': '1', 08:25:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:25:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:25:28 INFO - 'PWD': '/builds/slave/test', 08:25:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:25:28 INFO - 'RUST_BACKTRACE': 'full', 08:25:28 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 08:25:28 INFO - 'SHELL': '/bin/bash', 08:25:28 INFO - 'SHLVL': '1', 08:25:28 INFO - 'TERM': 'linux', 08:25:28 INFO - 'TMOUT': '86400', 08:25:28 INFO - 'USER': 'cltbld', 08:25:28 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1507814394.778953-131491975', 08:25:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:25:28 INFO - '_': '/tools/buildbot/bin/python'} 08:25:28 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eUlwW_TISOGJjYS0gNJqdw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-071', '--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 08:25:28 INFO - mozversion application_buildid: 20171012140558 08:25:28 INFO - mozversion application_changeset: f4ba99b411837e4b16b134cbab5bcc355ddd6b22 08:25:28 INFO - mozversion application_display_name: Firefox 08:25:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:25:28 INFO - mozversion application_name: Firefox 08:25:28 INFO - mozversion application_remotingname: firefox 08:25:28 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 08:25:28 INFO - mozversion application_vendor: Mozilla 08:25:28 INFO - mozversion application_version: 57.0 08:25:28 INFO - mozversion platform_buildid: 20171012140558 08:25:28 INFO - mozversion platform_changeset: f4ba99b411837e4b16b134cbab5bcc355ddd6b22 08:25:28 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 08:25:28 INFO - mozversion platform_version: 57.0 08:25:28 INFO - using testdate: 1507821928 08:25:28 INFO - actual date: 1507821928 08:25:28 INFO - starting webserver on 'localhost:42474' 08:25:28 INFO - SUITE-START | Running 2 tests 08:25:28 INFO - TEST-START | dromaeo_css 08:25:28 INFO - Initialising browser for dromaeo_css test... 08:25:28 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:42474/getInfo.html -profile /tmp/tmpElC3ZA/profile 08:25:28 INFO - TEST-INFO | started process 3783 (/builds/slave/test/build/application/firefox/firefox http://localhost:42474/getInfo.html) 08:25:35 INFO - TEST-INFO | 3783: exit 0 08:25:35 INFO - Browser initialized. 08:25:35 INFO - Running cycle 1/1 for dromaeo_css test... 08:25:35 INFO - Using env: {'DISPLAY': ':0', 08:25:35 INFO - 'HOME': '/home/cltbld', 08:25:35 INFO - 'JSGC_DISABLE_POISONING': '1', 08:25:35 INFO - 'LANG': 'en_US.UTF-8', 08:25:35 INFO - 'LANGUAGE': 'en_US:en', 08:25:35 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 08:25:35 INFO - 'LOGNAME': 'cltbld', 08:25:35 INFO - 'MAIL': '/var/mail/cltbld', 08:25:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:25:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:25:35 INFO - 'MOZ_CRASHREPORTER': '1', 08:25:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:25:35 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 08:25:35 INFO - 'MOZ_NO_REMOTE': '1', 08:25:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:25:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:25:35 INFO - 'NO_EM_RESTART': '1', 08:25:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:25:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:25:35 INFO - 'PWD': '/builds/slave/test', 08:25:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:25:35 INFO - 'RUST_BACKTRACE': 'full', 08:25:35 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 08:25:35 INFO - 'SHELL': '/bin/bash', 08:25:35 INFO - 'SHLVL': '1', 08:25:35 INFO - 'TERM': 'linux', 08:25:35 INFO - 'TMOUT': '86400', 08:25:35 INFO - 'USER': 'cltbld', 08:25:35 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1507814394.778953-131491975', 08:25:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:25:35 INFO - '_': '/tools/buildbot/bin/python'} 08:25:35 INFO - TEST-INFO | started process 4026 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpElC3ZA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 08:25:36 INFO - PID 4026 | 08:25:36 INFO - PID 4026 | (/builds/slave/test/build/application/firefox/firefox:4076): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 08:25:36 INFO - PID 4026 | 08:25:46 INFO - PID 4026 | RSS: Main: 141574144 08:25:46 INFO - PID 4026 | 08:28:43 INFO - PID 4026 | Cycle 1(1): loaded http://localhost:42474/tests/dromaeo/cssquery-dojo.html (next: http://localhost:42474/tests/dromaeo/cssquery-ext.html) 08:28:45 INFO - PID 4026 | RSS: Main: 145428480 08:28:45 INFO - PID 4026 | 08:31:46 INFO - PID 4026 | Cycle 1(1): loaded http://localhost:42474/tests/dromaeo/cssquery-ext.html (next: http://localhost:42474/tests/dromaeo/cssquery-jquery.html) 08:31:47 INFO - PID 4026 | RSS: Main: 143900672 08:31:47 INFO - PID 4026 | 08:34:48 INFO - PID 4026 | Cycle 1(1): loaded http://localhost:42474/tests/dromaeo/cssquery-jquery.html (next: http://localhost:42474/tests/dromaeo/cssquery-mootools.html) 08:34:49 INFO - PID 4026 | RSS: Main: 145022976 08:34:49 INFO - PID 4026 | 08:37:51 INFO - PID 4026 | Cycle 1(1): loaded http://localhost:42474/tests/dromaeo/cssquery-mootools.html (next: http://localhost:42474/tests/dromaeo/cssquery-prototype.html) 08:37:51 INFO - PID 4026 | RSS: Main: 144990208 08:37:51 INFO - PID 4026 | 08:40:54 INFO - PID 4026 | Cycle 1(1): loaded http://localhost:42474/tests/dromaeo/cssquery-prototype.html (next: http://localhost:42474/tests/dromaeo/cssquery-yui.html) 08:40:55 INFO - PID 4026 | RSS: Main: 146382848 08:40:55 INFO - PID 4026 | 08:43:56 INFO - PID 4026 | Cycle 1(1): loaded http://localhost:42474/tests/dromaeo/cssquery-yui.html (next: http://localhost:42474/tests/dromaeo/cssquery-dojo.html) 08:43:56 INFO - PID 4026 | RSS: Main: 149053440 08:43:56 INFO - PID 4026 | 08:43:56 INFO - PID 4026 | __start_tp_report 08:43:56 INFO - PID 4026 | _x_x_mozilla_page_load 08:43:56 INFO - PID 4026 | _x_x_mozilla_page_load_details 08:43:56 INFO - PID 4026 | |i|pagename|runs| 08:43:56 INFO - PID 4026 | |0;dojo.html;4045.494313210849;4051.6758659307256;4064.004318941856;4070.409790580366;4097.651699605533;6494.415502604765;6533.18335208099;6536.117624120742;6588.044733513638;6653.767118150872;6467.237744988116;6479.967600162005;6537.6731163441755;6566.63883486408;6617.636030018347;5809.070548712207;5873.089671100986;5953.3748956359605;5955.731992060357;5963.344032156459;5876.08920617305;5881.705914704269;5903.793367232148;5976.252968378953;6012.518998480133;5971;5978.8804223915495;5979.910301345483;6011.699415029231;6030.909536356936;5938.940610593882;5989.610675306135;5996.8500787480225;6022.187004754368;6040.57715959883;5966.791162309299;5978.073398623221;6009.489193418564;6037.366076561985;6037.667928263943;6513.739450422025;6567.770128045496;6651.434628056621;6691.397774200345;6725.09211256479;7010.719571217145;7049.506534542584;7076.292370762934;7090.610016449094;7190.532615380036;6956.373926346653;7046.1192350956135;7046.365827075588;7084.256153103953;7160.427165826722;6665.633390163539;6714.496412769062;6714.899276510856;6731.0913026741255;6748.089007983957;7323.963380183065;7328.67020984057;7415.1472580652835;7443.2184620615135;7475.0656167979005;7032.226455089936;7036.92963070373;7050.083489146378;7101.396381307543;7161.283871612901;7066.470014748812;7178.497505174692;7211.639418029077;7256.129264488295;7266.527675701062;7199.496035277585;7219.747308844166;7237.348638622444;7238.348548630648;7239.674214660353;7140.321669441395;7151.463640227003;7226;7231.819204519824;7236.96381518089;5239.895202095937;5271.42014378418;5288.68267903927;5301.946980530222;5345.572354211654;4802.423709154924;4849.515048495193;4887.926681099786;4908.4846091160625;4910.140725373046;6739.663016849138;6744.393004629607;6810.387065164159;6825.829354266084;6831.351021652935;7098.751543695946;7201.3158749918675;7212.531185472927;7299.233580473974;7302.634868256566;6678.165229346332;6771.830704232433;6781.525293229427;6794.8301292467095;6798.082258895085;6001.879962400728;6003.7898673546215;6057.091436284504;6077.726502307319;6087.878242435215;7406.592637404887;7448.59032753193;7487.513311634726;7506.061742282215;7547.698092076255;7149.82125446868;7170.605616691132;7229.240929702306;7250.492465527363;7253.455990800817;7410.296021877913;7452.478326517092;7459.627018648937;7504.174540800398;7523.285287897641;7045.330693584101;7051.43588512928;7055.18865330473;7128.322809333105;7133.322334378226;7015.579065256101;7156.033935418652;7187.892181617384;7238.348548630648;7283.927160728324;6853.691583878837;6857.245702972768;6867.690953906987;6883.414909732729;6895.138107736533;6941;6992.370686638227;7002.929970700432;7004.7198112076985;7012.053372794608;6820.590764554143;6835.863282734417;6844.863102738017;6919.065926099912;6933.445324373937;6850.862982740217;6966.12923384577;6979.371856532936;7030.543014704028;7054.259302773135;6806.217285012089;6821.147356580427;6828.385445309946;6831.863362732618;6850.006749021264 08:43:56 INFO - PID 4026 | |1;ext.html;968.3415277611226;993.7863359377733;1018.8318927376982;1043.6190790361516;1044.2220545693458;45942.77028614857;46553.30170047452;46747;46764.76617616907;46800;43735.78132109343;44327.55672443275;44562.554374456246;44565.777171114096;44579.33131003038;8158.592070396471;8326.750197494084;8327.167283271685;8350.958245208765;8354.665813367488;7845.137034926153;7941.404394670394;7959.602019898982;7960.960195199016;7966.681332746683;8939.463632182058;9077.818443631124;9092.63629454821;9093.454392736425;9094.226990705798;8894.911050889505;8942.284617230605;9005.369624126313;9011.95494022529;9015.27877769777;8874.55627218643;8944.731658050205;8966.955165224132;8967.865482017774;8972.641094356282;41307.7934610325;41839;41898.581014189775;41970.580294196974;42003.579964200275;3933.3116704576582;3955;3959.089409435846;3967.0677390813134;3975.5030621172355;34567.82716086428;34689.653103468896;34750.30499390023;34910.82544587261;34929;5738.368779434259;5741.3110426748635;5749.626274292199;5820.476157145878;5866;235069;235321;235864;236790;237517;53871;54616;54903.450965490636;55013.72493137589;55029.72485137629;59522;59564.70217648884;59638;59644.105338419955;59950.700246499364;19083.713744293847;19145.13846876894;19218.711719324223;19271.13279902408;19284.8071519283;15864.365425383086;15962.441314553935;15994.360225590846;16017.359305627644;16017.519474415787;15471.845281547265;15582.376704931903;15602.765958510632;15686.215689215494;15705.21473926322;5934.732937017845;5969.224000879858;6017.969910150508;6036.094585812163;6056.182415373956;1130.8925652063042;1131.1911982932243;1137.5961533655434;1138.248755821154;1145.954161833534;27196.320091997462;27547.86226068857;27606.723932760415;27780.861095694392;27871.86064069667;1230.883066108736;1235.2403271987841;1240.7704574653717;1250.4498020870788;1267.6830792301926;14348.713025739427;14448;14630.049046811922;14660.780088298685;14909;305393;306112;306233;307676;307932;63601;67310.3268967304;68610;68699.31300686928;68811.65594171998;1563.9452619158276;1564.8982816117143;1587.8253392126858;1588.110658031506;1600.2318886934336;31873.840630796698;32173.67826321707;32287.51568726518;32414;32866.67133328732;45863.31205031994;46283.76858115688;46336.53663463322;46455;46519;260723;260760;261435;261849;262027;40384.1923161541;40758.38862417125;40763;40934.59065409308;41042;50111.74944125256;50818;50850.74574627103;51257.48742512527;51400.74299628627;36444.351335039275;36563.26873462569;36628.81685591555;36787.44818827666;37355.252894941405;36433.27133457263;36915.63084369122;36939.26121477609;37183.44224836684;37310.04175344506;43012;43246;43536.34695479634;43737.78131109324;43883.561164389226;34373;35112.297754045285;35249.295014100084;35319.11702207466;35810.8209458951;26082.60876086822;26595.202143935712;26775.330616734736;27312.72687273181;27446.86276568684 08:43:56 INFO - PID 4026 | |2;jquery.html;630.7508534129016;641.4964253061345;644.8742495213434;651.3291309950753;654.4666097130838;24692.629610555858;25160.496790064186;25580.488390232185;25725.614115788256;25823.870880645616;25857.612135817977;26708.866455667743;26900.865495672493;26910.865445672793;26969.32576685584;6518.08746775453;6718;6719.798406047827;6725.562838415512;6728.394444499994;6272.435480806726;6575.342465753434;6578.506612004119;6605.735770569171;6626.469882409396;6591.538592298517;6593.736250549996;6594.373534514238;6598.076269322299;6605.405513503783;6570;6590.604563726191;6601.537892347537;6603.570767900095;6603.735850565971;6174.073888916653;6191.473724733403;6192.133101365813;6192.28788689302;6195.1946246988055;29807;30007;30151;30304;30320.84839575788;2414.9275521734376;2450.3261603059123;2452.7792498675203;2454.8281620286407;2458.840175388612;20349.18603255868;20832.062557184963;20956.266530671353;21003.474913127142;21032.5793484131;2578.2136448383235;2583.573710337792;2654.734526547349;2661.8535980521065;2665.4402575458985;139980.10069647615;147826;162372;167568.32431675648;168068;16269.18654067292;16314.510564683078;16341.937774044649;16562;16814.15929203535;26379.472410552065;26505.204843854328;26547.469050618878;26572.601410978852;26575.867120664272;11365.31808091517;11559.595414160633;11588.188826782045;11757;11786.116041296937;10777.407242601732;10786.73033174177;10810.67567972962;10826.188035897181;10830.241883068267;9699;9762.804743905082;10001.949990250003;10021.248406369552;10028.147607453302;2880.812747171427;2894.247495651146;2961.4077184563175;3001.369712360429;3025.440293545701;1063.9361638301727;1091.5742860284495;1098.7363032872054;1105.2152971390403;1115.9888401116048;22024.779752202274;22039;22085.116595336327;22383.664245036336;22417.775822241896;1154.05944155513;1158.652404278713;1166.9474873630706;1168.3340495917416;1178.116412690482;11929.522819087313;11945.58190463347;11946.581869634521;11987.580434684744;12267.754644907052;658801;675748;675930;680188;691341;138680;142581;142861;144192;145660;1282.948682052726;1285.5886116442648;1319.9736005280033;1331.2012792324529;1333.06019256424;19704.40886773399;19725.70411443801;19792.505187370432;19917.701234481203;20036.699449507978;66774;67813.3218667807;68455.65772171108;68990;69057;81530;81618.59190704009;81731.18268817235;82809.58595206986;86463.56768216119;15972.281247343664;15999.360025598846;16120.838791612403;16128.758068628746;16308.510744677678;24501.754982449947;24525.141620043214;24578.50842983166;24986.875065624554;25060.373490662878;20276.695849562562;20394;20410.18359265613;20662.483437914172;20722.378328650164;17833.46499605014;18118.27526898909;18626.81373186251;18646.906765466087;18664.533386665444;19271.1327990238;19371.3220037298;19555.120019598868;19721.605567888848;19735.703964440258;15089.54731358061;15093.849061509245;15133;15188;15246.313915874034;20112.195512179933;20123.39629811108;20139.496512587302;20331.898340508204;20383.388498345073 08:43:56 INFO - PID 4026 | |3;mootools.html;1462.4661998370595;1514.401811284542;1514.4396573267893;1515.4392874636387;1527.8395768444313;17343.132843357827;18039.549011274725;18043.909780451097;18055.729164062515;18069.1868865901;17364;17472.301107955667;17590.560235994108;17664.646707065884;17713.645727085484;2886.610307608467;2930.9853450732717;2957.423302456022;2957.8964736234234;2962.822230666156;2865.369618683886;2884.6105775720325;2892.2335581071034;2894.6526416829906;2906.72386123319;2838.6451693538306;2906.1426879070723;2934.0464349086633;2945.970540294602;2948.041886386922;2951.837648929308;2965.0808249442744;2975.8065725727865;2981;2981.2993946422575;2781.3324802047364;2782.38787466757;2797.2447439191305;2805.1864959161817;2805.579163125527;17068.743968840477;17213.311467541407;17218.655626887517;17275.74086388705;17362.305507779674;8705.390622656416;8987.236084932789;8994.910050899472;9004.324675649286;9004.954975225148;5539.435763718709;5893.675847828367;5978.611390259619;6106.175666285039;6220.564560480768;1987.4435158155738;2006.8996550172433;2011.4267433781301;2035.1655821113272;2081.8230450411734;31572.36855262905;31701;31724.682753172405;31774.523382149055;31807;7084.610346430995;7257.201707812242;7356.1540422850985;7370.189279179286;7552.358049565739;19642.60714785696;19654.803451965297;19991.800081999285;19994.600107997758;20003.799962000485;8379.874301885477;8750.737477875675;8860.911390886009;8864.290856731446;8925.910740892508;8075.919240807634;8146.75559733209;8160.591970401575;8175.918240817516;8200.26197642203;6089.117078023662;6267.341929097471;6416;6500.902486462707;6513.088167656532;1713.3831820544594;1762.4007837335369;1788;1789.3468883857558;1790.51656052865;1029.351508549609;1039.2205845615788;1040.458961340099;1049.8372752223418;1064.505005172583;8911.955440222888;8966.775830604156;9021.052789457013;9156.313276504288;9211.585478653476;1105.1932089574655;1111.7609713911386;1119.462657924201;1121.7644294698205;1126.915481338903;5564.777408903679;5603.495685388335;5654.095344744821;5654.378018418053;5675.063614503643;25815;26879.731202687723;27047.86476067607;27340.58989115165;27416.451670966868;25637.23088307354;26371.604425933627;26520.33699157498;26601.600975985377;26789.19632410992;13118.737625247442;13155.210687358789;13271.469141234436;13324.400401981935;13340.666483337995;9773.8760042596;9993.300468967393;10062.446565438971;10070.69787906364;10092.293539452168;22069.668954966008;22071.448213794785;22308.77691223132;22565.77434225637;22805.429864253525;23240.65139022882;23508;23681.64477532873;23782.16762413307;24024;12875.80686289725;12878.227306361649;12891;13081.607551773463;13085.149465284727;16022.198890055684;16053.678926421173;16145;16199.514014579581;16209;11619;11708.473118709508;11719.296842189497;11814.881851181379;12082.516699331929;12512.687182820502;12601.24392536414;12613.30626815534;12635.620931372074;12727.809082863949;10676.786464270826;10816.242862999829;10938.890611094106;10953.233273670767;10964.451777411257;8131.552764598004;8197.754067377988;8215.794605134919;8227.79430514218;8290.668373265002;8678.262347700516;8747.081556436737;8776.56117194125;8782.604782784661;8806.295496360404 08:43:56 INFO - PID 4026 | |4;prototype.html;570.9771609135632;571.3829064610221;582.1994757208838;612.3142080869424;613.5521069619178;6438.8088203682355;6508.414242718154;6539.2479864815505;6591.868162636744;6597.472202223823;6395.424411802936;6423.614583125016;6441.935580644192;6490.318516555764;6490.448311893483;5303.124984377573;5333.439988801188;5373;5383.650062745909;5388.461153884619;5036.395632524102;5251.5798736101215;5293.417724050351;5391.892162156754;5414.88994756074;5153.6392452528335;5159.638825282232;5176.145935920568;5191.988646584971;5200.193969934666;5121.923171152435;5140.228965655144;5154.453663900834;5175.560077393388;5202.609804264676;4711.505291944365;4720.244760838284;4730.95269047312;4739.715617062987;4747.7388743619085;6325.810225693236;6330.778422755227;6382.1384113144595;6390.201224846895;6439.4848412127385;6082.513398928075;6099.329073801922;6125.173101631267;6130.509559235251;6137.723802428902;6700.195976482853;6728.394444500019;6804.421624161952;6836.965815170942;6911;5041.420236672793;5166.61250406221;5237.947620523784;5267.3942496613;5271.209318602202;6907.827304317432;7145;7146.356827885464;7165.390941769956;7191.999999999947;5333.759980800874;5391.137418013099;5432.91850622241;5457.208704737791;5480.671159730429;5509.311336082989;5544.833654990357;5551.805686800943;5567.387587365387;5568;6092.664903430265;6095.847603809941;6121.204243448323;6123.693815309217;6132.363083873888;6888.483363747739;6907.171139463297;6913.51605387618;6914.135733033371;6961.500414672352;1861.088066847235;1873.278787666758;1905.4093231098404;1909.9140538675792;1911.1972971351788;1291.3026965438746;1341.5304643374936;1368.6988862450246;1371.451419432235;1373.6565858535366;6507.251666058369;6549.7380104795275;6589;6589.439897608757;6598.307177746364;6884.793456196322;6945;6996.755113571001;6997.195322537871;7041.753538626123;6443.710033048524;6501.967490162613;6539.280679125292;6584.7695330664155;6602.867942641216;3657.213699054716;3679.4296883983025;3704.6110158433517;3726.5838981187517;3736.9065773355883;7247.23903990084;7285.526440781332;7324.523905946203;7335.926640733524;7383.6677349519405;7207.279272072751;7240.52936559122;7241.45689073311;7267.418606511466;7307.123145222501;7193.244709305448;7246.601436920914;7284.417246620364;7289.671964761598;7299.343059124704;6967.930320696728;7007.7196912122945;7019.684114214975;7020.438364930896;7040.119985001875;6897.448204143757;6912.55068420551;6912.861742765016;6944.965275173592;6956.756513522002;7242.565446073252;7273.236310187566;7326.780196594111;7358.558486490828;7359.632018398952;5405.486478784509;5450.5912056595125;5460.836174914759;5466.72666366688;5491.313585801775;6804.965975170093;6934.583924964519;6940.375366217065;7009.264027277266;7076.858462830817;6671.132752742113;6694.196696396269;6729.764458243938;6755.493337999768;6823.28355522663;6728.495362847708;6773.05177275172;6810.489213308922;6810.693518791765;6815.659217039228;6712.765053223114;6773.119494465688;6811.7275308989065;6828.624425656637;6846.7945961621235;6822.522423430312;6836;6881.380675739208;6892.450388050259;6904.620245886324;6442.903356449563;6617.239017513048;6702.564333318319;6744.123263975652;6764.932350676627 08:43:56 INFO - PID 4026 | |5;yui.html;1005.6429967361582;1034.9223808214385;1047.2459828923172;1050.2963014780096;1064.3507460449125;22235;22750.88624556877;22785.430364240903;22799.544009119807;22814.771852281472;20329.390118296476;20552.486187845312;20751.858647774367;20863;20916.058777355018;1073.6993641780296;1103.22222832903;1104.2104895000073;1106.6292791914677;1106.6458733205377;1075.2849355178835;1105.4583254205422;1113.62136873463;1122.691259903525;1127.0870594818182;1169.6724917023248;1169.7368092179277;1169.9824502632425;1171.197729555253;1171.2503997441645;1168.5617893290016;1169.0355456748132;1169.2516789254885;1177.682025853015;1181.1082632612365;862;862.2627653356416;862.3360012790179;863.4905405810601;867.9132086791334;22127.668084978737;22663;22688.432789180402;22907.885460572757;22931.656025159635;24254.393640159138;24520;24704;24729.752702472924;24846.254612361477;19220.519487012796;19504.512387190432;19521.414357569294;19624.803751962583;19695.507612309808;4079.9796001019804;4104.774237416941;4106.691998100125;4108.958910410887;4109.589041095896;143128;143745;144733;145438;146411.99999999892;18647.90676046611;19097.61804763897;19201.231950722093;19241.807581924;19261.325853595055;36108.81945590255;36258.637413625525;36298.637013630054;36398;36466;9284.814303713887;9371.250299975985;9387.624495020124;9389.718308450621;9422.575984080733;4778.4982576829625;4952.777125029383;4990.950090499121;4996.7001979881325;4997.425296090923;6230.719617617127;6305.369463053658;6356.110144579762;6358.523110766619;6378.808635740936;1164.7495788405533;1176.9176157669053;1191.53530123252;1201.4473342262656;1208.516593362662;765.9961700191463;773.331068625646;784.4156103702778;792.7344339646168;795.5187111797395;13357.732845343038;13412.731745365038;14123.505677301246;14444.205568693613;14548.563543093724;773.2769860180749;775.7362496751132;779.6881247500932;782.22950393862;802.8233788566506;6645.1361323027695;6660.666966651648;6685.899711504331;6713.160854893138;6768.526203165731;146563;151375;151603;153152.2342388281;158701;107936;109208;132795.33602331925;135843.32078339544;140611.29694351464;114922;115510;117301;121244.39377803054;124543;14869.47956821506;14917.403303868157;14923.253837307873;15049.924750376178;15195.176578810613;55875.720621396635;57122.42877571171;57832.4216757827;58488.707556461944;58733.70633146807;70693.29306706868;72096.27903721106;72493;73455.26544734485;73880.26119738734;21803.345899623037;22008.88995555012;22226.555468890856;22263.66604500966;22295.665565016214;24911.377215569755;25000;25025.124120655688;25102;25323.493530128926;20423.080961356478;20582.38252852417;20603.896980515;20613.484662883548;20972.68540971917;21553.568928621655;21562.8921855396;21761.891190543945;21781.23765668194;21944.561108778053;21211.68182477295;21213.681794773398;21484.46288842729;21549.46126346791;21580;14635.487757928422;14639.634009149431;14698;14704.191269480281;14874.628134296296;15221.543353699406;15223.695526089195;15262.771058433911;15355;15360.69278614399 08:43:56 INFO - PID 4026 | __end_tp_report 08:43:56 INFO - PID 4026 | __start_cc_report 08:43:56 INFO - PID 4026 | _x_x_mozilla_cycle_collect,261 08:43:56 INFO - PID 4026 | __end_cc_report 08:43:56 INFO - PID 4026 | __startTimestamp1507823036870__endTimestamp 08:43:56 INFO - PID 4026 | 08:43:56 INFO - PID 4026 | ------- Summary: start ------- 08:43:56 INFO - PID 4026 | Number of tests: 6 08:43:56 INFO - PID 4026 | 08:43:56 INFO - PID 4026 | [#0] dojo.html Cycles:165 Average:6621.85 Median:6850.01 stddev:768.06 (11.2%) stddev-sans-first:743.35 08:43:56 INFO - PID 4026 | Values: 4045.5 4051.7 4064.0 4070.4 4097.7 6494.4 6533.2 6536.1 6588.0 6653.8 6467.2 6480.0 6537.7 6566.6 6617.6 5809.1 5873.1 5953.4 5955.7 5963.3 5876.1 5881.7 5903.8 5976.3 6012.5 5971.0 5978.9 5979.9 6011.7 6030.9 5938.9 5989.6 5996.9 6022.2 6040.6 5966.8 5978.1 6009.5 6037.4 6037.7 6513.7 6567.8 6651.4 6691.4 6725.1 7010.7 7049.5 7076.3 7090.6 7190.5 6956.4 7046.1 7046.4 7084.3 7160.4 6665.6 6714.5 6714.9 6731.1 6748.1 7324.0 7328.7 7415.1 7443.2 7475.1 7032.2 7036.9 7050.1 7101.4 7161.3 7066.5 7178.5 7211.6 7256.1 7266.5 7199.5 7219.7 7237.3 7238.3 7239.7 7140.3 7151.5 7226.0 7231.8 7237.0 5239.9 5271.4 5288.7 5301.9 5345.6 4802.4 4849.5 4887.9 4908.5 4910.1 6739.7 6744.4 6810.4 6825.8 6831.4 7098.8 7201.3 7212.5 7299.2 7302.6 6678.2 6771.8 6781.5 6794.8 6798.1 6001.9 6003.8 6057.1 6077.7 6087.9 7406.6 7448.6 7487.5 7506.1 7547.7 7149.8 7170.6 7229.2 7250.5 7253.5 7410.3 7452.5 7459.6 7504.2 7523.3 7045.3 7051.4 7055.2 7128.3 7133.3 7015.6 7156.0 7187.9 7238.3 7283.9 6853.7 6857.2 6867.7 6883.4 6895.1 6941.0 6992.4 7002.9 7004.7 7012.1 6820.6 6835.9 6844.9 6919.1 6933.4 6850.9 6966.1 6979.4 7030.5 7054.3 6806.2 6821.1 6828.4 6831.9 6850.0 08:43:56 INFO - PID 4026 | 08:43:56 INFO - PID 4026 | [#1] ext.html Cycles:180 Average:46105.43 Median:3963.08 stddev:70132.55 (1769.6%) stddev-sans-first:70247.40 08:43:56 INFO - PID 4026 | Values: 968.3 993.8 1018.8 1043.6 1044.2 45942.8 46553.3 46747.0 46764.8 46800.0 43735.8 44327.6 44562.6 44565.8 44579.3 8158.6 8326.8 8327.2 8351.0 8354.7 7845.1 7941.4 7959.6 7961.0 7966.7 8939.5 9077.8 9092.6 9093.5 9094.2 8894.9 8942.3 9005.4 9012.0 9015.3 8874.6 8944.7 8967.0 8967.9 8972.6 41307.8 41839.0 41898.6 41970.6 42003.6 3933.3 3955.0 3959.1 3967.1 3975.5 34567.8 34689.7 34750.3 34910.8 34929.0 5738.4 5741.3 5749.6 5820.5 5866.0 235069.0 235321.0 235864.0 236790.0 237517.0 53871.0 54616.0 54903.5 55013.7 55029.7 59522.0 59564.7 59638.0 59644.1 59950.7 19083.7 19145.1 19218.7 19271.1 19284.8 15864.4 15962.4 15994.4 16017.4 16017.5 15471.8 15582.4 15602.8 15686.2 15705.2 5934.7 5969.2 6018.0 6036.1 6056.2 1130.9 1131.2 1137.6 1138.2 1146.0 27196.3 27547.9 27606.7 27780.9 27871.9 1230.9 1235.2 1240.8 1250.4 1267.7 14348.7 14448.0 14630.0 14660.8 14909.0 305393.0 306112.0 306233.0 307676.0 307932.0 63601.0 67310.3 68610.0 68699.3 68811.7 1563.9 1564.9 1587.8 1588.1 1600.2 31873.8 32173.7 32287.5 32414.0 32866.7 45863.3 46283.8 46336.5 46455.0 46519.0 260723.0 260760.0 261435.0 261849.0 262027.0 40384.2 40758.4 40763.0 40934.6 41042.0 50111.7 50818.0 50850.7 51257.5 51400.7 36444.4 36563.3 36628.8 36787.4 37355.3 36433.3 36915.6 36939.3 37183.4 37310.0 43012.0 43246.0 43536.3 43737.8 43883.6 34373.0 35112.3 35249.3 35319.1 35810.8 26082.6 26595.2 26775.3 27312.7 27446.9 08:43:56 INFO - PID 4026 | 08:43:56 INFO - PID 4026 | [#2] jquery.html Cycles:180 Average:42755.76 Median:22234.39 stddev:112922.15 (507.9%) stddev-sans-first:113194.63 08:43:56 INFO - PID 4026 | Values: 630.8 641.5 644.9 651.3 654.5 24692.6 25160.5 25580.5 25725.6 25823.9 25857.6 26708.9 26900.9 26910.9 26969.3 6518.1 6718.0 6719.8 6725.6 6728.4 6272.4 6575.3 6578.5 6605.7 6626.5 6591.5 6593.7 6594.4 6598.1 6605.4 6570.0 6590.6 6601.5 6603.6 6603.7 6174.1 6191.5 6192.1 6192.3 6195.2 29807.0 30007.0 30151.0 30304.0 30320.8 2414.9 2450.3 2452.8 2454.8 2458.8 20349.2 20832.1 20956.3 21003.5 21032.6 2578.2 2583.6 2654.7 2661.9 2665.4 139980.1 147826.0 162372.0 167568.3 168068.0 16269.2 16314.5 16341.9 16562.0 16814.2 26379.5 26505.2 26547.5 26572.6 26575.9 11365.3 11559.6 11588.2 11757.0 11786.1 10777.4 10786.7 10810.7 10826.2 10830.2 9699.0 9762.8 10001.9 10021.2 10028.1 2880.8 2894.2 2961.4 3001.4 3025.4 1063.9 1091.6 1098.7 1105.2 1116.0 22024.8 22039.0 22085.1 22383.7 22417.8 1154.1 1158.7 1166.9 1168.3 1178.1 11929.5 11945.6 11946.6 11987.6 12267.8 658801.0 675748.0 675930.0 680188.0 691341.0 138680.0 142581.0 142861.0 144192.0 145660.0 1282.9 1285.6 1320.0 1331.2 1333.1 19704.4 19725.7 19792.5 19917.7 20036.7 66774.0 67813.3 68455.7 68990.0 69057.0 81530.0 81618.6 81731.2 82809.6 86463.6 15972.3 15999.4 16120.8 16128.8 16308.5 24501.8 24525.1 24578.5 24986.9 25060.4 20276.7 20394.0 20410.2 20662.5 20722.4 17833.5 18118.3 18626.8 18646.9 18664.5 19271.1 19371.3 19555.1 19721.6 19735.7 15089.5 15093.8 15133.0 15188.0 15246.3 20112.2 20123.4 20139.5 20331.9 20383.4 08:43:56 INFO - PID 4026 | 08:43:56 INFO - PID 4026 | [#3] mootools.html Cycles:180 Average:10840.97 Median:26445.97 stddev:8118.07 (30.7%) stddev-sans-first:8110.26 08:43:56 INFO - PID 4026 | Values: 1462.5 1514.4 1514.4 1515.4 1527.8 17343.1 18039.5 18043.9 18055.7 18069.2 17364.0 17472.3 17590.6 17664.6 17713.6 2886.6 2931.0 2957.4 2957.9 2962.8 2865.4 2884.6 2892.2 2894.7 2906.7 2838.6 2906.1 2934.0 2946.0 2948.0 2951.8 2965.1 2975.8 2981.0 2981.3 2781.3 2782.4 2797.2 2805.2 2805.6 17068.7 17213.3 17218.7 17275.7 17362.3 8705.4 8987.2 8994.9 9004.3 9005.0 5539.4 5893.7 5978.6 6106.2 6220.6 1987.4 2006.9 2011.4 2035.2 2081.8 31572.4 31701.0 31724.7 31774.5 31807.0 7084.6 7257.2 7356.2 7370.2 7552.4 19642.6 19654.8 19991.8 19994.6 20003.8 8379.9 8750.7 8860.9 8864.3 8925.9 8075.9 8146.8 8160.6 8175.9 8200.3 6089.1 6267.3 6416.0 6500.9 6513.1 1713.4 1762.4 1788.0 1789.3 1790.5 1029.4 1039.2 1040.5 1049.8 1064.5 8912.0 8966.8 9021.1 9156.3 9211.6 1105.2 1111.8 1119.5 1121.8 1126.9 5564.8 5603.5 5654.1 5654.4 5675.1 25815.0 26879.7 27047.9 27340.6 27416.5 25637.2 26371.6 26520.3 26601.6 26789.2 13118.7 13155.2 13271.5 13324.4 13340.7 9773.9 9993.3 10062.4 10070.7 10092.3 22069.7 22071.4 22308.8 22565.8 22805.4 23240.7 23508.0 23681.6 23782.2 24024.0 12875.8 12878.2 12891.0 13081.6 13085.1 16022.2 16053.7 16145.0 16199.5 16209.0 11619.0 11708.5 11719.3 11814.9 12082.5 12512.7 12601.2 12613.3 12635.6 12727.8 10676.8 10816.2 10938.9 10953.2 10964.5 8131.6 8197.8 8215.8 8227.8 8290.7 8678.3 8747.1 8776.6 8782.6 8806.3 08:43:56 INFO - PID 4026 | 08:43:56 INFO - PID 4026 | [#4] prototype.html Cycles:180 Average:5866.85 Median:6523.83 stddev:1630.97 (25.0%) stddev-sans-first:1586.37 08:43:56 INFO - PID 4026 | Values: 571.0 571.4 582.2 612.3 613.6 6438.8 6508.4 6539.2 6591.9 6597.5 6395.4 6423.6 6441.9 6490.3 6490.4 5303.1 5333.4 5373.0 5383.7 5388.5 5036.4 5251.6 5293.4 5391.9 5414.9 5153.6 5159.6 5176.1 5192.0 5200.2 5121.9 5140.2 5154.5 5175.6 5202.6 4711.5 4720.2 4731.0 4739.7 4747.7 6325.8 6330.8 6382.1 6390.2 6439.5 6082.5 6099.3 6125.2 6130.5 6137.7 6700.2 6728.4 6804.4 6837.0 6911.0 5041.4 5166.6 5237.9 5267.4 5271.2 6907.8 7145.0 7146.4 7165.4 7192.0 5333.8 5391.1 5432.9 5457.2 5480.7 5509.3 5544.8 5551.8 5567.4 5568.0 6092.7 6095.8 6121.2 6123.7 6132.4 6888.5 6907.2 6913.5 6914.1 6961.5 1861.1 1873.3 1905.4 1909.9 1911.2 1291.3 1341.5 1368.7 1371.5 1373.7 6507.3 6549.7 6589.0 6589.4 6598.3 6884.8 6945.0 6996.8 6997.2 7041.8 6443.7 6502.0 6539.3 6584.8 6602.9 3657.2 3679.4 3704.6 3726.6 3736.9 7247.2 7285.5 7324.5 7335.9 7383.7 7207.3 7240.5 7241.5 7267.4 7307.1 7193.2 7246.6 7284.4 7289.7 7299.3 6967.9 7007.7 7019.7 7020.4 7040.1 6897.4 6912.6 6912.9 6945.0 6956.8 7242.6 7273.2 7326.8 7358.6 7359.6 5405.5 5450.6 5460.8 5466.7 5491.3 6805.0 6934.6 6940.4 7009.3 7076.9 6671.1 6694.2 6729.8 6755.5 6823.3 6728.5 6773.1 6810.5 6810.7 6815.7 6712.8 6773.1 6811.7 6828.6 6846.8 6822.5 6836.0 6881.4 6892.5 6904.6 6442.9 6617.2 6702.6 6744.1 6764.9 08:43:56 INFO - PID 4026 | 08:43:56 INFO - PID 4026 | [#5] yui.html Cycles:180 Average:29088.90 Median:21212.68 stddev:40919.73 (192.9%) stddev-sans-first:40980.19 08:43:56 INFO - PID 4026 | Values: 1005.6 1034.9 1047.2 1050.3 1064.4 22235.0 22750.9 22785.4 22799.5 22814.8 20329.4 20552.5 20751.9 20863.0 20916.1 1073.7 1103.2 1104.2 1106.6 1106.6 1075.3 1105.5 1113.6 1122.7 1127.1 1169.7 1169.7 1170.0 1171.2 1171.3 1168.6 1169.0 1169.3 1177.7 1181.1 862.0 862.3 862.3 863.5 867.9 22127.7 22663.0 22688.4 22907.9 22931.7 24254.4 24520.0 24704.0 24729.8 24846.3 19220.5 19504.5 19521.4 19624.8 19695.5 4080.0 4104.8 4106.7 4109.0 4109.6 143128.0 143745.0 144733.0 145438.0 146412.0 18647.9 19097.6 19201.2 19241.8 19261.3 36108.8 36258.6 36298.6 36398.0 36466.0 9284.8 9371.3 9387.6 9389.7 9422.6 4778.5 4952.8 4991.0 4996.7 4997.4 6230.7 6305.4 6356.1 6358.5 6378.8 1164.7 1176.9 1191.5 1201.4 1208.5 766.0 773.3 784.4 792.7 795.5 13357.7 13412.7 14123.5 14444.2 14548.6 773.3 775.7 779.7 782.2 802.8 6645.1 6660.7 6685.9 6713.2 6768.5 146563.0 151375.0 151603.0 153152.2 158701.0 107936.0 109208.0 132795.3 135843.3 140611.3 114922.0 115510.0 117301.0 121244.4 124543.0 14869.5 14917.4 14923.3 15049.9 15195.2 55875.7 57122.4 57832.4 58488.7 58733.7 70693.3 72096.3 72493.0 73455.3 73880.3 21803.3 22008.9 22226.6 22263.7 22295.7 24911.4 25000.0 25025.1 25102.0 25323.5 20423.1 20582.4 20603.9 20613.5 20972.7 21553.6 21562.9 21761.9 21781.2 21944.6 21211.7 21213.7 21484.5 21549.5 21580.0 14635.5 14639.6 14698.0 14704.2 14874.6 15221.5 15223.7 15262.8 15355.0 15360.7 08:43:56 INFO - PID 4026 | -------- Summary: end -------- 08:43:56 INFO - PID 4026 | 08:43:57 INFO - TEST-INFO | 4026: exit 0 08:43:57 INFO - TEST-OK | dromaeo_css | took 1108724ms 08:43:57 INFO - TEST-START | kraken 08:43:57 INFO - Initialising browser for kraken test... 08:43:57 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:42474/getInfo.html -profile /tmp/tmpWAkAOB/profile 08:43:57 INFO - TEST-INFO | started process 4412 (/builds/slave/test/build/application/firefox/firefox http://localhost:42474/getInfo.html) 08:44:02 INFO - TEST-INFO | 4412: exit 0 08:44:02 INFO - Browser initialized. 08:44:02 INFO - Running cycle 1/1 for kraken test... 08:44:02 INFO - Using env: {'DISPLAY': ':0', 08:44:02 INFO - 'HOME': '/home/cltbld', 08:44:02 INFO - 'JSGC_DISABLE_POISONING': '1', 08:44:02 INFO - 'LANG': 'en_US.UTF-8', 08:44:02 INFO - 'LANGUAGE': 'en_US:en', 08:44:02 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 08:44:02 INFO - 'LOGNAME': 'cltbld', 08:44:02 INFO - 'MAIL': '/var/mail/cltbld', 08:44:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:44:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:44:02 INFO - 'MOZ_CRASHREPORTER': '1', 08:44:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:02 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 08:44:02 INFO - 'MOZ_NO_REMOTE': '1', 08:44:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:44:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:44:02 INFO - 'NO_EM_RESTART': '1', 08:44:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:44:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:02 INFO - 'PWD': '/builds/slave/test', 08:44:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:44:02 INFO - 'RUST_BACKTRACE': 'full', 08:44:02 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 08:44:02 INFO - 'SHELL': '/bin/bash', 08:44:02 INFO - 'SHLVL': '1', 08:44:02 INFO - 'TERM': 'linux', 08:44:02 INFO - 'TMOUT': '86400', 08:44:02 INFO - 'USER': 'cltbld', 08:44:02 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1507814394.778953-131491975', 08:44:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:02 INFO - '_': '/tools/buildbot/bin/python'} 08:44:02 INFO - TEST-INFO | started process 4644 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWAkAOB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 08:44:02 INFO - PID 4644 | 08:44:02 INFO - PID 4644 | (/builds/slave/test/build/application/firefox/firefox:4692): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 08:44:02 INFO - PID 4644 | 08:44:13 INFO - PID 4644 | RSS: Main: 143261696 08:44:13 INFO - PID 4644 | 08:44:15 INFO - PID 4644 | Cycle 1(1): loaded http://localhost:42474/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:42474/tests/kraken/driver.html?testName=audio-beat-detection) 08:44:16 INFO - PID 4644 | RSS: Main: 149811200 08:44:16 INFO - PID 4644 | 08:44:19 INFO - PID 4644 | Cycle 1(1): loaded http://localhost:42474/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:42474/tests/kraken/driver.html?testName=audio-dft) 08:44:19 INFO - PID 4644 | RSS: Main: 150458368 08:44:19 INFO - PID 4644 | 08:44:22 INFO - PID 4644 | Cycle 1(1): loaded http://localhost:42474/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:42474/tests/kraken/driver.html?testName=audio-fft) 08:44:23 INFO - PID 4644 | RSS: Main: 152104960 08:44:23 INFO - PID 4644 | 08:44:25 INFO - PID 4644 | Cycle 1(1): loaded http://localhost:42474/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:42474/tests/kraken/driver.html?testName=audio-oscillator) 08:44:25 INFO - PID 4644 | RSS: Main: 152281088 08:44:25 INFO - PID 4644 | 08:44:27 INFO - PID 4644 | Cycle 1(1): loaded http://localhost:42474/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:42474/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:44:28 INFO - PID 4644 | RSS: Main: 152309760 08:44:28 INFO - PID 4644 | 08:44:31 INFO - PID 4644 | Cycle 1(1): loaded http://localhost:42474/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:42474/tests/kraken/driver.html?testName=imaging-darkroom) 08:44:32 INFO - PID 4644 | RSS: Main: 152199168 08:44:32 INFO - PID 4644 | 08:44:35 INFO - PID 4644 | Cycle 1(1): loaded http://localhost:42474/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:42474/tests/kraken/driver.html?testName=imaging-desaturate) 08:44:36 INFO - PID 4644 | RSS: Main: 154464256 08:44:36 INFO - PID 4644 | 08:44:39 INFO - PID 4644 | Cycle 1(1): loaded http://localhost:42474/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:42474/tests/kraken/driver.html?testName=json-parse-financial) 08:44:39 INFO - PID 4644 | RSS: Main: 155062272 08:44:39 INFO - PID 4644 | 08:44:41 INFO - PID 4644 | Cycle 1(1): loaded http://localhost:42474/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:42474/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:44:42 INFO - PID 4644 | RSS: Main: 154951680 08:44:42 INFO - PID 4644 | 08:44:43 INFO - PID 4644 | Cycle 1(1): loaded http://localhost:42474/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:42474/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:44:43 INFO - PID 4644 | RSS: Main: 155045888 08:44:43 INFO - PID 4644 | 08:44:45 INFO - PID 4644 | Cycle 1(1): loaded http://localhost:42474/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:42474/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:44:46 INFO - PID 4644 | RSS: Main: 151621632 08:44:46 INFO - PID 4644 | 08:44:49 INFO - PID 4644 | Cycle 1(1): loaded http://localhost:42474/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:42474/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:44:49 INFO - PID 4644 | RSS: Main: 152104960 08:44:49 INFO - PID 4644 | 08:44:52 INFO - PID 4644 | Cycle 1(1): loaded http://localhost:42474/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:42474/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:44:52 INFO - PID 4644 | RSS: Main: 152244224 08:44:52 INFO - PID 4644 | 08:44:54 INFO - PID 4644 | Cycle 1(1): loaded http://localhost:42474/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:42474/tests/kraken/driver.html?testName=ai-astar) 08:44:54 INFO - PID 4644 | RSS: Main: 153604096 08:44:54 INFO - PID 4644 | 08:44:54 INFO - PID 4644 | __start_tp_report 08:44:54 INFO - PID 4644 | _x_x_mozilla_page_load 08:44:54 INFO - PID 4644 | _x_x_mozilla_page_load_details 08:44:54 INFO - PID 4644 | |i|pagename|runs| 08:44:54 INFO - PID 4644 | |0;ai-astar;105;106;105;103;104;105;108;107;104;107 08:44:54 INFO - PID 4644 | |1;audio-beat-detection;110;109;109;113;113;111;110;112;134;112 08:44:54 INFO - PID 4644 | |2;audio-dft;162;160;162;160;161;161;161;161;163;161 08:44:54 INFO - PID 4644 | |3;audio-fft;74;72;73;74;74;75;73;72;72;84 08:44:54 INFO - PID 4644 | |4;audio-oscillator;78;77;77;77;77;77;76;77;76;76 08:44:54 INFO - PID 4644 | |5;imaging-gaussian-blur;110;115;110;114;115;113;114;115;114;114 08:44:54 INFO - PID 4644 | |6;imaging-darkroom;149;149;150;152;148;148;149;151;148;152 08:44:54 INFO - PID 4644 | |7;imaging-desaturate;93;94;95;94;94;94;95;94;94;93 08:44:54 INFO - PID 4644 | |8;json-parse-financial;45;46;41;41;43;43;42;45;42;42 08:44:54 INFO - PID 4644 | |9;json-stringify-tinderbox;38;39;39;39;38;41;39;39;38;39 08:44:54 INFO - PID 4644 | |10;stanford-crypto-aes;69;70;68;71;70;72;69;69;69;71 08:44:54 INFO - PID 4644 | |11;stanford-crypto-ccm;107;103;101;109;110;115;113;104;107;103 08:44:54 INFO - PID 4644 | |12;stanford-crypto-pbkdf2;155;152;155;155;146;154;144;145;158;149 08:44:54 INFO - PID 4644 | |13;stanford-crypto-sha256-iterative;66;60;57;58;57;57;57;64;58;59 08:44:54 INFO - PID 4644 | __end_tp_report 08:44:54 INFO - PID 4644 | __start_cc_report 08:44:54 INFO - PID 4644 | _x_x_mozilla_cycle_collect,694 08:44:54 INFO - PID 4644 | __end_cc_report 08:44:54 INFO - PID 4644 | __startTimestamp1507823094829__endTimestamp 08:44:54 INFO - PID 4644 | 08:44:54 INFO - PID 4644 | ------- Summary: start ------- 08:44:54 INFO - PID 4644 | Number of tests: 14 08:44:54 INFO - PID 4644 | 08:44:54 INFO - PID 4644 | [#0] ai-astar Cycles:10 Average:105.40 Median:105.50 stddev:1.58 (1.5%) stddev-sans-first:1.67 08:44:54 INFO - PID 4644 | Values: 105.0 106.0 105.0 103.0 104.0 105.0 108.0 107.0 104.0 107.0 08:44:54 INFO - PID 4644 | 08:44:54 INFO - PID 4644 | [#1] audio-beat-detection Cycles:10 Average:113.30 Median:112.00 stddev:7.42 (6.6%) stddev-sans-first:7.78 08:44:54 INFO - PID 4644 | Values: 110.0 109.0 109.0 113.0 113.0 111.0 110.0 112.0 134.0 112.0 08:44:54 INFO - PID 4644 | 08:44:54 INFO - PID 4644 | [#2] audio-dft Cycles:10 Average:161.20 Median:161.00 stddev:0.92 (0.6%) stddev-sans-first:0.93 08:44:54 INFO - PID 4644 | Values: 162.0 160.0 162.0 160.0 161.0 161.0 161.0 161.0 163.0 161.0 08:44:54 INFO - PID 4644 | 08:44:54 INFO - PID 4644 | [#3] audio-fft Cycles:10 Average:74.30 Median:74.00 stddev:3.56 (4.8%) stddev-sans-first:3.77 08:44:54 INFO - PID 4644 | Values: 74.0 72.0 73.0 74.0 74.0 75.0 73.0 72.0 72.0 84.0 08:44:54 INFO - PID 4644 | 08:44:54 INFO - PID 4644 | [#4] audio-oscillator Cycles:10 Average:76.80 Median:77.00 stddev:0.63 (0.8%) stddev-sans-first:0.50 08:44:54 INFO - PID 4644 | Values: 78.0 77.0 77.0 77.0 77.0 77.0 76.0 77.0 76.0 76.0 08:44:54 INFO - PID 4644 | 08:44:54 INFO - PID 4644 | [#5] imaging-gaussian-blur Cycles:10 Average:113.40 Median:114.00 stddev:1.90 (1.7%) stddev-sans-first:1.56 08:44:54 INFO - PID 4644 | Values: 110.0 115.0 110.0 114.0 115.0 113.0 114.0 115.0 114.0 114.0 08:44:54 INFO - PID 4644 | 08:44:54 INFO - PID 4644 | [#6] imaging-darkroom Cycles:10 Average:149.60 Median:149.50 stddev:1.58 (1.1%) stddev-sans-first:1.66 08:44:54 INFO - PID 4644 | Values: 149.0 149.0 150.0 152.0 148.0 148.0 149.0 151.0 148.0 152.0 08:44:54 INFO - PID 4644 | 08:44:54 INFO - PID 4644 | [#7] imaging-desaturate Cycles:10 Average:94.00 Median:94.00 stddev:0.67 (0.7%) stddev-sans-first:0.60 08:44:54 INFO - PID 4644 | Values: 93.0 94.0 95.0 94.0 94.0 94.0 95.0 94.0 94.0 93.0 08:44:54 INFO - PID 4644 | 08:44:54 INFO - PID 4644 | [#8] json-parse-financial Cycles:10 Average:43.00 Median:43.00 stddev:1.76 (4.1%) stddev-sans-first:1.72 08:44:54 INFO - PID 4644 | Values: 45.0 46.0 41.0 41.0 43.0 43.0 42.0 45.0 42.0 42.0 08:44:54 INFO - PID 4644 | 08:44:54 INFO - PID 4644 | [#9] json-stringify-tinderbox Cycles:10 Average:38.90 Median:39.00 stddev:0.88 (2.2%) stddev-sans-first:0.87 08:44:54 INFO - PID 4644 | Values: 38.0 39.0 39.0 39.0 38.0 41.0 39.0 39.0 38.0 39.0 08:44:54 INFO - PID 4644 | 08:44:54 INFO - PID 4644 | [#10] stanford-crypto-aes Cycles:10 Average:69.80 Median:70.00 stddev:1.23 (1.8%) stddev-sans-first:1.27 08:44:54 INFO - PID 4644 | Values: 69.0 70.0 68.0 71.0 70.0 72.0 69.0 69.0 69.0 71.0 08:44:54 INFO - PID 4644 | 08:44:54 INFO - PID 4644 | [#11] stanford-crypto-ccm Cycles:10 Average:107.20 Median:108.00 stddev:4.59 (4.2%) stddev-sans-first:4.87 08:44:54 INFO - PID 4644 | Values: 107.0 103.0 101.0 109.0 110.0 115.0 113.0 104.0 107.0 103.0 08:44:54 INFO - PID 4644 | 08:44:54 INFO - PID 4644 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:151.30 Median:154.50 stddev:4.95 (3.2%) stddev-sans-first:5.06 08:44:54 INFO - PID 4644 | Values: 155.0 152.0 155.0 155.0 146.0 154.0 144.0 145.0 158.0 149.0 08:44:54 INFO - PID 4644 | 08:44:54 INFO - PID 4644 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:59.30 Median:58.50 stddev:3.20 (5.5%) stddev-sans-first:2.30 08:44:54 INFO - PID 4644 | Values: 66.0 60.0 57.0 58.0 57.0 57.0 57.0 64.0 58.0 59.0 08:44:54 INFO - PID 4644 | -------- Summary: end -------- 08:44:54 INFO - PID 4644 | 08:44:55 INFO - PID 4644 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 08:44:55 INFO - TEST-INFO | 4644: exit 0 08:44:55 INFO - TEST-OK | kraken | took 57947ms 08:44:55 INFO - SUITE-END | took 1166s 08:44:55 INFO - Completed test suite (00:19:26) 08:44:55 INFO - javascript benchmark 08:44:55 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9548.318804921679, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [4045.494313210849, 4051.6758659307256, 4064.004318941856, 4070.409790580366, 4097.651699605533, 6494.415502604765, 6533.18335208099, 6536.117624120742, 6588.044733513638, 6653.767118150872, 6467.237744988116, 6479.967600162005, 6537.6731163441755, 6566.63883486408, 6617.636030018347, 5809.070548712207, 5873.089671100986, 5953.3748956359605, 5955.731992060357, 5963.344032156459, 5876.08920617305, 5881.705914704269, 5903.793367232148, 5976.252968378953, 6012.518998480133, 5971.0, 5978.8804223915495, 5979.910301345483, 6011.699415029231, 6030.909536356936, 5938.940610593882, 5989.610675306135, 5996.8500787480225, 6022.187004754368, 6040.57715959883, 5966.791162309299, 5978.073398623221, 6009.489193418564, 6037.366076561985, 6037.667928263943, 6513.739450422025, 6567.770128045496, 6651.434628056621, 6691.397774200345, 6725.09211256479, 7010.719571217145, 7049.506534542584, 7076.292370762934, 7090.610016449094, 7190.532615380036, 6956.373926346653, 7046.1192350956135, 7046.365827075588, 7084.256153103953, 7160.427165826722, 6665.633390163539, 6714.496412769062, 6714.899276510856, 6731.0913026741255, 6748.089007983957, 7323.963380183065, 7328.67020984057, 7415.1472580652835, 7443.2184620615135, 7475.0656167979005, 7032.226455089936, 7036.92963070373, 7050.083489146378, 7101.396381307543, 7161.283871612901, 7066.470014748812, 7178.497505174692, 7211.639418029077, 7256.129264488295, 7266.527675701062, 7199.496035277585, 7219.747308844166, 7237.348638622444, 7238.348548630648, 7239.674214660353, 7140.321669441395, 7151.463640227003, 7226.0, 7231.819204519824, 7236.96381518089, 5239.895202095937, 5271.42014378418, 5288.68267903927, 5301.946980530222, 5345.572354211654, 4802.423709154924, 4849.515048495193, 4887.926681099786, 4908.4846091160625, 4910.140725373046, 6739.663016849138, 6744.393004629607, 6810.387065164159, 6825.829354266084, 6831.351021652935, 7098.751543695946, 7201.3158749918675, 7212.531185472927, 7299.233580473974, 7302.634868256566, 6678.165229346332, 6771.830704232433, 6781.525293229427, 6794.8301292467095, 6798.082258895085, 6001.879962400728, 6003.7898673546215, 6057.091436284504, 6077.726502307319, 6087.878242435215, 7406.592637404887, 7448.59032753193, 7487.513311634726, 7506.061742282215, 7547.698092076255, 7149.82125446868, 7170.605616691132, 7229.240929702306, 7250.492465527363, 7253.455990800817, 7410.296021877913, 7452.478326517092, 7459.627018648937, 7504.174540800398, 7523.285287897641, 7045.330693584101, 7051.43588512928, 7055.18865330473, 7128.322809333105, 7133.322334378226, 7015.579065256101, 7156.033935418652, 7187.892181617384, 7238.348548630648, 7283.927160728324, 6853.691583878837, 6857.245702972768, 6867.690953906987, 6883.414909732729, 6895.138107736533, 6941.0, 6992.370686638227, 7002.929970700432, 7004.7198112076985, 7012.053372794608, 6820.590764554143, 6835.863282734417, 6844.863102738017, 6919.065926099912, 6933.445324373937, 6850.862982740217, 6966.12923384577, 6979.371856532936, 7030.543014704028, 7054.259302773135, 6806.217285012089, 6821.147356580427, 6828.385445309946, 6831.863362732618, 6850.006749021264], "value": 6570.642482659806, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [968.3415277611226, 993.7863359377733, 1018.8318927376982, 1043.6190790361516, 1044.2220545693458, 45942.77028614857, 46553.30170047452, 46747.0, 46764.76617616907, 46800.0, 43735.78132109343, 44327.55672443275, 44562.554374456246, 44565.777171114096, 44579.33131003038, 8158.592070396471, 8326.750197494084, 8327.167283271685, 8350.958245208765, 8354.665813367488, 7845.137034926153, 7941.404394670394, 7959.602019898982, 7960.960195199016, 7966.681332746683, 8939.463632182058, 9077.818443631124, 9092.63629454821, 9093.454392736425, 9094.226990705798, 8894.911050889505, 8942.284617230605, 9005.369624126313, 9011.95494022529, 9015.27877769777, 8874.55627218643, 8944.731658050205, 8966.955165224132, 8967.865482017774, 8972.641094356282, 41307.7934610325, 41839.0, 41898.581014189775, 41970.580294196974, 42003.579964200275, 3933.3116704576582, 3955.0, 3959.089409435846, 3967.0677390813134, 3975.5030621172355, 34567.82716086428, 34689.653103468896, 34750.30499390023, 34910.82544587261, 34929.0, 5738.368779434259, 5741.3110426748635, 5749.626274292199, 5820.476157145878, 5866.0, 235069.0, 235321.0, 235864.0, 236790.0, 237517.0, 53871.0, 54616.0, 54903.450965490636, 55013.72493137589, 55029.72485137629, 59522.0, 59564.70217648884, 59638.0, 59644.105338419955, 59950.700246499364, 19083.713744293847, 19145.13846876894, 19218.711719324223, 19271.13279902408, 19284.8071519283, 15864.365425383086, 15962.441314553935, 15994.360225590846, 16017.359305627644, 16017.519474415787, 15471.845281547265, 15582.376704931903, 15602.765958510632, 15686.215689215494, 15705.21473926322, 5934.732937017845, 5969.224000879858, 6017.969910150508, 6036.094585812163, 6056.182415373956, 1130.8925652063042, 1131.1911982932243, 1137.5961533655434, 1138.248755821154, 1145.954161833534, 27196.320091997462, 27547.86226068857, 27606.723932760415, 27780.861095694392, 27871.86064069667, 1230.883066108736, 1235.2403271987841, 1240.7704574653717, 1250.4498020870788, 1267.6830792301926, 14348.713025739427, 14448.0, 14630.049046811922, 14660.780088298685, 14909.0, 305393.0, 306112.0, 306233.0, 307676.0, 307932.0, 63601.0, 67310.3268967304, 68610.0, 68699.31300686928, 68811.65594171998, 1563.9452619158276, 1564.8982816117143, 1587.8253392126858, 1588.110658031506, 1600.2318886934336, 31873.840630796698, 32173.67826321707, 32287.51568726518, 32414.0, 32866.67133328732, 45863.31205031994, 46283.76858115688, 46336.53663463322, 46455.0, 46519.0, 260723.0, 260760.0, 261435.0, 261849.0, 262027.0, 40384.1923161541, 40758.38862417125, 40763.0, 40934.59065409308, 41042.0, 50111.74944125256, 50818.0, 50850.74574627103, 51257.48742512527, 51400.74299628627, 36444.351335039275, 36563.26873462569, 36628.81685591555, 36787.44818827666, 37355.252894941405, 36433.27133457263, 36915.63084369122, 36939.26121477609, 37183.44224836684, 37310.04175344506, 43012.0, 43246.0, 43536.34695479634, 43737.78131109324, 43883.561164389226, 34373.0, 35112.297754045285, 35249.295014100084, 35319.11702207466, 35810.8209458951, 26082.60876086822, 26595.202143935712, 26775.330616734736, 27312.72687273181, 27446.86276568684], "value": 19947.59535653675, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [630.7508534129016, 641.4964253061345, 644.8742495213434, 651.3291309950753, 654.4666097130838, 24692.629610555858, 25160.496790064186, 25580.488390232185, 25725.614115788256, 25823.870880645616, 25857.612135817977, 26708.866455667743, 26900.865495672493, 26910.865445672793, 26969.32576685584, 6518.08746775453, 6718.0, 6719.798406047827, 6725.562838415512, 6728.394444499994, 6272.435480806726, 6575.342465753434, 6578.506612004119, 6605.735770569171, 6626.469882409396, 6591.538592298517, 6593.736250549996, 6594.373534514238, 6598.076269322299, 6605.405513503783, 6570.0, 6590.604563726191, 6601.537892347537, 6603.570767900095, 6603.735850565971, 6174.073888916653, 6191.473724733403, 6192.133101365813, 6192.28788689302, 6195.1946246988055, 29807.0, 30007.0, 30151.0, 30304.0, 30320.84839575788, 2414.9275521734376, 2450.3261603059123, 2452.7792498675203, 2454.8281620286407, 2458.840175388612, 20349.18603255868, 20832.062557184963, 20956.266530671353, 21003.474913127142, 21032.5793484131, 2578.2136448383235, 2583.573710337792, 2654.734526547349, 2661.8535980521065, 2665.4402575458985, 139980.10069647615, 147826.0, 162372.0, 167568.32431675648, 168068.0, 16269.18654067292, 16314.510564683078, 16341.937774044649, 16562.0, 16814.15929203535, 26379.472410552065, 26505.204843854328, 26547.469050618878, 26572.601410978852, 26575.867120664272, 11365.31808091517, 11559.595414160633, 11588.188826782045, 11757.0, 11786.116041296937, 10777.407242601732, 10786.73033174177, 10810.67567972962, 10826.188035897181, 10830.241883068267, 9699.0, 9762.804743905082, 10001.949990250003, 10021.248406369552, 10028.147607453302, 2880.812747171427, 2894.247495651146, 2961.4077184563175, 3001.369712360429, 3025.440293545701, 1063.9361638301727, 1091.5742860284495, 1098.7363032872054, 1105.2152971390403, 1115.9888401116048, 22024.779752202274, 22039.0, 22085.116595336327, 22383.664245036336, 22417.775822241896, 1154.05944155513, 1158.652404278713, 1166.9474873630706, 1168.3340495917416, 1178.116412690482, 11929.522819087313, 11945.58190463347, 11946.581869634521, 11987.580434684744, 12267.754644907052, 658801.0, 675748.0, 675930.0, 680188.0, 691341.0, 138680.0, 142581.0, 142861.0, 144192.0, 145660.0, 1282.948682052726, 1285.5886116442648, 1319.9736005280033, 1331.2012792324529, 1333.06019256424, 19704.40886773399, 19725.70411443801, 19792.505187370432, 19917.701234481203, 20036.699449507978, 66774.0, 67813.3218667807, 68455.65772171108, 68990.0, 69057.0, 81530.0, 81618.59190704009, 81731.18268817235, 82809.58595206986, 86463.56768216119, 15972.281247343664, 15999.360025598846, 16120.838791612403, 16128.758068628746, 16308.510744677678, 24501.754982449947, 24525.141620043214, 24578.50842983166, 24986.875065624554, 25060.373490662878, 20276.695849562562, 20394.0, 20410.18359265613, 20662.483437914172, 20722.378328650164, 17833.46499605014, 18118.27526898909, 18626.81373186251, 18646.906765466087, 18664.533386665444, 19271.1327990238, 19371.3220037298, 19555.120019598868, 19721.605567888848, 19735.703964440258, 15089.54731358061, 15093.849061509245, 15133.0, 15188.0, 15246.313915874034, 20112.195512179933, 20123.39629811108, 20139.496512587302, 20331.898340508204, 20383.388498345073], "value": 13212.410278370202, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1462.4661998370595, 1514.401811284542, 1514.4396573267893, 1515.4392874636387, 1527.8395768444313, 17343.132843357827, 18039.549011274725, 18043.909780451097, 18055.729164062515, 18069.1868865901, 17364.0, 17472.301107955667, 17590.560235994108, 17664.646707065884, 17713.645727085484, 2886.610307608467, 2930.9853450732717, 2957.423302456022, 2957.8964736234234, 2962.822230666156, 2865.369618683886, 2884.6105775720325, 2892.2335581071034, 2894.6526416829906, 2906.72386123319, 2838.6451693538306, 2906.1426879070723, 2934.0464349086633, 2945.970540294602, 2948.041886386922, 2951.837648929308, 2965.0808249442744, 2975.8065725727865, 2981.0, 2981.2993946422575, 2781.3324802047364, 2782.38787466757, 2797.2447439191305, 2805.1864959161817, 2805.579163125527, 17068.743968840477, 17213.311467541407, 17218.655626887517, 17275.74086388705, 17362.305507779674, 8705.390622656416, 8987.236084932789, 8994.910050899472, 9004.324675649286, 9004.954975225148, 5539.435763718709, 5893.675847828367, 5978.611390259619, 6106.175666285039, 6220.564560480768, 1987.4435158155738, 2006.8996550172433, 2011.4267433781301, 2035.1655821113272, 2081.8230450411734, 31572.36855262905, 31701.0, 31724.682753172405, 31774.523382149055, 31807.0, 7084.610346430995, 7257.201707812242, 7356.1540422850985, 7370.189279179286, 7552.358049565739, 19642.60714785696, 19654.803451965297, 19991.800081999285, 19994.600107997758, 20003.799962000485, 8379.874301885477, 8750.737477875675, 8860.911390886009, 8864.290856731446, 8925.910740892508, 8075.919240807634, 8146.75559733209, 8160.591970401575, 8175.918240817516, 8200.26197642203, 6089.117078023662, 6267.341929097471, 6416.0, 6500.902486462707, 6513.088167656532, 1713.3831820544594, 1762.4007837335369, 1788.0, 1789.3468883857558, 1790.51656052865, 1029.351508549609, 1039.2205845615788, 1040.458961340099, 1049.8372752223418, 1064.505005172583, 8911.955440222888, 8966.775830604156, 9021.052789457013, 9156.313276504288, 9211.585478653476, 1105.1932089574655, 1111.7609713911386, 1119.462657924201, 1121.7644294698205, 1126.915481338903, 5564.777408903679, 5603.495685388335, 5654.095344744821, 5654.378018418053, 5675.063614503643, 25815.0, 26879.731202687723, 27047.86476067607, 27340.58989115165, 27416.451670966868, 25637.23088307354, 26371.604425933627, 26520.33699157498, 26601.600975985377, 26789.19632410992, 13118.737625247442, 13155.210687358789, 13271.469141234436, 13324.400401981935, 13340.666483337995, 9773.8760042596, 9993.300468967393, 10062.446565438971, 10070.69787906364, 10092.293539452168, 22069.668954966008, 22071.448213794785, 22308.77691223132, 22565.77434225637, 22805.429864253525, 23240.65139022882, 23508.0, 23681.64477532873, 23782.16762413307, 24024.0, 12875.80686289725, 12878.227306361649, 12891.0, 13081.607551773463, 13085.149465284727, 16022.198890055684, 16053.678926421173, 16145.0, 16199.514014579581, 16209.0, 11619.0, 11708.473118709508, 11719.296842189497, 11814.881851181379, 12082.516699331929, 12512.687182820502, 12601.24392536414, 12613.30626815534, 12635.620931372074, 12727.809082863949, 10676.786464270826, 10816.242862999829, 10938.890611094106, 10953.233273670767, 10964.451777411257, 8131.552764598004, 8197.754067377988, 8215.794605134919, 8227.79430514218, 8290.668373265002, 8678.262347700516, 8747.081556436737, 8776.56117194125, 8782.604782784661, 8806.295496360404], "value": 7587.170145289558, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [570.9771609135632, 571.3829064610221, 582.1994757208838, 612.3142080869424, 613.5521069619178, 6438.8088203682355, 6508.414242718154, 6539.2479864815505, 6591.868162636744, 6597.472202223823, 6395.424411802936, 6423.614583125016, 6441.935580644192, 6490.318516555764, 6490.448311893483, 5303.124984377573, 5333.439988801188, 5373.0, 5383.650062745909, 5388.461153884619, 5036.395632524102, 5251.5798736101215, 5293.417724050351, 5391.892162156754, 5414.88994756074, 5153.6392452528335, 5159.638825282232, 5176.145935920568, 5191.988646584971, 5200.193969934666, 5121.923171152435, 5140.228965655144, 5154.453663900834, 5175.560077393388, 5202.609804264676, 4711.505291944365, 4720.244760838284, 4730.95269047312, 4739.715617062987, 4747.7388743619085, 6325.810225693236, 6330.778422755227, 6382.1384113144595, 6390.201224846895, 6439.4848412127385, 6082.513398928075, 6099.329073801922, 6125.173101631267, 6130.509559235251, 6137.723802428902, 6700.195976482853, 6728.394444500019, 6804.421624161952, 6836.965815170942, 6911.0, 5041.420236672793, 5166.61250406221, 5237.947620523784, 5267.3942496613, 5271.209318602202, 6907.827304317432, 7145.0, 7146.356827885464, 7165.390941769956, 7191.999999999947, 5333.759980800874, 5391.137418013099, 5432.91850622241, 5457.208704737791, 5480.671159730429, 5509.311336082989, 5544.833654990357, 5551.805686800943, 5567.387587365387, 5568.0, 6092.664903430265, 6095.847603809941, 6121.204243448323, 6123.693815309217, 6132.363083873888, 6888.483363747739, 6907.171139463297, 6913.51605387618, 6914.135733033371, 6961.500414672352, 1861.088066847235, 1873.278787666758, 1905.4093231098404, 1909.9140538675792, 1911.1972971351788, 1291.3026965438746, 1341.5304643374936, 1368.6988862450246, 1371.451419432235, 1373.6565858535366, 6507.251666058369, 6549.7380104795275, 6589.0, 6589.439897608757, 6598.307177746364, 6884.793456196322, 6945.0, 6996.755113571001, 6997.195322537871, 7041.753538626123, 6443.710033048524, 6501.967490162613, 6539.280679125292, 6584.7695330664155, 6602.867942641216, 3657.213699054716, 3679.4296883983025, 3704.6110158433517, 3726.5838981187517, 3736.9065773355883, 7247.23903990084, 7285.526440781332, 7324.523905946203, 7335.926640733524, 7383.6677349519405, 7207.279272072751, 7240.52936559122, 7241.45689073311, 7267.418606511466, 7307.123145222501, 7193.244709305448, 7246.601436920914, 7284.417246620364, 7289.671964761598, 7299.343059124704, 6967.930320696728, 7007.7196912122945, 7019.684114214975, 7020.438364930896, 7040.119985001875, 6897.448204143757, 6912.55068420551, 6912.861742765016, 6944.965275173592, 6956.756513522002, 7242.565446073252, 7273.236310187566, 7326.780196594111, 7358.558486490828, 7359.632018398952, 5405.486478784509, 5450.5912056595125, 5460.836174914759, 5466.72666366688, 5491.313585801775, 6804.965975170093, 6934.583924964519, 6940.375366217065, 7009.264027277266, 7076.858462830817, 6671.132752742113, 6694.196696396269, 6729.764458243938, 6755.493337999768, 6823.28355522663, 6728.495362847708, 6773.05177275172, 6810.489213308922, 6810.693518791765, 6815.659217039228, 6712.765053223114, 6773.119494465688, 6811.7275308989065, 6828.624425656637, 6846.7945961621235, 6822.522423430312, 6836.0, 6881.380675739208, 6892.450388050259, 6904.620245886324, 6442.903356449563, 6617.239017513048, 6702.564333318319, 6744.123263975652, 6764.932350676627], "value": 5398.791012647496, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1005.6429967361582, 1034.9223808214385, 1047.2459828923172, 1050.2963014780096, 1064.3507460449125, 22235.0, 22750.88624556877, 22785.430364240903, 22799.544009119807, 22814.771852281472, 20329.390118296476, 20552.486187845312, 20751.858647774367, 20863.0, 20916.058777355018, 1073.6993641780296, 1103.22222832903, 1104.2104895000073, 1106.6292791914677, 1106.6458733205377, 1075.2849355178835, 1105.4583254205422, 1113.62136873463, 1122.691259903525, 1127.0870594818182, 1169.6724917023248, 1169.7368092179277, 1169.9824502632425, 1171.197729555253, 1171.2503997441645, 1168.5617893290016, 1169.0355456748132, 1169.2516789254885, 1177.682025853015, 1181.1082632612365, 862.0, 862.2627653356416, 862.3360012790179, 863.4905405810601, 867.9132086791334, 22127.668084978737, 22663.0, 22688.432789180402, 22907.885460572757, 22931.656025159635, 24254.393640159138, 24520.0, 24704.0, 24729.752702472924, 24846.254612361477, 19220.519487012796, 19504.512387190432, 19521.414357569294, 19624.803751962583, 19695.507612309808, 4079.9796001019804, 4104.774237416941, 4106.691998100125, 4108.958910410887, 4109.589041095896, 143128.0, 143745.0, 144733.0, 145438.0, 146411.99999999892, 18647.90676046611, 19097.61804763897, 19201.231950722093, 19241.807581924, 19261.325853595055, 36108.81945590255, 36258.637413625525, 36298.637013630054, 36398.0, 36466.0, 9284.814303713887, 9371.250299975985, 9387.624495020124, 9389.718308450621, 9422.575984080733, 4778.4982576829625, 4952.777125029383, 4990.950090499121, 4996.7001979881325, 4997.425296090923, 6230.719617617127, 6305.369463053658, 6356.110144579762, 6358.523110766619, 6378.808635740936, 1164.7495788405533, 1176.9176157669053, 1191.53530123252, 1201.4473342262656, 1208.516593362662, 765.9961700191463, 773.331068625646, 784.4156103702778, 792.7344339646168, 795.5187111797395, 13357.732845343038, 13412.731745365038, 14123.505677301246, 14444.205568693613, 14548.563543093724, 773.2769860180749, 775.7362496751132, 779.6881247500932, 782.22950393862, 802.8233788566506, 6645.1361323027695, 6660.666966651648, 6685.899711504331, 6713.160854893138, 6768.526203165731, 146563.0, 151375.0, 151603.0, 153152.2342388281, 158701.0, 107936.0, 109208.0, 132795.33602331925, 135843.32078339544, 140611.29694351464, 114922.0, 115510.0, 117301.0, 121244.39377803054, 124543.0, 14869.47956821506, 14917.403303868157, 14923.253837307873, 15049.924750376178, 15195.176578810613, 55875.720621396635, 57122.42877571171, 57832.4216757827, 58488.707556461944, 58733.70633146807, 70693.29306706868, 72096.27903721106, 72493.0, 73455.26544734485, 73880.26119738734, 21803.345899623037, 22008.88995555012, 22226.555468890856, 22263.66604500966, 22295.665565016214, 24911.377215569755, 25000.0, 25025.124120655688, 25102.0, 25323.493530128926, 20423.080961356478, 20582.38252852417, 20603.896980515, 20613.484662883548, 20972.68540971917, 21553.568928621655, 21562.8921855396, 21761.891190543945, 21781.23765668194, 21944.561108778053, 21211.68182477295, 21213.681794773398, 21484.46288842729, 21549.46126346791, 21580.0, 14635.487757928422, 14639.634009149431, 14698.0, 14704.191269480281, 14874.628134296296, 15221.543353699406, 15223.695526089195, 15262.771058433911, 15355.0, 15360.69278614399], "value": 10682.638234280616, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1357.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 106.0, 105.0, 103.0, 104.0, 105.0, 108.0, 107.0, 104.0, 107.0], "value": 105.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 109.0, 109.0, 113.0, 113.0, 111.0, 110.0, 112.0, 134.0, 112.0], "value": 113.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 160.0, 162.0, 160.0, 161.0, 161.0, 161.0, 161.0, 163.0, 161.0], "value": 161.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 72.0, 73.0, 74.0, 74.0, 75.0, 73.0, 72.0, 72.0, 84.0], "value": 74.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 77.0, 77.0, 77.0, 77.0, 77.0, 76.0, 77.0, 76.0, 76.0], "value": 76.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 115.0, 110.0, 114.0, 115.0, 113.0, 114.0, 115.0, 114.0, 114.0], "value": 113.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 149.0, 150.0, 152.0, 148.0, 148.0, 149.0, 151.0, 148.0, 152.0], "value": 149.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 95.0, 94.0, 94.0, 94.0, 95.0, 94.0, 94.0, 93.0], "value": 94.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [45.0, 46.0, 41.0, 41.0, 43.0, 43.0, 42.0, 45.0, 42.0, 42.0], "value": 43.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 39.0, 39.0, 39.0, 38.0, 41.0, 39.0, 39.0, 38.0, 39.0], "value": 38.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 70.0, 68.0, 71.0, 70.0, 72.0, 69.0, 69.0, 69.0, 71.0], "value": 69.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 103.0, 101.0, 109.0, 110.0, 115.0, 113.0, 104.0, 107.0, 103.0], "value": 107.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 152.0, 155.0, 155.0, 146.0, 154.0, 144.0, 145.0, 158.0, 149.0], "value": 151.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 60.0, 57.0, 58.0, 57.0, 57.0, 57.0, 64.0, 58.0, 59.0], "value": 59.3, "unit": "score"}]}]} 08:44:55 INFO - Return code: 0 08:44:55 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:44:55 INFO - # TBPL SUCCESS # 08:44:55 INFO - Running post-action listener: _package_coverage_data 08:44:55 INFO - Running post-action listener: _resource_record_post_action 08:44:55 INFO - [mozharness: 2017-10-12 15:44:55.623132Z] Finished run-tests step (success) 08:44:55 INFO - Running post-run listener: _resource_record_post_run 08:44:55 INFO - Total resource usage - Wall time: 1182s; CPU: 13.0%; Read bytes: 9261056; Write bytes: 421388288; Read time: 2824; Write time: 326996 08:44:55 INFO - TinderboxPrint: CPU usage
13.1% 08:44:55 INFO - TinderboxPrint: I/O read bytes / time
9,261,056 / 2,824 08:44:55 INFO - TinderboxPrint: I/O write bytes / time
421,388,288 / 326,996 08:44:55 INFO - TinderboxPrint: CPU idle
8,192.2 (86.9%) 08:44:55 INFO - TinderboxPrint: CPU user
1,179.1 (12.5%) 08:44:55 INFO - TinderboxPrint: Swap in / out
0 / 0 08:44:55 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 4096; Write bytes: 137109504; Read time: 220; Write time: 139104 08:44:55 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 08:44:55 INFO - run-tests - Wall time: 1167s; CPU: 13.0%; Read bytes: 6758400; Write bytes: 284278784; Read time: 2356; Write time: 187892 08:44:56 INFO - Running post-run listener: _upload_blobber_files 08:44:56 INFO - Blob upload gear active. 08:44:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:44:56 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 08:44:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:44:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:44:56 INFO - (blobuploader) - INFO - Open directory for files ... 08:44:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:44:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:44:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:44:57 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:44:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:44:57 INFO - (blobuploader) - INFO - Done attempting. 08:44:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:44:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:44:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:44:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:44:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:44:58 INFO - (blobuploader) - INFO - Done attempting. 08:44:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:44:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:44:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:45:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:45:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:45:05 INFO - (blobuploader) - INFO - Done attempting. 08:45:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:45:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:45:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:45:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:45:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:45:06 INFO - (blobuploader) - INFO - Done attempting. 08:45:06 INFO - (blobuploader) - INFO - Iteration through files over. 08:45:06 INFO - Return code: 0 08:45:06 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:45:06 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:45:06 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/7458d7839dbae58fef5a47f4f24b7dd7f6ce9649d01412626be666251d9f7bbe127604e27e6ed58f1e95c5c8e422ed95269d4888b807cb6f96e204e1034b5c73", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/4959e31c99d8d9cf5bff1631497389ccb51ab7bc6baaaafa8c5f839df0e7230f032ee262653821d6ce32c7a831a8e0edd5cee506db0c5c0216d545c8aba1d9c4", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/0b85815df2d2d6dd58690e73722bb00508abdbf28d56a7a7583ce51e6a67956dc225363288c5084dbe100b3a2c191a37e1743e60057f3e9e706dbc92b9a0f724", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/adcee37581181bdc209f6567863ccba624257a18479a727cc21a45c05984ce956a883f30c8031c9e16e64764f36c8dd4f1778ef5f8068d51ab33ce6915129147"} 08:45:06 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:45:06 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:45:06 INFO - Contents: 08:45:06 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/7458d7839dbae58fef5a47f4f24b7dd7f6ce9649d01412626be666251d9f7bbe127604e27e6ed58f1e95c5c8e422ed95269d4888b807cb6f96e204e1034b5c73", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/4959e31c99d8d9cf5bff1631497389ccb51ab7bc6baaaafa8c5f839df0e7230f032ee262653821d6ce32c7a831a8e0edd5cee506db0c5c0216d545c8aba1d9c4", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/0b85815df2d2d6dd58690e73722bb00508abdbf28d56a7a7583ce51e6a67956dc225363288c5084dbe100b3a2c191a37e1743e60057f3e9e706dbc92b9a0f724", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/adcee37581181bdc209f6567863ccba624257a18479a727cc21a45c05984ce956a883f30c8031c9e16e64764f36c8dd4f1778ef5f8068d51ab33ce6915129147"} 08:45:06 INFO - Running post-run listener: copy_logs_to_upload_dir 08:45:06 INFO - Copying logs to upload dir... 08:45:06 INFO - mkdir: /builds/slave/test/build/upload/logs 08:45:06 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1236.376664 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2017-10-12 08:45:06.819440) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-12 08:45:06.823170) ========= 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=dbb0bb31bf4a4c7db9ba1648000001b6-1507814394.778953-131491975 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/7458d7839dbae58fef5a47f4f24b7dd7f6ce9649d01412626be666251d9f7bbe127604e27e6ed58f1e95c5c8e422ed95269d4888b807cb6f96e204e1034b5c73", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/4959e31c99d8d9cf5bff1631497389ccb51ab7bc6baaaafa8c5f839df0e7230f032ee262653821d6ce32c7a831a8e0edd5cee506db0c5c0216d545c8aba1d9c4", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/0b85815df2d2d6dd58690e73722bb00508abdbf28d56a7a7583ce51e6a67956dc225363288c5084dbe100b3a2c191a37e1743e60057f3e9e706dbc92b9a0f724", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/adcee37581181bdc209f6567863ccba624257a18479a727cc21a45c05984ce956a883f30c8031c9e16e64764f36c8dd4f1778ef5f8068d51ab33ce6915129147"} build_url:https://queue.taskcluster.net/v1/task/eUlwW_TISOGJjYS0gNJqdw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011146 build_url: 'https://queue.taskcluster.net/v1/task/eUlwW_TISOGJjYS0gNJqdw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/7458d7839dbae58fef5a47f4f24b7dd7f6ce9649d01412626be666251d9f7bbe127604e27e6ed58f1e95c5c8e422ed95269d4888b807cb6f96e204e1034b5c73", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/4959e31c99d8d9cf5bff1631497389ccb51ab7bc6baaaafa8c5f839df0e7230f032ee262653821d6ce32c7a831a8e0edd5cee506db0c5c0216d545c8aba1d9c4", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/0b85815df2d2d6dd58690e73722bb00508abdbf28d56a7a7583ce51e6a67956dc225363288c5084dbe100b3a2c191a37e1743e60057f3e9e706dbc92b9a0f724", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/adcee37581181bdc209f6567863ccba624257a18479a727cc21a45c05984ce956a883f30c8031c9e16e64764f36c8dd4f1778ef5f8068d51ab33ce6915129147"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-12 08:45:06.852449) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-12 08:45:06.852784) ========= 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=dbb0bb31bf4a4c7db9ba1648000001b6-1507814394.778953-131491975 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004403 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-12 08:45:06.936015) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-12 08:45:06.936359) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-12 08:45:06.936698) ========= ========= Total master_lag: 0.21 =========