builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-005 starttime: 1506453095.62 results: success (0) revision: 39aaf54972cb11a63815a96b532786133baa95bc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-26 12:11:35.618270) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-26 12:11:35.618732) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-26 12:11:35.619008) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-26 12:11:35.636018) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-26 12:11:35.636353) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1506453094.566063-2043789727 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-26 12:11:35-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 49.6M=0s 2017-09-26 12:11:35 (49.6 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.123053 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-26 12:11:35.776656) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-26 12:11:35.776971) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1506453094.566063-2043789727 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015166 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-26 12:11:35.826721) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-26 12:11:35.827062) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 39aaf54972cb11a63815a96b532786133baa95bc --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 39aaf54972cb11a63815a96b532786133baa95bc --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=7fc86f70bae03e018da0b1d8000001c4-1506453094.566063-2043789727 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-26 12:11:35,859 truncating revision to first 12 chars 2017-09-26 12:11:35,859 Setting DEBUG logging. 2017-09-26 12:11:35,859 attempt 1/10 2017-09-26 12:11:35,859 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/39aaf54972cb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-26 12:11:36,660 unpacking tar archive at: mozilla-central-39aaf54972cb/testing/mozharness/ program finished with exit code 0 elapsedTime=1.077211 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-26 12:11:36.921619) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-26 12:11:36.921948) ========= script_repo_revision: 39aaf54972cb11a63815a96b532786133baa95bc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-26 12:11:36.922349) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-26 12:11:36.922627) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-26 12:11:36.936007) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2017-09-26 12:11:36.936467) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-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', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-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=7fc86f70bae03e018da0b1d8000001c4-1506453094.566063-2043789727 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:11:37 INFO - MultiFileLogger online at 20170926 12:11:37 in /builds/slave/test 12:11:37 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 12:11:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:11:37 INFO - {'append_to_log': False, 12:11:37 INFO - 'base_work_dir': '/builds/slave/test', 12:11:37 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 12:11:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:11:37 INFO - 'branch': 'Firefox-Non-PGO', 12:11:37 INFO - 'buildbot_json_path': 'buildprops.json', 12:11:37 INFO - 'code_coverage': False, 12:11:37 INFO - 'config_files': ('talos/linux_config.py',), 12:11:37 INFO - 'default_actions': ('clobber', 12:11:37 INFO - 'read-buildbot-config', 12:11:37 INFO - 'download-and-extract', 12:11:37 INFO - 'populate-webroot', 12:11:37 INFO - 'create-virtualenv', 12:11:37 INFO - 'install', 12:11:37 INFO - 'setup-mitmproxy', 12:11:37 INFO - 'run-tests'), 12:11:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:11:37 INFO - 'disable_ccov_upload': False, 12:11:37 INFO - 'disable_stylo': False, 12:11:37 INFO - 'download_minidump_stackwalk': True, 12:11:37 INFO - 'download_symbols': 'ondemand', 12:11:37 INFO - 'enable_stylo': False, 12:11:37 INFO - 'enable_webrender': False, 12:11:37 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 12:11:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:11:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:11:37 INFO - 'gecko_profile': False, 12:11:37 INFO - 'gecko_profile_interval': 0, 12:11:37 INFO - 'installer_path': 'installer.exe', 12:11:37 INFO - 'log_level': 'info', 12:11:37 INFO - 'log_name': 'talos', 12:11:37 INFO - 'log_to_console': True, 12:11:37 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:11:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:11:37 INFO - 'opt_config_files': (), 12:11:37 INFO - 'pip_index': False, 12:11:37 INFO - 'suite': 'dromaeojs-e10s', 12:11:37 INFO - 'system_bits': '32', 12:11:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:11:37 INFO - 'title': 'talos-linux64-ix-005', 12:11:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:11:37 INFO - 'use_talos_json': True, 12:11:37 INFO - 'verify': 'False', 12:11:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:11:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:11:37 INFO - 'work_dir': 'build'} 12:11:37 INFO - [mozharness: 2017-09-26 19:11:37.114171Z] Running clobber step. 12:11:37 INFO - Running pre-action listener: _resource_record_pre_action 12:11:37 INFO - Running main action method: clobber 12:11:37 INFO - rmtree: /builds/slave/test/build 12:11:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:11:38 INFO - Running post-action listener: _resource_record_post_action 12:11:38 INFO - [mozharness: 2017-09-26 19:11:38.156220Z] Finished clobber step (success) 12:11:38 INFO - [mozharness: 2017-09-26 19:11:38.156318Z] Running read-buildbot-config step. 12:11:38 INFO - Running pre-action listener: _resource_record_pre_action 12:11:38 INFO - Running main action method: read_buildbot_config 12:11:38 INFO - Using buildbot properties: 12:11:38 INFO - { 12:11:38 INFO - "project": "", 12:11:38 INFO - "product": "firefox", 12:11:38 INFO - "who": "ryanvm@gmail.com", 12:11:38 INFO - "installer_path": "public/build/target.tar.bz2", 12:11:38 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 12:11:38 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 12:11:38 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 12:11:38 INFO - "stage_platform": "linux64", 12:11:38 INFO - "basedir": "/builds/slave/test", 12:11:38 INFO - "buildnumber": 1179, 12:11:38 INFO - "platform": "ubuntu64_hw", 12:11:38 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 12:11:38 INFO - "slavebuilddir": "test", 12:11:38 INFO - "taskId": "AMbfYu5WRlCTVytEqsGFYg", 12:11:38 INFO - "branch": "mozilla-central", 12:11:38 INFO - "script_repo_revision": "production", 12:11:38 INFO - "revision": "39aaf54972cb11a63815a96b532786133baa95bc", 12:11:38 INFO - "slavename": "talos-linux64-ix-005", 12:11:38 INFO - "repo_path": "mozilla-central" 12:11:38 INFO - } 12:11:38 INFO - Finding installer, test and symbols from parent task. 12:11:38 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AMbfYu5WRlCTVytEqsGFYg'}, attempt #1 12:11:38 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AMbfYu5WRlCTVytEqsGFYg'}, attempt #1 12:11:39 INFO - Task dependencies: CfEmsjFlSySZu2syzfRtJA 12:11:39 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CfEmsjFlSySZu2syzfRtJA'}, attempt #1 12:11:39 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/CfEmsjFlSySZu2syzfRtJA/artifacts/public/build/target.tar.bz2 12:11:39 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/CfEmsjFlSySZu2syzfRtJA/artifacts/public/build/target.test_packages.json 12:11:39 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/CfEmsjFlSySZu2syzfRtJA/artifacts/public/build/target.crashreporter-symbols.zip 12:11:39 INFO - Running post-action listener: _resource_record_post_action 12:11:39 INFO - [mozharness: 2017-09-26 19:11:39.599510Z] Finished read-buildbot-config step (success) 12:11:39 INFO - [mozharness: 2017-09-26 19:11:39.599726Z] Running download-and-extract step. 12:11:39 INFO - Running pre-action listener: _resource_record_pre_action 12:11:39 INFO - Running main action method: download_and_extract 12:11:39 INFO - mkdir: /builds/slave/test/build/tests 12:11:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:11:39 INFO - trying https://queue.taskcluster.net/v1/task/CfEmsjFlSySZu2syzfRtJA/artifacts/public/build/target.test_packages.json 12:11:39 INFO - Downloading https://queue.taskcluster.net/v1/task/CfEmsjFlSySZu2syzfRtJA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 12:11:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CfEmsjFlSySZu2syzfRtJA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 12:11:40 INFO - Downloaded 989 bytes. 12:11:40 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 12:11:40 INFO - Using the following test package requirements: 12:11:40 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 12:11:40 INFO - u'common': [u'target.common.tests.zip'], 12:11:40 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 12:11:40 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 12:11:40 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 12:11:40 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 12:11:40 INFO - u'mozbase': [u'target.common.tests.zip'], 12:11:40 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 12:11:40 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 12:11:40 INFO - u'web-platform': [u'target.common.tests.zip', 12:11:40 INFO - u'target.web-platform.tests.tar.gz'], 12:11:40 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 12:11:40 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 12:11:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CfEmsjFlSySZu2syzfRtJA/artifacts/public/build/target.common.tests.zip 12:11:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CfEmsjFlSySZu2syzfRtJA/artifacts/public/build/target.common.tests.zip'}, attempt #1 12:11:40 INFO - Fetch https://queue.taskcluster.net/v1/task/CfEmsjFlSySZu2syzfRtJA/artifacts/public/build/target.common.tests.zip into memory 12:11:41 INFO - Content-Length response header: 54943858 12:11:41 INFO - Bytes received: 54943858 12:11:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CfEmsjFlSySZu2syzfRtJA/artifacts/public/build/target.talos.tests.zip 12:11:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CfEmsjFlSySZu2syzfRtJA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 12:11:51 INFO - Fetch https://queue.taskcluster.net/v1/task/CfEmsjFlSySZu2syzfRtJA/artifacts/public/build/target.talos.tests.zip into memory 12:11:53 INFO - Content-Length response header: 14513823 12:11:53 INFO - Bytes received: 14513823 12:11:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:11:53 INFO - trying https://queue.taskcluster.net/v1/task/CfEmsjFlSySZu2syzfRtJA/artifacts/public/build/target.tar.bz2 12:11:53 INFO - Downloading https://queue.taskcluster.net/v1/task/CfEmsjFlSySZu2syzfRtJA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 12:11:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CfEmsjFlSySZu2syzfRtJA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 12:11:54 INFO - Downloaded 61626997 bytes. 12:11:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CfEmsjFlSySZu2syzfRtJA/artifacts/public/build/target.tar.bz2 12:11:54 INFO - mkdir: /builds/slave/test/properties 12:11:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:11:54 INFO - Writing to file /builds/slave/test/properties/build_url 12:11:54 INFO - Contents: 12:11:54 INFO - build_url:https://queue.taskcluster.net/v1/task/CfEmsjFlSySZu2syzfRtJA/artifacts/public/build/target.tar.bz2 12:11:54 INFO - Running post-action listener: _resource_record_post_action 12:11:54 INFO - Running post-action listener: find_tests_for_verification 12:11:54 INFO - Running post-action listener: set_extra_try_arguments 12:11:54 INFO - [mozharness: 2017-09-26 19:11:54.501508Z] Finished download-and-extract step (success) 12:11:54 INFO - [mozharness: 2017-09-26 19:11:54.501607Z] Running populate-webroot step. 12:11:54 INFO - Running pre-action listener: _resource_record_pre_action 12:11:54 INFO - Running main action method: populate_webroot 12:11:54 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:11:54 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:11:54 INFO - 'tests': ['tresize', 12:11:54 INFO - 'tcanvasmark']}, 12:11:54 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:11:54 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:11:54 INFO - 'tests': ['dromaeo_css', 12:11:54 INFO - 'kraken']}, 12:11:54 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:11:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:11:54 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:11:54 INFO - 'talos_options': ['--disable-stylo'], 12:11:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:11:54 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:11:54 INFO - 'tests': ['damp', 'tps']}, 12:11:54 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:11:54 INFO - 'talos_options': ['--disable-stylo'], 12:11:54 INFO - 'tests': ['damp', 'tps']}, 12:11:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:11:54 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:11:54 INFO - 'tests': ['dromaeo_dom']}, 12:11:54 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 12:11:54 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:11:54 INFO - 'tests': ['basic_compositor_video', 12:11:54 INFO - 'glvideo']}, 12:11:54 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 12:11:54 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 12:11:54 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:11:54 INFO - 'talos_options': ['--disable-stylo'], 12:11:54 INFO - 'tests': ['ts_paint_webext', 12:11:54 INFO - 'tp5o_webext']}, 12:11:54 INFO - 'other-e10s': {'tests': ['a11yr', 12:11:54 INFO - 'ts_paint', 12:11:54 INFO - 'tpaint', 12:11:54 INFO - 'sessionrestore', 12:11:54 INFO - 'sessionrestore_many_windows', 12:11:54 INFO - 'sessionrestore_no_auto_restore', 12:11:54 INFO - 'tabpaint']}, 12:11:54 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:11:54 INFO - 'tests': ['a11yr', 12:11:54 INFO - 'ts_paint', 12:11:54 INFO - 'tpaint', 12:11:54 INFO - 'sessionrestore', 12:11:54 INFO - 'sessionrestore_many_windows', 12:11:54 INFO - 'sessionrestore_no_auto_restore', 12:11:54 INFO - 'tabpaint']}, 12:11:54 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 12:11:54 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 12:11:54 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:11:54 INFO - 'tests': ['perf_reftest_singletons']}, 12:11:54 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:11:54 INFO - 'tests': ['perf_reftest']}, 12:11:54 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:11:54 INFO - 'tsvgr_opacity', 12:11:54 INFO - 'tart', 12:11:54 INFO - 'tscrollx', 12:11:54 INFO - 'tsvg_static']}, 12:11:54 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:11:54 INFO - 'tests': ['tsvgx', 12:11:54 INFO - 'tsvgr_opacity', 12:11:54 INFO - 'tart', 12:11:54 INFO - 'tscrollx', 12:11:54 INFO - 'tsvg_static']}, 12:11:54 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:11:54 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:11:54 INFO - 'talos_options': ['--disable-stylo'], 12:11:54 INFO - 'tests': ['tp5o']}, 12:11:54 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:11:54 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 12:11:54 INFO - 'talos_options': ['--mitmproxy', 12:11:54 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:11:54 INFO - '--firstNonBlankPaint'], 12:11:54 INFO - 'tests': ['tp6_google', 12:11:54 INFO - 'tp6_youtube', 12:11:54 INFO - 'tp6_amazon', 12:11:54 INFO - 'tp6_facebook']}, 12:11:54 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:11:54 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 12:11:54 INFO - 'talos_options': ['--disable-stylo', 12:11:54 INFO - '--mitmproxy', 12:11:54 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:11:54 INFO - '--firstNonBlankPaint'], 12:11:54 INFO - 'tests': ['tp6_google', 12:11:54 INFO - 'tp6_youtube', 12:11:54 INFO - 'tp6_amazon', 12:11:54 INFO - 'tp6_facebook']}, 12:11:54 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:11:54 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 12:11:54 INFO - 'talos_options': ['--stylo-threads=1', 12:11:54 INFO - '--mitmproxy', 12:11:54 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:11:54 INFO - '--firstNonBlankPaint'], 12:11:54 INFO - 'tests': ['tp6_google', 12:11:54 INFO - 'tp6_youtube', 12:11:54 INFO - 'tp6_amazon', 12:11:54 INFO - 'tp6_facebook']}, 12:11:54 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:11:54 INFO - 'talos_options': ['--xperf_path', 12:11:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:11:54 INFO - 'tests': ['tp5n']}, 12:11:54 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:11:54 INFO - 'talos_options': ['--disable-stylo', 12:11:54 INFO - '--xperf_path', 12:11:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:11:54 INFO - 'tests': ['tp5n']}}} 12:11:54 INFO - Running post-action listener: _resource_record_post_action 12:11:54 INFO - [mozharness: 2017-09-26 19:11:54.517007Z] Finished populate-webroot step (success) 12:11:54 INFO - [mozharness: 2017-09-26 19:11:54.517095Z] Running create-virtualenv step. 12:11:54 INFO - Running pre-action listener: _resource_record_pre_action 12:11:54 INFO - Running main action method: create_virtualenv 12:11:54 INFO - Creating virtualenv /builds/slave/test/build/venv 12:11:54 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 12:11:54 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 12:11:54 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 12:11:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:11:54 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:11:55 INFO - Installing distribute.............................................................................................................................................................................................done. 12:11:55 INFO - Installing pip...............done. 12:11:55 INFO - Return code: 0 12:11:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:11:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:11:55 INFO - Reading from file tmpfile_stdout 12:11:55 INFO - Output received: 12:11:55 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 12:11:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:11:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:11:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:11:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19d91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ba1b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d469c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d43d60>, '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 0x1d44a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d44ef0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1506453094.566063-2043789727', '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 12:11:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 12:11:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:11:55 INFO - Using env: {'DISPLAY': ':0', 12:11:55 INFO - 'HOME': '/home/cltbld', 12:11:55 INFO - 'LANG': 'en_US.UTF-8', 12:11:55 INFO - 'LANGUAGE': 'en_US:en', 12:11:55 INFO - 'LOGNAME': 'cltbld', 12:11:55 INFO - 'MAIL': '/var/mail/cltbld', 12:11:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:55 INFO - 'MOZ_NO_REMOTE': '1', 12:11:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:11:55 INFO - 'NO_EM_RESTART': '1', 12:11:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:11:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:55 INFO - 'PWD': '/builds/slave/test', 12:11:55 INFO - 'SHELL': '/bin/bash', 12:11:55 INFO - 'SHLVL': '1', 12:11:55 INFO - 'TERM': 'linux', 12:11:55 INFO - 'TMOUT': '86400', 12:11:55 INFO - 'USER': 'cltbld', 12:11:55 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1506453094.566063-2043789727', 12:11:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:55 INFO - '_': '/tools/buildbot/bin/python'} 12:11:58 INFO - Ignoring indexes: http://pypi.python.org/simple/ 12:11:58 INFO - Downloading/unpacking pip>=1.5 12:11:58 INFO - Running setup.py egg_info for package pip 12:11:58 INFO - warning: no files found matching 'pip/cacert.pem' 12:11:58 INFO - warning: no files found matching '*.html' under directory 'docs' 12:11:58 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 12:11:58 INFO - no previously-included directories found matching 'docs/_build/_sources' 12:11:58 INFO - Installing collected packages: pip 12:11:58 INFO - Found existing installation: pip 1.1 12:11:58 INFO - Uninstalling pip: 12:11:58 INFO - Successfully uninstalled pip 12:11:58 INFO - Running setup.py install for pip 12:11:58 INFO - warning: no files found matching 'pip/cacert.pem' 12:11:58 INFO - warning: no files found matching '*.html' under directory 'docs' 12:11:58 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 12:11:58 INFO - no previously-included directories found matching 'docs/_build/_sources' 12:11:58 INFO - Installing pip script to /builds/slave/test/build/venv/bin 12:11:58 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 12:11:58 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 12:11:58 INFO - Successfully installed pip 12:11:58 INFO - Cleaning up... 12:11:58 INFO - Return code: 0 12:11:58 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:11: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')]} 12:11:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11: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')]} 12:11:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11: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', '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 0x19d91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ba1b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d469c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d43d60>, '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 0x1d44a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d44ef0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1506453094.566063-2043789727', '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 12:11: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', 'psutil>=3.1.1'] in /builds/slave/test/build 12:11: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 psutil>=3.1.1 12:11:58 INFO - Using env: {'DISPLAY': ':0', 12:11:58 INFO - 'HOME': '/home/cltbld', 12:11:58 INFO - 'LANG': 'en_US.UTF-8', 12:11:58 INFO - 'LANGUAGE': 'en_US:en', 12:11:58 INFO - 'LOGNAME': 'cltbld', 12:11:58 INFO - 'MAIL': '/var/mail/cltbld', 12:11:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:58 INFO - 'MOZ_NO_REMOTE': '1', 12:11:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:11:58 INFO - 'NO_EM_RESTART': '1', 12:11:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:11:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:58 INFO - 'PWD': '/builds/slave/test', 12:11:58 INFO - 'SHELL': '/bin/bash', 12:11:58 INFO - 'SHLVL': '1', 12:11:58 INFO - 'TERM': 'linux', 12:11:58 INFO - 'TMOUT': '86400', 12:11:58 INFO - 'USER': 'cltbld', 12:11:58 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1506453094.566063-2043789727', 12:11:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:58 INFO - '_': '/tools/buildbot/bin/python'} 12:11:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:58 INFO - Downloading/unpacking psutil>=3.1.1 12:11: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 12:11: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 12:11: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 12:11: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 12:11:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:11:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:11:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:11:59 INFO - Installing collected packages: psutil 12:11:59 INFO - Running setup.py install for psutil 12:11:59 INFO - building 'psutil._psutil_linux' extension 12:11:59 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 12:11:59 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 12:11:59 INFO - building 'psutil._psutil_posix' extension 12:11:59 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 12:12:00 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 12:12:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:12:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:12:00 INFO - Successfully installed psutil 12:12:00 INFO - Cleaning up... 12:12:00 INFO - Return code: 0 12:12:00 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:12:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:12:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:12:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:12:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:12:00 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 0x19d91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ba1b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d469c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d43d60>, '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 0x1d44a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d44ef0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1506453094.566063-2043789727', '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 12:12:00 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 12:12:00 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 12:12:00 INFO - Using env: {'DISPLAY': ':0', 12:12:00 INFO - 'HOME': '/home/cltbld', 12:12:00 INFO - 'LANG': 'en_US.UTF-8', 12:12:00 INFO - 'LANGUAGE': 'en_US:en', 12:12:00 INFO - 'LOGNAME': 'cltbld', 12:12:00 INFO - 'MAIL': '/var/mail/cltbld', 12:12:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:12:00 INFO - 'MOZ_NO_REMOTE': '1', 12:12:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:12:00 INFO - 'NO_EM_RESTART': '1', 12:12:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:12:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:12:00 INFO - 'PWD': '/builds/slave/test', 12:12:00 INFO - 'SHELL': '/bin/bash', 12:12:00 INFO - 'SHLVL': '1', 12:12:00 INFO - 'TERM': 'linux', 12:12:00 INFO - 'TMOUT': '86400', 12:12:00 INFO - 'USER': 'cltbld', 12:12:00 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1506453094.566063-2043789727', 12:12:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:12:00 INFO - '_': '/tools/buildbot/bin/python'} 12:12:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:12:00 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:12:00 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 12:12:00 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 12:12:00 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 12:12:00 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 12:12:01 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:12:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:12:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:12:01 INFO - Installing collected packages: mozsystemmonitor 12:12:01 INFO - Running setup.py install for mozsystemmonitor 12:12:02 INFO - Successfully installed mozsystemmonitor 12:12:02 INFO - Cleaning up... 12:12:02 INFO - Return code: 0 12:12:02 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:12:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:12:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:12:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:12:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:12:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x19d91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ba1b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d469c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d43d60>, '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 0x1d44a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d44ef0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1506453094.566063-2043789727', '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 12:12:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 12:12:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 12:12:02 INFO - Using env: {'DISPLAY': ':0', 12:12:02 INFO - 'HOME': '/home/cltbld', 12:12:02 INFO - 'LANG': 'en_US.UTF-8', 12:12:02 INFO - 'LANGUAGE': 'en_US:en', 12:12:02 INFO - 'LOGNAME': 'cltbld', 12:12:02 INFO - 'MAIL': '/var/mail/cltbld', 12:12:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:12:02 INFO - 'MOZ_NO_REMOTE': '1', 12:12:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:12:02 INFO - 'NO_EM_RESTART': '1', 12:12:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:12:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:12:02 INFO - 'PWD': '/builds/slave/test', 12:12:02 INFO - 'SHELL': '/bin/bash', 12:12:02 INFO - 'SHLVL': '1', 12:12:02 INFO - 'TERM': 'linux', 12:12:02 INFO - 'TMOUT': '86400', 12:12:02 INFO - 'USER': 'cltbld', 12:12:02 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1506453094.566063-2043789727', 12:12:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:12:02 INFO - '_': '/tools/buildbot/bin/python'} 12:12:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:12:02 INFO - Downloading/unpacking jsonschema==2.5.1 12:12:02 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 12:12:02 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 12:12:02 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 12:12:02 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 12:12:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:12:03 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:12: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 12:12: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 12:12: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 12:12: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 12:12:03 INFO - Downloading functools32-3.2.3-2.tar.gz 12:12:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:12:04 INFO - warning: no files found matching '*.txt' 12:12:04 INFO - no previously-included directories found matching 'build' 12:12:04 INFO - no previously-included directories found matching 'dist' 12:12:04 INFO - no previously-included directories found matching '.git*' 12:12:04 INFO - Installing collected packages: jsonschema, functools32 12:12:04 INFO - Running setup.py install for functools32 12:12:04 INFO - warning: no files found matching '*.txt' 12:12:04 INFO - no previously-included directories found matching 'build' 12:12:04 INFO - no previously-included directories found matching 'dist' 12:12:04 INFO - no previously-included directories found matching '.git*' 12:12:04 INFO - Successfully installed jsonschema functools32 12:12:04 INFO - Cleaning up... 12:12:04 INFO - Return code: 0 12:12:04 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:12: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')]} 12:12:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:12: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')]} 12:12:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:12:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x19d91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ba1b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d469c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d43d60>, '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 0x1d44a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d44ef0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1506453094.566063-2043789727', '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 12:12:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:12:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 12:12:04 INFO - Using env: {'DISPLAY': ':0', 12:12:04 INFO - 'HOME': '/home/cltbld', 12:12:04 INFO - 'LANG': 'en_US.UTF-8', 12:12:04 INFO - 'LANGUAGE': 'en_US:en', 12:12:04 INFO - 'LOGNAME': 'cltbld', 12:12:04 INFO - 'MAIL': '/var/mail/cltbld', 12:12:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:12:04 INFO - 'MOZ_NO_REMOTE': '1', 12:12:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:12:04 INFO - 'NO_EM_RESTART': '1', 12:12:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:12:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:12:04 INFO - 'PWD': '/builds/slave/test', 12:12:04 INFO - 'SHELL': '/bin/bash', 12:12:04 INFO - 'SHLVL': '1', 12:12:04 INFO - 'TERM': 'linux', 12:12:04 INFO - 'TMOUT': '86400', 12:12:04 INFO - 'USER': 'cltbld', 12:12:04 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1506453094.566063-2043789727', 12:12:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:12:04 INFO - '_': '/tools/buildbot/bin/python'} 12:12:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:12:04 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:12:04 INFO - Cleaning up... 12:12:04 INFO - Return code: 0 12:12:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:12: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')]} 12:12:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:12: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')]} 12:12:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:12:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x19d91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ba1b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d469c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d43d60>, '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 0x1d44a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d44ef0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1506453094.566063-2043789727', '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 12:12:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:12:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:12:04 INFO - Using env: {'DISPLAY': ':0', 12:12:04 INFO - 'HOME': '/home/cltbld', 12:12:04 INFO - 'LANG': 'en_US.UTF-8', 12:12:04 INFO - 'LANGUAGE': 'en_US:en', 12:12:04 INFO - 'LOGNAME': 'cltbld', 12:12:04 INFO - 'MAIL': '/var/mail/cltbld', 12:12:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:12:04 INFO - 'MOZ_NO_REMOTE': '1', 12:12:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:12:04 INFO - 'NO_EM_RESTART': '1', 12:12:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:12:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:12:04 INFO - 'PWD': '/builds/slave/test', 12:12:04 INFO - 'SHELL': '/bin/bash', 12:12:04 INFO - 'SHLVL': '1', 12:12:04 INFO - 'TERM': 'linux', 12:12:04 INFO - 'TMOUT': '86400', 12:12:04 INFO - 'USER': 'cltbld', 12:12:04 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1506453094.566063-2043789727', 12:12:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:12:04 INFO - '_': '/tools/buildbot/bin/python'} 12:12:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:12:04 INFO - Downloading/unpacking blobuploader==1.2.4 12:12:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:12:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:12:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:12:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:12:05 INFO - Downloading blobuploader-1.2.4.tar.gz 12:12:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:12:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:12:05 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 12:12:05 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 12:12:05 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 12:12:05 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 12:12:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:12:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:12:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:12:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:12:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:12:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:12:06 INFO - Downloading docopt-0.6.1.tar.gz 12:12:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:12:06 INFO - Installing collected packages: blobuploader, requests, docopt 12:12:06 INFO - Running setup.py install for blobuploader 12:12:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:12:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:12:06 INFO - Running setup.py install for requests 12:12:06 INFO - Running setup.py install for docopt 12:12:06 INFO - Successfully installed blobuploader requests docopt 12:12:06 INFO - Cleaning up... 12:12:06 INFO - Return code: 0 12:12:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:12:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:12:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:12:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:12:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:12:06 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 0x19d91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ba1b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d469c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d43d60>, '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 0x1d44a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d44ef0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1506453094.566063-2043789727', '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 12:12:06 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 12:12:06 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 12:12:06 INFO - Using env: {'DISPLAY': ':0', 12:12:06 INFO - 'HOME': '/home/cltbld', 12:12:06 INFO - 'LANG': 'en_US.UTF-8', 12:12:06 INFO - 'LANGUAGE': 'en_US:en', 12:12:06 INFO - 'LOGNAME': 'cltbld', 12:12:06 INFO - 'MAIL': '/var/mail/cltbld', 12:12:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:12:06 INFO - 'MOZ_NO_REMOTE': '1', 12:12:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:12:06 INFO - 'NO_EM_RESTART': '1', 12:12:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:12:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:12:06 INFO - 'PWD': '/builds/slave/test', 12:12:06 INFO - 'SHELL': '/bin/bash', 12:12:06 INFO - 'SHLVL': '1', 12:12:07 INFO - 'TERM': 'linux', 12:12:07 INFO - 'TMOUT': '86400', 12:12:07 INFO - 'USER': 'cltbld', 12:12:07 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1506453094.566063-2043789727', 12:12:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:12:07 INFO - '_': '/tools/buildbot/bin/python'} 12:12:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:12:07 INFO - Running setup.py (path:/tmp/pip-3SGf0l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:12:07 INFO - Running setup.py (path:/tmp/pip-E6j6sz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:12:07 INFO - Running setup.py (path:/tmp/pip-t1w6RA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:12:07 INFO - Running setup.py (path:/tmp/pip-HwMJqw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:12:07 INFO - Running setup.py (path:/tmp/pip-457uH5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:12:07 INFO - Running setup.py (path:/tmp/pip-_zuFiS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:12:07 INFO - Running setup.py (path:/tmp/pip-dYRUso-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:12:07 INFO - Running setup.py (path:/tmp/pip-O95NKK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:12:07 INFO - Running setup.py (path:/tmp/pip-KvmtLZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:12:07 INFO - Running setup.py (path:/tmp/pip-ZpBTQi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:12:07 INFO - Running setup.py (path:/tmp/pip-foCcZP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:12:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:12:07 INFO - Running setup.py (path:/tmp/pip-G6mMwT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:12:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:12:08 INFO - Running setup.py (path:/tmp/pip-rC3PCY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:12:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:12:08 INFO - Running setup.py (path:/tmp/pip-eUpoLY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:12:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:12:08 INFO - Running setup.py (path:/tmp/pip-hRNdwK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:12:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:12:08 INFO - Running setup.py (path:/tmp/pip-T67mCS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:12:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:12:08 INFO - Running setup.py (path:/tmp/pip-eVEk85-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:12:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:12:08 INFO - Running setup.py install for manifestparser 12:12:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:12:08 INFO - Running setup.py install for mozcrash 12:12:08 INFO - Running setup.py install for mozdebug 12:12:08 INFO - Running setup.py install for mozdevice 12:12:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:12:08 INFO - Running setup.py install for mozfile 12:12:08 INFO - Running setup.py install for mozhttpd 12:12:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:12:09 INFO - Running setup.py install for mozinfo 12:12:09 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:12:09 INFO - Running setup.py install for mozInstall 12:12:09 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:12:09 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:12:09 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:12:09 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:12:09 INFO - Running setup.py install for mozleak 12:12:09 INFO - Running setup.py install for mozlog 12:12:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:12:09 INFO - Running setup.py install for moznetwork 12:12:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:12:09 INFO - Running setup.py install for mozprocess 12:12:09 INFO - Running setup.py install for mozprofile 12:12:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:12:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:12:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:12:09 INFO - Running setup.py install for mozrunner 12:12:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:12:09 INFO - Running setup.py install for mozscreenshot 12:12:09 INFO - Running setup.py install for moztest 12:12:10 INFO - Running setup.py install for mozversion 12:12:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:12:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:12:10 INFO - Cleaning up... 12:12:10 INFO - Return code: 0 12:12:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:12: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')]} 12:12:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:12: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')]} 12:12:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:12:10 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 0x19d91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ba1b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d469c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d43d60>, '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 0x1d44a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d44ef0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1506453094.566063-2043789727', '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 12:12:10 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 12:12:10 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 12:12:10 INFO - Using env: {'DISPLAY': ':0', 12:12:10 INFO - 'HOME': '/home/cltbld', 12:12:10 INFO - 'LANG': 'en_US.UTF-8', 12:12:10 INFO - 'LANGUAGE': 'en_US:en', 12:12:10 INFO - 'LOGNAME': 'cltbld', 12:12:10 INFO - 'MAIL': '/var/mail/cltbld', 12:12:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:12:10 INFO - 'MOZ_NO_REMOTE': '1', 12:12:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:12:10 INFO - 'NO_EM_RESTART': '1', 12:12:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:12:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:12:10 INFO - 'PWD': '/builds/slave/test', 12:12:10 INFO - 'SHELL': '/bin/bash', 12:12:10 INFO - 'SHLVL': '1', 12:12:10 INFO - 'TERM': 'linux', 12:12:10 INFO - 'TMOUT': '86400', 12:12:10 INFO - 'USER': 'cltbld', 12:12:10 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1506453094.566063-2043789727', 12:12:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:12:10 INFO - '_': '/tools/buildbot/bin/python'} 12:12:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:12:10 INFO - Running setup.py (path:/tmp/pip-t7b01K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:12:10 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)) 12:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:12:10 INFO - Running setup.py (path:/tmp/pip-qWtQxQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:12:10 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)) 12:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:12:10 INFO - Running setup.py (path:/tmp/pip-0WMfEd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:12:10 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)) 12:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:12:10 INFO - Running setup.py (path:/tmp/pip-IqDTjm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:12:10 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)) 12:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:12:10 INFO - Running setup.py (path:/tmp/pip-HnU67v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:12:10 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)) 12:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:12:10 INFO - Running setup.py (path:/tmp/pip-NhA8QV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:12:10 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)) 12:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:12:10 INFO - Running setup.py (path:/tmp/pip-vMrHeJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:12:10 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)) 12:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:12:10 INFO - Running setup.py (path:/tmp/pip-LYgVpJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:12:10 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)) 12:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:12:10 INFO - Running setup.py (path:/tmp/pip-pd8EzW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:12:11 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)) 12:12:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:12:11 INFO - Running setup.py (path:/tmp/pip-58eBds-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:12:11 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)) 12:12:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:12:11 INFO - Running setup.py (path:/tmp/pip-_1EEKE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:12:11 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)) 12:12:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:12:11 INFO - Running setup.py (path:/tmp/pip-KuBAur-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:12:11 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)) 12:12:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:12:11 INFO - Running setup.py (path:/tmp/pip-hMlZCF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:12:11 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)) 12:12:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:12:11 INFO - Running setup.py (path:/tmp/pip-T_Qwdn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:12:11 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)) 12:12:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:12:11 INFO - Running setup.py (path:/tmp/pip-wBtYyc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:12:11 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)) 12:12:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:12:11 INFO - Running setup.py (path:/tmp/pip-HR7Gtl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:12:11 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)) 12:12:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:12:11 INFO - Running setup.py (path:/tmp/pip-Lr3lWH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:12:11 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)) 12:12:11 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)) 12:12:11 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)) 12:12:11 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)) 12:12:11 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)) 12:12:11 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)) 12:12:11 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)) 12:12:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:12:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:12:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:12:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:12:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:12:12 INFO - Downloading blessings-1.6.tar.gz 12:12:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:12:12 INFO - Installing collected packages: blessings 12:12:12 INFO - Running setup.py install for blessings 12:12:12 INFO - Successfully installed blessings 12:12:12 INFO - Cleaning up... 12:12:12 INFO - Return code: 0 12:12:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:12:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:12:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:12:13 INFO - Reading from file tmpfile_stdout 12:12:13 INFO - Current package versions: 12:12:13 INFO - argparse == 1.2.1 12:12:13 INFO - blessings == 1.6 12:12:13 INFO - blobuploader == 1.2.4 12:12:13 INFO - docopt == 0.6.1 12:12:13 INFO - functools32 == 3.2.3-2 12:12:13 INFO - jsonschema == 2.5.1 12:12:13 INFO - manifestparser == 1.1 12:12:13 INFO - mozInstall == 1.13 12:12:13 INFO - mozcrash == 1.0 12:12:13 INFO - mozdebug == 0.1 12:12:13 INFO - mozdevice == 0.51 12:12:13 INFO - mozfile == 1.2 12:12:13 INFO - mozhttpd == 0.7 12:12:13 INFO - mozinfo == 0.10 12:12:13 INFO - mozleak == 0.1 12:12:13 INFO - mozlog == 3.5 12:12:13 INFO - moznetwork == 0.27 12:12:13 INFO - mozprocess == 0.25 12:12:13 INFO - mozprofile == 0.28 12:12:13 INFO - mozrunner == 6.13 12:12:13 INFO - mozscreenshot == 0.1 12:12:13 INFO - mozsystemmonitor == 0.3 12:12:13 INFO - moztest == 0.8 12:12:13 INFO - mozversion == 1.4 12:12:13 INFO - psutil == 3.1.1 12:12:13 INFO - requests == 1.2.3 12:12:13 INFO - wsgiref == 0.1.2 12:12:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:12:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:12:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:12:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:12:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:12:13 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 0x19d91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ba1b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d469c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d43d60>, '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 0x1d44a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d44ef0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1506453094.566063-2043789727', '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 12:12:13 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 12:12:13 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 12:12:13 INFO - Using env: {'DISPLAY': ':0', 12:12:13 INFO - 'HOME': '/home/cltbld', 12:12:13 INFO - 'LANG': 'en_US.UTF-8', 12:12:13 INFO - 'LANGUAGE': 'en_US:en', 12:12:13 INFO - 'LOGNAME': 'cltbld', 12:12:13 INFO - 'MAIL': '/var/mail/cltbld', 12:12:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:12:13 INFO - 'MOZ_NO_REMOTE': '1', 12:12:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:12:13 INFO - 'NO_EM_RESTART': '1', 12:12:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:12:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:12:13 INFO - 'PWD': '/builds/slave/test', 12:12:13 INFO - 'SHELL': '/bin/bash', 12:12:13 INFO - 'SHLVL': '1', 12:12:13 INFO - 'TERM': 'linux', 12:12:13 INFO - 'TMOUT': '86400', 12:12:13 INFO - 'USER': 'cltbld', 12:12:13 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1506453094.566063-2043789727', 12:12:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:12:13 INFO - '_': '/tools/buildbot/bin/python'} 12:12:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:12:13 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)) 12:12:13 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)) 12:12:13 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)) 12:12:13 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)) 12:12:13 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)) 12:12:13 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)) 12:12:13 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)) 12:12:13 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)) 12:12:13 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)) 12:12:13 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 12:12:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:12:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:12:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:12:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:12:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 12:12:14 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)) 12:12:14 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)) 12:12:14 INFO - Installing collected packages: simplejson 12:12:14 INFO - Running setup.py install for simplejson 12:12:14 INFO - building 'simplejson._speedups' extension 12:12:14 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 12:12:15 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 12:12:15 INFO - Successfully installed simplejson 12:12:15 INFO - Cleaning up... 12:12:15 INFO - Return code: 0 12:12:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:12:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:12:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:12:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:12:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:12:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19d91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ba1b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d469c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d43d60>, '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 0x1d44a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d44ef0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1506453094.566063-2043789727', '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 12:12:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:12:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:12:15 INFO - Using env: {'DISPLAY': ':0', 12:12:15 INFO - 'HOME': '/home/cltbld', 12:12:15 INFO - 'LANG': 'en_US.UTF-8', 12:12:15 INFO - 'LANGUAGE': 'en_US:en', 12:12:15 INFO - 'LOGNAME': 'cltbld', 12:12:15 INFO - 'MAIL': '/var/mail/cltbld', 12:12:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:12:15 INFO - 'MOZ_NO_REMOTE': '1', 12:12:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:12:15 INFO - 'NO_EM_RESTART': '1', 12:12:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:12:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:12:15 INFO - 'PWD': '/builds/slave/test', 12:12:15 INFO - 'SHELL': '/bin/bash', 12:12:15 INFO - 'SHLVL': '1', 12:12:15 INFO - 'TERM': 'linux', 12:12:15 INFO - 'TMOUT': '86400', 12:12:15 INFO - 'USER': 'cltbld', 12:12:15 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1506453094.566063-2043789727', 12:12:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:12:15 INFO - '_': '/tools/buildbot/bin/python'} 12:12:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:12:15 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:12:15 INFO - Cleaning up... 12:12:15 INFO - Return code: 0 12:12:15 INFO - Running post-action listener: _resource_record_post_action 12:12:15 INFO - Running post-action listener: _start_resource_monitoring 12:12:15 INFO - Starting resource monitoring. 12:12:15 INFO - [mozharness: 2017-09-26 19:12:15.875572Z] Finished create-virtualenv step (success) 12:12:15 INFO - [mozharness: 2017-09-26 19:12:15.876099Z] Running install step. 12:12:15 INFO - Running pre-action listener: _resource_record_pre_action 12:12:15 INFO - Running main action method: install 12:12:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:12:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:12:16 INFO - Reading from file tmpfile_stdout 12:12:16 INFO - Detecting whether we're running mozinstall >=1.0... 12:12:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:12:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:12:16 INFO - Reading from file tmpfile_stdout 12:12:16 INFO - Output received: 12:12:16 INFO - Usage: mozinstall [options] installer 12:12:16 INFO - Options: 12:12:16 INFO - -h, --help show this help message and exit 12:12:16 INFO - -d DEST, --destination=DEST 12:12:16 INFO - Directory to install application into. [default: 12:12:16 INFO - "/builds/slave/test"] 12:12:16 INFO - --app=APP Application being installed. [default: firefox] 12:12:16 INFO - mkdir: /builds/slave/test/build/application 12:12:16 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'] 12:12:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 12:12:34 INFO - Reading from file tmpfile_stdout 12:12:34 INFO - Output received: 12:12:34 INFO - /builds/slave/test/build/application/firefox/firefox 12:12:34 INFO - Running post-action listener: _resource_record_post_action 12:12:34 INFO - [mozharness: 2017-09-26 19:12:34.247928Z] Finished install step (success) 12:12:34 INFO - [mozharness: 2017-09-26 19:12:34.248182Z] Running setup-mitmproxy step. 12:12:34 INFO - Running pre-action listener: _resource_record_pre_action 12:12:34 INFO - Running main action method: setup_mitmproxy 12:12:34 INFO - Skipping: mitmproxy is not required 12:12:34 INFO - Running post-action listener: _resource_record_post_action 12:12:34 INFO - [mozharness: 2017-09-26 19:12:34.249306Z] Finished setup-mitmproxy step (success) 12:12:34 INFO - [mozharness: 2017-09-26 19:12:34.249623Z] Running run-tests step. 12:12:34 INFO - Running pre-action listener: _resource_record_pre_action 12:12:34 INFO - Running pre-action listener: _set_gcov_prefix 12:12:34 INFO - Running main action method: run_tests 12:12:34 WARNING - Try message not found. 12:12:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:12:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:12:34 INFO - Python 2.7.3 12:12:34 INFO - Return code: 0 12:12:34 INFO - grabbing minidump binary from tooltool 12:12:34 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')]} 12:12:34 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 0x1d43d60>, '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 0x1d44a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d44ef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:12:34 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 12:12:34 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 12:12:34 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 12:12:34 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:12:34 INFO - Return code: 0 12:12:34 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:12:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:12:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:12:34 INFO - ENV: RUST_BACKTRACE is now full 12:12:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:12:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:12:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CfEmsjFlSySZu2syzfRtJA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-005', '--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 12:12:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/CfEmsjFlSySZu2syzfRtJA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-005 --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 12:12:34 INFO - Using env: {'DISPLAY': ':0', 12:12:34 INFO - 'HOME': '/home/cltbld', 12:12:34 INFO - 'LANG': 'en_US.UTF-8', 12:12:34 INFO - 'LANGUAGE': 'en_US:en', 12:12:34 INFO - 'LOGNAME': 'cltbld', 12:12:34 INFO - 'MAIL': '/var/mail/cltbld', 12:12:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:12:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:12:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:12:34 INFO - 'MOZ_NO_REMOTE': '1', 12:12:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:12:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:12:34 INFO - 'NO_EM_RESTART': '1', 12:12:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:12:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:12:34 INFO - 'PWD': '/builds/slave/test', 12:12:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:12:34 INFO - 'RUST_BACKTRACE': 'full', 12:12:34 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:12:34 INFO - 'SHELL': '/bin/bash', 12:12:34 INFO - 'SHLVL': '1', 12:12:34 INFO - 'TERM': 'linux', 12:12:34 INFO - 'TMOUT': '86400', 12:12:34 INFO - 'USER': 'cltbld', 12:12:34 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1506453094.566063-2043789727', 12:12:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:12:34 INFO - '_': '/tools/buildbot/bin/python'} 12:12:34 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CfEmsjFlSySZu2syzfRtJA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-005', '--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 12:12:34 INFO - mozversion application_buildid: 20170926181431 12:12:34 INFO - mozversion application_changeset: 39aaf54972cb11a63815a96b532786133baa95bc 12:12:34 INFO - mozversion application_display_name: Nightly 12:12:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:12:34 INFO - mozversion application_name: Firefox 12:12:34 INFO - mozversion application_remotingname: firefox 12:12:34 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 12:12:34 INFO - mozversion application_vendor: Mozilla 12:12:34 INFO - mozversion application_version: 58.0a1 12:12:34 INFO - mozversion platform_buildid: 20170926181431 12:12:34 INFO - mozversion platform_changeset: 39aaf54972cb11a63815a96b532786133baa95bc 12:12:34 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 12:12:34 INFO - mozversion platform_version: 58.0a1 12:12:34 INFO - using testdate: 1506453154 12:12:34 INFO - actual date: 1506453154 12:12:34 INFO - starting webserver on 'localhost:59581' 12:12:34 INFO - SUITE-START | Running 2 tests 12:12:34 INFO - TEST-START | dromaeo_css 12:12:34 INFO - Initialising browser for dromaeo_css test... 12:12:34 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:59581/getInfo.html -profile /tmp/tmp9EK584/profile 12:12:34 INFO - TEST-INFO | started process 19994 (/builds/slave/test/build/application/firefox/firefox http://localhost:59581/getInfo.html) 12:12:40 INFO - TEST-INFO | 19994: exit 0 12:12:40 INFO - Browser initialized. 12:12:40 INFO - Running cycle 1/1 for dromaeo_css test... 12:12:40 INFO - Using env: {'DISPLAY': ':0', 12:12:40 INFO - 'HOME': '/home/cltbld', 12:12:40 INFO - 'JSGC_DISABLE_POISONING': '1', 12:12:40 INFO - 'LANG': 'en_US.UTF-8', 12:12:40 INFO - 'LANGUAGE': 'en_US:en', 12:12:40 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 12:12:40 INFO - 'LOGNAME': 'cltbld', 12:12:40 INFO - 'MAIL': '/var/mail/cltbld', 12:12:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:12:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:12:40 INFO - 'MOZ_CRASHREPORTER': '1', 12:12:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:12:40 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 12:12:40 INFO - 'MOZ_NO_REMOTE': '1', 12:12:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:12:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:12:40 INFO - 'NO_EM_RESTART': '1', 12:12:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:12:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:12:40 INFO - 'PWD': '/builds/slave/test', 12:12:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:12:40 INFO - 'RUST_BACKTRACE': 'full', 12:12:40 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:12:40 INFO - 'SHELL': '/bin/bash', 12:12:40 INFO - 'SHLVL': '1', 12:12:40 INFO - 'TERM': 'linux', 12:12:40 INFO - 'TMOUT': '86400', 12:12:40 INFO - 'USER': 'cltbld', 12:12:40 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1506453094.566063-2043789727', 12:12:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:12:40 INFO - '_': '/tools/buildbot/bin/python'} 12:12:40 INFO - TEST-INFO | started process 20231 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9EK584/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 12:12:41 INFO - PID 20231 | 12:12:41 INFO - PID 20231 | (/builds/slave/test/build/application/firefox/firefox:20280): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 12:12:41 INFO - PID 20231 | 12:12:51 INFO - PID 20231 | RSS: Main: 152027136 12:12:51 INFO - PID 20231 | 12:15:48 INFO - PID 20231 | Cycle 1(1): loaded http://localhost:59581/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59581/tests/dromaeo/cssquery-ext.html) 12:15:49 INFO - PID 20231 | RSS: Main: 152780800 12:15:49 INFO - PID 20231 | 12:18:51 INFO - PID 20231 | Cycle 1(1): loaded http://localhost:59581/tests/dromaeo/cssquery-ext.html (next: http://localhost:59581/tests/dromaeo/cssquery-jquery.html) 12:18:52 INFO - PID 20231 | RSS: Main: 152809472 12:18:52 INFO - PID 20231 | 12:21:53 INFO - PID 20231 | Cycle 1(1): loaded http://localhost:59581/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59581/tests/dromaeo/cssquery-mootools.html) 12:21:54 INFO - PID 20231 | RSS: Main: 154869760 12:21:54 INFO - PID 20231 | 12:24:56 INFO - PID 20231 | Cycle 1(1): loaded http://localhost:59581/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59581/tests/dromaeo/cssquery-prototype.html) 12:24:56 INFO - PID 20231 | RSS: Main: 153624576 12:24:56 INFO - PID 20231 | 12:28:00 INFO - PID 20231 | Cycle 1(1): loaded http://localhost:59581/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59581/tests/dromaeo/cssquery-yui.html) 12:28:00 INFO - PID 20231 | RSS: Main: 154525696 12:28:00 INFO - PID 20231 | 12:31:02 INFO - PID 20231 | Cycle 1(1): loaded http://localhost:59581/tests/dromaeo/cssquery-yui.html (next: http://localhost:59581/tests/dromaeo/cssquery-dojo.html) 12:31:02 INFO - PID 20231 | RSS: Main: 157184000 12:31:02 INFO - PID 20231 | 12:31:02 INFO - PID 20231 | __start_tp_report 12:31:02 INFO - PID 20231 | _x_x_mozilla_page_load 12:31:02 INFO - PID 20231 | _x_x_mozilla_page_load_details 12:31:02 INFO - PID 20231 | |i|pagename|runs| 12:31:02 INFO - PID 20231 | |0;dojo.html;3101.426236146313;3120.0639808057585;3121.0636808957306;3158.5104308832138;3185.824779637122;6067.605605635631;6213.906791398133;6285.0572414137905;6299.77950771723;6375.872482550346;6173.6295822250695;6204.503639708823;6210.7205175767085;6253.374662533744;6293.213348331458;5765.423457654243;5797.623154494955;5813.302403711539;5841.64950102993;5860.53115750741;524.7070407628654;5800.071988481844;5812.389699081599;5917.230760001194;5929;5654.717264136777;5667.376588575254;5687.374388817227;5769.394213607553;5806.9709651451685;5708.600397972144;5713.485786279213;5774.133879918004;5790.826275211708;5793.13103034544;5691.715414229272;5699.2306038684665;5753.395893431213;5758.452946970031;5773.595848290622;5995.400459954013;6142.539309551801;6238.812835614894;6245.937540624581;6333.398327158912;6526.608403495806;6606.86786264272;6684.197896252432;6709.463242940602;6783.253842077367;6732.83167920801;6784.626845523494;6785.321467853225;6789.626570538619;6791.3548212919695;6224.066390041485;6248.812535623939;6293.339199384044;6324.051392291147;6330.905036424458;6902.896456553156;6907.965460172667;6958.304169583002;6999.405050570707;7004.859902801915;6439.581427207217;6482.1573195485225;6502.837429064311;6514.055461958084;6557.901631475532;6732.1921369435;6744.7976560703255;6752.291009444036;6752.729890804313;6778.76274330406;6646.900296495556;6647.368499992493;6651.534392592472;6657.300983396771;6736.090627765287;6529.053287273318;6666.633335166515;6668.299828517936;6761.188657361148;6783;5206.7917283309;5249.632525723163;5318.308619879391;5318.361796584435;5318.547923426475;4614.492405835355;4644.210484217623;4677.508861569486;4685.484596694361;4689.906201875943;5616.85957851057;5709.714514274269;5775.8556036099435;5797;5798.82603521895;6509.251436084911;6617.371349721769;6649.102371179829;6707.429868461236;6799.252082270947;5714.914276285859;5761.884762304814;5780.1907732917425;5781.219535362672;5792.652440853562;5657.43425657431;5780.017397042513;5836.562257830679;5864.149698293723;5871.6183448075735;6393.073004414334;6407.871842563122;6430.324815894357;6432.614043157332;6437.0988061671405;6634.900476492857;6643.335666433415;6713.530052896251;6784.423324017316;6784.96607516979;6586.077949087227;6589.209294884645;6591.901121483277;6597.109390232449;6599.96700016516;6530.934690653033;6621.3047629998155;6697.397234248941;6717.664116794239;6727.26000139994;6528.314526974599;6687.465002799861;6737.797866064026;6742.056112144206;6852.6916288768125;6463.224413070461;6478.967605161944;6482.092507048923;6484.935150648433;6557.442617377385;6561.737530498726;6579.046038324512;6585.6377899216;6588.637624930675;6591.505637077143;6420.325865784025;6420.871582568229;6500.089987401673;6501.219853617407;6506;6551.312112228338;6563.868722625425;6569.770058047945;6618.139641846529;6639.03733958583;6210.161628180138;6300.558960872878;6301.6219026858535;6305.936940630719;6313.463355614824 12:31:02 INFO - PID 20231 | |1;ext.html;825.8719898415748;833.3416600885301;853.4409961475232;879.511870911644;883.0418995390002;40265.19469610606;41717;41807.79096104519;41812.790936045356;41909.79045104774;37840.05399864998;37872.43191352132;38071.61928380715;38223.617763822425;38260.80869595655;7006.1592608887;7130.144382674061;7141.3572778449925;7162.212156662789;7166.283371662818;7036;7117.395021423185;7158.35574798268;7167.784966451015;7171.067761191038;7744.341730952876;7941.126476087626;7948.602569871512;7954.244346787046;7974.362051035903;7833.41249406294;7899.447038707292;7903.130655627905;7917.920820791776;7929.762107136795;7655.885161722578;7813.335866451358;7868.842623147562;7913.525188488651;7938.6427610757655;38045.61954380448;38069.80965095157;38119.618803811885;38250;38538;3461.532693086426;3477.6696213859896;3518.3315170117853;3526.9647303526895;3528.7706299090733;31125.688743112507;31256.531152032738;31420.842895785605;31465.685343146506;31469;5120.846374608767;5124.8206312778875;5148.691078535262;5162.148245539519;5165.793368265265;191351;192381;192593;192801.03599482053;193801;47300;47331.5266847334;47560;47595;47636.761816190694;49658.255126173135;49795.751021245385;49796;49797.253041204414;49933.75033124884;14610.561683149523;14625.488107916171;14821.036632618845;14865.851341486663;14868;11801.645950621494;11859.169858110023;11892.881071189177;11894.583689570823;11915.702107447383;13924.58226253214;13961.441542338396;14015.089019213923;14117.505887293895;14194.077384969944;5637.126245431965;5826.592138550261;5886.8822623547285;5910.674912879746;5926.2295901532525;1001.2840818814437;1014.6043043213006;1019.9235057370727;1032.509557959978;1035.782485678016;21930.232441864777;21978.670319945213;22056.117755289928;22150.88924555399;22275.55448891013;1114.6767437443214;1114.765899161169;1130.8077626803463;1135.4152611405134;1135.608215165783;11720.472578733978;11921.582744604071;12036.097292703082;12061.276323420623;12078.57724979639;215713;215981;217204;218566;218614;50913.23630145551;52733;53057.46942530525;54296.185557216675;54297.45702543003;1440.7838824176247;1454.040333379964;1460.5472303585923;1468.434652658734;1469.6766711323278;25862.353441164123;26150.607740884283;26215.868920655274;26621.600675990263;26853.462930741665;36623.45064823976;36673;36679.449808252364;36712.08219794527;36728;191801;193427;193554;193941;194315;32729.18177045593;32804.835975819966;32981.67018329786;33011.33977320392;33033.83483082569;39545.60454395419;41271;41332.1733565321;41769.373459397524;41917.79041104775;30836.69163308428;30843.537346940262;30922.381552368377;31313.373732525677;31637;30649.860306678314;30913.072607821803;30922.845385772926;30996.760129594564;31673.366532668755;34504.82747586246;34510.13724656904;34672;34726.30547389088;34776.82611586926;30572;30602.540961885144;30753.846230768704;30759.538606920465;30793.69206307908;22365;22646.980885859848;22908.427289317235;22977.88511057434;23215.53568928646 12:31:02 INFO - PID 20231 | |2;jquery.html;582.8630271886103;589.3693747689971;590.3004939147111;591.1428428778271;597.4861619007653;21977.340679779612;22967.540649187027;23015.769842301594;23029;23124.88437557814;24687.876560617216;24832.006719731187;24997.500049998987;25570.360740981483;25992.22023339298;6500.967495162529;6652.101966234545;6681.131452911139;6681.465482761391;6691.230508491512;6479.773207937723;6498.3826536478955;6578;6587.868242635169;6765.357291057342;6677.866442671144;6728.4953628477815;6731.360520750521;6731.663416829163;6731.8317042074095;6707.228668703064;6732.4950628703045;6737.831554211135;6738.898916516257;6749.493787965897;6045.6674882881425;6257.9687101564205;6296.9370306296805;6302.68486575674;6307.0539419087045;29119.56320655192;29558.113256602337;29790.55314170289;29804.70195298041;29852.55221171684;2048.621005114059;2056.1364227024687;2061.6186005589016;2069.865458745177;2080.9791902080938;19578.902105489382;20036.79963200364;20443.5911281775;20563.280285189947;20747.89626051875;2402.9879850600637;2444.828861979662;2460.938476538083;2466.8889899954547;2474.9628755568683;125711;132848;136582;143320.56679433177;143590.28204858908;14970.476033338999;15500.844991550166;15521;15619.84380156184;15931.761023584655;23964.161254356015;24228.636570451457;24237.75762242355;24284.87857560701;24297.39256518601;9650.565724542412;9767.316287859923;9855.310128291094;9875.802483950281;9887.851682224773;8855.424397414148;8875.023747387911;8876.068012858686;8974.551272436353;9014.323925705598;9050.954745226232;9233.722988310361;9257.537123143817;9443.858342124873;9619.663311784054;2660.255128564005;2697.7302269772867;2705.675318961737;2795.65054368204;2823.39297051135;946.9526523673788;963.8072385522923;967.2455484721928;973.9026097390346;976.5995941663883;19330.516737081405;19330.516737081685;19724.802751972584;19761.505962351057;19778;988.9159421449114;992.7617371830709;1021.8722659667542;1028.6451174344838;1033.379972016784;10864.185186111074;10881.564737410574;10938.671839844817;11061.612843550438;11063.391513466675;455468;464722;466927;472719;483199;106338.46830765797;107496;108681;109200;110562;1023.7901230247782;1044.5717255925035;1119.238917536067;1147;1192.6720151958282;20381.69427458619;20598.794012060287;20667;20847.687284691045;21312.57374852463;54574.454255456934;55019.449805501434;55103.724481377336;55519.167212492655;56323.15515267192;68005.3199467999;69441.30558694349;69768.65115674389;69895.65052174707;70339.29660703328;14702.55892323232;14853.628659283604;15022.399104035716;15034.097954122788;15054.397824086915;23433.88283058574;23555.88222058879;23629.763702362758;23731.762682372955;23882.283531494082;18556.257749689863;18651.813481865007;18774.53063673419;18798.624027519647;18901;16475.752863707294;16821.158942053095;16946.830531694526;17168.828311716723;17711.557211069827;18938.242470301033;18959.620807584048;19025.90487047556;19044.238230470626;19095.14071866797;14446;14446.061006034573;14502.202378869264;14566.4901728439;14637.634059148606;18554.25782968666;18578.44264672062;18624.255029798656;18763.87416754999;18790.436286911416 12:31:02 INFO - PID 20231 | |3;mootools.html;1320.1221187910041;1366.1939455721122;1383.066430159642;1387.8334599848017;1391.387789372676;15317.69364612707;15767.763483547727;15885.92057039716;15900.602484937854;15914.124723140223;15450.768238476432;15574.922125389385;15604.609884752887;15628.84371156291;15641.296141673623;2712.6609173853267;2752.119321817019;2784.4431113777223;2801.789865760066;2817.1407720645193;2762.1989623009304;2805.424887897986;2816.3381605322734;2818.943621127576;2819.887204511817;2506.223070848043;2607.4263661994332;2612.725663805302;2615.2023632791993;2744.0944488318905;2785.247983044577;2787.679416867066;2817.732315430041;2818.436312737461;2818.450402171567;2280.8745518996448;2537.8223524353302;2540.123657338234;2563.461673048662;2563.948721025569;15593.37626494939;15721.13533755643;15725.764113538306;15737.842621573867;15748.370065197378;7775.4168437367425;8027.7190298339865;8071.31393831525;8076.111627720946;8076.676932922676;5455.781768729246;5476.890462190774;5553.972230138864;5620.381758006616;5737.110747834093;2016.1733689187508;2021.0905092708192;2024.7469066366705;2055.989720051405;2062.494688801238;27438.17685469439;27792;27809.16572502828;27858.860705696545;27887.442251154862;6885.242623311432;6949.166100068024;6978.476614253951;7204.279572042754;7269.164046134762;17388.565285867953;17412.303507859797;17413.390531331344;17417.738733919003;17418.91290543539;7805.453618246782;8009.559474228857;8037;8056.476329038594;8056.556889371141;7292.45306602007;7411.703531858773;7493.512921660074;7649.808754781064;7676.616169191518;5119.923201151985;5244.763985620656;5262.657927234794;5339.252504649352;5346.224797404355;1509.3962415034075;1515.9924200378928;1524.9008814427027;1529.8699610533006;1541.9845801542065;910.8087301666724;914.4193437167446;923.2567782934802;925.9444433334022;931.0968360690119;7733.381329493627;7813.257740514642;7821.491603045783;7821.648025838851;7897.565633890076;718.867009603225;743.8995735575736;744.4119145874698;745.0649434959091;749.5952185819597;4597.356370108188;4629.814807407666;5075.492450754895;5147.073526765218;5207.3751149862255;21789.891050544964;22511.98696058681;22532.324030279116;22608.773912260996;22785.316440506813;21262.149514019267;21815.23646672359;22163.667544986838;22342.888285558467;22353.329400118022;11082.05802506796;11201.719957000978;11202.383868887453;11262.605808796652;11423;8187.181281871765;8267.83464330722;8338.458000229966;8484.745457636282;8485.830283394174;18221.088945552936;18630.44108676742;18751.34370297033;18982.905085474486;18998;18838.152283147017;19192.13635386383;19394.70907936354;19851.900740496207;20083.297084601967;10840.241183117421;11120.833187502356;11124.944375278072;11129.332240065622;11144.164187685794;13971.510997115052;14017.5093871714;14202.644933876736;14206.92896535552;14251.073680211168;11701.69179882293;11917.463714132711;11961.043116550829;12002.75994480123;12037.819432708691;11037.889621103686;11108.38903860263;11128.38793866345;11157.163212759238;11418.771624567627;9912;10088.546015429176;10120.949395252977;10135.138513226164;10139.64511242103;7216.278372162742;7254.673539690621;7380.889286660597;7386.482946193715;7392.186859445565;7236.276372362932;7489.887651685338;7537.208593097866;7552.773416797505;7571.242875712384 12:31:02 INFO - PID 20231 | |4;prototype.html;507.32019094413477;518.3934796288344;537.9623426360154;540.3893600231737;542.9619926605139;6255;6344.777932772348;6352.364763523646;6391.552591318609;6440.7101680424375;6208.554252389922;6225.408586184317;6232.3144454110125;6233.594816336935;6236.3763623637615;4964.131277026524;5044.697318160904;5054.368203974504;5070.29015937769;5077.060743762398;4967.304577359173;5099.69401835891;5101.69389836611;5105.744712764347;5153.149730294497;5069.847904562869;5079.085764562377;5079.492050794928;5087.6184286178495;5098.388193416795;5068.645194836363;5101.566366858821;5105.617078719092;5109.795608175687;5119.334486516766;4629.189891768927;4660.790264438108;4663.27719203524;4666.160091183586;4684.126349460209;6254.8123556293385;6268.529860260499;6280.183576135073;6339.492840572743;6366.745330186787;5742.397048309909;5757.539396848284;5758.88482230353;5795.710214489259;5850.736716847752;6660.733570657168;6677.465802735818;6722.596644201363;6727.66361681914;6742.8194519398285;5002.52476014778;5019.949800501985;5034.295198672189;5051.520105589963;5057.115004874169;7006.480819871154;7017.245688577856;7047.154341479055;7051.647417629098;7072.084900993811;5087.125773937787;5137.486251374908;5142.948570514247;5143.562797162208;5150.459201783834;5217.295665085165;5250.947490525045;5253.921191182136;5266.052110620049;5287.101192797234;5780.71096445176;5810.912836307459;5815.854603634943;5862.794802181903;5871.794487192928;6339.302676705559;6508;6609.966950165219;6785.253622101565;6790.456763458911;1771.4154329071375;1776.9733453998201;1784.295203394652;1788.2131861980947;1807.566184115829;1111.922165448427;1241.503398640551;1251.8184863194967;1251.8795677868438;1264.3551788587886;5597.524210442077;5722.828315150552;5742.483176514133;5816.389279125716;5844.620099693507;6772.424343930821;6788.49086318528;6838.316168383122;6869.725210991604;6911.412529934912;5769.913451298234;5788.27646544182;5793.536517078624;5812.157237200667;5854.941450585525;3602.765820221677;3618.656227658421;3625.3474374612824;3663.7618554794385;3670.908227294339;6493.675316234169;6553.377429144223;6572.507061970276;6636.800895973129;6670.199576050905;6800.557963732342;6833.248342682301;6863.38229559342;6884.965575172092;6941.618210998343;6421.5825971311715;6480.837979050561;6495.188101487314;6536.869262614721;6547.247066587308;6667.733290668513;6736.5958042517605;6753.264927704287;6770.424513916175;6806.55757375769;6627.966860165669;6742.42689371409;6743.460523158037;6746.277605676145;6812.386885180159;6439.162908821823;6468.514861385321;6585;6646.567973081735;6657.833554161185;5442.251662910205;5537.141743029837;5551.667599776106;5584.776608935597;5608.46719561641;6719.664016799043;6798;6807.319268073153;6892.517523773488;6923.238443771081;6572.915468947761;6575.80272591823;6665.200175978914;6677.76627818024;6723.2940541244425;6540.901886471802;6599.9010014848855;6633.645056005049;6635.668216589054;6732.273429261157;6549.836254093686;6600.53796234259;6609.900851487136;6611;6633.958191774383;6455.991933698866;6581.769638062645;6626;6654.234763002317;6667.799966001027;6083.847903802441;6197.721102550487;6270.184875966095;6393.744250229939;6429.4534964526665 12:31:02 INFO - PID 20231 | |5;yui.html;896.0367604824806;916.3219217778844;933.1834644685896;935.0509233128372;946.1910066892807;18460.261589536403;19352.12915418808;19370.322038728664;19388.9030554847;19490.22039118437;16818.15909204541;17305.567360815985;17443.651126977453;17489;17600.911995440005;959.1080295325327;973.3332667123008;974.9561269742843;978.6427953796848;982.2387649571567;980.5342462330407;987.027777639025;988.8269552828262;993.4337427666234;994.1947022911465;1044.858944042556;1060.8037513060108;1061.3525749292926;1061.3843970497094;1061.7239517725409;1055.5408397347178;1055.5672174408455;1056.1392465140923;1056.799208150449;1057.2546354819833;716.5449939288537;734.229059487536;741.573595182772;741.740390863199;741.8293792427772;20298.391048268575;20439.080241389023;20533.691994620094;20543.178272869067;20573.279935202198;21834.23580174702;21996.230131945464;22374.2169024085;22446.438839028993;22459.887700561558;17082.914585426992;17291.135443227788;17368.478945631527;17488.300467981393;17500.649987000317;3668.779873207616;3689.0961714379882;3693.7045036397026;3704.907377315562;3708.128589781399;140275;142765;144583;145258;146802;15828;16089.839101609068;16134.515964520848;16239.106849123411;16239.59401014984;30458.238544036132;31296.21759456032;31555;31716.841415792773;31750;7903.841923161504;7934.047914250327;7971.681132754625;8033.437659363905;8035.236652518001;4378.277584198571;4432.357308190359;4443.866683999485;4456.24243878535;4503.369528266046;5458.426865179178;5582.134769110794;5588.524975377139;5603.271574695345;5609.971950140305;1019.50553981319;1029.7528593137592;1051.3271506236015;1052.9105026072716;1056.8626078609884;611.3611276216365;660.9107770451023;672.1497305908031;674.3593586093231;689.1867596236489;11320.150988675883;11600.535978560763;11781.646550603497;11991.160618756778;12136.08979326554;691.6196092149398;700.4641449291296;706.5654622407183;710.3393843725385;719.1514013464058;5518.668879867221;5538.723063846792;5645.858853528613;5655.604107712503;5702.942970570241;122550;124993.37503312607;125552;129266;129647.35176324059;106377.46811265894;107501;109254;112890.30664540039;113505;96239.51880240554;96796.51601741946;96821.51589242008;97497.51251243698;98645.51835449642;12087.81868271959;12631.368431578569;12686.175398599062;12918.354082296035;13285;42011.7899410501;42562.361564575935;43163.35254971241;43258.35112473252;44370.11259774851;79053.20946790688;79646.20353796388;80002;80878;82765.17234827575;18167.636647266718;18712.345067922557;19072.809271907103;19170.71243931314;19348.4195474136;19364.806351936862;19504.414867553995;19522.414327570197;19584;19973.800261997196;18288.451346459628;18355.26578936881;18429.631407372046;18645.720314195027;18832.905835470734;17659.911700441415;18088;18301;18390.540236494195;18492.907535462236;18471;18481.445556633324;18540.25838966426;18938.810611894256;19264.229430822612;13309.334533273104;13520.459181632625;13656.180629162282;13888.861111388756;14002.299885005505;13863.445462181804;14186.148831070168;14214;14392.424303027761;14486.275686215859 12:31:02 INFO - PID 20231 | __end_tp_report 12:31:02 INFO - PID 20231 | __start_cc_report 12:31:02 INFO - PID 20231 | _x_x_mozilla_cycle_collect,301 12:31:02 INFO - PID 20231 | __end_cc_report 12:31:02 INFO - PID 20231 | __startTimestamp1506454262526__endTimestamp 12:31:02 INFO - PID 20231 | 12:31:02 INFO - PID 20231 | ------- Summary: start ------- 12:31:02 INFO - PID 20231 | Number of tests: 6 12:31:02 INFO - PID 20231 | 12:31:02 INFO - PID 20231 | [#0] dojo.html Cycles:165 Average:6131.08 Median:6420.33 stddev:855.43 (13.3%) stddev-sans-first:824.37 12:31:02 INFO - PID 20231 | Values: 3101.4 3120.1 3121.1 3158.5 3185.8 6067.6 6213.9 6285.1 6299.8 6375.9 6173.6 6204.5 6210.7 6253.4 6293.2 5765.4 5797.6 5813.3 5841.6 5860.5 524.7 5800.1 5812.4 5917.2 5929.0 5654.7 5667.4 5687.4 5769.4 5807.0 5708.6 5713.5 5774.1 5790.8 5793.1 5691.7 5699.2 5753.4 5758.5 5773.6 5995.4 6142.5 6238.8 6245.9 6333.4 6526.6 6606.9 6684.2 6709.5 6783.3 6732.8 6784.6 6785.3 6789.6 6791.4 6224.1 6248.8 6293.3 6324.1 6330.9 6902.9 6908.0 6958.3 6999.4 7004.9 6439.6 6482.2 6502.8 6514.1 6557.9 6732.2 6744.8 6752.3 6752.7 6778.8 6646.9 6647.4 6651.5 6657.3 6736.1 6529.1 6666.6 6668.3 6761.2 6783.0 5206.8 5249.6 5318.3 5318.4 5318.5 4614.5 4644.2 4677.5 4685.5 4689.9 5616.9 5709.7 5775.9 5797.0 5798.8 6509.3 6617.4 6649.1 6707.4 6799.3 5714.9 5761.9 5780.2 5781.2 5792.7 5657.4 5780.0 5836.6 5864.1 5871.6 6393.1 6407.9 6430.3 6432.6 6437.1 6634.9 6643.3 6713.5 6784.4 6785.0 6586.1 6589.2 6591.9 6597.1 6600.0 6530.9 6621.3 6697.4 6717.7 6727.3 6528.3 6687.5 6737.8 6742.1 6852.7 6463.2 6479.0 6482.1 6484.9 6557.4 6561.7 6579.0 6585.6 6588.6 6591.5 6420.3 6420.9 6500.1 6501.2 6506.0 6551.3 6563.9 6569.8 6618.1 6639.0 6210.2 6300.6 6301.6 6305.9 6313.5 12:31:02 INFO - PID 20231 | 12:31:02 INFO - PID 20231 | [#1] ext.html Cycles:180 Average:36711.64 Median:34507.48 stddev:52131.08 (151.1%) stddev-sans-first:52207.69 12:31:02 INFO - PID 20231 | Values: 825.9 833.3 853.4 879.5 883.0 40265.2 41717.0 41807.8 41812.8 41909.8 37840.1 37872.4 38071.6 38223.6 38260.8 7006.2 7130.1 7141.4 7162.2 7166.3 7036.0 7117.4 7158.4 7167.8 7171.1 7744.3 7941.1 7948.6 7954.2 7974.4 7833.4 7899.4 7903.1 7917.9 7929.8 7655.9 7813.3 7868.8 7913.5 7938.6 38045.6 38069.8 38119.6 38250.0 38538.0 3461.5 3477.7 3518.3 3527.0 3528.8 31125.7 31256.5 31420.8 31465.7 31469.0 5120.8 5124.8 5148.7 5162.1 5165.8 191351.0 192381.0 192593.0 192801.0 193801.0 47300.0 47331.5 47560.0 47595.0 47636.8 49658.3 49795.8 49796.0 49797.3 49933.8 14610.6 14625.5 14821.0 14865.9 14868.0 11801.6 11859.2 11892.9 11894.6 11915.7 13924.6 13961.4 14015.1 14117.5 14194.1 5637.1 5826.6 5886.9 5910.7 5926.2 1001.3 1014.6 1019.9 1032.5 1035.8 21930.2 21978.7 22056.1 22150.9 22275.6 1114.7 1114.8 1130.8 1135.4 1135.6 11720.5 11921.6 12036.1 12061.3 12078.6 215713.0 215981.0 217204.0 218566.0 218614.0 50913.2 52733.0 53057.5 54296.2 54297.5 1440.8 1454.0 1460.5 1468.4 1469.7 25862.4 26150.6 26215.9 26621.6 26853.5 36623.5 36673.0 36679.4 36712.1 36728.0 191801.0 193427.0 193554.0 193941.0 194315.0 32729.2 32804.8 32981.7 33011.3 33033.8 39545.6 41271.0 41332.2 41769.4 41917.8 30836.7 30843.5 30922.4 31313.4 31637.0 30649.9 30913.1 30922.8 30996.8 31673.4 34504.8 34510.1 34672.0 34726.3 34776.8 30572.0 30602.5 30753.8 30759.5 30793.7 22365.0 22647.0 22908.4 22977.9 23215.5 12:31:02 INFO - PID 20231 | 12:31:02 INFO - PID 20231 | [#2] jquery.html Cycles:180 Average:34045.99 Median:24233.20 stddev:78978.59 (325.9%) stddev-sans-first:79160.18 12:31:02 INFO - PID 20231 | Values: 582.9 589.4 590.3 591.1 597.5 21977.3 22967.5 23015.8 23029.0 23124.9 24687.9 24832.0 24997.5 25570.4 25992.2 6501.0 6652.1 6681.1 6681.5 6691.2 6479.8 6498.4 6578.0 6587.9 6765.4 6677.9 6728.5 6731.4 6731.7 6731.8 6707.2 6732.5 6737.8 6738.9 6749.5 6045.7 6258.0 6296.9 6302.7 6307.1 29119.6 29558.1 29790.6 29804.7 29852.6 2048.6 2056.1 2061.6 2069.9 2081.0 19578.9 20036.8 20443.6 20563.3 20747.9 2403.0 2444.8 2460.9 2466.9 2475.0 125711.0 132848.0 136582.0 143320.6 143590.3 14970.5 15500.8 15521.0 15619.8 15931.8 23964.2 24228.6 24237.8 24284.9 24297.4 9650.6 9767.3 9855.3 9875.8 9887.9 8855.4 8875.0 8876.1 8974.6 9014.3 9051.0 9233.7 9257.5 9443.9 9619.7 2660.3 2697.7 2705.7 2795.7 2823.4 947.0 963.8 967.2 973.9 976.6 19330.5 19330.5 19724.8 19761.5 19778.0 988.9 992.8 1021.9 1028.6 1033.4 10864.2 10881.6 10938.7 11061.6 11063.4 455468.0 464722.0 466927.0 472719.0 483199.0 106338.5 107496.0 108681.0 109200.0 110562.0 1023.8 1044.6 1119.2 1147.0 1192.7 20381.7 20598.8 20667.0 20847.7 21312.6 54574.5 55019.4 55103.7 55519.2 56323.2 68005.3 69441.3 69768.7 69895.7 70339.3 14702.6 14853.6 15022.4 15034.1 15054.4 23433.9 23555.9 23629.8 23731.8 23882.3 18556.3 18651.8 18774.5 18798.6 18901.0 16475.8 16821.2 16946.8 17168.8 17711.6 18938.2 18959.6 19025.9 19044.2 19095.1 14446.0 14446.1 14502.2 14566.5 14637.6 18554.3 18578.4 18624.3 18763.9 18790.4 12:31:02 INFO - PID 20231 | 12:31:02 INFO - PID 20231 | [#3] mootools.html Cycles:180 Average:9495.24 Median:2610.08 stddev:6899.20 (264.3%) stddev-sans-first:6891.21 12:31:02 INFO - PID 20231 | Values: 1320.1 1366.2 1383.1 1387.8 1391.4 15317.7 15767.8 15885.9 15900.6 15914.1 15450.8 15574.9 15604.6 15628.8 15641.3 2712.7 2752.1 2784.4 2801.8 2817.1 2762.2 2805.4 2816.3 2818.9 2819.9 2506.2 2607.4 2612.7 2615.2 2744.1 2785.2 2787.7 2817.7 2818.4 2818.5 2280.9 2537.8 2540.1 2563.5 2563.9 15593.4 15721.1 15725.8 15737.8 15748.4 7775.4 8027.7 8071.3 8076.1 8076.7 5455.8 5476.9 5554.0 5620.4 5737.1 2016.2 2021.1 2024.7 2056.0 2062.5 27438.2 27792.0 27809.2 27858.9 27887.4 6885.2 6949.2 6978.5 7204.3 7269.2 17388.6 17412.3 17413.4 17417.7 17418.9 7805.5 8009.6 8037.0 8056.5 8056.6 7292.5 7411.7 7493.5 7649.8 7676.6 5119.9 5244.8 5262.7 5339.3 5346.2 1509.4 1516.0 1524.9 1529.9 1542.0 910.8 914.4 923.3 925.9 931.1 7733.4 7813.3 7821.5 7821.6 7897.6 718.9 743.9 744.4 745.1 749.6 4597.4 4629.8 5075.5 5147.1 5207.4 21789.9 22512.0 22532.3 22608.8 22785.3 21262.1 21815.2 22163.7 22342.9 22353.3 11082.1 11201.7 11202.4 11262.6 11423.0 8187.2 8267.8 8338.5 8484.7 8485.8 18221.1 18630.4 18751.3 18982.9 18998.0 18838.2 19192.1 19394.7 19851.9 20083.3 10840.2 11120.8 11124.9 11129.3 11144.2 13971.5 14017.5 14202.6 14206.9 14251.1 11701.7 11917.5 11961.0 12002.8 12037.8 11037.9 11108.4 11128.4 11157.2 11418.8 9912.0 10088.5 10120.9 10135.1 10139.6 7216.3 7254.7 7380.9 7386.5 7392.2 7236.3 7489.9 7537.2 7552.8 7571.2 12:31:02 INFO - PID 20231 | 12:31:02 INFO - PID 20231 | [#4] prototype.html Cycles:180 Average:5608.48 Median:6234.99 stddev:1554.10 (24.9%) stddev-sans-first:1510.55 12:31:02 INFO - PID 20231 | Values: 507.3 518.4 538.0 540.4 543.0 6255.0 6344.8 6352.4 6391.6 6440.7 6208.6 6225.4 6232.3 6233.6 6236.4 4964.1 5044.7 5054.4 5070.3 5077.1 4967.3 5099.7 5101.7 5105.7 5153.1 5069.8 5079.1 5079.5 5087.6 5098.4 5068.6 5101.6 5105.6 5109.8 5119.3 4629.2 4660.8 4663.3 4666.2 4684.1 6254.8 6268.5 6280.2 6339.5 6366.7 5742.4 5757.5 5758.9 5795.7 5850.7 6660.7 6677.5 6722.6 6727.7 6742.8 5002.5 5019.9 5034.3 5051.5 5057.1 7006.5 7017.2 7047.2 7051.6 7072.1 5087.1 5137.5 5142.9 5143.6 5150.5 5217.3 5250.9 5253.9 5266.1 5287.1 5780.7 5810.9 5815.9 5862.8 5871.8 6339.3 6508.0 6610.0 6785.3 6790.5 1771.4 1777.0 1784.3 1788.2 1807.6 1111.9 1241.5 1251.8 1251.9 1264.4 5597.5 5722.8 5742.5 5816.4 5844.6 6772.4 6788.5 6838.3 6869.7 6911.4 5769.9 5788.3 5793.5 5812.2 5854.9 3602.8 3618.7 3625.3 3663.8 3670.9 6493.7 6553.4 6572.5 6636.8 6670.2 6800.6 6833.2 6863.4 6885.0 6941.6 6421.6 6480.8 6495.2 6536.9 6547.2 6667.7 6736.6 6753.3 6770.4 6806.6 6628.0 6742.4 6743.5 6746.3 6812.4 6439.2 6468.5 6585.0 6646.6 6657.8 5442.3 5537.1 5551.7 5584.8 5608.5 6719.7 6798.0 6807.3 6892.5 6923.2 6572.9 6575.8 6665.2 6677.8 6723.3 6540.9 6599.9 6633.6 6635.7 6732.3 6549.8 6600.5 6609.9 6611.0 6634.0 6456.0 6581.8 6626.0 6654.2 6667.8 6083.8 6197.7 6270.2 6393.7 6429.5 12:31:02 INFO - PID 20231 | 12:31:02 INFO - PID 20231 | [#5] yui.html Cycles:180 Average:25744.11 Median:19497.32 stddev:36794.16 (188.7%) stddev-sans-first:36850.07 12:31:02 INFO - PID 20231 | Values: 896.0 916.3 933.2 935.1 946.2 18460.3 19352.1 19370.3 19388.9 19490.2 16818.2 17305.6 17443.7 17489.0 17600.9 959.1 973.3 975.0 978.6 982.2 980.5 987.0 988.8 993.4 994.2 1044.9 1060.8 1061.4 1061.4 1061.7 1055.5 1055.6 1056.1 1056.8 1057.3 716.5 734.2 741.6 741.7 741.8 20298.4 20439.1 20533.7 20543.2 20573.3 21834.2 21996.2 22374.2 22446.4 22459.9 17082.9 17291.1 17368.5 17488.3 17500.6 3668.8 3689.1 3693.7 3704.9 3708.1 140275.0 142765.0 144583.0 145258.0 146802.0 15828.0 16089.8 16134.5 16239.1 16239.6 30458.2 31296.2 31555.0 31716.8 31750.0 7903.8 7934.0 7971.7 8033.4 8035.2 4378.3 4432.4 4443.9 4456.2 4503.4 5458.4 5582.1 5588.5 5603.3 5610.0 1019.5 1029.8 1051.3 1052.9 1056.9 611.4 660.9 672.1 674.4 689.2 11320.2 11600.5 11781.6 11991.2 12136.1 691.6 700.5 706.6 710.3 719.2 5518.7 5538.7 5645.9 5655.6 5702.9 122550.0 124993.4 125552.0 129266.0 129647.4 106377.5 107501.0 109254.0 112890.3 113505.0 96239.5 96796.5 96821.5 97497.5 98645.5 12087.8 12631.4 12686.2 12918.4 13285.0 42011.8 42562.4 43163.4 43258.4 44370.1 79053.2 79646.2 80002.0 80878.0 82765.2 18167.6 18712.3 19072.8 19170.7 19348.4 19364.8 19504.4 19522.4 19584.0 19973.8 18288.5 18355.3 18429.6 18645.7 18832.9 17659.9 18088.0 18301.0 18390.5 18492.9 18471.0 18481.4 18540.3 18938.8 19264.2 13309.3 13520.5 13656.2 13888.9 14002.3 13863.4 14186.1 14214.0 14392.4 14486.3 12:31:02 INFO - PID 20231 | -------- Summary: end -------- 12:31:02 INFO - PID 20231 | 12:31:03 INFO - TEST-INFO | 20231: exit 0 12:31:03 INFO - TEST-OK | dromaeo_css | took 1108611ms 12:31:03 INFO - TEST-START | kraken 12:31:03 INFO - Initialising browser for kraken test... 12:31:03 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:59581/getInfo.html -profile /tmp/tmpxAWzXi/profile 12:31:03 INFO - TEST-INFO | started process 20605 (/builds/slave/test/build/application/firefox/firefox http://localhost:59581/getInfo.html) 12:31:08 INFO - TEST-INFO | 20605: exit 0 12:31:08 INFO - Browser initialized. 12:31:08 INFO - Running cycle 1/1 for kraken test... 12:31:08 INFO - Using env: {'DISPLAY': ':0', 12:31:08 INFO - 'HOME': '/home/cltbld', 12:31:08 INFO - 'JSGC_DISABLE_POISONING': '1', 12:31:08 INFO - 'LANG': 'en_US.UTF-8', 12:31:08 INFO - 'LANGUAGE': 'en_US:en', 12:31:08 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 12:31:08 INFO - 'LOGNAME': 'cltbld', 12:31:08 INFO - 'MAIL': '/var/mail/cltbld', 12:31:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:31:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:31:08 INFO - 'MOZ_CRASHREPORTER': '1', 12:31:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:31:08 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 12:31:08 INFO - 'MOZ_NO_REMOTE': '1', 12:31:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:31:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:31:08 INFO - 'NO_EM_RESTART': '1', 12:31:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:31:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:31:08 INFO - 'PWD': '/builds/slave/test', 12:31:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:31:08 INFO - 'RUST_BACKTRACE': 'full', 12:31:08 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:31:08 INFO - 'SHELL': '/bin/bash', 12:31:08 INFO - 'SHLVL': '1', 12:31:08 INFO - 'TERM': 'linux', 12:31:08 INFO - 'TMOUT': '86400', 12:31:08 INFO - 'USER': 'cltbld', 12:31:08 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1506453094.566063-2043789727', 12:31:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:31:08 INFO - '_': '/tools/buildbot/bin/python'} 12:31:08 INFO - TEST-INFO | started process 20839 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxAWzXi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 12:31:09 INFO - PID 20839 | 12:31:09 INFO - PID 20839 | (/builds/slave/test/build/application/firefox/firefox:20888): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 12:31:09 INFO - PID 20839 | 12:31:19 INFO - PID 20839 | RSS: Main: 153759744 12:31:19 INFO - PID 20839 | 12:31:22 INFO - PID 20839 | Cycle 1(1): loaded http://localhost:59581/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59581/tests/kraken/driver.html?testName=audio-beat-detection) 12:31:23 INFO - PID 20839 | RSS: Main: 160923648 12:31:23 INFO - PID 20839 | 12:31:25 INFO - PID 20839 | Cycle 1(1): loaded http://localhost:59581/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59581/tests/kraken/driver.html?testName=audio-dft) 12:31:26 INFO - PID 20839 | RSS: Main: 160792576 12:31:26 INFO - PID 20839 | 12:31:29 INFO - PID 20839 | Cycle 1(1): loaded http://localhost:59581/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59581/tests/kraken/driver.html?testName=audio-fft) 12:31:29 INFO - PID 20839 | RSS: Main: 155844608 12:31:29 INFO - PID 20839 | 12:31:32 INFO - PID 20839 | Cycle 1(1): loaded http://localhost:59581/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59581/tests/kraken/driver.html?testName=audio-oscillator) 12:31:32 INFO - PID 20839 | RSS: Main: 156389376 12:31:32 INFO - PID 20839 | 12:31:34 INFO - PID 20839 | Cycle 1(1): loaded http://localhost:59581/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59581/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:31:35 INFO - PID 20839 | RSS: Main: 156721152 12:31:35 INFO - PID 20839 | 12:31:38 INFO - PID 20839 | Cycle 1(1): loaded http://localhost:59581/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59581/tests/kraken/driver.html?testName=imaging-darkroom) 12:31:39 INFO - PID 20839 | RSS: Main: 159100928 12:31:39 INFO - PID 20839 | 12:31:42 INFO - PID 20839 | Cycle 1(1): loaded http://localhost:59581/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59581/tests/kraken/driver.html?testName=imaging-desaturate) 12:31:43 INFO - PID 20839 | RSS: Main: 159686656 12:31:43 INFO - PID 20839 | 12:31:46 INFO - PID 20839 | Cycle 1(1): loaded http://localhost:59581/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59581/tests/kraken/driver.html?testName=json-parse-financial) 12:31:47 INFO - PID 20839 | RSS: Main: 159211520 12:31:47 INFO - PID 20839 | 12:31:48 INFO - PID 20839 | Cycle 1(1): loaded http://localhost:59581/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59581/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:31:49 INFO - PID 20839 | RSS: Main: 159551488 12:31:49 INFO - PID 20839 | 12:31:50 INFO - PID 20839 | Cycle 1(1): loaded http://localhost:59581/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59581/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:31:51 INFO - PID 20839 | RSS: Main: 159412224 12:31:51 INFO - PID 20839 | 12:31:53 INFO - PID 20839 | Cycle 1(1): loaded http://localhost:59581/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59581/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:31:54 INFO - PID 20839 | RSS: Main: 156360704 12:31:54 INFO - PID 20839 | 12:31:56 INFO - PID 20839 | Cycle 1(1): loaded http://localhost:59581/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59581/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:31:57 INFO - PID 20839 | RSS: Main: 156942336 12:31:57 INFO - PID 20839 | 12:32:00 INFO - PID 20839 | Cycle 1(1): loaded http://localhost:59581/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59581/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:32:01 INFO - PID 20839 | RSS: Main: 157171712 12:32:01 INFO - PID 20839 | 12:32:02 INFO - PID 20839 | Cycle 1(1): loaded http://localhost:59581/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59581/tests/kraken/driver.html?testName=ai-astar) 12:32:03 INFO - PID 20839 | RSS: Main: 158531584 12:32:03 INFO - PID 20839 | 12:32:03 INFO - PID 20839 | __start_tp_report 12:32:03 INFO - PID 20839 | _x_x_mozilla_page_load 12:32:03 INFO - PID 20839 | _x_x_mozilla_page_load_details 12:32:03 INFO - PID 20839 | |i|pagename|runs| 12:32:03 INFO - PID 20839 | |0;ai-astar;108;109;107;107;108;109;109;107;108;107 12:32:03 INFO - PID 20839 | |1;audio-beat-detection;118;116;117;124;117;117;115;145;117;151 12:32:03 INFO - PID 20839 | |2;audio-dft;163;165;161;162;162;163;162;165;167;166 12:32:03 INFO - PID 20839 | |3;audio-fft;74;73;74;74;75;89;73;78;73;73 12:32:03 INFO - PID 20839 | |4;audio-oscillator;79;80;78;78;78;79;79;78;78;80 12:32:03 INFO - PID 20839 | |5;imaging-gaussian-blur;110;114;114;114;117;114;115;114;113;114 12:32:03 INFO - PID 20839 | |6;imaging-darkroom;148;147;148;148;147;148;174;150;148;150 12:32:03 INFO - PID 20839 | |7;imaging-desaturate;97;93;95;94;94;95;95;95;94;94 12:32:03 INFO - PID 20839 | |8;json-parse-financial;53;53;51;50;50;51;51;50;57;51 12:32:03 INFO - PID 20839 | |9;json-stringify-tinderbox;39;40;39;39;39;40;40;39;39;39 12:32:03 INFO - PID 20839 | |10;stanford-crypto-aes;77;82;76;74;73;74;74;76;76;71 12:32:03 INFO - PID 20839 | |11;stanford-crypto-ccm;119;112;113;111;127;112;113;113;113;110 12:32:03 INFO - PID 20839 | |12;stanford-crypto-pbkdf2;178;169;171;175;167;169;169;171;169;166 12:32:03 INFO - PID 20839 | |13;stanford-crypto-sha256-iterative;70;69;67;64;67;73;64;67;63;70 12:32:03 INFO - PID 20839 | __end_tp_report 12:32:03 INFO - PID 20839 | __start_cc_report 12:32:03 INFO - PID 20839 | _x_x_mozilla_cycle_collect,716 12:32:03 INFO - PID 20839 | __end_cc_report 12:32:03 INFO - PID 20839 | __startTimestamp1506454323159__endTimestamp 12:32:03 INFO - PID 20839 | 12:32:03 INFO - PID 20839 | ------- Summary: start ------- 12:32:03 INFO - PID 20839 | Number of tests: 14 12:32:03 INFO - PID 20839 | 12:32:03 INFO - PID 20839 | [#0] ai-astar Cycles:10 Average:107.90 Median:108.00 stddev:0.88 (0.8%) stddev-sans-first:0.93 12:32:03 INFO - PID 20839 | Values: 108.0 109.0 107.0 107.0 108.0 109.0 109.0 107.0 108.0 107.0 12:32:03 INFO - PID 20839 | 12:32:03 INFO - PID 20839 | [#1] audio-beat-detection Cycles:10 Average:123.70 Median:117.50 stddev:13.11 (11.2%) stddev-sans-first:13.74 12:32:03 INFO - PID 20839 | Values: 118.0 116.0 117.0 124.0 117.0 117.0 115.0 145.0 117.0 151.0 12:32:03 INFO - PID 20839 | 12:32:03 INFO - PID 20839 | [#2] audio-dft Cycles:10 Average:163.60 Median:164.00 stddev:2.01 (1.2%) stddev-sans-first:2.12 12:32:03 INFO - PID 20839 | Values: 163.0 165.0 161.0 162.0 162.0 163.0 162.0 165.0 167.0 166.0 12:32:03 INFO - PID 20839 | 12:32:03 INFO - PID 20839 | [#3] audio-fft Cycles:10 Average:75.60 Median:74.00 stddev:4.95 (6.7%) stddev-sans-first:5.21 12:32:03 INFO - PID 20839 | Values: 74.0 73.0 74.0 74.0 75.0 89.0 73.0 78.0 73.0 73.0 12:32:03 INFO - PID 20839 | 12:32:03 INFO - PID 20839 | [#4] audio-oscillator Cycles:10 Average:78.70 Median:79.00 stddev:0.82 (1.0%) stddev-sans-first:0.87 12:32:03 INFO - PID 20839 | Values: 79.0 80.0 78.0 78.0 78.0 79.0 79.0 78.0 78.0 80.0 12:32:03 INFO - PID 20839 | 12:32:03 INFO - PID 20839 | [#5] imaging-gaussian-blur Cycles:10 Average:113.90 Median:114.00 stddev:1.73 (1.5%) stddev-sans-first:1.12 12:32:03 INFO - PID 20839 | Values: 110.0 114.0 114.0 114.0 117.0 114.0 115.0 114.0 113.0 114.0 12:32:03 INFO - PID 20839 | 12:32:03 INFO - PID 20839 | [#6] imaging-darkroom Cycles:10 Average:150.80 Median:148.00 stddev:8.22 (5.6%) stddev-sans-first:8.65 12:32:03 INFO - PID 20839 | Values: 148.0 147.0 148.0 148.0 147.0 148.0 174.0 150.0 148.0 150.0 12:32:03 INFO - PID 20839 | 12:32:03 INFO - PID 20839 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:95.00 stddev:1.07 (1.1%) stddev-sans-first:0.71 12:32:03 INFO - PID 20839 | Values: 97.0 93.0 95.0 94.0 94.0 95.0 95.0 95.0 94.0 94.0 12:32:03 INFO - PID 20839 | 12:32:03 INFO - PID 20839 | [#8] json-parse-financial Cycles:10 Average:51.70 Median:51.00 stddev:2.16 (4.2%) stddev-sans-first:2.24 12:32:03 INFO - PID 20839 | Values: 53.0 53.0 51.0 50.0 50.0 51.0 51.0 50.0 57.0 51.0 12:32:03 INFO - PID 20839 | 12:32:03 INFO - PID 20839 | [#9] json-stringify-tinderbox Cycles:10 Average:39.30 Median:39.00 stddev:0.48 (1.2%) stddev-sans-first:0.50 12:32:03 INFO - PID 20839 | Values: 39.0 40.0 39.0 39.0 39.0 40.0 40.0 39.0 39.0 39.0 12:32:03 INFO - PID 20839 | 12:32:03 INFO - PID 20839 | [#10] stanford-crypto-aes Cycles:10 Average:75.30 Median:76.00 stddev:2.95 (3.9%) stddev-sans-first:3.06 12:32:03 INFO - PID 20839 | Values: 77.0 82.0 76.0 74.0 73.0 74.0 74.0 76.0 76.0 71.0 12:32:03 INFO - PID 20839 | 12:32:03 INFO - PID 20839 | [#11] stanford-crypto-ccm Cycles:10 Average:114.30 Median:113.00 stddev:5.06 (4.5%) stddev-sans-first:5.07 12:32:03 INFO - PID 20839 | Values: 119.0 112.0 113.0 111.0 127.0 112.0 113.0 113.0 113.0 110.0 12:32:03 INFO - PID 20839 | 12:32:03 INFO - PID 20839 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.40 Median:170.00 stddev:3.63 (2.1%) stddev-sans-first:2.60 12:32:03 INFO - PID 20839 | Values: 178.0 169.0 171.0 175.0 167.0 169.0 169.0 171.0 169.0 166.0 12:32:03 INFO - PID 20839 | 12:32:03 INFO - PID 20839 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:68.00 stddev:3.17 (4.7%) stddev-sans-first:3.22 12:32:03 INFO - PID 20839 | Values: 70.0 69.0 67.0 64.0 67.0 73.0 64.0 67.0 63.0 70.0 12:32:03 INFO - PID 20839 | -------- Summary: end -------- 12:32:03 INFO - PID 20839 | 12:32:03 INFO - PID 20839 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 12:32:03 INFO - TEST-INFO | 20839: exit 0 12:32:03 INFO - TEST-OK | kraken | took 60580ms 12:32:03 INFO - SUITE-END | took 1169s 12:32:04 INFO - Completed test suite (00:19:29) 12:32:04 INFO - javascript benchmark 12:32:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8576.467428715072, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3101.426236146313, 3120.0639808057585, 3121.0636808957306, 3158.5104308832138, 3185.824779637122, 6067.605605635631, 6213.906791398133, 6285.0572414137905, 6299.77950771723, 6375.872482550346, 6173.6295822250695, 6204.503639708823, 6210.7205175767085, 6253.374662533744, 6293.213348331458, 5765.423457654243, 5797.623154494955, 5813.302403711539, 5841.64950102993, 5860.53115750741, 524.7070407628654, 5800.071988481844, 5812.389699081599, 5917.230760001194, 5929.0, 5654.717264136777, 5667.376588575254, 5687.374388817227, 5769.394213607553, 5806.9709651451685, 5708.600397972144, 5713.485786279213, 5774.133879918004, 5790.826275211708, 5793.13103034544, 5691.715414229272, 5699.2306038684665, 5753.395893431213, 5758.452946970031, 5773.595848290622, 5995.400459954013, 6142.539309551801, 6238.812835614894, 6245.937540624581, 6333.398327158912, 6526.608403495806, 6606.86786264272, 6684.197896252432, 6709.463242940602, 6783.253842077367, 6732.83167920801, 6784.626845523494, 6785.321467853225, 6789.626570538619, 6791.3548212919695, 6224.066390041485, 6248.812535623939, 6293.339199384044, 6324.051392291147, 6330.905036424458, 6902.896456553156, 6907.965460172667, 6958.304169583002, 6999.405050570707, 7004.859902801915, 6439.581427207217, 6482.1573195485225, 6502.837429064311, 6514.055461958084, 6557.901631475532, 6732.1921369435, 6744.7976560703255, 6752.291009444036, 6752.729890804313, 6778.76274330406, 6646.900296495556, 6647.368499992493, 6651.534392592472, 6657.300983396771, 6736.090627765287, 6529.053287273318, 6666.633335166515, 6668.299828517936, 6761.188657361148, 6783.0, 5206.7917283309, 5249.632525723163, 5318.308619879391, 5318.361796584435, 5318.547923426475, 4614.492405835355, 4644.210484217623, 4677.508861569486, 4685.484596694361, 4689.906201875943, 5616.85957851057, 5709.714514274269, 5775.8556036099435, 5797.0, 5798.82603521895, 6509.251436084911, 6617.371349721769, 6649.102371179829, 6707.429868461236, 6799.252082270947, 5714.914276285859, 5761.884762304814, 5780.1907732917425, 5781.219535362672, 5792.652440853562, 5657.43425657431, 5780.017397042513, 5836.562257830679, 5864.149698293723, 5871.6183448075735, 6393.073004414334, 6407.871842563122, 6430.324815894357, 6432.614043157332, 6437.0988061671405, 6634.900476492857, 6643.335666433415, 6713.530052896251, 6784.423324017316, 6784.96607516979, 6586.077949087227, 6589.209294884645, 6591.901121483277, 6597.109390232449, 6599.96700016516, 6530.934690653033, 6621.3047629998155, 6697.397234248941, 6717.664116794239, 6727.26000139994, 6528.314526974599, 6687.465002799861, 6737.797866064026, 6742.056112144206, 6852.6916288768125, 6463.224413070461, 6478.967605161944, 6482.092507048923, 6484.935150648433, 6557.442617377385, 6561.737530498726, 6579.046038324512, 6585.6377899216, 6588.637624930675, 6591.505637077143, 6420.325865784025, 6420.871582568229, 6500.089987401673, 6501.219853617407, 6506.0, 6551.312112228338, 6563.868722625425, 6569.770058047945, 6618.139641846529, 6639.03733958583, 6210.161628180138, 6300.558960872878, 6301.6219026858535, 6305.936940630719, 6313.463355614824], "value": 6070.593693084881, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [825.8719898415748, 833.3416600885301, 853.4409961475232, 879.511870911644, 883.0418995390002, 40265.19469610606, 41717.0, 41807.79096104519, 41812.790936045356, 41909.79045104774, 37840.05399864998, 37872.43191352132, 38071.61928380715, 38223.617763822425, 38260.80869595655, 7006.1592608887, 7130.144382674061, 7141.3572778449925, 7162.212156662789, 7166.283371662818, 7036.0, 7117.395021423185, 7158.35574798268, 7167.784966451015, 7171.067761191038, 7744.341730952876, 7941.126476087626, 7948.602569871512, 7954.244346787046, 7974.362051035903, 7833.41249406294, 7899.447038707292, 7903.130655627905, 7917.920820791776, 7929.762107136795, 7655.885161722578, 7813.335866451358, 7868.842623147562, 7913.525188488651, 7938.6427610757655, 38045.61954380448, 38069.80965095157, 38119.618803811885, 38250.0, 38538.0, 3461.532693086426, 3477.6696213859896, 3518.3315170117853, 3526.9647303526895, 3528.7706299090733, 31125.688743112507, 31256.531152032738, 31420.842895785605, 31465.685343146506, 31469.0, 5120.846374608767, 5124.8206312778875, 5148.691078535262, 5162.148245539519, 5165.793368265265, 191351.0, 192381.0, 192593.0, 192801.03599482053, 193801.0, 47300.0, 47331.5266847334, 47560.0, 47595.0, 47636.761816190694, 49658.255126173135, 49795.751021245385, 49796.0, 49797.253041204414, 49933.75033124884, 14610.561683149523, 14625.488107916171, 14821.036632618845, 14865.851341486663, 14868.0, 11801.645950621494, 11859.169858110023, 11892.881071189177, 11894.583689570823, 11915.702107447383, 13924.58226253214, 13961.441542338396, 14015.089019213923, 14117.505887293895, 14194.077384969944, 5637.126245431965, 5826.592138550261, 5886.8822623547285, 5910.674912879746, 5926.2295901532525, 1001.2840818814437, 1014.6043043213006, 1019.9235057370727, 1032.509557959978, 1035.782485678016, 21930.232441864777, 21978.670319945213, 22056.117755289928, 22150.88924555399, 22275.55448891013, 1114.6767437443214, 1114.765899161169, 1130.8077626803463, 1135.4152611405134, 1135.608215165783, 11720.472578733978, 11921.582744604071, 12036.097292703082, 12061.276323420623, 12078.57724979639, 215713.0, 215981.0, 217204.0, 218566.0, 218614.0, 50913.23630145551, 52733.0, 53057.46942530525, 54296.185557216675, 54297.45702543003, 1440.7838824176247, 1454.040333379964, 1460.5472303585923, 1468.434652658734, 1469.6766711323278, 25862.353441164123, 26150.607740884283, 26215.868920655274, 26621.600675990263, 26853.462930741665, 36623.45064823976, 36673.0, 36679.449808252364, 36712.08219794527, 36728.0, 191801.0, 193427.0, 193554.0, 193941.0, 194315.0, 32729.18177045593, 32804.835975819966, 32981.67018329786, 33011.33977320392, 33033.83483082569, 39545.60454395419, 41271.0, 41332.1733565321, 41769.373459397524, 41917.79041104775, 30836.69163308428, 30843.537346940262, 30922.381552368377, 31313.373732525677, 31637.0, 30649.860306678314, 30913.072607821803, 30922.845385772926, 30996.760129594564, 31673.366532668755, 34504.82747586246, 34510.13724656904, 34672.0, 34726.30547389088, 34776.82611586926, 30572.0, 30602.540961885144, 30753.846230768704, 30759.538606920465, 30793.69206307908, 22365.0, 22646.980885859848, 22908.427289317235, 22977.88511057434, 23215.53568928646], "value": 16851.09204303604, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [582.8630271886103, 589.3693747689971, 590.3004939147111, 591.1428428778271, 597.4861619007653, 21977.340679779612, 22967.540649187027, 23015.769842301594, 23029.0, 23124.88437557814, 24687.876560617216, 24832.006719731187, 24997.500049998987, 25570.360740981483, 25992.22023339298, 6500.967495162529, 6652.101966234545, 6681.131452911139, 6681.465482761391, 6691.230508491512, 6479.773207937723, 6498.3826536478955, 6578.0, 6587.868242635169, 6765.357291057342, 6677.866442671144, 6728.4953628477815, 6731.360520750521, 6731.663416829163, 6731.8317042074095, 6707.228668703064, 6732.4950628703045, 6737.831554211135, 6738.898916516257, 6749.493787965897, 6045.6674882881425, 6257.9687101564205, 6296.9370306296805, 6302.68486575674, 6307.0539419087045, 29119.56320655192, 29558.113256602337, 29790.55314170289, 29804.70195298041, 29852.55221171684, 2048.621005114059, 2056.1364227024687, 2061.6186005589016, 2069.865458745177, 2080.9791902080938, 19578.902105489382, 20036.79963200364, 20443.5911281775, 20563.280285189947, 20747.89626051875, 2402.9879850600637, 2444.828861979662, 2460.938476538083, 2466.8889899954547, 2474.9628755568683, 125711.0, 132848.0, 136582.0, 143320.56679433177, 143590.28204858908, 14970.476033338999, 15500.844991550166, 15521.0, 15619.84380156184, 15931.761023584655, 23964.161254356015, 24228.636570451457, 24237.75762242355, 24284.87857560701, 24297.39256518601, 9650.565724542412, 9767.316287859923, 9855.310128291094, 9875.802483950281, 9887.851682224773, 8855.424397414148, 8875.023747387911, 8876.068012858686, 8974.551272436353, 9014.323925705598, 9050.954745226232, 9233.722988310361, 9257.537123143817, 9443.858342124873, 9619.663311784054, 2660.255128564005, 2697.7302269772867, 2705.675318961737, 2795.65054368204, 2823.39297051135, 946.9526523673788, 963.8072385522923, 967.2455484721928, 973.9026097390346, 976.5995941663883, 19330.516737081405, 19330.516737081685, 19724.802751972584, 19761.505962351057, 19778.0, 988.9159421449114, 992.7617371830709, 1021.8722659667542, 1028.6451174344838, 1033.379972016784, 10864.185186111074, 10881.564737410574, 10938.671839844817, 11061.612843550438, 11063.391513466675, 455468.0, 464722.0, 466927.0, 472719.0, 483199.0, 106338.46830765797, 107496.0, 108681.0, 109200.0, 110562.0, 1023.7901230247782, 1044.5717255925035, 1119.238917536067, 1147.0, 1192.6720151958282, 20381.69427458619, 20598.794012060287, 20667.0, 20847.687284691045, 21312.57374852463, 54574.454255456934, 55019.449805501434, 55103.724481377336, 55519.167212492655, 56323.15515267192, 68005.3199467999, 69441.30558694349, 69768.65115674389, 69895.65052174707, 70339.29660703328, 14702.55892323232, 14853.628659283604, 15022.399104035716, 15034.097954122788, 15054.397824086915, 23433.88283058574, 23555.88222058879, 23629.763702362758, 23731.762682372955, 23882.283531494082, 18556.257749689863, 18651.813481865007, 18774.53063673419, 18798.624027519647, 18901.0, 16475.752863707294, 16821.158942053095, 16946.830531694526, 17168.828311716723, 17711.557211069827, 18938.242470301033, 18959.620807584048, 19025.90487047556, 19044.238230470626, 19095.14071866797, 14446.0, 14446.061006034573, 14502.202378869264, 14566.4901728439, 14637.634059148606, 18554.25782968666, 18578.44264672062, 18624.255029798656, 18763.87416754999, 18790.436286911416], "value": 12039.950396374155, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1320.1221187910041, 1366.1939455721122, 1383.066430159642, 1387.8334599848017, 1391.387789372676, 15317.69364612707, 15767.763483547727, 15885.92057039716, 15900.602484937854, 15914.124723140223, 15450.768238476432, 15574.922125389385, 15604.609884752887, 15628.84371156291, 15641.296141673623, 2712.6609173853267, 2752.119321817019, 2784.4431113777223, 2801.789865760066, 2817.1407720645193, 2762.1989623009304, 2805.424887897986, 2816.3381605322734, 2818.943621127576, 2819.887204511817, 2506.223070848043, 2607.4263661994332, 2612.725663805302, 2615.2023632791993, 2744.0944488318905, 2785.247983044577, 2787.679416867066, 2817.732315430041, 2818.436312737461, 2818.450402171567, 2280.8745518996448, 2537.8223524353302, 2540.123657338234, 2563.461673048662, 2563.948721025569, 15593.37626494939, 15721.13533755643, 15725.764113538306, 15737.842621573867, 15748.370065197378, 7775.4168437367425, 8027.7190298339865, 8071.31393831525, 8076.111627720946, 8076.676932922676, 5455.781768729246, 5476.890462190774, 5553.972230138864, 5620.381758006616, 5737.110747834093, 2016.1733689187508, 2021.0905092708192, 2024.7469066366705, 2055.989720051405, 2062.494688801238, 27438.17685469439, 27792.0, 27809.16572502828, 27858.860705696545, 27887.442251154862, 6885.242623311432, 6949.166100068024, 6978.476614253951, 7204.279572042754, 7269.164046134762, 17388.565285867953, 17412.303507859797, 17413.390531331344, 17417.738733919003, 17418.91290543539, 7805.453618246782, 8009.559474228857, 8037.0, 8056.476329038594, 8056.556889371141, 7292.45306602007, 7411.703531858773, 7493.512921660074, 7649.808754781064, 7676.616169191518, 5119.923201151985, 5244.763985620656, 5262.657927234794, 5339.252504649352, 5346.224797404355, 1509.3962415034075, 1515.9924200378928, 1524.9008814427027, 1529.8699610533006, 1541.9845801542065, 910.8087301666724, 914.4193437167446, 923.2567782934802, 925.9444433334022, 931.0968360690119, 7733.381329493627, 7813.257740514642, 7821.491603045783, 7821.648025838851, 7897.565633890076, 718.867009603225, 743.8995735575736, 744.4119145874698, 745.0649434959091, 749.5952185819597, 4597.356370108188, 4629.814807407666, 5075.492450754895, 5147.073526765218, 5207.3751149862255, 21789.891050544964, 22511.98696058681, 22532.324030279116, 22608.773912260996, 22785.316440506813, 21262.149514019267, 21815.23646672359, 22163.667544986838, 22342.888285558467, 22353.329400118022, 11082.05802506796, 11201.719957000978, 11202.383868887453, 11262.605808796652, 11423.0, 8187.181281871765, 8267.83464330722, 8338.458000229966, 8484.745457636282, 8485.830283394174, 18221.088945552936, 18630.44108676742, 18751.34370297033, 18982.905085474486, 18998.0, 18838.152283147017, 19192.13635386383, 19394.70907936354, 19851.900740496207, 20083.297084601967, 10840.241183117421, 11120.833187502356, 11124.944375278072, 11129.332240065622, 11144.164187685794, 13971.510997115052, 14017.5093871714, 14202.644933876736, 14206.92896535552, 14251.073680211168, 11701.69179882293, 11917.463714132711, 11961.043116550829, 12002.75994480123, 12037.819432708691, 11037.889621103686, 11108.38903860263, 11128.38793866345, 11157.163212759238, 11418.771624567627, 9912.0, 10088.546015429176, 10120.949395252977, 10135.138513226164, 10139.64511242103, 7216.278372162742, 7254.673539690621, 7380.889286660597, 7386.482946193715, 7392.186859445565, 7236.276372362932, 7489.887651685338, 7537.208593097866, 7552.773416797505, 7571.242875712384], "value": 6708.953480639038, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [507.32019094413477, 518.3934796288344, 537.9623426360154, 540.3893600231737, 542.9619926605139, 6255.0, 6344.777932772348, 6352.364763523646, 6391.552591318609, 6440.7101680424375, 6208.554252389922, 6225.408586184317, 6232.3144454110125, 6233.594816336935, 6236.3763623637615, 4964.131277026524, 5044.697318160904, 5054.368203974504, 5070.29015937769, 5077.060743762398, 4967.304577359173, 5099.69401835891, 5101.69389836611, 5105.744712764347, 5153.149730294497, 5069.847904562869, 5079.085764562377, 5079.492050794928, 5087.6184286178495, 5098.388193416795, 5068.645194836363, 5101.566366858821, 5105.617078719092, 5109.795608175687, 5119.334486516766, 4629.189891768927, 4660.790264438108, 4663.27719203524, 4666.160091183586, 4684.126349460209, 6254.8123556293385, 6268.529860260499, 6280.183576135073, 6339.492840572743, 6366.745330186787, 5742.397048309909, 5757.539396848284, 5758.88482230353, 5795.710214489259, 5850.736716847752, 6660.733570657168, 6677.465802735818, 6722.596644201363, 6727.66361681914, 6742.8194519398285, 5002.52476014778, 5019.949800501985, 5034.295198672189, 5051.520105589963, 5057.115004874169, 7006.480819871154, 7017.245688577856, 7047.154341479055, 7051.647417629098, 7072.084900993811, 5087.125773937787, 5137.486251374908, 5142.948570514247, 5143.562797162208, 5150.459201783834, 5217.295665085165, 5250.947490525045, 5253.921191182136, 5266.052110620049, 5287.101192797234, 5780.71096445176, 5810.912836307459, 5815.854603634943, 5862.794802181903, 5871.794487192928, 6339.302676705559, 6508.0, 6609.966950165219, 6785.253622101565, 6790.456763458911, 1771.4154329071375, 1776.9733453998201, 1784.295203394652, 1788.2131861980947, 1807.566184115829, 1111.922165448427, 1241.503398640551, 1251.8184863194967, 1251.8795677868438, 1264.3551788587886, 5597.524210442077, 5722.828315150552, 5742.483176514133, 5816.389279125716, 5844.620099693507, 6772.424343930821, 6788.49086318528, 6838.316168383122, 6869.725210991604, 6911.412529934912, 5769.913451298234, 5788.27646544182, 5793.536517078624, 5812.157237200667, 5854.941450585525, 3602.765820221677, 3618.656227658421, 3625.3474374612824, 3663.7618554794385, 3670.908227294339, 6493.675316234169, 6553.377429144223, 6572.507061970276, 6636.800895973129, 6670.199576050905, 6800.557963732342, 6833.248342682301, 6863.38229559342, 6884.965575172092, 6941.618210998343, 6421.5825971311715, 6480.837979050561, 6495.188101487314, 6536.869262614721, 6547.247066587308, 6667.733290668513, 6736.5958042517605, 6753.264927704287, 6770.424513916175, 6806.55757375769, 6627.966860165669, 6742.42689371409, 6743.460523158037, 6746.277605676145, 6812.386885180159, 6439.162908821823, 6468.514861385321, 6585.0, 6646.567973081735, 6657.833554161185, 5442.251662910205, 5537.141743029837, 5551.667599776106, 5584.776608935597, 5608.46719561641, 6719.664016799043, 6798.0, 6807.319268073153, 6892.517523773488, 6923.238443771081, 6572.915468947761, 6575.80272591823, 6665.200175978914, 6677.76627818024, 6723.2940541244425, 6540.901886471802, 6599.9010014848855, 6633.645056005049, 6635.668216589054, 6732.273429261157, 6549.836254093686, 6600.53796234259, 6609.900851487136, 6611.0, 6633.958191774383, 6455.991933698866, 6581.769638062645, 6626.0, 6654.234763002317, 6667.799966001027, 6083.847903802441, 6197.721102550487, 6270.184875966095, 6393.744250229939, 6429.4534964526665], "value": 5150.267969094976, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [896.0367604824806, 916.3219217778844, 933.1834644685896, 935.0509233128372, 946.1910066892807, 18460.261589536403, 19352.12915418808, 19370.322038728664, 19388.9030554847, 19490.22039118437, 16818.15909204541, 17305.567360815985, 17443.651126977453, 17489.0, 17600.911995440005, 959.1080295325327, 973.3332667123008, 974.9561269742843, 978.6427953796848, 982.2387649571567, 980.5342462330407, 987.027777639025, 988.8269552828262, 993.4337427666234, 994.1947022911465, 1044.858944042556, 1060.8037513060108, 1061.3525749292926, 1061.3843970497094, 1061.7239517725409, 1055.5408397347178, 1055.5672174408455, 1056.1392465140923, 1056.799208150449, 1057.2546354819833, 716.5449939288537, 734.229059487536, 741.573595182772, 741.740390863199, 741.8293792427772, 20298.391048268575, 20439.080241389023, 20533.691994620094, 20543.178272869067, 20573.279935202198, 21834.23580174702, 21996.230131945464, 22374.2169024085, 22446.438839028993, 22459.887700561558, 17082.914585426992, 17291.135443227788, 17368.478945631527, 17488.300467981393, 17500.649987000317, 3668.779873207616, 3689.0961714379882, 3693.7045036397026, 3704.907377315562, 3708.128589781399, 140275.0, 142765.0, 144583.0, 145258.0, 146802.0, 15828.0, 16089.839101609068, 16134.515964520848, 16239.106849123411, 16239.59401014984, 30458.238544036132, 31296.21759456032, 31555.0, 31716.841415792773, 31750.0, 7903.841923161504, 7934.047914250327, 7971.681132754625, 8033.437659363905, 8035.236652518001, 4378.277584198571, 4432.357308190359, 4443.866683999485, 4456.24243878535, 4503.369528266046, 5458.426865179178, 5582.134769110794, 5588.524975377139, 5603.271574695345, 5609.971950140305, 1019.50553981319, 1029.7528593137592, 1051.3271506236015, 1052.9105026072716, 1056.8626078609884, 611.3611276216365, 660.9107770451023, 672.1497305908031, 674.3593586093231, 689.1867596236489, 11320.150988675883, 11600.535978560763, 11781.646550603497, 11991.160618756778, 12136.08979326554, 691.6196092149398, 700.4641449291296, 706.5654622407183, 710.3393843725385, 719.1514013464058, 5518.668879867221, 5538.723063846792, 5645.858853528613, 5655.604107712503, 5702.942970570241, 122550.0, 124993.37503312607, 125552.0, 129266.0, 129647.35176324059, 106377.46811265894, 107501.0, 109254.0, 112890.30664540039, 113505.0, 96239.51880240554, 96796.51601741946, 96821.51589242008, 97497.51251243698, 98645.51835449642, 12087.81868271959, 12631.368431578569, 12686.175398599062, 12918.354082296035, 13285.0, 42011.7899410501, 42562.361564575935, 43163.35254971241, 43258.35112473252, 44370.11259774851, 79053.20946790688, 79646.20353796388, 80002.0, 80878.0, 82765.17234827575, 18167.636647266718, 18712.345067922557, 19072.809271907103, 19170.71243931314, 19348.4195474136, 19364.806351936862, 19504.414867553995, 19522.414327570197, 19584.0, 19973.800261997196, 18288.451346459628, 18355.26578936881, 18429.631407372046, 18645.720314195027, 18832.905835470734, 17659.911700441415, 18088.0, 18301.0, 18390.540236494195, 18492.907535462236, 18471.0, 18481.445556633324, 18540.25838966426, 18938.810611894256, 19264.229430822612, 13309.334533273104, 13520.459181632625, 13656.180629162282, 13888.861111388756, 14002.299885005505, 13863.445462181804, 14186.148831070168, 14214.0, 14392.424303027761, 14486.275686215859], "value": 9350.978996780823, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1427.2000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 109.0, 107.0, 107.0, 108.0, 109.0, 109.0, 107.0, 108.0, 107.0], "value": 107.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 116.0, 117.0, 124.0, 117.0, 117.0, 115.0, 145.0, 117.0, 151.0], "value": 123.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 165.0, 161.0, 162.0, 162.0, 163.0, 162.0, 165.0, 167.0, 166.0], "value": 163.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 73.0, 74.0, 74.0, 75.0, 89.0, 73.0, 78.0, 73.0, 73.0], "value": 75.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 80.0, 78.0, 78.0, 78.0, 79.0, 79.0, 78.0, 78.0, 80.0], "value": 78.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 114.0, 114.0, 114.0, 117.0, 114.0, 115.0, 114.0, 113.0, 114.0], "value": 113.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 147.0, 148.0, 148.0, 147.0, 148.0, 174.0, 150.0, 148.0, 150.0], "value": 150.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 93.0, 95.0, 94.0, 94.0, 95.0, 95.0, 95.0, 94.0, 94.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 51.0, 50.0, 50.0, 51.0, 51.0, 50.0, 57.0, 51.0], "value": 51.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [39.0, 40.0, 39.0, 39.0, 39.0, 40.0, 40.0, 39.0, 39.0, 39.0], "value": 39.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 82.0, 76.0, 74.0, 73.0, 74.0, 74.0, 76.0, 76.0, 71.0], "value": 75.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 112.0, 113.0, 111.0, 127.0, 112.0, 113.0, 113.0, 113.0, 110.0], "value": 114.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 169.0, 171.0, 175.0, 167.0, 169.0, 169.0, 171.0, 169.0, 166.0], "value": 170.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 69.0, 67.0, 64.0, 67.0, 73.0, 64.0, 67.0, 63.0, 70.0], "value": 67.4, "unit": "score"}]}]} 12:32:04 INFO - Return code: 0 12:32:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:32:04 INFO - # TBPL SUCCESS # 12:32:04 INFO - Running post-action listener: _package_coverage_data 12:32:04 INFO - Running post-action listener: _resource_record_post_action 12:32:04 INFO - [mozharness: 2017-09-26 19:32:04.421908Z] Finished run-tests step (success) 12:32:04 INFO - Running post-run listener: _resource_record_post_run 12:32:04 INFO - Total resource usage - Wall time: 1188s; CPU: 13.0%; Read bytes: 40960; Write bytes: 347557888; Read time: 52; Write time: 183508 12:32:04 INFO - TinderboxPrint: CPU usage
13.1% 12:32:04 INFO - TinderboxPrint: I/O read bytes / time
40,960 / 52 12:32:04 INFO - TinderboxPrint: I/O write bytes / time
347,557,888 / 183,508 12:32:04 INFO - TinderboxPrint: CPU idle
8,227.9 (86.9%) 12:32:04 INFO - TinderboxPrint: CPU user
1,193.8 (12.6%) 12:32:04 INFO - TinderboxPrint: Swap in / out
0 / 0 12:32:04 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 13103104; Read time: 0; Write time: 996 12:32:04 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 12:32:04 INFO - run-tests - Wall time: 1170s; CPU: 13.0%; Read bytes: 36864; Write bytes: 334454784; Read time: 36; Write time: 182512 12:32:05 INFO - Running post-run listener: _upload_blobber_files 12:32:05 INFO - Blob upload gear active. 12:32:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:32:05 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 12:32:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:32:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:32:05 INFO - (blobuploader) - INFO - Open directory for files ... 12:32:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:32:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:32:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:32:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:32:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:32:06 INFO - (blobuploader) - INFO - Done attempting. 12:32:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:32:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:32:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:32:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:32:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:32:07 INFO - (blobuploader) - INFO - Done attempting. 12:32:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:32:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:32:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:32:15 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:32:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:32:15 INFO - (blobuploader) - INFO - Done attempting. 12:32:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:32:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:32:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:32:16 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:32:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:32:16 INFO - (blobuploader) - INFO - Done attempting. 12:32:16 INFO - (blobuploader) - INFO - Iteration through files over. 12:32:16 INFO - Return code: 0 12:32:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:32:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:32:16 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6ccef34421f5197996b6b2cfd9af8b004ef9ee0727dea2348d23c349b42eaefb44700cfe5d3aa98fb48223fb158282cbdf81437977ab7c3d900d9c8fd3db5188", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/87e25c06dc2703e7d5b8011330070001215c88ec167819b51539465d7cf2d6f36727cb1c2f4a0ff1f562d7f00e2d8022e1fd465dd12f87d485f3cdc3fee7185f", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/39f1e8b4ab19cdbd265af342cccb85750848627246fce5e0008c27cacfae1aba727cbe772c4e49120664929d36119ff7e64bd954858bae4bd63eb19c93c7928b", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8cefc047ad2b4704e7ecd90204cbcafbd84b7fa46af70ddfe78ccab8b13217246442eff839caed40cdc9fb4c3df854c515a32bd6fed2fdafac0fb9096d012c6b"} 12:32:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:32:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:32:16 INFO - Contents: 12:32:16 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6ccef34421f5197996b6b2cfd9af8b004ef9ee0727dea2348d23c349b42eaefb44700cfe5d3aa98fb48223fb158282cbdf81437977ab7c3d900d9c8fd3db5188", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/87e25c06dc2703e7d5b8011330070001215c88ec167819b51539465d7cf2d6f36727cb1c2f4a0ff1f562d7f00e2d8022e1fd465dd12f87d485f3cdc3fee7185f", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/39f1e8b4ab19cdbd265af342cccb85750848627246fce5e0008c27cacfae1aba727cbe772c4e49120664929d36119ff7e64bd954858bae4bd63eb19c93c7928b", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8cefc047ad2b4704e7ecd90204cbcafbd84b7fa46af70ddfe78ccab8b13217246442eff839caed40cdc9fb4c3df854c515a32bd6fed2fdafac0fb9096d012c6b"} 12:32:16 INFO - Running post-run listener: copy_logs_to_upload_dir 12:32:16 INFO - Copying logs to upload dir... 12:32:16 INFO - mkdir: /builds/slave/test/build/upload/logs 12:32:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.239248 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2017-09-26 12:32:16.190208) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-26 12:32:16.194002) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1506453094.566063-2043789727 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6ccef34421f5197996b6b2cfd9af8b004ef9ee0727dea2348d23c349b42eaefb44700cfe5d3aa98fb48223fb158282cbdf81437977ab7c3d900d9c8fd3db5188", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/87e25c06dc2703e7d5b8011330070001215c88ec167819b51539465d7cf2d6f36727cb1c2f4a0ff1f562d7f00e2d8022e1fd465dd12f87d485f3cdc3fee7185f", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/39f1e8b4ab19cdbd265af342cccb85750848627246fce5e0008c27cacfae1aba727cbe772c4e49120664929d36119ff7e64bd954858bae4bd63eb19c93c7928b", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8cefc047ad2b4704e7ecd90204cbcafbd84b7fa46af70ddfe78ccab8b13217246442eff839caed40cdc9fb4c3df854c515a32bd6fed2fdafac0fb9096d012c6b"} build_url:https://queue.taskcluster.net/v1/task/CfEmsjFlSySZu2syzfRtJA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011334 build_url: 'https://queue.taskcluster.net/v1/task/CfEmsjFlSySZu2syzfRtJA/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6ccef34421f5197996b6b2cfd9af8b004ef9ee0727dea2348d23c349b42eaefb44700cfe5d3aa98fb48223fb158282cbdf81437977ab7c3d900d9c8fd3db5188", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/87e25c06dc2703e7d5b8011330070001215c88ec167819b51539465d7cf2d6f36727cb1c2f4a0ff1f562d7f00e2d8022e1fd465dd12f87d485f3cdc3fee7185f", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/39f1e8b4ab19cdbd265af342cccb85750848627246fce5e0008c27cacfae1aba727cbe772c4e49120664929d36119ff7e64bd954858bae4bd63eb19c93c7928b", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8cefc047ad2b4704e7ecd90204cbcafbd84b7fa46af70ddfe78ccab8b13217246442eff839caed40cdc9fb4c3df854c515a32bd6fed2fdafac0fb9096d012c6b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-26 12:32:16.222422) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-26 12:32:16.222759) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1506453094.566063-2043789727 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004493 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-26 12:32:16.308665) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-26 12:32:16.308976) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-26 12:32:16.309293) ========= ========= Total master_lag: 0.18 =========