builder: mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s slave: talos-linux64-ix-009 starttime: 1504823907.37 results: success (0) revision: 64bf417d1bdf9bb8b562d73cc0742b1ec60a8d0e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-07 15:38:27.371689) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-07 15:38:27.372197) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-07 15:38:27.372835) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-07 15:38:27.409564) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 15:38:27.410158) ========= 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=17051fcd5208b27b50bedfeb000001dd-1504823906.590082-885688012 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-07 15:38:27-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 21.2M=0.001s 2017-09-07 15:38:27 (21.2 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.226084 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 15:38:27.655909) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 15:38:27.656239) ========= 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=17051fcd5208b27b50bedfeb000001dd-1504823906.590082-885688012 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019918 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 15:38:27.706321) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 49 secs) (at 2017-09-07 15:38:27.706652) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 64bf417d1bdf9bb8b562d73cc0742b1ec60a8d0e --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 64bf417d1bdf9bb8b562d73cc0742b1ec60a8d0e --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=17051fcd5208b27b50bedfeb000001dd-1504823906.590082-885688012 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-07 15:38:27,763 truncating revision to first 12 chars 2017-09-07 15:38:27,763 Setting DEBUG logging. 2017-09-07 15:38:27,763 attempt 1/10 2017-09-07 15:38:27,764 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/64bf417d1bdf?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-07 15:38:28,417 attempt 1/10 2017-09-07 15:38:28,937 current task status: no status available at this point. state: PENDING 2017-09-07 15:38:28,938 sleeping for 10.00s (attempt 1/10) 2017-09-07 15:38:38,948 attempt 2/10 2017-09-07 15:38:39,473 current task status: no status available at this point. state: PENDING 2017-09-07 15:38:39,473 sleeping for 14.00s (attempt 2/10) 2017-09-07 15:38:53,487 attempt 3/10 2017-09-07 15:38:54,063 current task status: no status available at this point. state: STARTED 2017-09-07 15:38:54,064 sleeping for 22.00s (attempt 3/10) 2017-09-07 15:39:16,086 attempt 4/10 2017-09-07 15:39:16,394 current task status: Task completed! Check 's3_urls' for upload locations. state: SUCCESS 2017-09-07 15:39:16,568 unpacking tar archive at: mozilla-central-64bf417d1bdf/testing/mozharness/ program finished with exit code 0 elapsedTime=49.129727 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 49 secs) (at 2017-09-07 15:39:16.852452) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-07 15:39:16.852892) ========= script_repo_revision: 64bf417d1bdf9bb8b562d73cc0742b1ec60a8d0e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-07 15:39:16.853384) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-07 15:39:16.853660) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-07 15:39:16.867517) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2017-09-07 15:39:16.867903) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=17051fcd5208b27b50bedfeb000001dd-1504823906.590082-885688012 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:39:17 INFO - MultiFileLogger online at 20170907 15:39:17 in /builds/slave/test 15:39:17 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 15:39:17 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:39:17 INFO - {'append_to_log': False, 15:39:17 INFO - 'base_work_dir': '/builds/slave/test', 15:39:17 INFO - 'blob_upload_branch': 'Firefox', 15:39:17 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:39:17 INFO - 'branch': 'Firefox', 15:39:17 INFO - 'buildbot_json_path': 'buildprops.json', 15:39:17 INFO - 'code_coverage': False, 15:39:17 INFO - 'config_files': ('talos/linux_config.py',), 15:39:17 INFO - 'default_actions': ('clobber', 15:39:17 INFO - 'read-buildbot-config', 15:39:17 INFO - 'download-and-extract', 15:39:17 INFO - 'populate-webroot', 15:39:17 INFO - 'create-virtualenv', 15:39:17 INFO - 'install', 15:39:17 INFO - 'setup-mitmproxy', 15:39:17 INFO - 'run-tests'), 15:39:17 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:39:17 INFO - 'disable_ccov_upload': False, 15:39:17 INFO - 'disable_stylo': False, 15:39:17 INFO - 'download_minidump_stackwalk': True, 15:39:17 INFO - 'download_symbols': 'ondemand', 15:39:17 INFO - 'enable_stylo': False, 15:39:17 INFO - 'enable_webrender': False, 15:39:17 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 15:39:17 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:39:17 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:39:17 INFO - 'gecko_profile': False, 15:39:17 INFO - 'gecko_profile_interval': 0, 15:39:17 INFO - 'installer_path': 'installer.exe', 15:39:17 INFO - 'log_level': 'info', 15:39:17 INFO - 'log_name': 'talos', 15:39:17 INFO - 'log_to_console': True, 15:39:17 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:39:17 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:39:17 INFO - 'opt_config_files': (), 15:39:17 INFO - 'pip_index': False, 15:39:17 INFO - 'suite': 'dromaeojs-e10s', 15:39:17 INFO - 'system_bits': '32', 15:39:17 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:39:17 INFO - 'title': 'talos-linux64-ix-009', 15:39:17 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:39:17 INFO - 'use_talos_json': True, 15:39:17 INFO - 'verify': 'False', 15:39:17 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:39:17 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:39:17 INFO - 'work_dir': 'build'} 15:39:17 INFO - [mozharness: 2017-09-07 22:39:17.076036Z] Running clobber step. 15:39:17 INFO - Running pre-action listener: _resource_record_pre_action 15:39:17 INFO - Running main action method: clobber 15:39:17 INFO - rmtree: /builds/slave/test/build 15:39:17 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:39:18 INFO - Running post-action listener: _resource_record_post_action 15:39:18 INFO - [mozharness: 2017-09-07 22:39:18.613219Z] Finished clobber step (success) 15:39:18 INFO - [mozharness: 2017-09-07 22:39:18.613317Z] Running read-buildbot-config step. 15:39:18 INFO - Running pre-action listener: _resource_record_pre_action 15:39:18 INFO - Running main action method: read_buildbot_config 15:39:18 INFO - Using buildbot properties: 15:39:18 INFO - { 15:39:18 INFO - "project": "", 15:39:18 INFO - "product": "firefox", 15:39:18 INFO - "who": "archaeopteryx@coole-files.de", 15:39:18 INFO - "installer_path": "public/build/target.tar.bz2", 15:39:18 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s", 15:39:18 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 15:39:18 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos dromaeojs-e10s", 15:39:18 INFO - "stage_platform": "linux64-qr", 15:39:18 INFO - "basedir": "/builds/slave/test", 15:39:18 INFO - "buildnumber": 36, 15:39:18 INFO - "platform": "ubuntu64_hw_qr", 15:39:18 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 15:39:18 INFO - "slavebuilddir": "test", 15:39:18 INFO - "taskId": "Q55fY60XSySrd-Km-g00FA", 15:39:18 INFO - "branch": "mozilla-central", 15:39:18 INFO - "script_repo_revision": "production", 15:39:18 INFO - "revision": "64bf417d1bdf9bb8b562d73cc0742b1ec60a8d0e", 15:39:18 INFO - "slavename": "talos-linux64-ix-009", 15:39:18 INFO - "repo_path": "mozilla-central" 15:39:18 INFO - } 15:39:18 INFO - Finding installer, test and symbols from parent task. 15:39:18 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Q55fY60XSySrd-Km-g00FA'}, attempt #1 15:39:19 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Q55fY60XSySrd-Km-g00FA'}, attempt #1 15:39:19 INFO - Task dependencies: HU_acp97TAesHcVo7pnBow 15:39:19 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HU_acp97TAesHcVo7pnBow'}, attempt #1 15:39:19 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/HU_acp97TAesHcVo7pnBow/artifacts/public/build/target.tar.bz2 15:39:19 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/HU_acp97TAesHcVo7pnBow/artifacts/public/build/target.test_packages.json 15:39:19 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/HU_acp97TAesHcVo7pnBow/artifacts/public/build/target.crashreporter-symbols.zip 15:39:19 INFO - Running post-action listener: _resource_record_post_action 15:39:19 INFO - [mozharness: 2017-09-07 22:39:19.991247Z] Finished read-buildbot-config step (success) 15:39:19 INFO - [mozharness: 2017-09-07 22:39:19.991467Z] Running download-and-extract step. 15:39:19 INFO - Running pre-action listener: _resource_record_pre_action 15:39:19 INFO - Running main action method: download_and_extract 15:39:19 INFO - mkdir: /builds/slave/test/build/tests 15:39:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:19 INFO - trying https://queue.taskcluster.net/v1/task/HU_acp97TAesHcVo7pnBow/artifacts/public/build/target.test_packages.json 15:39:19 INFO - Downloading https://queue.taskcluster.net/v1/task/HU_acp97TAesHcVo7pnBow/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 15:39:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HU_acp97TAesHcVo7pnBow/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 15:39:20 INFO - Downloaded 989 bytes. 15:39:20 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 15:39:20 INFO - Using the following test package requirements: 15:39:20 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 15:39:20 INFO - u'common': [u'target.common.tests.zip'], 15:39:20 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 15:39:20 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 15:39:20 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 15:39:20 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 15:39:20 INFO - u'mozbase': [u'target.common.tests.zip'], 15:39:20 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 15:39:20 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 15:39:20 INFO - u'web-platform': [u'target.common.tests.zip', 15:39:20 INFO - u'target.web-platform.tests.tar.gz'], 15:39:20 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 15:39:20 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 15:39:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HU_acp97TAesHcVo7pnBow/artifacts/public/build/target.common.tests.zip 15:39:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HU_acp97TAesHcVo7pnBow/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:39:20 INFO - Fetch https://queue.taskcluster.net/v1/task/HU_acp97TAesHcVo7pnBow/artifacts/public/build/target.common.tests.zip into memory 15:39:21 INFO - Content-Length response header: 55021152 15:39:21 INFO - Bytes received: 55021152 15:39:32 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 15:39:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HU_acp97TAesHcVo7pnBow/artifacts/public/build/target.common.tests.zip 15:39:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HU_acp97TAesHcVo7pnBow/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:39:32 INFO - Fetch https://queue.taskcluster.net/v1/task/HU_acp97TAesHcVo7pnBow/artifacts/public/build/target.common.tests.zip into memory 15:39:33 INFO - Content-Length response header: 55021152 15:39:33 INFO - Bytes received: 55021152 15:39:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HU_acp97TAesHcVo7pnBow/artifacts/public/build/target.talos.tests.zip 15:39:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HU_acp97TAesHcVo7pnBow/artifacts/public/build/target.talos.tests.zip'}, attempt #1 15:39:45 INFO - Fetch https://queue.taskcluster.net/v1/task/HU_acp97TAesHcVo7pnBow/artifacts/public/build/target.talos.tests.zip into memory 15:39:46 INFO - Content-Length response header: 14496665 15:39:46 INFO - Bytes received: 14496665 15:39:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:46 INFO - trying https://queue.taskcluster.net/v1/task/HU_acp97TAesHcVo7pnBow/artifacts/public/build/target.tar.bz2 15:39:46 INFO - Downloading https://queue.taskcluster.net/v1/task/HU_acp97TAesHcVo7pnBow/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 15:39:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HU_acp97TAesHcVo7pnBow/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 15:39:47 INFO - Downloaded 62707151 bytes. 15:39:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HU_acp97TAesHcVo7pnBow/artifacts/public/build/target.tar.bz2 15:39:47 INFO - mkdir: /builds/slave/test/properties 15:39:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:39:47 INFO - Writing to file /builds/slave/test/properties/build_url 15:39:47 INFO - Contents: 15:39:47 INFO - build_url:https://queue.taskcluster.net/v1/task/HU_acp97TAesHcVo7pnBow/artifacts/public/build/target.tar.bz2 15:39:47 INFO - Running post-action listener: _resource_record_post_action 15:39:47 INFO - Running post-action listener: find_tests_for_verification 15:39:47 INFO - Running post-action listener: set_extra_try_arguments 15:39:47 INFO - [mozharness: 2017-09-07 22:39:47.853303Z] Finished download-and-extract step (success) 15:39:47 INFO - [mozharness: 2017-09-07 22:39:47.853400Z] Running populate-webroot step. 15:39:47 INFO - Running pre-action listener: _resource_record_pre_action 15:39:47 INFO - Running main action method: populate_webroot 15:39:47 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:39:47 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:39:47 INFO - 'tests': ['tresize', 15:39:47 INFO - 'tcanvasmark']}, 15:39:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:39:47 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:39:47 INFO - 'tests': ['dromaeo_css', 15:39:47 INFO - 'kraken']}, 15:39:47 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:39:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:39:47 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:39:47 INFO - 'talos_options': ['--disable-stylo'], 15:39:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:39:47 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:39:47 INFO - 'tests': ['damp', 'tps']}, 15:39:47 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:39:47 INFO - 'talos_options': ['--disable-stylo'], 15:39:47 INFO - 'tests': ['damp', 'tps']}, 15:39:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:39:47 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:39:47 INFO - 'tests': ['dromaeo_dom']}, 15:39:47 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 15:39:47 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:39:47 INFO - 'tests': ['basic_compositor_video', 15:39:47 INFO - 'glvideo']}, 15:39:47 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 15:39:47 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 15:39:47 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:39:47 INFO - 'talos_options': ['--disable-stylo'], 15:39:47 INFO - 'tests': ['ts_paint_webext', 15:39:47 INFO - 'tp5o_webext']}, 15:39:47 INFO - 'other-e10s': {'tests': ['a11yr', 15:39:47 INFO - 'ts_paint', 15:39:47 INFO - 'tpaint', 15:39:47 INFO - 'sessionrestore', 15:39:47 INFO - 'sessionrestore_many_windows', 15:39:47 INFO - 'sessionrestore_no_auto_restore', 15:39:47 INFO - 'tabpaint']}, 15:39:47 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:39:47 INFO - 'tests': ['a11yr', 15:39:47 INFO - 'ts_paint', 15:39:47 INFO - 'tpaint', 15:39:47 INFO - 'sessionrestore', 15:39:47 INFO - 'sessionrestore_many_windows', 15:39:47 INFO - 'sessionrestore_no_auto_restore', 15:39:47 INFO - 'tabpaint']}, 15:39:47 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 15:39:47 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 15:39:47 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:39:47 INFO - 'tests': ['bloom_basic_singleton']}, 15:39:47 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:39:47 INFO - 'tests': ['bloom_basic']}, 15:39:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:39:47 INFO - 'tsvgr_opacity', 15:39:47 INFO - 'tart', 15:39:47 INFO - 'tscrollx', 15:39:47 INFO - 'tsvg_static']}, 15:39:47 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:39:47 INFO - 'tests': ['tsvgx', 15:39:47 INFO - 'tsvgr_opacity', 15:39:47 INFO - 'tart', 15:39:47 INFO - 'tscrollx', 15:39:47 INFO - 'tsvg_static']}, 15:39:47 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:39:47 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:39:47 INFO - 'talos_options': ['--disable-stylo'], 15:39:47 INFO - 'tests': ['tp5o']}, 15:39:47 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:39:47 INFO - 'talos_options': ['--mitmproxy', 15:39:47 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:39:47 INFO - '--firstNonBlankPaint'], 15:39:47 INFO - 'tests': ['tp6_google', 15:39:47 INFO - 'tp6_youtube', 15:39:47 INFO - 'tp6_amazon', 15:39:47 INFO - 'tp6_facebook']}, 15:39:47 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:39:47 INFO - 'talos_options': ['--disable-stylo', 15:39:47 INFO - '--mitmproxy', 15:39:47 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:39:47 INFO - '--firstNonBlankPaint'], 15:39:47 INFO - 'tests': ['tp6_google', 15:39:47 INFO - 'tp6_youtube', 15:39:47 INFO - 'tp6_amazon', 15:39:47 INFO - 'tp6_facebook']}, 15:39:47 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:39:47 INFO - 'talos_options': ['--stylo-threads=1', 15:39:47 INFO - '--mitmproxy', 15:39:47 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:39:47 INFO - '--firstNonBlankPaint'], 15:39:47 INFO - 'tests': ['tp6_google', 15:39:47 INFO - 'tp6_youtube', 15:39:47 INFO - 'tp6_amazon', 15:39:47 INFO - 'tp6_facebook']}, 15:39:47 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:39:47 INFO - 'talos_options': ['--xperf_path', 15:39:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:39:47 INFO - 'tests': ['tp5n']}, 15:39:47 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:39:47 INFO - 'talos_options': ['--disable-stylo', 15:39:47 INFO - '--xperf_path', 15:39:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:39:47 INFO - 'tests': ['tp5n']}}} 15:39:47 INFO - Running post-action listener: _resource_record_post_action 15:39:47 INFO - [mozharness: 2017-09-07 22:39:47.868369Z] Finished populate-webroot step (success) 15:39:47 INFO - [mozharness: 2017-09-07 22:39:47.868460Z] Running create-virtualenv step. 15:39:47 INFO - Running pre-action listener: _resource_record_pre_action 15:39:47 INFO - Running main action method: create_virtualenv 15:39:47 INFO - Creating virtualenv /builds/slave/test/build/venv 15:39:47 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:39:47 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 15:39:47 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 15:39:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:39:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:39:48 INFO - Installing distribute.............................................................................................................................................................................................done. 15:39:48 INFO - Installing pip...............done. 15:39:48 INFO - Return code: 0 15:39:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:39:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:39:48 INFO - Reading from file tmpfile_stdout 15:39:48 INFO - Output received: 15:39:48 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 15:39:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:39:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:48 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 0x1ab31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c7ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e1fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e23090>, '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 0x1e23d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e1df90>, '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': '17051fcd5208b27b50bedfeb000001dd-1504823906.590082-885688012', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:39:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:39:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:39:48 INFO - Using env: {'DISPLAY': ':0', 15:39:48 INFO - 'HOME': '/home/cltbld', 15:39:48 INFO - 'LANG': 'en_US.UTF-8', 15:39:48 INFO - 'LANGUAGE': 'en_US:en', 15:39:48 INFO - 'LOGNAME': 'cltbld', 15:39:48 INFO - 'MAIL': '/var/mail/cltbld', 15:39:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:48 INFO - 'MOZ_NO_REMOTE': '1', 15:39:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:39:48 INFO - 'NO_EM_RESTART': '1', 15:39:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:39:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:48 INFO - 'PWD': '/builds/slave/test', 15:39:48 INFO - 'SHELL': '/bin/bash', 15:39:48 INFO - 'SHLVL': '1', 15:39:48 INFO - 'TERM': 'linux', 15:39:48 INFO - 'TMOUT': '86400', 15:39:48 INFO - 'USER': 'cltbld', 15:39:48 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1504823906.590082-885688012', 15:39:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:48 INFO - '_': '/tools/buildbot/bin/python'} 15:39:50 INFO - Ignoring indexes: http://pypi.python.org/simple/ 15:39:50 INFO - Downloading/unpacking pip>=1.5 15:39:50 INFO - Running setup.py egg_info for package pip 15:39:50 INFO - warning: no files found matching 'pip/cacert.pem' 15:39:50 INFO - warning: no files found matching '*.html' under directory 'docs' 15:39:50 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:39:50 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:39:50 INFO - Installing collected packages: pip 15:39:50 INFO - Found existing installation: pip 1.1 15:39:50 INFO - Uninstalling pip: 15:39:50 INFO - Successfully uninstalled pip 15:39:50 INFO - Running setup.py install for pip 15:39:50 INFO - warning: no files found matching 'pip/cacert.pem' 15:39:50 INFO - warning: no files found matching '*.html' under directory 'docs' 15:39:50 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:39:50 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:39:50 INFO - Installing pip script to /builds/slave/test/build/venv/bin 15:39:50 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 15:39:50 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 15:39:50 INFO - Successfully installed pip 15:39:50 INFO - Cleaning up... 15:39:50 INFO - Return code: 0 15:39:50 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:39:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:50 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 0x1ab31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c7ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e1fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e23090>, '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 0x1e23d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e1df90>, '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': '17051fcd5208b27b50bedfeb000001dd-1504823906.590082-885688012', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:39:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:39:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:39:50 INFO - Using env: {'DISPLAY': ':0', 15:39:50 INFO - 'HOME': '/home/cltbld', 15:39:50 INFO - 'LANG': 'en_US.UTF-8', 15:39:50 INFO - 'LANGUAGE': 'en_US:en', 15:39:50 INFO - 'LOGNAME': 'cltbld', 15:39:50 INFO - 'MAIL': '/var/mail/cltbld', 15:39:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:50 INFO - 'MOZ_NO_REMOTE': '1', 15:39:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:39:50 INFO - 'NO_EM_RESTART': '1', 15:39:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:39:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:50 INFO - 'PWD': '/builds/slave/test', 15:39:50 INFO - 'SHELL': '/bin/bash', 15:39:50 INFO - 'SHLVL': '1', 15:39:50 INFO - 'TERM': 'linux', 15:39:50 INFO - 'TMOUT': '86400', 15:39:50 INFO - 'USER': 'cltbld', 15:39:50 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1504823906.590082-885688012', 15:39:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:50 INFO - '_': '/tools/buildbot/bin/python'} 15:39:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:50 INFO - Downloading/unpacking psutil>=3.1.1 15:39:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:39:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:39:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:39:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:39:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:39:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:39:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:39:51 INFO - Installing collected packages: psutil 15:39:51 INFO - Running setup.py install for psutil 15:39:51 INFO - building 'psutil._psutil_linux' extension 15:39:51 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 15:39:51 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 15:39:51 INFO - building 'psutil._psutil_posix' extension 15:39:51 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 15:39:51 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 15:39:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:39:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:39:51 INFO - Successfully installed psutil 15:39:51 INFO - Cleaning up... 15:39:51 INFO - Return code: 0 15:39:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:39:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ab31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c7ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e1fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e23090>, '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 0x1e23d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e1df90>, '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': '17051fcd5208b27b50bedfeb000001dd-1504823906.590082-885688012', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:39:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:39:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:39:52 INFO - Using env: {'DISPLAY': ':0', 15:39:52 INFO - 'HOME': '/home/cltbld', 15:39:52 INFO - 'LANG': 'en_US.UTF-8', 15:39:52 INFO - 'LANGUAGE': 'en_US:en', 15:39:52 INFO - 'LOGNAME': 'cltbld', 15:39:52 INFO - 'MAIL': '/var/mail/cltbld', 15:39:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:52 INFO - 'MOZ_NO_REMOTE': '1', 15:39:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:39:52 INFO - 'NO_EM_RESTART': '1', 15:39:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:39:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:52 INFO - 'PWD': '/builds/slave/test', 15:39:52 INFO - 'SHELL': '/bin/bash', 15:39:52 INFO - 'SHLVL': '1', 15:39:52 INFO - 'TERM': 'linux', 15:39:52 INFO - 'TMOUT': '86400', 15:39:52 INFO - 'USER': 'cltbld', 15:39:52 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1504823906.590082-885688012', 15:39:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:52 INFO - '_': '/tools/buildbot/bin/python'} 15:39:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:52 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:39:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:39:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:39:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:39:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:39:54 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:39:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:39:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:39:54 INFO - Installing collected packages: mozsystemmonitor 15:39:54 INFO - Running setup.py install for mozsystemmonitor 15:39:54 INFO - Successfully installed mozsystemmonitor 15:39:54 INFO - Cleaning up... 15:39:54 INFO - Return code: 0 15:39:54 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:39:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1ab31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c7ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e1fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e23090>, '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 0x1e23d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e1df90>, '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': '17051fcd5208b27b50bedfeb000001dd-1504823906.590082-885688012', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:39:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:39:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:39:54 INFO - Using env: {'DISPLAY': ':0', 15:39:54 INFO - 'HOME': '/home/cltbld', 15:39:54 INFO - 'LANG': 'en_US.UTF-8', 15:39:54 INFO - 'LANGUAGE': 'en_US:en', 15:39:54 INFO - 'LOGNAME': 'cltbld', 15:39:54 INFO - 'MAIL': '/var/mail/cltbld', 15:39:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:54 INFO - 'MOZ_NO_REMOTE': '1', 15:39:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:39:54 INFO - 'NO_EM_RESTART': '1', 15:39:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:39:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:54 INFO - 'PWD': '/builds/slave/test', 15:39:54 INFO - 'SHELL': '/bin/bash', 15:39:54 INFO - 'SHLVL': '1', 15:39:54 INFO - 'TERM': 'linux', 15:39:54 INFO - 'TMOUT': '86400', 15:39:54 INFO - 'USER': 'cltbld', 15:39:54 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1504823906.590082-885688012', 15:39:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:54 INFO - '_': '/tools/buildbot/bin/python'} 15:39:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:54 INFO - Downloading/unpacking jsonschema==2.5.1 15:39:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:39:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:39:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:39:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:39:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:39:55 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:39:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:39:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:39:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:39:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:39:55 INFO - Downloading functools32-3.2.3-2.tar.gz 15:39:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:39:55 INFO - warning: no files found matching '*.txt' 15:39:55 INFO - no previously-included directories found matching 'build' 15:39:55 INFO - no previously-included directories found matching 'dist' 15:39:55 INFO - no previously-included directories found matching '.git*' 15:39:55 INFO - Installing collected packages: jsonschema, functools32 15:39:56 INFO - Running setup.py install for functools32 15:39:56 INFO - warning: no files found matching '*.txt' 15:39:56 INFO - no previously-included directories found matching 'build' 15:39:56 INFO - no previously-included directories found matching 'dist' 15:39:56 INFO - no previously-included directories found matching '.git*' 15:39:56 INFO - Successfully installed jsonschema functools32 15:39:56 INFO - Cleaning up... 15:39:56 INFO - Return code: 0 15:39:56 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:39:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:56 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 0x1ab31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c7ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e1fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e23090>, '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 0x1e23d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e1df90>, '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': '17051fcd5208b27b50bedfeb000001dd-1504823906.590082-885688012', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:39:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 15:39:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 15:39:56 INFO - Using env: {'DISPLAY': ':0', 15:39:56 INFO - 'HOME': '/home/cltbld', 15:39:56 INFO - 'LANG': 'en_US.UTF-8', 15:39:56 INFO - 'LANGUAGE': 'en_US:en', 15:39:56 INFO - 'LOGNAME': 'cltbld', 15:39:56 INFO - 'MAIL': '/var/mail/cltbld', 15:39:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:56 INFO - 'MOZ_NO_REMOTE': '1', 15:39:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:39:56 INFO - 'NO_EM_RESTART': '1', 15:39:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:39:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:56 INFO - 'PWD': '/builds/slave/test', 15:39:56 INFO - 'SHELL': '/bin/bash', 15:39:56 INFO - 'SHLVL': '1', 15:39:56 INFO - 'TERM': 'linux', 15:39:56 INFO - 'TMOUT': '86400', 15:39:56 INFO - 'USER': 'cltbld', 15:39:56 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1504823906.590082-885688012', 15:39:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:56 INFO - '_': '/tools/buildbot/bin/python'} 15:39:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:56 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:39:56 INFO - Cleaning up... 15:39:56 INFO - Return code: 0 15:39:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:39:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:56 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 0x1ab31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c7ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e1fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e23090>, '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 0x1e23d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e1df90>, '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': '17051fcd5208b27b50bedfeb000001dd-1504823906.590082-885688012', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:39:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:39:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:39:56 INFO - Using env: {'DISPLAY': ':0', 15:39:56 INFO - 'HOME': '/home/cltbld', 15:39:56 INFO - 'LANG': 'en_US.UTF-8', 15:39:56 INFO - 'LANGUAGE': 'en_US:en', 15:39:56 INFO - 'LOGNAME': 'cltbld', 15:39:56 INFO - 'MAIL': '/var/mail/cltbld', 15:39:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:56 INFO - 'MOZ_NO_REMOTE': '1', 15:39:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:39:56 INFO - 'NO_EM_RESTART': '1', 15:39:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:39:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:56 INFO - 'PWD': '/builds/slave/test', 15:39:56 INFO - 'SHELL': '/bin/bash', 15:39:56 INFO - 'SHLVL': '1', 15:39:56 INFO - 'TERM': 'linux', 15:39:56 INFO - 'TMOUT': '86400', 15:39:56 INFO - 'USER': 'cltbld', 15:39:56 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1504823906.590082-885688012', 15:39:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:56 INFO - '_': '/tools/buildbot/bin/python'} 15:39:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:56 INFO - Downloading/unpacking blobuploader==1.2.4 15:39:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:39:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:39:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:39:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:39:57 INFO - Downloading blobuploader-1.2.4.tar.gz 15:39:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:39:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:39:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:39:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:39:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:39:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:39:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:39:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:39: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 15:39: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 15:39: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 15:39: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 15:39:58 INFO - Downloading docopt-0.6.1.tar.gz 15:39:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:39:58 INFO - Installing collected packages: blobuploader, requests, docopt 15:39:58 INFO - Running setup.py install for blobuploader 15:39:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:39:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:39:58 INFO - Running setup.py install for requests 15:39:58 INFO - Running setup.py install for docopt 15:39:58 INFO - Successfully installed blobuploader requests docopt 15:39:58 INFO - Cleaning up... 15:39:58 INFO - Return code: 0 15:39:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:39: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')]} 15:39:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39: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')]} 15:39:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:58 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 0x1ab31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c7ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e1fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e23090>, '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 0x1e23d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e1df90>, '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': '17051fcd5208b27b50bedfeb000001dd-1504823906.590082-885688012', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:39:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:39:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:39:58 INFO - Using env: {'DISPLAY': ':0', 15:39:58 INFO - 'HOME': '/home/cltbld', 15:39:58 INFO - 'LANG': 'en_US.UTF-8', 15:39:58 INFO - 'LANGUAGE': 'en_US:en', 15:39:58 INFO - 'LOGNAME': 'cltbld', 15:39:58 INFO - 'MAIL': '/var/mail/cltbld', 15:39:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:58 INFO - 'MOZ_NO_REMOTE': '1', 15:39:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:39:58 INFO - 'NO_EM_RESTART': '1', 15:39:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:39:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:58 INFO - 'PWD': '/builds/slave/test', 15:39:58 INFO - 'SHELL': '/bin/bash', 15:39:58 INFO - 'SHLVL': '1', 15:39:58 INFO - 'TERM': 'linux', 15:39:58 INFO - 'TMOUT': '86400', 15:39:58 INFO - 'USER': 'cltbld', 15:39:58 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1504823906.590082-885688012', 15:39:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:58 INFO - '_': '/tools/buildbot/bin/python'} 15:39:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:39:59 INFO - Running setup.py (path:/tmp/pip-yrGlvU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:39:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:39:59 INFO - Running setup.py (path:/tmp/pip-HCB3vT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:39:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:39:59 INFO - Running setup.py (path:/tmp/pip-OjHdxZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:39:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:39:59 INFO - Running setup.py (path:/tmp/pip-8_PCvx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:39:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:39:59 INFO - Running setup.py (path:/tmp/pip-rH6rRY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:39:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:39:59 INFO - Running setup.py (path:/tmp/pip-wSDabl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:39:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:39:59 INFO - Running setup.py (path:/tmp/pip-ktJbRP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:39:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:39:59 INFO - Running setup.py (path:/tmp/pip-2Mkm9k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:39:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:39:59 INFO - Running setup.py (path:/tmp/pip-fgqffR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:39:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:39:59 INFO - Running setup.py (path:/tmp/pip-Bmiq4x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:39:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:39:59 INFO - Running setup.py (path:/tmp/pip-KOK4qs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:39:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:39:59 INFO - Running setup.py (path:/tmp/pip-hDNi3X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:39:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:39:59 INFO - Running setup.py (path:/tmp/pip-CE7rKR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:40:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:40:00 INFO - Running setup.py (path:/tmp/pip-PkDE09-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:40:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:40:00 INFO - Running setup.py (path:/tmp/pip-88WU5b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:40:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:40:00 INFO - Running setup.py (path:/tmp/pip-AOLb5Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:40:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:40:00 INFO - Running setup.py (path:/tmp/pip-TMaznh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:40:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:40:00 INFO - Running setup.py install for manifestparser 15:40:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:40:00 INFO - Running setup.py install for mozcrash 15:40:00 INFO - Running setup.py install for mozdebug 15:40:00 INFO - Running setup.py install for mozdevice 15:40:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:40:00 INFO - Running setup.py install for mozfile 15:40:00 INFO - Running setup.py install for mozhttpd 15:40:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:40:00 INFO - Running setup.py install for mozinfo 15:40:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:40:00 INFO - Running setup.py install for mozInstall 15:40:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:40:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:40:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:40:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:40:01 INFO - Running setup.py install for mozleak 15:40:01 INFO - Running setup.py install for mozlog 15:40:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:40:01 INFO - Running setup.py install for moznetwork 15:40:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:40:01 INFO - Running setup.py install for mozprocess 15:40:01 INFO - Running setup.py install for mozprofile 15:40:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:40:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:40:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:40:01 INFO - Running setup.py install for mozrunner 15:40:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:40:01 INFO - Running setup.py install for mozscreenshot 15:40:01 INFO - Running setup.py install for moztest 15:40:01 INFO - Running setup.py install for mozversion 15:40:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:40:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:40:02 INFO - Cleaning up... 15:40:02 INFO - Return code: 0 15:40:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:40:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:02 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 0x1ab31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c7ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e1fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e23090>, '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 0x1e23d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e1df90>, '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': '17051fcd5208b27b50bedfeb000001dd-1504823906.590082-885688012', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:40:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:40:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:40:02 INFO - Using env: {'DISPLAY': ':0', 15:40:02 INFO - 'HOME': '/home/cltbld', 15:40:02 INFO - 'LANG': 'en_US.UTF-8', 15:40:02 INFO - 'LANGUAGE': 'en_US:en', 15:40:02 INFO - 'LOGNAME': 'cltbld', 15:40:02 INFO - 'MAIL': '/var/mail/cltbld', 15:40:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:02 INFO - 'MOZ_NO_REMOTE': '1', 15:40:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:40:02 INFO - 'NO_EM_RESTART': '1', 15:40:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:40:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:02 INFO - 'PWD': '/builds/slave/test', 15:40:02 INFO - 'SHELL': '/bin/bash', 15:40:02 INFO - 'SHLVL': '1', 15:40:02 INFO - 'TERM': 'linux', 15:40:02 INFO - 'TMOUT': '86400', 15:40:02 INFO - 'USER': 'cltbld', 15:40:02 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1504823906.590082-885688012', 15:40:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:02 INFO - '_': '/tools/buildbot/bin/python'} 15:40:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:40:02 INFO - Running setup.py (path:/tmp/pip-v1Ezz0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:40:02 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 15:40:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:40:02 INFO - Running setup.py (path:/tmp/pip-XrI2xv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:40:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:40:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:40:02 INFO - Running setup.py (path:/tmp/pip-dhEQBm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:40:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:40:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:40:02 INFO - Running setup.py (path:/tmp/pip-MYxotB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:40:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:40:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:40:02 INFO - Running setup.py (path:/tmp/pip-ia8LBF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:40:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 15:40:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:40:02 INFO - Running setup.py (path:/tmp/pip-KT0kDQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:40:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 15:40:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:40:02 INFO - Running setup.py (path:/tmp/pip-PmpoIs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:40:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 15:40:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:40:02 INFO - Running setup.py (path:/tmp/pip-jN84HG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:40:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:40:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:40:02 INFO - Running setup.py (path:/tmp/pip-x6lymv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:40:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 15:40:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:40:02 INFO - Running setup.py (path:/tmp/pip-p9C3G4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:40:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:40:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:40:03 INFO - Running setup.py (path:/tmp/pip-t23MHt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:40:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 15:40:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:40:03 INFO - Running setup.py (path:/tmp/pip-vpirO5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:40:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 15:40:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:40:03 INFO - Running setup.py (path:/tmp/pip-Y8591K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:40:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 15:40:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:40:03 INFO - Running setup.py (path:/tmp/pip-Y9I3qw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:40:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 15:40:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:40:03 INFO - Running setup.py (path:/tmp/pip-z3eOqn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:40:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 15:40:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:40:03 INFO - Running setup.py (path:/tmp/pip-I3Hz5m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:40:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 15:40:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:40:03 INFO - Running setup.py (path:/tmp/pip-MKp3NA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:40:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 15:40:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:40:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:40:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:40:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:40:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:40:03 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:40:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:40: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 15:40: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 15:40: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 15:40: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 15:40:04 INFO - Downloading blessings-1.6.tar.gz 15:40:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:40:04 INFO - Installing collected packages: blessings 15:40:04 INFO - Running setup.py install for blessings 15:40:04 INFO - Successfully installed blessings 15:40:04 INFO - Cleaning up... 15:40:04 INFO - Return code: 0 15:40:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:40:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:40:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:40:05 INFO - Reading from file tmpfile_stdout 15:40:05 INFO - Current package versions: 15:40:05 INFO - argparse == 1.2.1 15:40:05 INFO - blessings == 1.6 15:40:05 INFO - blobuploader == 1.2.4 15:40:05 INFO - docopt == 0.6.1 15:40:05 INFO - functools32 == 3.2.3-2 15:40:05 INFO - jsonschema == 2.5.1 15:40:05 INFO - manifestparser == 1.1 15:40:05 INFO - mozInstall == 1.13 15:40:05 INFO - mozcrash == 1.0 15:40:05 INFO - mozdebug == 0.1 15:40:05 INFO - mozdevice == 0.51 15:40:05 INFO - mozfile == 1.2 15:40:05 INFO - mozhttpd == 0.7 15:40:05 INFO - mozinfo == 0.10 15:40:05 INFO - mozleak == 0.1 15:40:05 INFO - mozlog == 3.5 15:40:05 INFO - moznetwork == 0.27 15:40:05 INFO - mozprocess == 0.25 15:40:05 INFO - mozprofile == 0.28 15:40:05 INFO - mozrunner == 6.13 15:40:05 INFO - mozscreenshot == 0.1 15:40:05 INFO - mozsystemmonitor == 0.3 15:40:05 INFO - moztest == 0.8 15:40:05 INFO - mozversion == 1.4 15:40:05 INFO - psutil == 3.1.1 15:40:05 INFO - requests == 1.2.3 15:40:05 INFO - wsgiref == 0.1.2 15:40:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:40:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:05 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 0x1ab31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c7ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e1fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e23090>, '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 0x1e23d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e1df90>, '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': '17051fcd5208b27b50bedfeb000001dd-1504823906.590082-885688012', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:40:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 15:40:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:40:05 INFO - Using env: {'DISPLAY': ':0', 15:40:05 INFO - 'HOME': '/home/cltbld', 15:40:05 INFO - 'LANG': 'en_US.UTF-8', 15:40:05 INFO - 'LANGUAGE': 'en_US:en', 15:40:05 INFO - 'LOGNAME': 'cltbld', 15:40:05 INFO - 'MAIL': '/var/mail/cltbld', 15:40:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:05 INFO - 'MOZ_NO_REMOTE': '1', 15:40:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:40:05 INFO - 'NO_EM_RESTART': '1', 15:40:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:40:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:05 INFO - 'PWD': '/builds/slave/test', 15:40:05 INFO - 'SHELL': '/bin/bash', 15:40:05 INFO - 'SHLVL': '1', 15:40:05 INFO - 'TERM': 'linux', 15:40:05 INFO - 'TMOUT': '86400', 15:40:05 INFO - 'USER': 'cltbld', 15:40:05 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1504823906.590082-885688012', 15:40:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:05 INFO - '_': '/tools/buildbot/bin/python'} 15:40:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:40:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 15:40:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 15:40:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:40:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 15:40:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 15:40:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 15:40:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 15:40:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 15:40:05 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 15:40: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 15:40: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 15:40: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 15:40: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 15:40:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 15:40:06 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:40:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:40:06 INFO - Installing collected packages: simplejson 15:40:06 INFO - Running setup.py install for simplejson 15:40:06 INFO - building 'simplejson._speedups' extension 15:40:06 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 15:40:07 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 15:40:07 INFO - Successfully installed simplejson 15:40:07 INFO - Cleaning up... 15:40:07 INFO - Return code: 0 15:40:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:40:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:07 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 0x1ab31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c7ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e1fa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e23090>, '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 0x1e23d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e1df90>, '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': '17051fcd5208b27b50bedfeb000001dd-1504823906.590082-885688012', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:40:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:40:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:40:07 INFO - Using env: {'DISPLAY': ':0', 15:40:07 INFO - 'HOME': '/home/cltbld', 15:40:07 INFO - 'LANG': 'en_US.UTF-8', 15:40:07 INFO - 'LANGUAGE': 'en_US:en', 15:40:07 INFO - 'LOGNAME': 'cltbld', 15:40:07 INFO - 'MAIL': '/var/mail/cltbld', 15:40:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:07 INFO - 'MOZ_NO_REMOTE': '1', 15:40:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:40:07 INFO - 'NO_EM_RESTART': '1', 15:40:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:40:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:07 INFO - 'PWD': '/builds/slave/test', 15:40:07 INFO - 'SHELL': '/bin/bash', 15:40:07 INFO - 'SHLVL': '1', 15:40:07 INFO - 'TERM': 'linux', 15:40:07 INFO - 'TMOUT': '86400', 15:40:07 INFO - 'USER': 'cltbld', 15:40:07 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1504823906.590082-885688012', 15:40:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:07 INFO - '_': '/tools/buildbot/bin/python'} 15:40:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:40:07 INFO - Cleaning up... 15:40:07 INFO - Return code: 0 15:40:07 INFO - Running post-action listener: _resource_record_post_action 15:40:07 INFO - Running post-action listener: _start_resource_monitoring 15:40:07 INFO - Starting resource monitoring. 15:40:07 INFO - [mozharness: 2017-09-07 22:40:07.759138Z] Finished create-virtualenv step (success) 15:40:07 INFO - [mozharness: 2017-09-07 22:40:07.759608Z] Running install step. 15:40:07 INFO - Running pre-action listener: _resource_record_pre_action 15:40:07 INFO - Running main action method: install 15:40:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:40:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:40:07 INFO - Reading from file tmpfile_stdout 15:40:07 INFO - Detecting whether we're running mozinstall >=1.0... 15:40:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:40:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:40:08 INFO - Reading from file tmpfile_stdout 15:40:08 INFO - Output received: 15:40:08 INFO - Usage: mozinstall [options] installer 15:40:08 INFO - Options: 15:40:08 INFO - -h, --help show this help message and exit 15:40:08 INFO - -d DEST, --destination=DEST 15:40:08 INFO - Directory to install application into. [default: 15:40:08 INFO - "/builds/slave/test"] 15:40:08 INFO - --app=APP Application being installed. [default: firefox] 15:40:08 INFO - mkdir: /builds/slave/test/build/application 15:40:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 15:40:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 15:40:24 INFO - Reading from file tmpfile_stdout 15:40:24 INFO - Output received: 15:40:24 INFO - /builds/slave/test/build/application/firefox/firefox 15:40:24 INFO - Running post-action listener: _resource_record_post_action 15:40:24 INFO - [mozharness: 2017-09-07 22:40:24.620505Z] Finished install step (success) 15:40:24 INFO - [mozharness: 2017-09-07 22:40:24.620760Z] Running setup-mitmproxy step. 15:40:24 INFO - Running pre-action listener: _resource_record_pre_action 15:40:24 INFO - Running main action method: setup_mitmproxy 15:40:24 INFO - Skipping: mitmproxy is not required 15:40:24 INFO - Running post-action listener: _resource_record_post_action 15:40:24 INFO - [mozharness: 2017-09-07 22:40:24.621898Z] Finished setup-mitmproxy step (success) 15:40:24 INFO - [mozharness: 2017-09-07 22:40:24.622117Z] Running run-tests step. 15:40:24 INFO - Running pre-action listener: _resource_record_pre_action 15:40:24 INFO - Running pre-action listener: _set_gcov_prefix 15:40:24 INFO - Running main action method: run_tests 15:40:24 WARNING - Try message not found. 15:40:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:40:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:40:24 INFO - Python 2.7.3 15:40:24 INFO - Return code: 0 15:40:24 INFO - grabbing minidump binary from tooltool 15:40:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:24 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 0x1e23090>, '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 0x1e23d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e1df90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:40:24 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 15:40:24 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 15:40:24 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 15:40:24 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:40:24 INFO - Return code: 0 15:40:24 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:40:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:40:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:40:24 INFO - ENV: RUST_BACKTRACE is now full 15:40:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:40:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:40:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HU_acp97TAesHcVo7pnBow/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-009', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 15:40:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/HU_acp97TAesHcVo7pnBow/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-009 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 15:40:24 INFO - Using env: {'DISPLAY': ':0', 15:40:24 INFO - 'HOME': '/home/cltbld', 15:40:24 INFO - 'LANG': 'en_US.UTF-8', 15:40:24 INFO - 'LANGUAGE': 'en_US:en', 15:40:24 INFO - 'LOGNAME': 'cltbld', 15:40:24 INFO - 'MAIL': '/var/mail/cltbld', 15:40:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:40:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:40:24 INFO - 'MOZ_ACCELERATED': '1', 15:40:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:24 INFO - 'MOZ_NO_REMOTE': '1', 15:40:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:40:24 INFO - 'MOZ_WEBRENDER': '1', 15:40:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:40:24 INFO - 'NO_EM_RESTART': '1', 15:40:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:40:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:24 INFO - 'PWD': '/builds/slave/test', 15:40:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:40:24 INFO - 'RUST_BACKTRACE': 'full', 15:40:24 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:40:24 INFO - 'SHELL': '/bin/bash', 15:40:24 INFO - 'SHLVL': '1', 15:40:24 INFO - 'TERM': 'linux', 15:40:24 INFO - 'TMOUT': '86400', 15:40:24 INFO - 'USER': 'cltbld', 15:40:24 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1504823906.590082-885688012', 15:40:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:24 INFO - '_': '/tools/buildbot/bin/python'} 15:40:24 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HU_acp97TAesHcVo7pnBow/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-009', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 15:40:24 INFO - mozversion application_buildid: 20170907215054 15:40:24 INFO - mozversion application_changeset: 64bf417d1bdf9bb8b562d73cc0742b1ec60a8d0e 15:40:24 INFO - mozversion application_display_name: Nightly 15:40:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:40:24 INFO - mozversion application_name: Firefox 15:40:24 INFO - mozversion application_remotingname: firefox 15:40:24 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:40:24 INFO - mozversion application_vendor: Mozilla 15:40:24 INFO - mozversion application_version: 57.0a1 15:40:24 INFO - mozversion platform_buildid: 20170907215054 15:40:24 INFO - mozversion platform_changeset: 64bf417d1bdf9bb8b562d73cc0742b1ec60a8d0e 15:40:24 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:40:24 INFO - mozversion platform_version: 57.0a1 15:40:24 INFO - using testdate: 1504824024 15:40:24 INFO - actual date: 1504824024 15:40:24 INFO - starting webserver on 'localhost:47620' 15:40:24 INFO - SUITE-START | Running 2 tests 15:40:24 INFO - TEST-START | dromaeo_css 15:40:24 INFO - Initialising browser for dromaeo_css test... 15:40:25 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:47620/getInfo.html -profile /tmp/tmppJumfO/profile 15:40:25 INFO - TEST-INFO | started process 30661 (/builds/slave/test/build/application/firefox/firefox http://localhost:47620/getInfo.html) 15:40:30 INFO - TEST-INFO | 30661: exit 0 15:40:31 INFO - Browser initialized. 15:40:31 INFO - Running cycle 1/1 for dromaeo_css test... 15:40:31 INFO - Using env: {'DISPLAY': ':0', 15:40:31 INFO - 'HOME': '/home/cltbld', 15:40:31 INFO - 'JSGC_DISABLE_POISONING': '1', 15:40:31 INFO - 'LANG': 'en_US.UTF-8', 15:40:31 INFO - 'LANGUAGE': 'en_US:en', 15:40:31 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 15:40:31 INFO - 'LOGNAME': 'cltbld', 15:40:31 INFO - 'MAIL': '/var/mail/cltbld', 15:40:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:40:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:40:31 INFO - 'MOZ_ACCELERATED': '1', 15:40:31 INFO - 'MOZ_CRASHREPORTER': '1', 15:40:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:31 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 15:40:31 INFO - 'MOZ_NO_REMOTE': '1', 15:40:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:40:31 INFO - 'MOZ_WEBRENDER': '1', 15:40:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:40:31 INFO - 'NO_EM_RESTART': '1', 15:40:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:40:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:31 INFO - 'PWD': '/builds/slave/test', 15:40:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:40:31 INFO - 'RUST_BACKTRACE': 'full', 15:40:31 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:40:31 INFO - 'SHELL': '/bin/bash', 15:40:31 INFO - 'SHLVL': '1', 15:40:31 INFO - 'TERM': 'linux', 15:40:31 INFO - 'TMOUT': '86400', 15:40:31 INFO - 'USER': 'cltbld', 15:40:31 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1504823906.590082-885688012', 15:40:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:31 INFO - '_': '/tools/buildbot/bin/python'} 15:40:31 INFO - TEST-INFO | started process 30865 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppJumfO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 15:40:31 INFO - PID 30865 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 15:40:32 INFO - PID 30865 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 15:40:32 INFO - PID 30865 | 15:40:32 INFO - PID 30865 | (/builds/slave/test/build/application/firefox/firefox:30927): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 15:40:32 INFO - PID 30865 | 15:40:42 INFO - PID 30865 | RSS: Main: 214810624 15:40:42 INFO - PID 30865 | 15:43:40 INFO - PID 30865 | Cycle 1(1): loaded http://localhost:47620/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47620/tests/dromaeo/cssquery-ext.html) 15:43:42 INFO - PID 30865 | RSS: Main: 220389376 15:43:42 INFO - PID 30865 | 15:46:44 INFO - PID 30865 | Cycle 1(1): loaded http://localhost:47620/tests/dromaeo/cssquery-ext.html (next: http://localhost:47620/tests/dromaeo/cssquery-jquery.html) 15:46:44 INFO - PID 30865 | RSS: Main: 217849856 15:46:44 INFO - PID 30865 | 15:49:46 INFO - PID 30865 | Cycle 1(1): loaded http://localhost:47620/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47620/tests/dromaeo/cssquery-mootools.html) 15:49:47 INFO - PID 30865 | RSS: Main: 222765056 15:49:47 INFO - PID 30865 | 15:52:49 INFO - PID 30865 | Cycle 1(1): loaded http://localhost:47620/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47620/tests/dromaeo/cssquery-prototype.html) 15:52:50 INFO - PID 30865 | RSS: Main: 223907840 15:52:50 INFO - PID 30865 | 15:55:53 INFO - PID 30865 | Cycle 1(1): loaded http://localhost:47620/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47620/tests/dromaeo/cssquery-yui.html) 15:55:54 INFO - PID 30865 | RSS: Main: 222175232 15:55:54 INFO - PID 30865 | 15:58:56 INFO - PID 30865 | Cycle 1(1): loaded http://localhost:47620/tests/dromaeo/cssquery-yui.html (next: http://localhost:47620/tests/dromaeo/cssquery-dojo.html) 15:58:56 INFO - PID 30865 | RSS: Main: 222334976 15:58:56 INFO - PID 30865 | 15:58:56 INFO - PID 30865 | __start_tp_report 15:58:56 INFO - PID 30865 | _x_x_mozilla_page_load 15:58:56 INFO - PID 30865 | _x_x_mozilla_page_load_details 15:58:56 INFO - PID 30865 | |i|pagename|runs| 15:58:56 INFO - PID 30865 | |0;dojo.html;3104.6740092290324;3109.797863138896;3120.329129237215;3128.155398042528;3128.906132816015;6012.969935150318;6263.091851681509;6265.467435268008;6308.5899416537895;6372.203474565679;6133.785317513888;6160.322364539908;6224.190855188819;6234.376562343763;6365;5744.741486633091;5842.328132264791;5844.123381492767;5844.620099693507;5870.148828419877;5657.66054036757;5719;5833.970830145843;5846.619969701977;5887.0286402743495;5693.800716974886;5770.047942089571;5779.595428320019;5786.826395208151;5884.6174998625165;5492.505674489315;5599.1041433370465;5611.7474713637575;5616.9608622404985;5624.437556244343;5529.391766905637;5532.889342213173;5566.304211973504;5629.549636029108;5648.519875810561;6177.66022868742;6200.689965501707;6231.3145553989025;6263.1544741459775;6265.655388953605;6597.340265973412;6612.603243805339;6626.602403855784;6711.56374835639;6718.160229971254;6444.516661250425;6585.242697089848;6661;6727.461803055744;6732.1921369435495;6139.048447490602;6142.170806941051;6188.47397971173;6207;6273.780417685359;6841.692123854438;6921.411680007256;6923.342282483156;6924.307569243036;6937.583744975318;6472.741090356427;6503.447206987365;6514;6607.174103237096;6612.2726500084955;6457.806265812033;6519.348065193538;6639.70121344531;6672.833179170462;6754.662266886636;6482.189726284215;6490.935090649127;6494.610323380518;6611.074449577063;6715.966420167868;6582.80251592453;6664.833379165462;6694.531382803157;6709.463242940651;6725.092112564839;5232.084385232569;5271;5278.683279003272;5279.498447647544;5284.920726189111;4651.62786977041;4675.345451636774;4694.460137084211;4697.13103075932;4716.056788642285;5715.085586306171;5715.599908006483;5798.739056742456;5799.536037117105;5802.042662960649;6514.05546195799;6614.669266536654;6678.098456708282;6692.330766923269;6785.1518560179975;5583.134614134734;5645.266115404971;5653.23681303027;5678.886422271614;5792.739326730307;5679.659220446786;5719.828405147852;5750.7987220447085;5751.942480575224;5755.366909639936;6464.41820236181;6500.967495162494;6525.934740652533;6527.412532872065;6578.440832529192;6435.09908612804;6609.768658096944;6613.008048792527;6674.098996635489;6801.421879140328;6305.211848518935;6355.713992870239;6371.171747672773;6380.521460890359;6397.424231819158;6457.4188323049275;6470.38531339522;6491.188601424822;6608.768693095719;6679.432248258758;6391.073294372383;6552.213734351718;6571.934280657323;6741.629210393476;6759.222689390783;6323.747050117944;6332.430081292706;6442.226932768098;6549.803505894831;6552.508561857784;6435.099086127852;6489.383508566679;6509.8698026040165;6527.184101987252;6529.575577587441;6401.807945761635;6424.2933277338525;6439.935600643934;6457;6468.15913931186;6486.902696459462;6497.057926600711;6574.671266436755;6604.933950660432;6629.668516574248;6022;6183.505319574513;6274.215723034621;6347.96826015867;6353.587016844075 15:58:56 INFO - PID 30865 | |1;ext.html;735.5072101691868;746.902902622659;752.5898385379966;755.7203834581205;760.4296777416937;36878;39661;40672.79663601685;40972.79513602435;41043.99999999996;37350.43974340387;37583.62416375835;37803;37822.432663510066;37880.24239515215;6899.068625735525;7088.964555177217;7652.387808975296;7795;7829.256220659028;7700.345470635003;7816.296533312001;7819.960900195491;7833.060032796073;7870.488418252824;7843.882341764878;7886.921130788704;7890.723824666138;7916;7927.128015918244;7774.650140743652;7792.72725454612;7800.14198438177;7801.492902961318;7842.058952925629;7669.616519174075;7671.117821450549;7724.111727151393;7724.343430808388;7725.304722574995;37210.627893721256;37328.25343493142;37360.62639373598;37553.812230938944;37635.623643763756;3602.657747513982;3628.1655219299446;3642.1987162824134;3652.433872749728;3659.963400365989;31207.687923120706;31329.373412531848;31468;31491;31494.212644683837;5114.2584325273;5235.947640523584;5289.629725919187;5303.124984377573;5324;188637;188795;190363;191221;191711.9999999986;42758.57241427546;43626.563734362884;47891.281630775564;48834.26748598774;48890;45008;49557;50133;50287;50774;14868.405263789327;14898.925505372621;14998.325075371635;15033.398664053315;15054.172020538976;12067.87932120685;12194.146409751233;12380.257184568774;12388.690282742824;12463.875361246453;13721.588352349445;13803.17180969145;13871.861281387259;13913.165210087427;13991.16053036821;5476.479734425223;5541.944580554143;5544.91682624761;5550.47270509301;5651.321841378978;997.8652881860855;1003.1072345612411;1008.495752123938;1025.5128813813378;1029.4132344563527;22511.88744056302;22947.196848110238;22979.195728149436;23053.077876884738;23139.537209256057;1099.8515200447998;1109.6560066379448;1111.9221654484109;1116.983245251322;1118.0608289037248;12099;12136.87863121375;12172.208806427554;12224.938875305566;12340.197887137307;213167;214657;214800;215038;215110;52172.21741673878;52543.73728131335;52776.47223527792;53070.73464632652;53784.46215537873;1410.4992727581766;1455.1560095144791;1466.296177834624;1472.3521650473758;1483.406637345049;21640.67538986885;22173.55652886901;22386.440338991655;22653.093876244766;22727;36728.81635591805;37030.44454333241;37077.81461092785;37095.62904370922;37171.81414092912;180865;181393;181467;183304;184528;28987.275318117216;29568.55647165251;29935.850320748257;32807.835960821;33324.66675333216;40563.797181013906;41688.3746743793;41746.37380439235;41965.58034419739;42078.579214207464;30734.385312294075;30886.76452941947;30973.225669358446;31074;31377.529337059506;30094.699053010067;30550;30617.693823061483;30780.384392312477;31267.53098703567;33733;35151;35193.12017199488;35252.118697032776;35680.64319356877;30031.54952675668;30474.695253047186;30595.388092238474;30648.540271896385;31289.68710312959;22870.199543015915;23407.41481462977;23468.64797028012;24873;24938 15:58:56 INFO - PID 30865 | |2;jquery.html;553.2613960362918;564.9858503985346;565.2849145830523;578.1587789765886;589.0751520113422;22179.66730499044;22853.31440056799;22905.31284061481;23075;23083.538329233405;23643.408914777137;23828.76171238287;24510.63234051486;24610.507789844236;25644.871775641095;6555.180602424709;6722.966385168092;6725.966370168142;6732;6780.627065511395;6660.56706314088;6716.832079198035;6742.561733487308;6746.156730408699;6774.864502709943;6206.534509911752;6481.513886458511;6484.902726459083;6546.11627430298;6677.1987361516685;6485.319041500646;6503.414692677635;6512.8276910156155;6517.348265173492;6543.1821022372205;5995.37048609894;6054.545909056838;6073.331933487313;6545.083688283644;6880.69036893341;31489.370212595848;32162.356752865046;32183.839080804446;32404.351912961843;32439.189020274684;2089.8746075235535;2106.536561956367;2109.4515425989352;2112.9788702112937;2120.8197303229244;20663.070161842752;20992.265270715452;21121.68317475239;21295.467613309636;21403.464913377135;2470.5923522618928;2537.860417677027;2544.096845619815;2554.0805310088354;2612.05965852293;120386;127177.36411317885;137809;145357.27321363325;145721;20865.80909004563;21061.578768424544;22164.778352216596;22218.66671999921;22533.43666408353;43436.78281608635;43518.34722479165;43678;43789.34315985263;43853.78073109614;10827.455547946227;10895.237333386587;11003.834942475869;11029.669109926715;11146.888531114586;9113.362064655543;9137.223336016514;9191;9230.815383692288;9360.719178424522;11625.302481851117;11852;11939.403029848647;11963;12091.516339346328;4311.051568654891;4340.739555626673;4385.079133381962;4398.208322501981;4450.548078603684;952.8284908716497;961.916272499835;963.5086106085806;974.152487336008;975.6146322202832;19639.214431422868;19643.31248406299;20069.698954515694;20283.087261073288;20325;1021.1320377678913;1024.3802499487851;1032.2052019944601;1040.3341861208833;1046.0376453662648;11053.778924421467;11066.833997490045;11080.501377438035;11209.766925638174;11278.379689117177;461056;465837;466958;467575;476526;107798;108398.45800771103;109000;109893.45053274842;111218;1138.1975707126621;1142.4744617476053;1157.6411312493153;1162.7035106047904;1167.9007284380752;17664.55838604045;17682.292708291523;17790.288388464316;17825.46523604294;18082;54892.17661734997;56296.71851640879;57674.13488797587;57944.71027644835;58063.4193658058;77397.61301193683;80515.59742201486;80976.59511702404;81135.59432203037;81609;14601.342939567958;15055.548333549574;15080.472183473526;15104.773428398363;15109.697806043598;23352;23822.761772382055;24036.51926961416;24063.639045413984;24293.757062429857;18683.813161868205;18791.436256912315;18870.3395381161;19055.14251858642;19215.231390744215;17022.914885425493;17062.658746825244;17067.829321707122;17319.56701082483;17419.47741567755;19257.13342899545;19435.902820485808;19587.51031224231;19655.901720491307;19702;14812.777808332668;14952.775708364166;14999.175045372172;15011.474598389004;15019.924900375428;18611.255549777856;18946.905265474135;18993;19202.423927282205;19330.4200873974 15:58:56 INFO - PID 30865 | |3;mootools.html;1335.8063080853276;1382.9930850345747;1387.2439520461348;1401.1873113594118;1406.4093080906018;14949.5515134546;15587.766183507243;15664.295106720196;15673.216339183053;15713.84286157138;15093.396264149449;15122.092674439515;15171;15230.466933657324;15238.542843714678;2744.0944488318805;2768.709285525016;2794.1338185162563;2799.5800629905475;2805.985970070157;2608.921732348023;2723.673159220896;2727.4136060746937;2730.0581299451655;2736.945261094777;2561.9359516012064;2637.3274814922265;2819.0415258812;2831.150654803561;2831.263871393442;2789.6512935883115;2811.1847564206355;2811.4096039831556;2812.325041989928;2813.2685501769583;2655.243255672124;2657.255968328871;2660.401409682806;2673.8529380884042;2680.490706765708;14849;15183.544493665207;15213.31540080688;15281.312340944683;15289.388424463008;7916.762497125095;8139.633716482773;8156.062052863937;8183.386246031571;8197.139300373434;5057.2666963290485;5163.457836927106;5203.115470370009;5266.894662106774;5310.176922577007;1836.4582448177819;1846.2891786662096;1864.1052294898525;1913.0434782608695;1913.0721600023978;25493.362665933313;25779.484410311878;27246;27311.9999999998;27565.58651620207;6812.591244525344;6843.965780171067;6918.204406493217;7066.505344625827;7077.398421134261;17109.572260693334;17148.22832972494;17153.399631012853;17186.74219886703;17219.052952087757;7901.091374492007;8011.479253848481;8013.4791238569305;8019.518828870287;8025.157358477392;7344.853102937911;7352.816179595553;7404.703811847466;7441.069866266716;7520;5097.388313402341;5149.45930677281;5171.63798534099;5281.392639846466;5294.788208471695;1408.7041721238654;1447.3993292783587;1470.9338079786435;1480.305050716923;1485.23745832751;902.5803001604286;914.4696076275744;917.4128557723062;939.3236869453982;945.1776954049883;8213.630386632616;8534.146585341416;8735.519546425008;8802.07578204292;8813.691520796741;970.1801977329145;978.496074521622;999.3853779925294;1011.2959662967776;1032.200044965143;5242.18746094356;5417.349918009864;5510.476504732044;5513.807016754395;5541.6120871539415;21460.78539214619;21885.452863678536;21991.230306939182;22030;22342.776572234394;20836.166553337698;21598.676019859715;21946.670799937696;21991.230306939502;22122.88938555329;9193;9336.533173341442;9946.850797237903;11469.770604587862;11697.824532632018;8476.533790641573;9093.454392736458;9162.816743665222;9200.171984521425;9217.400868943498;18542.907285463487;18688.53278668044;18765.343212988028;19084.427467176007;19305.420837374902;19086.236550537822;19312.103184522435;19624.90187549053;19944;20164;11937.164398492023;12183.208091474025;12381;12407.627771166879;12432.81350779721;14216.360263787947;14374.425022998963;14377.20925349074;14382.784258236345;14391.568252952427;11528;11563.826542601699;11683.18217724751;11722.648320550397;11729.589464369048;11114.444277785917;11137.164712646421;11419.200655954004;11522.423878806194;11745.941270293935;10231.386116833013;10324;10354.171666266831;10405.687829365132;10411.063004329344;7346.37555807741;7381.224971377928;7389.593572353572;7433.739819106306;7472.0286362772495;7077.752278670222;7287.198408174991;7376.889346659697;7499.737509187153;7593.392528597588 15:58:56 INFO - PID 30865 | |4;prototype.html;501.42596086119147;504.6089280807375;518.7924830067973;519.727143249794;529.7007190937121;5926.407359264077;6125.785597504088;6152.907706384408;6186.257649082111;6234.968825155874;6017.729202185901;6057.757689692418;6103.9318119329055;6114.449699527042;6159.23009623797;5079.339685840836;5116.923246151292;5123.513266239701;5131.820386286481;5139.717315547643;5137.61101087994;5141.871453213662;5176.922346164792;5196.142636464979;5205.9219111713355;4737.170995075865;4906.9263961040615;4923.138450771118;4931.679440836334;5073.162928116874;4866.489018653061;4912.312276281324;4947.356843610326;4963.875903102433;4969.006198760227;4339.240632889232;4370.606645401929;4400.405945197389;4411.933820992687;4443.244648409778;5852.707364631751;5913.704314784287;5971.313298970631;5994.130851026577;6087.695615219265;5458.863528411782;5472.91790623141;5579.218909352694;5633.8591535211535;5635.351934527541;6335.239771227436;6344.936550634481;6377.234731832164;6386;6410.102585638015;4557.225271703818;4787.54518320759;4797.808087676523;4815.22956326986;4830.951690483121;6522.93477065228;6597.63712995785;6675.46596272297;6688.896332105197;6741.426978706816;4893.755312234374;4903.705777653352;4906.337644418029;4935.679180853233;4938.678985865908;4896.828610998669;4938.308636790852;4951.653384263067;5005.249212618136;5033.496650335011;5440.863978400572;5476.397596264408;5633.69014704187;5635.943640563624;5679.403662615448;6516.152900122954;6524.380183882524;6572.638504882278;6581.466135537852;6603.76886808969;1741.2077504735314;1746.4847869878417;1753.9123043847756;1756.1833747307564;1760.74469201965;1132.4960392625203;1173.4836671864352;1190.3512585640674;1204.656672848234;1212.0424864357235;5623;5637.23897273871;5671.773129074791;5683.630564013326;5726.341470730836;6595.73617055322;6626.469882409396;6627.204735431779;6629.900551491732;6683.465322774166;5585.430701877182;5603.495685388335;5630.634008789416;5646.661200327993;5680.943190568041;3436.226848959014;3453.810040447749;3490.650934906489;3499.4051011328133;3537.2041290709385;6360.363963603602;6397.6481293529305;6456;6464.353564643592;6554.311797261219;6756.459483241329;6840.726370945106;6857.451403887677;6861.382475577222;6887.931120688729;6443.130177426081;6455.48356131499;6474.665547243174;6497.15536980199;6523.151990241238;6101.84745381369;6391.041343798467;6593.977933420328;6600.2409722882485;6613.900791488228;6382.744690212339;6401.8719625608155;6442.903356449563;6473.158489396348;6486;6242.28213755424;6261.874762504815;6324;6374.426301632875;6428.4857211423905;4932.975335123301;4942.752862356796;4964.205727083649;4974.651774375759;5001.274815151855;6301.936980630135;6502.512311576556;6514.348565143448;6605.702743376656;6636.83407914806;6225.221847269091;6385.329540398191;6394.71223794921;6403.2316122063785;6450.742779503874;6293.779717709858;6336.9049464259;6395.616263024233;6400.055974251896;6465.321141280097;6294.213223347082;6357.936420635734;6370.554061215856;6413.647249401328;6415.807525774234;6418.486521078209;6453.322401148;6457.5479716419395;6470.967645161744;6505.60966342021;6282.434580887742;6289.96855015722;6298.488362792805;6318.557700961072;6324.778632747832 15:58:56 INFO - PID 30865 | |5;yui.html;882.801369691819;893.9374243802934;916.720400277915;920.4339331311241;929.8837645294338;19890.10494527746;20870.478238044037;20898.477538061536;20993.68509472359;21015.789842101574;18631.53421164468;18918;19142.71285930712;19228.71156932647;19301.227950881985;948.5873644964445;964.0744884910495;968.5738275158943;973.060996138726;976.7362812040744;984.6110786239431;989.579428742785;989.6239429016963;991.3605724307818;993.8608594796734;993.4287784523891;1037.3101887245011;1044.5769463367349;1046.1682962045154;1046.7383154211448;1026.0867827633413;1034.2760067952427;1034.2811745836646;1035.59094157808;1035.7410647338165;716.616610113592;754.1553460124641;755.3881356101521;755.7619349904762;761.090496856257;20768.480787980272;20808.5838283235;20836;20905.163793448242;20941.5811683767;21659.566808663894;21717.131314747392;22157;22391.888040559857;22464.438389040242;17550.7367389488;17606.38377656776;17708.380206692706;17966.461006169706;18088.095595220188;3618.5115009473648;3639.1811842335264;3648;3648.0515066082744;3662.3957047087197;139206.3039684795;140387;140740;141769.2911535446;142348;15948.2823272953;16320;16366.754498682529;16432.424865129662;16453.42413015539;30533.541996870066;31038.22404439862;31613.683863161074;31907.840460798012;32205.51691724626;9205.447673139633;9319.440833550008;9332.626694932262;9378.577963991636;9390.812183756287;4333.5016473106;4348.565143485626;4368.039031413084;4410.514843367227;4474.239379305526;5391.32608423947;5474.206240095164;5499.175123731472;5500.339959204921;5507.0637991541535;1117.5920788912151;1157.2709193208393;1158.3976332307152;1166.2419427372106;1168.5442677355838;671.4091599392503;692.882210024297;694.1219357512649;717.3579646216712;722.0216606498225;11706.70733231676;12081.214721043105;12292.139550231397;13176.209427434193;13240.602781916557;683.2143035509204;687.0793137196181;708.7873637908607;712.3517598984898;716.5951237550767;5616.943830561724;5683.943160568341;5696.544276457957;5706.857328566736;5817.88364232713;121520;121877;122499.38750306191;122954;123230.38384808198;109227.45386273177;109340.29198391388;109753.45123274492;110338.44830775795;111257.44371278092;92767.53616231876;95723;96329.51835240779;97310.99999999859;97968;10311;10460;10650.147988161083;10698.251122421356;10845.403502807105;42042;42398;43609;43727.34408983932;44343.77828110839;55208.72395637996;55785.44214557965;56481;57368;59045.114323284324;14456.20490873012;14579.781303280248;14623.195724234844;14751.778723318945;14926.552203433914;15932;16034.839651603335;16059.19704014818;16067.839321607102;16113.758293625839;16623.418180363627;16758.245878935235;17030.403935862185;17112.57218569496;17438.564035899202;16269.186540672683;16440.095794731405;16546.089965052037;16552.75170872462;16680.833191667927;14720.558383248519;14768.040077394935;14778.704425911637;16594;16896.915515422344;13423.26172060546;13696.93151534236;13914.51299204523;14099;14171.929140354232;13851.099678521266;14058.297085145497;14104.647383815076;14159.221242831743;14217.64455888611 15:58:56 INFO - PID 30865 | __end_tp_report 15:58:56 INFO - PID 30865 | __start_cc_report 15:58:56 INFO - PID 30865 | _x_x_mozilla_cycle_collect,356 15:58:56 INFO - PID 30865 | __end_cc_report 15:58:56 INFO - PID 30865 | __startTimestamp1504825136701__endTimestamp 15:58:56 INFO - PID 30865 | 15:58:56 INFO - PID 30865 | ------- Summary: start ------- 15:58:56 INFO - PID 30865 | Number of tests: 6 15:58:56 INFO - PID 30865 | 15:58:56 INFO - PID 30865 | [#0] dojo.html Cycles:165 Average:6113.92 Median:6365.00 stddev:723.57 (11.4%) stddev-sans-first:686.20 15:58:56 INFO - PID 30865 | Values: 3104.7 3109.8 3120.3 3128.2 3128.9 6013.0 6263.1 6265.5 6308.6 6372.2 6133.8 6160.3 6224.2 6234.4 6365.0 5744.7 5842.3 5844.1 5844.6 5870.1 5657.7 5719.0 5834.0 5846.6 5887.0 5693.8 5770.0 5779.6 5786.8 5884.6 5492.5 5599.1 5611.7 5617.0 5624.4 5529.4 5532.9 5566.3 5629.5 5648.5 6177.7 6200.7 6231.3 6263.2 6265.7 6597.3 6612.6 6626.6 6711.6 6718.2 6444.5 6585.2 6661.0 6727.5 6732.2 6139.0 6142.2 6188.5 6207.0 6273.8 6841.7 6921.4 6923.3 6924.3 6937.6 6472.7 6503.4 6514.0 6607.2 6612.3 6457.8 6519.3 6639.7 6672.8 6754.7 6482.2 6490.9 6494.6 6611.1 6716.0 6582.8 6664.8 6694.5 6709.5 6725.1 5232.1 5271.0 5278.7 5279.5 5284.9 4651.6 4675.3 4694.5 4697.1 4716.1 5715.1 5715.6 5798.7 5799.5 5802.0 6514.1 6614.7 6678.1 6692.3 6785.2 5583.1 5645.3 5653.2 5678.9 5792.7 5679.7 5719.8 5750.8 5751.9 5755.4 6464.4 6501.0 6525.9 6527.4 6578.4 6435.1 6609.8 6613.0 6674.1 6801.4 6305.2 6355.7 6371.2 6380.5 6397.4 6457.4 6470.4 6491.2 6608.8 6679.4 6391.1 6552.2 6571.9 6741.6 6759.2 6323.7 6332.4 6442.2 6549.8 6552.5 6435.1 6489.4 6509.9 6527.2 6529.6 6401.8 6424.3 6439.9 6457.0 6468.2 6486.9 6497.1 6574.7 6604.9 6629.7 6022.0 6183.5 6274.2 6348.0 6353.6 15:58:56 INFO - PID 30865 | 15:58:56 INFO - PID 30865 | [#1] ext.html Cycles:180 Average:36070.29 Median:35172.06 stddev:50742.76 (144.3%) stddev-sans-first:50815.74 15:58:56 INFO - PID 30865 | Values: 735.5 746.9 752.6 755.7 760.4 36878.0 39661.0 40672.8 40972.8 41044.0 37350.4 37583.6 37803.0 37822.4 37880.2 6899.1 7089.0 7652.4 7795.0 7829.3 7700.3 7816.3 7820.0 7833.1 7870.5 7843.9 7886.9 7890.7 7916.0 7927.1 7774.7 7792.7 7800.1 7801.5 7842.1 7669.6 7671.1 7724.1 7724.3 7725.3 37210.6 37328.3 37360.6 37553.8 37635.6 3602.7 3628.2 3642.2 3652.4 3660.0 31207.7 31329.4 31468.0 31491.0 31494.2 5114.3 5235.9 5289.6 5303.1 5324.0 188637.0 188795.0 190363.0 191221.0 191712.0 42758.6 43626.6 47891.3 48834.3 48890.0 45008.0 49557.0 50133.0 50287.0 50774.0 14868.4 14898.9 14998.3 15033.4 15054.2 12067.9 12194.1 12380.3 12388.7 12463.9 13721.6 13803.2 13871.9 13913.2 13991.2 5476.5 5541.9 5544.9 5550.5 5651.3 997.9 1003.1 1008.5 1025.5 1029.4 22511.9 22947.2 22979.2 23053.1 23139.5 1099.9 1109.7 1111.9 1117.0 1118.1 12099.0 12136.9 12172.2 12224.9 12340.2 213167.0 214657.0 214800.0 215038.0 215110.0 52172.2 52543.7 52776.5 53070.7 53784.5 1410.5 1455.2 1466.3 1472.4 1483.4 21640.7 22173.6 22386.4 22653.1 22727.0 36728.8 37030.4 37077.8 37095.6 37171.8 180865.0 181393.0 181467.0 183304.0 184528.0 28987.3 29568.6 29935.9 32807.8 33324.7 40563.8 41688.4 41746.4 41965.6 42078.6 30734.4 30886.8 30973.2 31074.0 31377.5 30094.7 30550.0 30617.7 30780.4 31267.5 33733.0 35151.0 35193.1 35252.1 35680.6 30031.5 30474.7 30595.4 30648.5 31289.7 22870.2 23407.4 23468.6 24873.0 24938.0 15:58:56 INFO - PID 30865 | 15:58:56 INFO - PID 30865 | [#2] jquery.html Cycles:180 Average:35288.31 Median:22693.38 stddev:78873.44 (347.6%) stddev-sans-first:79051.59 15:58:56 INFO - PID 30865 | Values: 553.3 565.0 565.3 578.2 589.1 22179.7 22853.3 22905.3 23075.0 23083.5 23643.4 23828.8 24510.6 24610.5 25644.9 6555.2 6723.0 6726.0 6732.0 6780.6 6660.6 6716.8 6742.6 6746.2 6774.9 6206.5 6481.5 6484.9 6546.1 6677.2 6485.3 6503.4 6512.8 6517.3 6543.2 5995.4 6054.5 6073.3 6545.1 6880.7 31489.4 32162.4 32183.8 32404.4 32439.2 2089.9 2106.5 2109.5 2113.0 2120.8 20663.1 20992.3 21121.7 21295.5 21403.5 2470.6 2537.9 2544.1 2554.1 2612.1 120386.0 127177.4 137809.0 145357.3 145721.0 20865.8 21061.6 22164.8 22218.7 22533.4 43436.8 43518.3 43678.0 43789.3 43853.8 10827.5 10895.2 11003.8 11029.7 11146.9 9113.4 9137.2 9191.0 9230.8 9360.7 11625.3 11852.0 11939.4 11963.0 12091.5 4311.1 4340.7 4385.1 4398.2 4450.5 952.8 961.9 963.5 974.2 975.6 19639.2 19643.3 20069.7 20283.1 20325.0 1021.1 1024.4 1032.2 1040.3 1046.0 11053.8 11066.8 11080.5 11209.8 11278.4 461056.0 465837.0 466958.0 467575.0 476526.0 107798.0 108398.5 109000.0 109893.5 111218.0 1138.2 1142.5 1157.6 1162.7 1167.9 17664.6 17682.3 17790.3 17825.5 18082.0 54892.2 56296.7 57674.1 57944.7 58063.4 77397.6 80515.6 80976.6 81135.6 81609.0 14601.3 15055.5 15080.5 15104.8 15109.7 23352.0 23822.8 24036.5 24063.6 24293.8 18683.8 18791.4 18870.3 19055.1 19215.2 17022.9 17062.7 17067.8 17319.6 17419.5 19257.1 19435.9 19587.5 19655.9 19702.0 14812.8 14952.8 14999.2 15011.5 15019.9 18611.3 18946.9 18993.0 19202.4 19330.4 15:58:56 INFO - PID 30865 | 15:58:56 INFO - PID 30865 | [#3] mootools.html Cycles:180 Average:9478.71 Median:2658.83 stddev:6754.85 (254.1%) stddev-sans-first:6746.10 15:58:56 INFO - PID 30865 | Values: 1335.8 1383.0 1387.2 1401.2 1406.4 14949.6 15587.8 15664.3 15673.2 15713.8 15093.4 15122.1 15171.0 15230.5 15238.5 2744.1 2768.7 2794.1 2799.6 2806.0 2608.9 2723.7 2727.4 2730.1 2736.9 2561.9 2637.3 2819.0 2831.2 2831.3 2789.7 2811.2 2811.4 2812.3 2813.3 2655.2 2657.3 2660.4 2673.9 2680.5 14849.0 15183.5 15213.3 15281.3 15289.4 7916.8 8139.6 8156.1 8183.4 8197.1 5057.3 5163.5 5203.1 5266.9 5310.2 1836.5 1846.3 1864.1 1913.0 1913.1 25493.4 25779.5 27246.0 27312.0 27565.6 6812.6 6844.0 6918.2 7066.5 7077.4 17109.6 17148.2 17153.4 17186.7 17219.1 7901.1 8011.5 8013.5 8019.5 8025.2 7344.9 7352.8 7404.7 7441.1 7520.0 5097.4 5149.5 5171.6 5281.4 5294.8 1408.7 1447.4 1470.9 1480.3 1485.2 902.6 914.5 917.4 939.3 945.2 8213.6 8534.1 8735.5 8802.1 8813.7 970.2 978.5 999.4 1011.3 1032.2 5242.2 5417.3 5510.5 5513.8 5541.6 21460.8 21885.5 21991.2 22030.0 22342.8 20836.2 21598.7 21946.7 21991.2 22122.9 9193.0 9336.5 9946.9 11469.8 11697.8 8476.5 9093.5 9162.8 9200.2 9217.4 18542.9 18688.5 18765.3 19084.4 19305.4 19086.2 19312.1 19624.9 19944.0 20164.0 11937.2 12183.2 12381.0 12407.6 12432.8 14216.4 14374.4 14377.2 14382.8 14391.6 11528.0 11563.8 11683.2 11722.6 11729.6 11114.4 11137.2 11419.2 11522.4 11745.9 10231.4 10324.0 10354.2 10405.7 10411.1 7346.4 7381.2 7389.6 7433.7 7472.0 7077.8 7287.2 7376.9 7499.7 7593.4 15:58:56 INFO - PID 30865 | 15:58:56 INFO - PID 30865 | [#4] prototype.html Cycles:180 Average:5424.04 Median:6037.74 stddev:1510.37 (25.0%) stddev-sans-first:1468.72 15:58:56 INFO - PID 30865 | Values: 501.4 504.6 518.8 519.7 529.7 5926.4 6125.8 6152.9 6186.3 6235.0 6017.7 6057.8 6103.9 6114.4 6159.2 5079.3 5116.9 5123.5 5131.8 5139.7 5137.6 5141.9 5176.9 5196.1 5205.9 4737.2 4906.9 4923.1 4931.7 5073.2 4866.5 4912.3 4947.4 4963.9 4969.0 4339.2 4370.6 4400.4 4411.9 4443.2 5852.7 5913.7 5971.3 5994.1 6087.7 5458.9 5472.9 5579.2 5633.9 5635.4 6335.2 6344.9 6377.2 6386.0 6410.1 4557.2 4787.5 4797.8 4815.2 4831.0 6522.9 6597.6 6675.5 6688.9 6741.4 4893.8 4903.7 4906.3 4935.7 4938.7 4896.8 4938.3 4951.7 5005.2 5033.5 5440.9 5476.4 5633.7 5635.9 5679.4 6516.2 6524.4 6572.6 6581.5 6603.8 1741.2 1746.5 1753.9 1756.2 1760.7 1132.5 1173.5 1190.4 1204.7 1212.0 5623.0 5637.2 5671.8 5683.6 5726.3 6595.7 6626.5 6627.2 6629.9 6683.5 5585.4 5603.5 5630.6 5646.7 5680.9 3436.2 3453.8 3490.7 3499.4 3537.2 6360.4 6397.6 6456.0 6464.4 6554.3 6756.5 6840.7 6857.5 6861.4 6887.9 6443.1 6455.5 6474.7 6497.2 6523.2 6101.8 6391.0 6594.0 6600.2 6613.9 6382.7 6401.9 6442.9 6473.2 6486.0 6242.3 6261.9 6324.0 6374.4 6428.5 4933.0 4942.8 4964.2 4974.7 5001.3 6301.9 6502.5 6514.3 6605.7 6636.8 6225.2 6385.3 6394.7 6403.2 6450.7 6293.8 6336.9 6395.6 6400.1 6465.3 6294.2 6357.9 6370.6 6413.6 6415.8 6418.5 6453.3 6457.5 6471.0 6505.6 6282.4 6290.0 6298.5 6318.6 6324.8 15:58:56 INFO - PID 30865 | 15:58:56 INFO - PID 30865 | [#5] yui.html Cycles:180 Average:24586.65 Median:20788.53 stddev:35415.55 (170.4%) stddev-sans-first:35470.17 15:58:56 INFO - PID 30865 | Values: 882.8 893.9 916.7 920.4 929.9 19890.1 20870.5 20898.5 20993.7 21015.8 18631.5 18918.0 19142.7 19228.7 19301.2 948.6 964.1 968.6 973.1 976.7 984.6 989.6 989.6 991.4 993.9 993.4 1037.3 1044.6 1046.2 1046.7 1026.1 1034.3 1034.3 1035.6 1035.7 716.6 754.2 755.4 755.8 761.1 20768.5 20808.6 20836.0 20905.2 20941.6 21659.6 21717.1 22157.0 22391.9 22464.4 17550.7 17606.4 17708.4 17966.5 18088.1 3618.5 3639.2 3648.0 3648.1 3662.4 139206.3 140387.0 140740.0 141769.3 142348.0 15948.3 16320.0 16366.8 16432.4 16453.4 30533.5 31038.2 31613.7 31907.8 32205.5 9205.4 9319.4 9332.6 9378.6 9390.8 4333.5 4348.6 4368.0 4410.5 4474.2 5391.3 5474.2 5499.2 5500.3 5507.1 1117.6 1157.3 1158.4 1166.2 1168.5 671.4 692.9 694.1 717.4 722.0 11706.7 12081.2 12292.1 13176.2 13240.6 683.2 687.1 708.8 712.4 716.6 5616.9 5683.9 5696.5 5706.9 5817.9 121520.0 121877.0 122499.4 122954.0 123230.4 109227.5 109340.3 109753.5 110338.4 111257.4 92767.5 95723.0 96329.5 97311.0 97968.0 10311.0 10460.0 10650.1 10698.3 10845.4 42042.0 42398.0 43609.0 43727.3 44343.8 55208.7 55785.4 56481.0 57368.0 59045.1 14456.2 14579.8 14623.2 14751.8 14926.6 15932.0 16034.8 16059.2 16067.8 16113.8 16623.4 16758.2 17030.4 17112.6 17438.6 16269.2 16440.1 16546.1 16552.8 16680.8 14720.6 14768.0 14778.7 16594.0 16896.9 13423.3 13696.9 13914.5 14099.0 14171.9 13851.1 14058.3 14104.6 14159.2 14217.6 15:58:56 INFO - PID 30865 | -------- Summary: end -------- 15:58:56 INFO - PID 30865 | 15:58:57 INFO - TEST-INFO | 30865: exit 0 15:58:57 INFO - TEST-OK | dromaeo_css | took 1112646ms 15:58:57 INFO - TEST-START | kraken 15:58:57 INFO - Initialising browser for kraken test... 15:58:57 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:47620/getInfo.html -profile /tmp/tmpQEgZKA/profile 15:58:57 INFO - TEST-INFO | started process 31262 (/builds/slave/test/build/application/firefox/firefox http://localhost:47620/getInfo.html) 15:59:04 INFO - TEST-INFO | 31262: exit 0 15:59:04 INFO - Browser initialized. 15:59:04 INFO - Running cycle 1/1 for kraken test... 15:59:04 INFO - Using env: {'DISPLAY': ':0', 15:59:04 INFO - 'HOME': '/home/cltbld', 15:59:04 INFO - 'JSGC_DISABLE_POISONING': '1', 15:59:04 INFO - 'LANG': 'en_US.UTF-8', 15:59:04 INFO - 'LANGUAGE': 'en_US:en', 15:59:04 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 15:59:04 INFO - 'LOGNAME': 'cltbld', 15:59:04 INFO - 'MAIL': '/var/mail/cltbld', 15:59:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:59:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:59:04 INFO - 'MOZ_ACCELERATED': '1', 15:59:04 INFO - 'MOZ_CRASHREPORTER': '1', 15:59:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:59:04 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 15:59:04 INFO - 'MOZ_NO_REMOTE': '1', 15:59:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:59:04 INFO - 'MOZ_WEBRENDER': '1', 15:59:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:59:04 INFO - 'NO_EM_RESTART': '1', 15:59:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:59:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:59:04 INFO - 'PWD': '/builds/slave/test', 15:59:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:59:04 INFO - 'RUST_BACKTRACE': 'full', 15:59:04 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:59:04 INFO - 'SHELL': '/bin/bash', 15:59:04 INFO - 'SHLVL': '1', 15:59:04 INFO - 'TERM': 'linux', 15:59:04 INFO - 'TMOUT': '86400', 15:59:04 INFO - 'USER': 'cltbld', 15:59:04 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1504823906.590082-885688012', 15:59:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:59:04 INFO - '_': '/tools/buildbot/bin/python'} 15:59:04 INFO - TEST-INFO | started process 31468 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQEgZKA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 15:59:05 INFO - PID 31468 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 15:59:05 INFO - PID 31468 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 15:59:05 INFO - PID 31468 | 15:59:05 INFO - PID 31468 | (/builds/slave/test/build/application/firefox/firefox:31529): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 15:59:05 INFO - PID 31468 | 15:59:15 INFO - PID 31468 | RSS: Main: 209162240 15:59:15 INFO - PID 31468 | 15:59:18 INFO - PID 31468 | Cycle 1(1): loaded http://localhost:47620/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47620/tests/kraken/driver.html?testName=audio-beat-detection) 15:59:19 INFO - PID 31468 | RSS: Main: 215846912 15:59:19 INFO - PID 31468 | 15:59:22 INFO - PID 31468 | Cycle 1(1): loaded http://localhost:47620/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47620/tests/kraken/driver.html?testName=audio-dft) 15:59:22 INFO - PID 31468 | RSS: Main: 216244224 15:59:22 INFO - PID 31468 | 15:59:25 INFO - PID 31468 | Cycle 1(1): loaded http://localhost:47620/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47620/tests/kraken/driver.html?testName=audio-fft) 15:59:26 INFO - PID 31468 | RSS: Main: 211574784 15:59:26 INFO - PID 31468 | 15:59:28 INFO - PID 31468 | Cycle 1(1): loaded http://localhost:47620/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47620/tests/kraken/driver.html?testName=audio-oscillator) 15:59:29 INFO - PID 31468 | RSS: Main: 211595264 15:59:29 INFO - PID 31468 | 15:59:31 INFO - PID 31468 | Cycle 1(1): loaded http://localhost:47620/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47620/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:59:32 INFO - PID 31468 | RSS: Main: 211853312 15:59:32 INFO - PID 31468 | 15:59:35 INFO - PID 31468 | Cycle 1(1): loaded http://localhost:47620/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47620/tests/kraken/driver.html?testName=imaging-darkroom) 15:59:36 INFO - PID 31468 | RSS: Main: 214503424 15:59:36 INFO - PID 31468 | 15:59:39 INFO - PID 31468 | Cycle 1(1): loaded http://localhost:47620/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47620/tests/kraken/driver.html?testName=imaging-desaturate) 15:59:40 INFO - PID 31468 | RSS: Main: 213872640 15:59:40 INFO - PID 31468 | 15:59:43 INFO - PID 31468 | Cycle 1(1): loaded http://localhost:47620/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47620/tests/kraken/driver.html?testName=json-parse-financial) 15:59:43 INFO - PID 31468 | RSS: Main: 215420928 15:59:43 INFO - PID 31468 | 15:59:45 INFO - PID 31468 | Cycle 1(1): loaded http://localhost:47620/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47620/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:59:46 INFO - PID 31468 | RSS: Main: 215793664 15:59:46 INFO - PID 31468 | 15:59:47 INFO - PID 31468 | Cycle 1(1): loaded http://localhost:47620/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47620/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:59:48 INFO - PID 31468 | RSS: Main: 215642112 15:59:48 INFO - PID 31468 | 15:59:50 INFO - PID 31468 | Cycle 1(1): loaded http://localhost:47620/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47620/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:59:51 INFO - PID 31468 | RSS: Main: 215179264 15:59:51 INFO - PID 31468 | 15:59:54 INFO - PID 31468 | Cycle 1(1): loaded http://localhost:47620/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47620/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:59:54 INFO - PID 31468 | RSS: Main: 216195072 15:59:54 INFO - PID 31468 | 15:59:57 INFO - PID 31468 | Cycle 1(1): loaded http://localhost:47620/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47620/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:59:58 INFO - PID 31468 | RSS: Main: 215539712 15:59:58 INFO - PID 31468 | 16:00:00 INFO - PID 31468 | Cycle 1(1): loaded http://localhost:47620/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47620/tests/kraken/driver.html?testName=ai-astar) 16:00:00 INFO - PID 31468 | RSS: Main: 218775552 16:00:00 INFO - PID 31468 | 16:00:00 INFO - PID 31468 | __start_tp_report 16:00:00 INFO - PID 31468 | _x_x_mozilla_page_load 16:00:00 INFO - PID 31468 | _x_x_mozilla_page_load_details 16:00:00 INFO - PID 31468 | |i|pagename|runs| 16:00:00 INFO - PID 31468 | |0;ai-astar;109;116;112;110;112;108;108;97;112;115 16:00:00 INFO - PID 31468 | |1;audio-beat-detection;125;122;131;117;123;125;116;127;116;116 16:00:00 INFO - PID 31468 | |2;audio-dft;165;167;175;170;176;168;164;163;165;162 16:00:00 INFO - PID 31468 | |3;audio-fft;81;91;82;79;75;78;76;74;77;76 16:00:00 INFO - PID 31468 | |4;audio-oscillator;79;89;85;83;81;79;85;90;83;80 16:00:00 INFO - PID 31468 | |5;imaging-gaussian-blur;111;116;114;114;124;120;115;126;115;124 16:00:00 INFO - PID 31468 | |6;imaging-darkroom;156;149;154;148;155;152;149;153;148;155 16:00:00 INFO - PID 31468 | |7;imaging-desaturate;94;107;102;94;94;99;94;95;95;95 16:00:00 INFO - PID 31468 | |8;json-parse-financial;54;53;52;52;53;51;53;57;57;52 16:00:00 INFO - PID 31468 | |9;json-stringify-tinderbox;39;42;39;41;38;46;38;39;39;47 16:00:00 INFO - PID 31468 | |10;stanford-crypto-aes;83;78;80;79;78;76;81;76;84;75 16:00:00 INFO - PID 31468 | |11;stanford-crypto-ccm;120;134;120;128;125;127;132;122;130;123 16:00:00 INFO - PID 31468 | |12;stanford-crypto-pbkdf2;172;173;176;169;177;171;182;169;171;179 16:00:00 INFO - PID 31468 | |13;stanford-crypto-sha256-iterative;69;67;64;66;72;66;66;64;65;67 16:00:00 INFO - PID 31468 | __end_tp_report 16:00:00 INFO - PID 31468 | __start_cc_report 16:00:00 INFO - PID 31468 | _x_x_mozilla_cycle_collect,957 16:00:00 INFO - PID 31468 | __end_cc_report 16:00:00 INFO - PID 31468 | __startTimestamp1504825200517__endTimestamp 16:00:00 INFO - PID 31468 | 16:00:00 INFO - PID 31468 | ------- Summary: start ------- 16:00:00 INFO - PID 31468 | Number of tests: 14 16:00:00 INFO - PID 31468 | 16:00:00 INFO - PID 31468 | [#0] ai-astar Cycles:10 Average:109.90 Median:112.00 stddev:5.28 (4.7%) stddev-sans-first:5.59 16:00:00 INFO - PID 31468 | Values: 109.0 116.0 112.0 110.0 112.0 108.0 108.0 97.0 112.0 115.0 16:00:00 INFO - PID 31468 | 16:00:00 INFO - PID 31468 | [#1] audio-beat-detection Cycles:10 Average:121.80 Median:124.00 stddev:5.35 (4.3%) stddev-sans-first:5.55 16:00:00 INFO - PID 31468 | Values: 125.0 122.0 131.0 117.0 123.0 125.0 116.0 127.0 116.0 116.0 16:00:00 INFO - PID 31468 | 16:00:00 INFO - PID 31468 | [#2] audio-dft Cycles:10 Average:167.50 Median:167.50 stddev:4.84 (2.9%) stddev-sans-first:5.04 16:00:00 INFO - PID 31468 | Values: 165.0 167.0 175.0 170.0 176.0 168.0 164.0 163.0 165.0 162.0 16:00:00 INFO - PID 31468 | 16:00:00 INFO - PID 31468 | [#3] audio-fft Cycles:10 Average:78.90 Median:78.50 stddev:4.95 (6.3%) stddev-sans-first:5.20 16:00:00 INFO - PID 31468 | Values: 81.0 91.0 82.0 79.0 75.0 78.0 76.0 74.0 77.0 76.0 16:00:00 INFO - PID 31468 | 16:00:00 INFO - PID 31468 | [#4] audio-oscillator Cycles:10 Average:83.40 Median:84.00 stddev:3.89 (4.6%) stddev-sans-first:3.79 16:00:00 INFO - PID 31468 | Values: 79.0 89.0 85.0 83.0 81.0 79.0 85.0 90.0 83.0 80.0 16:00:00 INFO - PID 31468 | 16:00:00 INFO - PID 31468 | [#5] imaging-gaussian-blur Cycles:10 Average:117.90 Median:118.00 stddev:5.20 (4.4%) stddev-sans-first:4.87 16:00:00 INFO - PID 31468 | Values: 111.0 116.0 114.0 114.0 124.0 120.0 115.0 126.0 115.0 124.0 16:00:00 INFO - PID 31468 | 16:00:00 INFO - PID 31468 | [#6] imaging-darkroom Cycles:10 Average:151.90 Median:153.50 stddev:3.14 (2.0%) stddev-sans-first:2.96 16:00:00 INFO - PID 31468 | Values: 156.0 149.0 154.0 148.0 155.0 152.0 149.0 153.0 148.0 155.0 16:00:00 INFO - PID 31468 | 16:00:00 INFO - PID 31468 | [#7] imaging-desaturate Cycles:10 Average:96.90 Median:94.50 stddev:4.43 (4.7%) stddev-sans-first:4.58 16:00:00 INFO - PID 31468 | Values: 94.0 107.0 102.0 94.0 94.0 99.0 94.0 95.0 95.0 95.0 16:00:00 INFO - PID 31468 | 16:00:00 INFO - PID 31468 | [#8] json-parse-financial Cycles:10 Average:53.40 Median:53.00 stddev:2.07 (3.9%) stddev-sans-first:2.18 16:00:00 INFO - PID 31468 | Values: 54.0 53.0 52.0 52.0 53.0 51.0 53.0 57.0 57.0 52.0 16:00:00 INFO - PID 31468 | 16:00:00 INFO - PID 31468 | [#9] json-stringify-tinderbox Cycles:10 Average:40.80 Median:40.00 stddev:3.26 (8.1%) stddev-sans-first:3.39 16:00:00 INFO - PID 31468 | Values: 39.0 42.0 39.0 41.0 38.0 46.0 38.0 39.0 39.0 47.0 16:00:00 INFO - PID 31468 | 16:00:00 INFO - PID 31468 | [#10] stanford-crypto-aes Cycles:10 Average:79.00 Median:79.50 stddev:3.02 (3.8%) stddev-sans-first:2.83 16:00:00 INFO - PID 31468 | Values: 83.0 78.0 80.0 79.0 78.0 76.0 81.0 76.0 84.0 75.0 16:00:00 INFO - PID 31468 | 16:00:00 INFO - PID 31468 | [#11] stanford-crypto-ccm Cycles:10 Average:126.10 Median:127.50 stddev:4.93 (3.9%) stddev-sans-first:4.71 16:00:00 INFO - PID 31468 | Values: 120.0 134.0 120.0 128.0 125.0 127.0 132.0 122.0 130.0 123.0 16:00:00 INFO - PID 31468 | 16:00:00 INFO - PID 31468 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.90 Median:174.50 stddev:4.41 (2.5%) stddev-sans-first:4.62 16:00:00 INFO - PID 31468 | Values: 172.0 173.0 176.0 169.0 177.0 171.0 182.0 169.0 171.0 179.0 16:00:00 INFO - PID 31468 | 16:00:00 INFO - PID 31468 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.60 Median:66.50 stddev:2.41 (3.6%) stddev-sans-first:2.40 16:00:00 INFO - PID 31468 | Values: 69.0 67.0 64.0 66.0 72.0 66.0 66.0 64.0 65.0 67.0 16:00:00 INFO - PID 31468 | -------- Summary: end -------- 16:00:00 INFO - PID 31468 | 16:00:01 INFO - PID 31468 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 16:00:01 INFO - TEST-INFO | 31468: exit 0 16:00:01 INFO - TEST-OK | kraken | took 63872ms 16:00:01 INFO - SUITE-END | took 1176s 16:00:01 INFO - Completed test suite (00:19:36) 16:00:01 INFO - javascript benchmark 16:00:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8574.84814836105, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3104.6740092290324, 3109.797863138896, 3120.329129237215, 3128.155398042528, 3128.906132816015, 6012.969935150318, 6263.091851681509, 6265.467435268008, 6308.5899416537895, 6372.203474565679, 6133.785317513888, 6160.322364539908, 6224.190855188819, 6234.376562343763, 6365.0, 5744.741486633091, 5842.328132264791, 5844.123381492767, 5844.620099693507, 5870.148828419877, 5657.66054036757, 5719.0, 5833.970830145843, 5846.619969701977, 5887.0286402743495, 5693.800716974886, 5770.047942089571, 5779.595428320019, 5786.826395208151, 5884.6174998625165, 5492.505674489315, 5599.1041433370465, 5611.7474713637575, 5616.9608622404985, 5624.437556244343, 5529.391766905637, 5532.889342213173, 5566.304211973504, 5629.549636029108, 5648.519875810561, 6177.66022868742, 6200.689965501707, 6231.3145553989025, 6263.1544741459775, 6265.655388953605, 6597.340265973412, 6612.603243805339, 6626.602403855784, 6711.56374835639, 6718.160229971254, 6444.516661250425, 6585.242697089848, 6661.0, 6727.461803055744, 6732.1921369435495, 6139.048447490602, 6142.170806941051, 6188.47397971173, 6207.0, 6273.780417685359, 6841.692123854438, 6921.411680007256, 6923.342282483156, 6924.307569243036, 6937.583744975318, 6472.741090356427, 6503.447206987365, 6514.0, 6607.174103237096, 6612.2726500084955, 6457.806265812033, 6519.348065193538, 6639.70121344531, 6672.833179170462, 6754.662266886636, 6482.189726284215, 6490.935090649127, 6494.610323380518, 6611.074449577063, 6715.966420167868, 6582.80251592453, 6664.833379165462, 6694.531382803157, 6709.463242940651, 6725.092112564839, 5232.084385232569, 5271.0, 5278.683279003272, 5279.498447647544, 5284.920726189111, 4651.62786977041, 4675.345451636774, 4694.460137084211, 4697.13103075932, 4716.056788642285, 5715.085586306171, 5715.599908006483, 5798.739056742456, 5799.536037117105, 5802.042662960649, 6514.05546195799, 6614.669266536654, 6678.098456708282, 6692.330766923269, 6785.1518560179975, 5583.134614134734, 5645.266115404971, 5653.23681303027, 5678.886422271614, 5792.739326730307, 5679.659220446786, 5719.828405147852, 5750.7987220447085, 5751.942480575224, 5755.366909639936, 6464.41820236181, 6500.967495162494, 6525.934740652533, 6527.412532872065, 6578.440832529192, 6435.09908612804, 6609.768658096944, 6613.008048792527, 6674.098996635489, 6801.421879140328, 6305.211848518935, 6355.713992870239, 6371.171747672773, 6380.521460890359, 6397.424231819158, 6457.4188323049275, 6470.38531339522, 6491.188601424822, 6608.768693095719, 6679.432248258758, 6391.073294372383, 6552.213734351718, 6571.934280657323, 6741.629210393476, 6759.222689390783, 6323.747050117944, 6332.430081292706, 6442.226932768098, 6549.803505894831, 6552.508561857784, 6435.099086127852, 6489.383508566679, 6509.8698026040165, 6527.184101987252, 6529.575577587441, 6401.807945761635, 6424.2933277338525, 6439.935600643934, 6457.0, 6468.15913931186, 6486.902696459462, 6497.057926600711, 6574.671266436755, 6604.933950660432, 6629.668516574248, 6022.0, 6183.505319574513, 6274.215723034621, 6347.96826015867, 6353.587016844075], "value": 6059.5395989921935, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [735.5072101691868, 746.902902622659, 752.5898385379966, 755.7203834581205, 760.4296777416937, 36878.0, 39661.0, 40672.79663601685, 40972.79513602435, 41043.99999999996, 37350.43974340387, 37583.62416375835, 37803.0, 37822.432663510066, 37880.24239515215, 6899.068625735525, 7088.964555177217, 7652.387808975296, 7795.0, 7829.256220659028, 7700.345470635003, 7816.296533312001, 7819.960900195491, 7833.060032796073, 7870.488418252824, 7843.882341764878, 7886.921130788704, 7890.723824666138, 7916.0, 7927.128015918244, 7774.650140743652, 7792.72725454612, 7800.14198438177, 7801.492902961318, 7842.058952925629, 7669.616519174075, 7671.117821450549, 7724.111727151393, 7724.343430808388, 7725.304722574995, 37210.627893721256, 37328.25343493142, 37360.62639373598, 37553.812230938944, 37635.623643763756, 3602.657747513982, 3628.1655219299446, 3642.1987162824134, 3652.433872749728, 3659.963400365989, 31207.687923120706, 31329.373412531848, 31468.0, 31491.0, 31494.212644683837, 5114.2584325273, 5235.947640523584, 5289.629725919187, 5303.124984377573, 5324.0, 188637.0, 188795.0, 190363.0, 191221.0, 191711.9999999986, 42758.57241427546, 43626.563734362884, 47891.281630775564, 48834.26748598774, 48890.0, 45008.0, 49557.0, 50133.0, 50287.0, 50774.0, 14868.405263789327, 14898.925505372621, 14998.325075371635, 15033.398664053315, 15054.172020538976, 12067.87932120685, 12194.146409751233, 12380.257184568774, 12388.690282742824, 12463.875361246453, 13721.588352349445, 13803.17180969145, 13871.861281387259, 13913.165210087427, 13991.16053036821, 5476.479734425223, 5541.944580554143, 5544.91682624761, 5550.47270509301, 5651.321841378978, 997.8652881860855, 1003.1072345612411, 1008.495752123938, 1025.5128813813378, 1029.4132344563527, 22511.88744056302, 22947.196848110238, 22979.195728149436, 23053.077876884738, 23139.537209256057, 1099.8515200447998, 1109.6560066379448, 1111.9221654484109, 1116.983245251322, 1118.0608289037248, 12099.0, 12136.87863121375, 12172.208806427554, 12224.938875305566, 12340.197887137307, 213167.0, 214657.0, 214800.0, 215038.0, 215110.0, 52172.21741673878, 52543.73728131335, 52776.47223527792, 53070.73464632652, 53784.46215537873, 1410.4992727581766, 1455.1560095144791, 1466.296177834624, 1472.3521650473758, 1483.406637345049, 21640.67538986885, 22173.55652886901, 22386.440338991655, 22653.093876244766, 22727.0, 36728.81635591805, 37030.44454333241, 37077.81461092785, 37095.62904370922, 37171.81414092912, 180865.0, 181393.0, 181467.0, 183304.0, 184528.0, 28987.275318117216, 29568.55647165251, 29935.850320748257, 32807.835960821, 33324.66675333216, 40563.797181013906, 41688.3746743793, 41746.37380439235, 41965.58034419739, 42078.579214207464, 30734.385312294075, 30886.76452941947, 30973.225669358446, 31074.0, 31377.529337059506, 30094.699053010067, 30550.0, 30617.693823061483, 30780.384392312477, 31267.53098703567, 33733.0, 35151.0, 35193.12017199488, 35252.118697032776, 35680.64319356877, 30031.54952675668, 30474.695253047186, 30595.388092238474, 30648.540271896385, 31289.68710312959, 22870.199543015915, 23407.41481462977, 23468.64797028012, 24873.0, 24938.0], "value": 16707.0716490842, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [553.2613960362918, 564.9858503985346, 565.2849145830523, 578.1587789765886, 589.0751520113422, 22179.66730499044, 22853.31440056799, 22905.31284061481, 23075.0, 23083.538329233405, 23643.408914777137, 23828.76171238287, 24510.63234051486, 24610.507789844236, 25644.871775641095, 6555.180602424709, 6722.966385168092, 6725.966370168142, 6732.0, 6780.627065511395, 6660.56706314088, 6716.832079198035, 6742.561733487308, 6746.156730408699, 6774.864502709943, 6206.534509911752, 6481.513886458511, 6484.902726459083, 6546.11627430298, 6677.1987361516685, 6485.319041500646, 6503.414692677635, 6512.8276910156155, 6517.348265173492, 6543.1821022372205, 5995.37048609894, 6054.545909056838, 6073.331933487313, 6545.083688283644, 6880.69036893341, 31489.370212595848, 32162.356752865046, 32183.839080804446, 32404.351912961843, 32439.189020274684, 2089.8746075235535, 2106.536561956367, 2109.4515425989352, 2112.9788702112937, 2120.8197303229244, 20663.070161842752, 20992.265270715452, 21121.68317475239, 21295.467613309636, 21403.464913377135, 2470.5923522618928, 2537.860417677027, 2544.096845619815, 2554.0805310088354, 2612.05965852293, 120386.0, 127177.36411317885, 137809.0, 145357.27321363325, 145721.0, 20865.80909004563, 21061.578768424544, 22164.778352216596, 22218.66671999921, 22533.43666408353, 43436.78281608635, 43518.34722479165, 43678.0, 43789.34315985263, 43853.78073109614, 10827.455547946227, 10895.237333386587, 11003.834942475869, 11029.669109926715, 11146.888531114586, 9113.362064655543, 9137.223336016514, 9191.0, 9230.815383692288, 9360.719178424522, 11625.302481851117, 11852.0, 11939.403029848647, 11963.0, 12091.516339346328, 4311.051568654891, 4340.739555626673, 4385.079133381962, 4398.208322501981, 4450.548078603684, 952.8284908716497, 961.916272499835, 963.5086106085806, 974.152487336008, 975.6146322202832, 19639.214431422868, 19643.31248406299, 20069.698954515694, 20283.087261073288, 20325.0, 1021.1320377678913, 1024.3802499487851, 1032.2052019944601, 1040.3341861208833, 1046.0376453662648, 11053.778924421467, 11066.833997490045, 11080.501377438035, 11209.766925638174, 11278.379689117177, 461056.0, 465837.0, 466958.0, 467575.0, 476526.0, 107798.0, 108398.45800771103, 109000.0, 109893.45053274842, 111218.0, 1138.1975707126621, 1142.4744617476053, 1157.6411312493153, 1162.7035106047904, 1167.9007284380752, 17664.55838604045, 17682.292708291523, 17790.288388464316, 17825.46523604294, 18082.0, 54892.17661734997, 56296.71851640879, 57674.13488797587, 57944.71027644835, 58063.4193658058, 77397.61301193683, 80515.59742201486, 80976.59511702404, 81135.59432203037, 81609.0, 14601.342939567958, 15055.548333549574, 15080.472183473526, 15104.773428398363, 15109.697806043598, 23352.0, 23822.761772382055, 24036.51926961416, 24063.639045413984, 24293.757062429857, 18683.813161868205, 18791.436256912315, 18870.3395381161, 19055.14251858642, 19215.231390744215, 17022.914885425493, 17062.658746825244, 17067.829321707122, 17319.56701082483, 17419.47741567755, 19257.13342899545, 19435.902820485808, 19587.51031224231, 19655.901720491307, 19702.0, 14812.777808332668, 14952.775708364166, 14999.175045372172, 15011.474598389004, 15019.924900375428, 18611.255549777856, 18946.905265474135, 18993.0, 19202.423927282205, 19330.4200873974], "value": 12762.952136630665, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1335.8063080853276, 1382.9930850345747, 1387.2439520461348, 1401.1873113594118, 1406.4093080906018, 14949.5515134546, 15587.766183507243, 15664.295106720196, 15673.216339183053, 15713.84286157138, 15093.396264149449, 15122.092674439515, 15171.0, 15230.466933657324, 15238.542843714678, 2744.0944488318805, 2768.709285525016, 2794.1338185162563, 2799.5800629905475, 2805.985970070157, 2608.921732348023, 2723.673159220896, 2727.4136060746937, 2730.0581299451655, 2736.945261094777, 2561.9359516012064, 2637.3274814922265, 2819.0415258812, 2831.150654803561, 2831.263871393442, 2789.6512935883115, 2811.1847564206355, 2811.4096039831556, 2812.325041989928, 2813.2685501769583, 2655.243255672124, 2657.255968328871, 2660.401409682806, 2673.8529380884042, 2680.490706765708, 14849.0, 15183.544493665207, 15213.31540080688, 15281.312340944683, 15289.388424463008, 7916.762497125095, 8139.633716482773, 8156.062052863937, 8183.386246031571, 8197.139300373434, 5057.2666963290485, 5163.457836927106, 5203.115470370009, 5266.894662106774, 5310.176922577007, 1836.4582448177819, 1846.2891786662096, 1864.1052294898525, 1913.0434782608695, 1913.0721600023978, 25493.362665933313, 25779.484410311878, 27246.0, 27311.9999999998, 27565.58651620207, 6812.591244525344, 6843.965780171067, 6918.204406493217, 7066.505344625827, 7077.398421134261, 17109.572260693334, 17148.22832972494, 17153.399631012853, 17186.74219886703, 17219.052952087757, 7901.091374492007, 8011.479253848481, 8013.4791238569305, 8019.518828870287, 8025.157358477392, 7344.853102937911, 7352.816179595553, 7404.703811847466, 7441.069866266716, 7520.0, 5097.388313402341, 5149.45930677281, 5171.63798534099, 5281.392639846466, 5294.788208471695, 1408.7041721238654, 1447.3993292783587, 1470.9338079786435, 1480.305050716923, 1485.23745832751, 902.5803001604286, 914.4696076275744, 917.4128557723062, 939.3236869453982, 945.1776954049883, 8213.630386632616, 8534.146585341416, 8735.519546425008, 8802.07578204292, 8813.691520796741, 970.1801977329145, 978.496074521622, 999.3853779925294, 1011.2959662967776, 1032.200044965143, 5242.18746094356, 5417.349918009864, 5510.476504732044, 5513.807016754395, 5541.6120871539415, 21460.78539214619, 21885.452863678536, 21991.230306939182, 22030.0, 22342.776572234394, 20836.166553337698, 21598.676019859715, 21946.670799937696, 21991.230306939502, 22122.88938555329, 9193.0, 9336.533173341442, 9946.850797237903, 11469.770604587862, 11697.824532632018, 8476.533790641573, 9093.454392736458, 9162.816743665222, 9200.171984521425, 9217.400868943498, 18542.907285463487, 18688.53278668044, 18765.343212988028, 19084.427467176007, 19305.420837374902, 19086.236550537822, 19312.103184522435, 19624.90187549053, 19944.0, 20164.0, 11937.164398492023, 12183.208091474025, 12381.0, 12407.627771166879, 12432.81350779721, 14216.360263787947, 14374.425022998963, 14377.20925349074, 14382.784258236345, 14391.568252952427, 11528.0, 11563.826542601699, 11683.18217724751, 11722.648320550397, 11729.589464369048, 11114.444277785917, 11137.164712646421, 11419.200655954004, 11522.423878806194, 11745.941270293935, 10231.386116833013, 10324.0, 10354.171666266831, 10405.687829365132, 10411.063004329344, 7346.37555807741, 7381.224971377928, 7389.593572353572, 7433.739819106306, 7472.0286362772495, 7077.752278670222, 7287.198408174991, 7376.889346659697, 7499.737509187153, 7593.392528597588], "value": 6773.077978995407, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [501.42596086119147, 504.6089280807375, 518.7924830067973, 519.727143249794, 529.7007190937121, 5926.407359264077, 6125.785597504088, 6152.907706384408, 6186.257649082111, 6234.968825155874, 6017.729202185901, 6057.757689692418, 6103.9318119329055, 6114.449699527042, 6159.23009623797, 5079.339685840836, 5116.923246151292, 5123.513266239701, 5131.820386286481, 5139.717315547643, 5137.61101087994, 5141.871453213662, 5176.922346164792, 5196.142636464979, 5205.9219111713355, 4737.170995075865, 4906.9263961040615, 4923.138450771118, 4931.679440836334, 5073.162928116874, 4866.489018653061, 4912.312276281324, 4947.356843610326, 4963.875903102433, 4969.006198760227, 4339.240632889232, 4370.606645401929, 4400.405945197389, 4411.933820992687, 4443.244648409778, 5852.707364631751, 5913.704314784287, 5971.313298970631, 5994.130851026577, 6087.695615219265, 5458.863528411782, 5472.91790623141, 5579.218909352694, 5633.8591535211535, 5635.351934527541, 6335.239771227436, 6344.936550634481, 6377.234731832164, 6386.0, 6410.102585638015, 4557.225271703818, 4787.54518320759, 4797.808087676523, 4815.22956326986, 4830.951690483121, 6522.93477065228, 6597.63712995785, 6675.46596272297, 6688.896332105197, 6741.426978706816, 4893.755312234374, 4903.705777653352, 4906.337644418029, 4935.679180853233, 4938.678985865908, 4896.828610998669, 4938.308636790852, 4951.653384263067, 5005.249212618136, 5033.496650335011, 5440.863978400572, 5476.397596264408, 5633.69014704187, 5635.943640563624, 5679.403662615448, 6516.152900122954, 6524.380183882524, 6572.638504882278, 6581.466135537852, 6603.76886808969, 1741.2077504735314, 1746.4847869878417, 1753.9123043847756, 1756.1833747307564, 1760.74469201965, 1132.4960392625203, 1173.4836671864352, 1190.3512585640674, 1204.656672848234, 1212.0424864357235, 5623.0, 5637.23897273871, 5671.773129074791, 5683.630564013326, 5726.341470730836, 6595.73617055322, 6626.469882409396, 6627.204735431779, 6629.900551491732, 6683.465322774166, 5585.430701877182, 5603.495685388335, 5630.634008789416, 5646.661200327993, 5680.943190568041, 3436.226848959014, 3453.810040447749, 3490.650934906489, 3499.4051011328133, 3537.2041290709385, 6360.363963603602, 6397.6481293529305, 6456.0, 6464.353564643592, 6554.311797261219, 6756.459483241329, 6840.726370945106, 6857.451403887677, 6861.382475577222, 6887.931120688729, 6443.130177426081, 6455.48356131499, 6474.665547243174, 6497.15536980199, 6523.151990241238, 6101.84745381369, 6391.041343798467, 6593.977933420328, 6600.2409722882485, 6613.900791488228, 6382.744690212339, 6401.8719625608155, 6442.903356449563, 6473.158489396348, 6486.0, 6242.28213755424, 6261.874762504815, 6324.0, 6374.426301632875, 6428.4857211423905, 4932.975335123301, 4942.752862356796, 4964.205727083649, 4974.651774375759, 5001.274815151855, 6301.936980630135, 6502.512311576556, 6514.348565143448, 6605.702743376656, 6636.83407914806, 6225.221847269091, 6385.329540398191, 6394.71223794921, 6403.2316122063785, 6450.742779503874, 6293.779717709858, 6336.9049464259, 6395.616263024233, 6400.055974251896, 6465.321141280097, 6294.213223347082, 6357.936420635734, 6370.554061215856, 6413.647249401328, 6415.807525774234, 6418.486521078209, 6453.322401148, 6457.5479716419395, 6470.967645161744, 6505.60966342021, 6282.434580887742, 6289.96855015722, 6298.488362792805, 6318.557700961072, 6324.778632747832], "value": 4980.436945038511, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [882.801369691819, 893.9374243802934, 916.720400277915, 920.4339331311241, 929.8837645294338, 19890.10494527746, 20870.478238044037, 20898.477538061536, 20993.68509472359, 21015.789842101574, 18631.53421164468, 18918.0, 19142.71285930712, 19228.71156932647, 19301.227950881985, 948.5873644964445, 964.0744884910495, 968.5738275158943, 973.060996138726, 976.7362812040744, 984.6110786239431, 989.579428742785, 989.6239429016963, 991.3605724307818, 993.8608594796734, 993.4287784523891, 1037.3101887245011, 1044.5769463367349, 1046.1682962045154, 1046.7383154211448, 1026.0867827633413, 1034.2760067952427, 1034.2811745836646, 1035.59094157808, 1035.7410647338165, 716.616610113592, 754.1553460124641, 755.3881356101521, 755.7619349904762, 761.090496856257, 20768.480787980272, 20808.5838283235, 20836.0, 20905.163793448242, 20941.5811683767, 21659.566808663894, 21717.131314747392, 22157.0, 22391.888040559857, 22464.438389040242, 17550.7367389488, 17606.38377656776, 17708.380206692706, 17966.461006169706, 18088.095595220188, 3618.5115009473648, 3639.1811842335264, 3648.0, 3648.0515066082744, 3662.3957047087197, 139206.3039684795, 140387.0, 140740.0, 141769.2911535446, 142348.0, 15948.2823272953, 16320.0, 16366.754498682529, 16432.424865129662, 16453.42413015539, 30533.541996870066, 31038.22404439862, 31613.683863161074, 31907.840460798012, 32205.51691724626, 9205.447673139633, 9319.440833550008, 9332.626694932262, 9378.577963991636, 9390.812183756287, 4333.5016473106, 4348.565143485626, 4368.039031413084, 4410.514843367227, 4474.239379305526, 5391.32608423947, 5474.206240095164, 5499.175123731472, 5500.339959204921, 5507.0637991541535, 1117.5920788912151, 1157.2709193208393, 1158.3976332307152, 1166.2419427372106, 1168.5442677355838, 671.4091599392503, 692.882210024297, 694.1219357512649, 717.3579646216712, 722.0216606498225, 11706.70733231676, 12081.214721043105, 12292.139550231397, 13176.209427434193, 13240.602781916557, 683.2143035509204, 687.0793137196181, 708.7873637908607, 712.3517598984898, 716.5951237550767, 5616.943830561724, 5683.943160568341, 5696.544276457957, 5706.857328566736, 5817.88364232713, 121520.0, 121877.0, 122499.38750306191, 122954.0, 123230.38384808198, 109227.45386273177, 109340.29198391388, 109753.45123274492, 110338.44830775795, 111257.44371278092, 92767.53616231876, 95723.0, 96329.51835240779, 97310.99999999859, 97968.0, 10311.0, 10460.0, 10650.147988161083, 10698.251122421356, 10845.403502807105, 42042.0, 42398.0, 43609.0, 43727.34408983932, 44343.77828110839, 55208.72395637996, 55785.44214557965, 56481.0, 57368.0, 59045.114323284324, 14456.20490873012, 14579.781303280248, 14623.195724234844, 14751.778723318945, 14926.552203433914, 15932.0, 16034.839651603335, 16059.19704014818, 16067.839321607102, 16113.758293625839, 16623.418180363627, 16758.245878935235, 17030.403935862185, 17112.57218569496, 17438.564035899202, 16269.186540672683, 16440.095794731405, 16546.089965052037, 16552.75170872462, 16680.833191667927, 14720.558383248519, 14768.040077394935, 14778.704425911637, 16594.0, 16896.915515422344, 13423.26172060546, 13696.93151534236, 13914.51299204523, 14099.0, 14171.929140354232, 13851.099678521266, 14058.297085145497, 14104.647383815076, 14159.221242831743, 14217.64455888611], "value": 9119.840817853104, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1467.9999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 116.0, 112.0, 110.0, 112.0, 108.0, 108.0, 97.0, 112.0, 115.0], "value": 109.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 122.0, 131.0, 117.0, 123.0, 125.0, 116.0, 127.0, 116.0, 116.0], "value": 121.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 167.0, 175.0, 170.0, 176.0, 168.0, 164.0, 163.0, 165.0, 162.0], "value": 167.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 91.0, 82.0, 79.0, 75.0, 78.0, 76.0, 74.0, 77.0, 76.0], "value": 78.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 89.0, 85.0, 83.0, 81.0, 79.0, 85.0, 90.0, 83.0, 80.0], "value": 83.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 116.0, 114.0, 114.0, 124.0, 120.0, 115.0, 126.0, 115.0, 124.0], "value": 117.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 149.0, 154.0, 148.0, 155.0, 152.0, 149.0, 153.0, 148.0, 155.0], "value": 151.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 107.0, 102.0, 94.0, 94.0, 99.0, 94.0, 95.0, 95.0, 95.0], "value": 96.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 52.0, 52.0, 53.0, 51.0, 53.0, 57.0, 57.0, 52.0], "value": 53.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [39.0, 42.0, 39.0, 41.0, 38.0, 46.0, 38.0, 39.0, 39.0, 47.0], "value": 40.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 78.0, 80.0, 79.0, 78.0, 76.0, 81.0, 76.0, 84.0, 75.0], "value": 79.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 134.0, 120.0, 128.0, 125.0, 127.0, 132.0, 122.0, 130.0, 123.0], "value": 126.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 173.0, 176.0, 169.0, 177.0, 171.0, 182.0, 169.0, 171.0, 179.0], "value": 173.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 67.0, 64.0, 66.0, 72.0, 66.0, 66.0, 64.0, 65.0, 67.0], "value": 66.6, "unit": "score"}]}]} 16:00:01 INFO - Return code: 0 16:00:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:00:01 INFO - # TBPL SUCCESS # 16:00:01 INFO - Running post-action listener: _package_coverage_data 16:00:01 INFO - Running post-action listener: _resource_record_post_action 16:00:01 INFO - [mozharness: 2017-09-07 23:00:01.813706Z] Finished run-tests step (success) 16:00:01 INFO - Running post-run listener: _resource_record_post_run 16:00:01 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 4096; Write bytes: 404054016; Read time: 12; Write time: 257412 16:00:01 INFO - TinderboxPrint: CPU usage
13.3% 16:00:01 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 16:00:01 INFO - TinderboxPrint: I/O write bytes / time
404,054,016 / 257,412 16:00:01 INFO - TinderboxPrint: CPU idle
8,251.5 (86.7%) 16:00:01 INFO - TinderboxPrint: CPU user
1,200.5 (12.6%) 16:00:01 INFO - TinderboxPrint: Swap in / out
0 / 0 16:00:01 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 107106304; Read time: 0; Write time: 77332 16:00:01 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 16:00:01 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 4096; Write bytes: 282824704; Read time: 12; Write time: 175356 16:00:02 INFO - Running post-run listener: _upload_blobber_files 16:00:02 INFO - Blob upload gear active. 16:00:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:00:02 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 16:00:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:00:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:00:02 INFO - (blobuploader) - INFO - Open directory for files ... 16:00:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:00:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:00:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:00:03 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:00:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:00:03 INFO - (blobuploader) - INFO - Done attempting. 16:00:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:00:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:00:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:00:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:00:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:00:10 INFO - (blobuploader) - INFO - Done attempting. 16:00:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:00:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:00:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:00:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:00:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:00:12 INFO - (blobuploader) - INFO - Done attempting. 16:00:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:00:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:00:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:00:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:00:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:00:12 INFO - (blobuploader) - INFO - Done attempting. 16:00:12 INFO - (blobuploader) - INFO - Iteration through files over. 16:00:12 INFO - Return code: 0 16:00:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:00:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:00:12 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5cfa9fd679d3b00cea3d3caf0158a5c983db3c5351826d560828544dfc94dff191e7e1e1e50e1a62a1078cb08e99909528713ce8fd8f2072ac61e688d40fa549", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/843f2bae3c965bafd0e0e30966fcdc5f6dc9dbfa9039c1c4e45e656dc287bcd631030b3db2d15fa98256098e5cc1514932fa114044eb2b5a658577d13eb38bbd", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e810a3554406afcefb9cd3a1ce1b8534d41aa01d1d8189e0a3924d10fab6383b8effa6417603822a08e386e4a4163ade629007dd12758bc33f7d77cbf75c2a9c", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/814a5a24c53162ab4752141f1b8f9bab03e5dfedd02ee57b326352eb00ab7bdec0758be16b386df20f7b249df5470d6de997a6fa07c7400a74a274afb8b0b0c9"} 16:00:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:00:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:00:12 INFO - Contents: 16:00:12 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5cfa9fd679d3b00cea3d3caf0158a5c983db3c5351826d560828544dfc94dff191e7e1e1e50e1a62a1078cb08e99909528713ce8fd8f2072ac61e688d40fa549", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/843f2bae3c965bafd0e0e30966fcdc5f6dc9dbfa9039c1c4e45e656dc287bcd631030b3db2d15fa98256098e5cc1514932fa114044eb2b5a658577d13eb38bbd", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e810a3554406afcefb9cd3a1ce1b8534d41aa01d1d8189e0a3924d10fab6383b8effa6417603822a08e386e4a4163ade629007dd12758bc33f7d77cbf75c2a9c", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/814a5a24c53162ab4752141f1b8f9bab03e5dfedd02ee57b326352eb00ab7bdec0758be16b386df20f7b249df5470d6de997a6fa07c7400a74a274afb8b0b0c9"} 16:00:12 INFO - Running post-run listener: copy_logs_to_upload_dir 16:00:12 INFO - Copying logs to upload dir... 16:00:12 INFO - mkdir: /builds/slave/test/build/upload/logs 16:00:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1256.039853 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2017-09-07 16:00:12.953757) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-07 16:00:12.957879) ========= 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=17051fcd5208b27b50bedfeb000001dd-1504823906.590082-885688012 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5cfa9fd679d3b00cea3d3caf0158a5c983db3c5351826d560828544dfc94dff191e7e1e1e50e1a62a1078cb08e99909528713ce8fd8f2072ac61e688d40fa549", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/843f2bae3c965bafd0e0e30966fcdc5f6dc9dbfa9039c1c4e45e656dc287bcd631030b3db2d15fa98256098e5cc1514932fa114044eb2b5a658577d13eb38bbd", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e810a3554406afcefb9cd3a1ce1b8534d41aa01d1d8189e0a3924d10fab6383b8effa6417603822a08e386e4a4163ade629007dd12758bc33f7d77cbf75c2a9c", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/814a5a24c53162ab4752141f1b8f9bab03e5dfedd02ee57b326352eb00ab7bdec0758be16b386df20f7b249df5470d6de997a6fa07c7400a74a274afb8b0b0c9"} build_url:https://queue.taskcluster.net/v1/task/HU_acp97TAesHcVo7pnBow/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011319 build_url: 'https://queue.taskcluster.net/v1/task/HU_acp97TAesHcVo7pnBow/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5cfa9fd679d3b00cea3d3caf0158a5c983db3c5351826d560828544dfc94dff191e7e1e1e50e1a62a1078cb08e99909528713ce8fd8f2072ac61e688d40fa549", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/843f2bae3c965bafd0e0e30966fcdc5f6dc9dbfa9039c1c4e45e656dc287bcd631030b3db2d15fa98256098e5cc1514932fa114044eb2b5a658577d13eb38bbd", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e810a3554406afcefb9cd3a1ce1b8534d41aa01d1d8189e0a3924d10fab6383b8effa6417603822a08e386e4a4163ade629007dd12758bc33f7d77cbf75c2a9c", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/814a5a24c53162ab4752141f1b8f9bab03e5dfedd02ee57b326352eb00ab7bdec0758be16b386df20f7b249df5470d6de997a6fa07c7400a74a274afb8b0b0c9"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-07 16:00:12.991642) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 16:00:12.991988) ========= 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=17051fcd5208b27b50bedfeb000001dd-1504823906.590082-885688012 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004555 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 16:00:13.074904) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-07 16:00:13.075295) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-07 16:00:13.075617) ========= ========= Total master_lag: 0.21 =========