builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-021 starttime: 1505730264.98 results: success (0) revision: ffe6cc09ccf38cca6f0e727837bbc6cb722d1e71 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-18 03:24:24.982780) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-18 03:24:24.983489) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-18 03:24:24.983775) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-18 03:24:25.042430) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-18 03:24:25.046253) ========= 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=df17445c3590ff7897617a0e00000225-1505728324.77209-1688482940 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-18 03:24:24-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 1.73M=0.007s 2017-09-18 03:24:25 (1.73 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.209683 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-18 03:24:25.272603) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-18 03:24:25.272898) ========= 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=df17445c3590ff7897617a0e00000225-1505728324.77209-1688482940 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014746 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-18 03:24:25.321658) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 11 secs) (at 2017-09-18 03:24:25.321963) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev ffe6cc09ccf38cca6f0e727837bbc6cb722d1e71 --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 ffe6cc09ccf38cca6f0e727837bbc6cb722d1e71 --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=df17445c3590ff7897617a0e00000225-1505728324.77209-1688482940 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-18 03:24:25,291 truncating revision to first 12 chars 2017-09-18 03:24:25,291 Setting DEBUG logging. 2017-09-18 03:24:25,291 attempt 1/10 2017-09-18 03:24:25,291 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/ffe6cc09ccf3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-18 03:24:25,764 attempt 1/10 2017-09-18 03:24:26,423 current task status: no status available at this point. state: STARTED 2017-09-18 03:24:26,423 sleeping for 9.00s (attempt 1/10) 2017-09-18 03:24:35,433 attempt 2/10 2017-09-18 03:24:35,855 current task status: Task completed! Check 's3_urls' for upload locations. state: SUCCESS 2017-09-18 03:24:36,025 unpacking tar archive at: mozilla-central-ffe6cc09ccf3/testing/mozharness/ program finished with exit code 0 elapsedTime=11.060504 ========= master_lag: 0.64 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 11 secs) (at 2017-09-18 03:24:37.024201) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-18 03:24:37.024525) ========= script_repo_revision: ffe6cc09ccf38cca6f0e727837bbc6cb722d1e71 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-18 03:24:37.024901) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 1 secs) (at 2017-09-18 03:24:37.031243) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 1 secs) (at 2017-09-18 03:24:38.045799) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2017-09-18 03:24:38.046051) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=df17445c3590ff7897617a0e00000225-1505728324.77209-1688482940 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:24:38 INFO - MultiFileLogger online at 20170918 03:24:38 in /builds/slave/test 03:24:38 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 03:24:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:24:38 INFO - {'append_to_log': False, 03:24:38 INFO - 'base_work_dir': '/builds/slave/test', 03:24:38 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 03:24:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:24:38 INFO - 'branch': 'Firefox-Non-PGO', 03:24:38 INFO - 'buildbot_json_path': 'buildprops.json', 03:24:38 INFO - 'code_coverage': False, 03:24:38 INFO - 'config_files': ('talos/linux_config.py',), 03:24:38 INFO - 'default_actions': ('clobber', 03:24:38 INFO - 'read-buildbot-config', 03:24:38 INFO - 'download-and-extract', 03:24:38 INFO - 'populate-webroot', 03:24:38 INFO - 'create-virtualenv', 03:24:38 INFO - 'install', 03:24:38 INFO - 'setup-mitmproxy', 03:24:38 INFO - 'run-tests'), 03:24:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:24:38 INFO - 'disable_ccov_upload': False, 03:24:38 INFO - 'disable_stylo': False, 03:24:38 INFO - 'download_minidump_stackwalk': True, 03:24:38 INFO - 'download_symbols': 'ondemand', 03:24:38 INFO - 'enable_stylo': False, 03:24:38 INFO - 'enable_webrender': False, 03:24:38 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 03:24:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:24:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:24:38 INFO - 'gecko_profile': False, 03:24:38 INFO - 'gecko_profile_interval': 0, 03:24:38 INFO - 'installer_path': 'installer.exe', 03:24:38 INFO - 'log_level': 'info', 03:24:38 INFO - 'log_name': 'talos', 03:24:38 INFO - 'log_to_console': True, 03:24:38 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:24:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:24:38 INFO - 'opt_config_files': (), 03:24:38 INFO - 'pip_index': False, 03:24:38 INFO - 'suite': 'dromaeojs-e10s', 03:24:38 INFO - 'system_bits': '32', 03:24:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:24:38 INFO - 'title': 'talos-linux64-ix-021', 03:24:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:24:38 INFO - 'use_talos_json': True, 03:24:38 INFO - 'verify': 'False', 03:24:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:24:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:24:38 INFO - 'work_dir': 'build'} 03:24:38 INFO - [mozharness: 2017-09-18 10:24:38.167637Z] Running clobber step. 03:24:38 INFO - Running pre-action listener: _resource_record_pre_action 03:24:38 INFO - Running main action method: clobber 03:24:38 INFO - rmtree: /builds/slave/test/build 03:24:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:24:39 INFO - Running post-action listener: _resource_record_post_action 03:24:39 INFO - [mozharness: 2017-09-18 10:24:39.688781Z] Finished clobber step (success) 03:24:39 INFO - [mozharness: 2017-09-18 10:24:39.688879Z] Running read-buildbot-config step. 03:24:39 INFO - Running pre-action listener: _resource_record_pre_action 03:24:39 INFO - Running main action method: read_buildbot_config 03:24:39 INFO - Using buildbot properties: 03:24:39 INFO - { 03:24:39 INFO - "project": "", 03:24:39 INFO - "product": "firefox", 03:24:39 INFO - "who": "archaeopteryx@coole-files.de", 03:24:39 INFO - "installer_path": "public/build/target.tar.bz2", 03:24:39 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 03:24:39 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 03:24:39 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 03:24:39 INFO - "stage_platform": "linux64", 03:24:39 INFO - "basedir": "/builds/slave/test", 03:24:39 INFO - "buildnumber": 844, 03:24:39 INFO - "platform": "ubuntu64_hw", 03:24:39 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 03:24:39 INFO - "slavebuilddir": "test", 03:24:39 INFO - "taskId": "Hmu5VxE9SD-d1DquIZqnZQ", 03:24:39 INFO - "branch": "mozilla-central", 03:24:39 INFO - "script_repo_revision": "production", 03:24:39 INFO - "revision": "ffe6cc09ccf38cca6f0e727837bbc6cb722d1e71", 03:24:39 INFO - "slavename": "talos-linux64-ix-021", 03:24:39 INFO - "repo_path": "mozilla-central" 03:24:39 INFO - } 03:24:39 INFO - Finding installer, test and symbols from parent task. 03:24:39 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Hmu5VxE9SD-d1DquIZqnZQ'}, attempt #1 03:24:40 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Hmu5VxE9SD-d1DquIZqnZQ'}, attempt #1 03:24:40 INFO - Task dependencies: L5VTY1pKRgOdH0QnkhRnug 03:24:40 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/L5VTY1pKRgOdH0QnkhRnug'}, attempt #1 03:24:41 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/L5VTY1pKRgOdH0QnkhRnug/artifacts/public/build/target.tar.bz2 03:24:41 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/L5VTY1pKRgOdH0QnkhRnug/artifacts/public/build/target.test_packages.json 03:24:41 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/L5VTY1pKRgOdH0QnkhRnug/artifacts/public/build/target.crashreporter-symbols.zip 03:24:41 INFO - Running post-action listener: _resource_record_post_action 03:24:41 INFO - [mozharness: 2017-09-18 10:24:41.039935Z] Finished read-buildbot-config step (success) 03:24:41 INFO - [mozharness: 2017-09-18 10:24:41.040239Z] Running download-and-extract step. 03:24:41 INFO - Running pre-action listener: _resource_record_pre_action 03:24:41 INFO - Running main action method: download_and_extract 03:24:41 INFO - mkdir: /builds/slave/test/build/tests 03:24:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:24:41 INFO - trying https://queue.taskcluster.net/v1/task/L5VTY1pKRgOdH0QnkhRnug/artifacts/public/build/target.test_packages.json 03:24:41 INFO - Downloading https://queue.taskcluster.net/v1/task/L5VTY1pKRgOdH0QnkhRnug/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 03:24:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/L5VTY1pKRgOdH0QnkhRnug/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 03:24:42 INFO - Downloaded 989 bytes. 03:24:42 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 03:24:42 INFO - Using the following test package requirements: 03:24:42 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 03:24:42 INFO - u'common': [u'target.common.tests.zip'], 03:24:42 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 03:24:42 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 03:24:42 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 03:24:42 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 03:24:42 INFO - u'mozbase': [u'target.common.tests.zip'], 03:24:42 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 03:24:42 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 03:24:42 INFO - u'web-platform': [u'target.common.tests.zip', 03:24:42 INFO - u'target.web-platform.tests.tar.gz'], 03:24:42 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 03:24:42 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 03:24:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/L5VTY1pKRgOdH0QnkhRnug/artifacts/public/build/target.common.tests.zip 03:24:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/L5VTY1pKRgOdH0QnkhRnug/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:24:42 INFO - Fetch https://queue.taskcluster.net/v1/task/L5VTY1pKRgOdH0QnkhRnug/artifacts/public/build/target.common.tests.zip into memory 03:24:43 INFO - Content-Length response header: 54731218 03:24:43 INFO - Bytes received: 54731218 03:24:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/L5VTY1pKRgOdH0QnkhRnug/artifacts/public/build/target.talos.tests.zip 03:24:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/L5VTY1pKRgOdH0QnkhRnug/artifacts/public/build/target.talos.tests.zip'}, attempt #1 03:24:53 INFO - Fetch https://queue.taskcluster.net/v1/task/L5VTY1pKRgOdH0QnkhRnug/artifacts/public/build/target.talos.tests.zip into memory 03:24:54 INFO - Content-Length response header: 14508748 03:24:54 INFO - Bytes received: 14508748 03:24:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:24:54 INFO - trying https://queue.taskcluster.net/v1/task/L5VTY1pKRgOdH0QnkhRnug/artifacts/public/build/target.tar.bz2 03:24:54 INFO - Downloading https://queue.taskcluster.net/v1/task/L5VTY1pKRgOdH0QnkhRnug/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 03:24:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/L5VTY1pKRgOdH0QnkhRnug/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 03:24:55 INFO - Downloaded 61588572 bytes. 03:24:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/L5VTY1pKRgOdH0QnkhRnug/artifacts/public/build/target.tar.bz2 03:24:56 INFO - mkdir: /builds/slave/test/properties 03:24:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:24:56 INFO - Writing to file /builds/slave/test/properties/build_url 03:24:56 INFO - Contents: 03:24:56 INFO - build_url:https://queue.taskcluster.net/v1/task/L5VTY1pKRgOdH0QnkhRnug/artifacts/public/build/target.tar.bz2 03:24:56 INFO - Running post-action listener: _resource_record_post_action 03:24:56 INFO - Running post-action listener: find_tests_for_verification 03:24:56 INFO - Running post-action listener: set_extra_try_arguments 03:24:56 INFO - [mozharness: 2017-09-18 10:24:56.562823Z] Finished download-and-extract step (success) 03:24:56 INFO - [mozharness: 2017-09-18 10:24:56.562935Z] Running populate-webroot step. 03:24:56 INFO - Running pre-action listener: _resource_record_pre_action 03:24:56 INFO - Running main action method: populate_webroot 03:24:56 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:24:56 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:24:56 INFO - 'tests': ['tresize', 03:24:56 INFO - 'tcanvasmark']}, 03:24:56 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:24:56 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:24:56 INFO - 'tests': ['dromaeo_css', 03:24:56 INFO - 'kraken']}, 03:24:56 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:24:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:24:56 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:24:56 INFO - 'talos_options': ['--disable-stylo'], 03:24:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:24:56 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:24:56 INFO - 'tests': ['damp', 'tps']}, 03:24:56 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:24:56 INFO - 'talos_options': ['--disable-stylo'], 03:24:56 INFO - 'tests': ['damp', 'tps']}, 03:24:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:24:56 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:24:56 INFO - 'tests': ['dromaeo_dom']}, 03:24:56 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 03:24:56 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:24:56 INFO - 'tests': ['basic_compositor_video', 03:24:56 INFO - 'glvideo']}, 03:24:56 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 03:24:56 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 03:24:56 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:24:56 INFO - 'talos_options': ['--disable-stylo'], 03:24:56 INFO - 'tests': ['ts_paint_webext', 03:24:56 INFO - 'tp5o_webext']}, 03:24:56 INFO - 'other-e10s': {'tests': ['a11yr', 03:24:56 INFO - 'ts_paint', 03:24:56 INFO - 'tpaint', 03:24:56 INFO - 'sessionrestore', 03:24:56 INFO - 'sessionrestore_many_windows', 03:24:56 INFO - 'sessionrestore_no_auto_restore', 03:24:56 INFO - 'tabpaint']}, 03:24:56 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:24:56 INFO - 'tests': ['a11yr', 03:24:56 INFO - 'ts_paint', 03:24:56 INFO - 'tpaint', 03:24:56 INFO - 'sessionrestore', 03:24:56 INFO - 'sessionrestore_many_windows', 03:24:56 INFO - 'sessionrestore_no_auto_restore', 03:24:56 INFO - 'tabpaint']}, 03:24:56 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 03:24:56 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 03:24:56 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:24:56 INFO - 'tests': ['perf_reftest_singletons']}, 03:24:56 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:24:56 INFO - 'tests': ['bloom_basic']}, 03:24:56 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:24:56 INFO - 'tsvgr_opacity', 03:24:56 INFO - 'tart', 03:24:56 INFO - 'tscrollx', 03:24:56 INFO - 'tsvg_static']}, 03:24:56 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:24:56 INFO - 'tests': ['tsvgx', 03:24:56 INFO - 'tsvgr_opacity', 03:24:56 INFO - 'tart', 03:24:56 INFO - 'tscrollx', 03:24:56 INFO - 'tsvg_static']}, 03:24:56 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:24:56 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:24:56 INFO - 'talos_options': ['--disable-stylo'], 03:24:56 INFO - 'tests': ['tp5o']}, 03:24:56 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:24:56 INFO - 'talos_options': ['--mitmproxy', 03:24:56 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:24:56 INFO - '--firstNonBlankPaint'], 03:24:56 INFO - 'tests': ['tp6_google', 03:24:56 INFO - 'tp6_youtube', 03:24:56 INFO - 'tp6_amazon', 03:24:56 INFO - 'tp6_facebook']}, 03:24:56 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:24:56 INFO - 'talos_options': ['--disable-stylo', 03:24:56 INFO - '--mitmproxy', 03:24:56 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:24:56 INFO - '--firstNonBlankPaint'], 03:24:56 INFO - 'tests': ['tp6_google', 03:24:56 INFO - 'tp6_youtube', 03:24:56 INFO - 'tp6_amazon', 03:24:56 INFO - 'tp6_facebook']}, 03:24:56 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:24:56 INFO - 'talos_options': ['--stylo-threads=1', 03:24:56 INFO - '--mitmproxy', 03:24:56 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:24:56 INFO - '--firstNonBlankPaint'], 03:24:56 INFO - 'tests': ['tp6_google', 03:24:56 INFO - 'tp6_youtube', 03:24:56 INFO - 'tp6_amazon', 03:24:56 INFO - 'tp6_facebook']}, 03:24:56 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:24:56 INFO - 'talos_options': ['--xperf_path', 03:24:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:24:56 INFO - 'tests': ['tp5n']}, 03:24:56 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:24:56 INFO - 'talos_options': ['--disable-stylo', 03:24:56 INFO - '--xperf_path', 03:24:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:24:56 INFO - 'tests': ['tp5n']}}} 03:24:56 INFO - Running post-action listener: _resource_record_post_action 03:24:56 INFO - [mozharness: 2017-09-18 10:24:56.580609Z] Finished populate-webroot step (success) 03:24:56 INFO - [mozharness: 2017-09-18 10:24:56.580703Z] Running create-virtualenv step. 03:24:56 INFO - Running pre-action listener: _resource_record_pre_action 03:24:56 INFO - Running main action method: create_virtualenv 03:24:56 INFO - Creating virtualenv /builds/slave/test/build/venv 03:24:56 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:24:56 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 03:24:56 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 03:24:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:24:56 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:24:57 INFO - Installing distribute.............................................................................................................................................................................................done. 03:24:57 INFO - Installing pip...............done. 03:24:57 INFO - Return code: 0 03:24:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:24:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:24:57 INFO - Reading from file tmpfile_stdout 03:24:57 INFO - Output received: 03:24:57 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 03:24:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:24:57 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')]} 03:24:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:24:57 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')]} 03:24:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:24:57 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 0xd551f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf1ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c1a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5090>, '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 0x10c5d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bff90>, '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': 'df17445c3590ff7897617a0e00000225-1505728324.77209-1688482940', '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 03:24:57 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 03:24:57 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 03:24:57 INFO - Using env: {'DISPLAY': ':0', 03:24:57 INFO - 'HOME': '/home/cltbld', 03:24:57 INFO - 'LANG': 'en_US.UTF-8', 03:24:57 INFO - 'LANGUAGE': 'en_US:en', 03:24:57 INFO - 'LOGNAME': 'cltbld', 03:24:57 INFO - 'MAIL': '/var/mail/cltbld', 03:24:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:24:57 INFO - 'MOZ_NO_REMOTE': '1', 03:24:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:24:57 INFO - 'NO_EM_RESTART': '1', 03:24:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:24:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:24:57 INFO - 'PWD': '/builds/slave/test', 03:24:57 INFO - 'SHELL': '/bin/bash', 03:24:57 INFO - 'SHLVL': '1', 03:24:57 INFO - 'TERM': 'linux', 03:24:57 INFO - 'TMOUT': '86400', 03:24:57 INFO - 'USER': 'cltbld', 03:24:57 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1505728324.77209-1688482940', 03:24:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:24:57 INFO - '_': '/tools/buildbot/bin/python'} 03:24:59 INFO - Ignoring indexes: http://pypi.python.org/simple/ 03:24:59 INFO - Downloading/unpacking pip>=1.5 03:24:59 INFO - Running setup.py egg_info for package pip 03:24:59 INFO - warning: no files found matching 'pip/cacert.pem' 03:24:59 INFO - warning: no files found matching '*.html' under directory 'docs' 03:24:59 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:24:59 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:24:59 INFO - Installing collected packages: pip 03:24:59 INFO - Found existing installation: pip 1.1 03:24:59 INFO - Uninstalling pip: 03:24:59 INFO - Successfully uninstalled pip 03:24:59 INFO - Running setup.py install for pip 03:24:59 INFO - warning: no files found matching 'pip/cacert.pem' 03:24:59 INFO - warning: no files found matching '*.html' under directory 'docs' 03:24:59 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:24:59 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:24:59 INFO - Installing pip script to /builds/slave/test/build/venv/bin 03:24:59 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 03:24:59 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 03:24:59 INFO - Successfully installed pip 03:24:59 INFO - Cleaning up... 03:24:59 INFO - Return code: 0 03:24:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:24:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:24:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:24:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:24:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:24:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xd551f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf1ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c1a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5090>, '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 0x10c5d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bff90>, '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': 'df17445c3590ff7897617a0e00000225-1505728324.77209-1688482940', '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 03:24:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:24:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:24:59 INFO - Using env: {'DISPLAY': ':0', 03:24:59 INFO - 'HOME': '/home/cltbld', 03:24:59 INFO - 'LANG': 'en_US.UTF-8', 03:24:59 INFO - 'LANGUAGE': 'en_US:en', 03:24:59 INFO - 'LOGNAME': 'cltbld', 03:24:59 INFO - 'MAIL': '/var/mail/cltbld', 03:24:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:24:59 INFO - 'MOZ_NO_REMOTE': '1', 03:24:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:24:59 INFO - 'NO_EM_RESTART': '1', 03:24:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:24:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:24:59 INFO - 'PWD': '/builds/slave/test', 03:24:59 INFO - 'SHELL': '/bin/bash', 03:24:59 INFO - 'SHLVL': '1', 03:24:59 INFO - 'TERM': 'linux', 03:24:59 INFO - 'TMOUT': '86400', 03:24:59 INFO - 'USER': 'cltbld', 03:24:59 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1505728324.77209-1688482940', 03:24:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:24:59 INFO - '_': '/tools/buildbot/bin/python'} 03:24:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:24:59 INFO - Downloading/unpacking psutil>=3.1.1 03:24:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:24:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:24:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:24:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:25:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:25:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:25:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:25:00 INFO - Installing collected packages: psutil 03:25:00 INFO - Running setup.py install for psutil 03:25:00 INFO - building 'psutil._psutil_linux' extension 03:25:00 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 03:25:00 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 03:25:00 INFO - building 'psutil._psutil_posix' extension 03:25:00 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 03:25:00 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 03:25:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:25:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:25:01 INFO - Successfully installed psutil 03:25:01 INFO - Cleaning up... 03:25:01 INFO - Return code: 0 03:25:01 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:25:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:25:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:25:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:25:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:25:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd551f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf1ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c1a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5090>, '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 0x10c5d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bff90>, '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': 'df17445c3590ff7897617a0e00000225-1505728324.77209-1688482940', '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 03:25:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:25:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:25:01 INFO - Using env: {'DISPLAY': ':0', 03:25:01 INFO - 'HOME': '/home/cltbld', 03:25:01 INFO - 'LANG': 'en_US.UTF-8', 03:25:01 INFO - 'LANGUAGE': 'en_US:en', 03:25:01 INFO - 'LOGNAME': 'cltbld', 03:25:01 INFO - 'MAIL': '/var/mail/cltbld', 03:25:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:25:01 INFO - 'MOZ_NO_REMOTE': '1', 03:25:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:25:01 INFO - 'NO_EM_RESTART': '1', 03:25:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:25:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:25:01 INFO - 'PWD': '/builds/slave/test', 03:25:01 INFO - 'SHELL': '/bin/bash', 03:25:01 INFO - 'SHLVL': '1', 03:25:01 INFO - 'TERM': 'linux', 03:25:01 INFO - 'TMOUT': '86400', 03:25:01 INFO - 'USER': 'cltbld', 03:25:01 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1505728324.77209-1688482940', 03:25:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:25:01 INFO - '_': '/tools/buildbot/bin/python'} 03:25:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:25:01 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:25:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:25:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:25:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:25:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:25:02 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:25:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:25:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:25:02 INFO - Installing collected packages: mozsystemmonitor 03:25:02 INFO - Running setup.py install for mozsystemmonitor 03:25:02 INFO - Successfully installed mozsystemmonitor 03:25:02 INFO - Cleaning up... 03:25:03 INFO - Return code: 0 03:25:03 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:25:03 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')]} 03:25:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:25:03 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')]} 03:25:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:25:03 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 0xd551f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf1ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c1a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5090>, '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 0x10c5d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bff90>, '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': 'df17445c3590ff7897617a0e00000225-1505728324.77209-1688482940', '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 03:25:03 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 03:25:03 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 03:25:03 INFO - Using env: {'DISPLAY': ':0', 03:25:03 INFO - 'HOME': '/home/cltbld', 03:25:03 INFO - 'LANG': 'en_US.UTF-8', 03:25:03 INFO - 'LANGUAGE': 'en_US:en', 03:25:03 INFO - 'LOGNAME': 'cltbld', 03:25:03 INFO - 'MAIL': '/var/mail/cltbld', 03:25:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:25:03 INFO - 'MOZ_NO_REMOTE': '1', 03:25:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:25:03 INFO - 'NO_EM_RESTART': '1', 03:25:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:25:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:25:03 INFO - 'PWD': '/builds/slave/test', 03:25:03 INFO - 'SHELL': '/bin/bash', 03:25:03 INFO - 'SHLVL': '1', 03:25:03 INFO - 'TERM': 'linux', 03:25:03 INFO - 'TMOUT': '86400', 03:25:03 INFO - 'USER': 'cltbld', 03:25:03 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1505728324.77209-1688482940', 03:25:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:25:03 INFO - '_': '/tools/buildbot/bin/python'} 03:25:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:25:03 INFO - Downloading/unpacking jsonschema==2.5.1 03:25:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:25:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:25:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:25:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:25:04 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:25:04 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:25:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:25:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:25:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:25:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:25:04 INFO - Downloading functools32-3.2.3-2.tar.gz 03:25:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:25:04 INFO - warning: no files found matching '*.txt' 03:25:04 INFO - no previously-included directories found matching 'build' 03:25:04 INFO - no previously-included directories found matching 'dist' 03:25:04 INFO - no previously-included directories found matching '.git*' 03:25:04 INFO - Installing collected packages: jsonschema, functools32 03:25:05 INFO - Running setup.py install for functools32 03:25:05 INFO - warning: no files found matching '*.txt' 03:25:05 INFO - no previously-included directories found matching 'build' 03:25:05 INFO - no previously-included directories found matching 'dist' 03:25:05 INFO - no previously-included directories found matching '.git*' 03:25:05 INFO - Successfully installed jsonschema functools32 03:25:05 INFO - Cleaning up... 03:25:05 INFO - Return code: 0 03:25:05 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:25: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')]} 03:25:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:25: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')]} 03:25:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:25:05 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 0xd551f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf1ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c1a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5090>, '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 0x10c5d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bff90>, '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': 'df17445c3590ff7897617a0e00000225-1505728324.77209-1688482940', '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 03:25:05 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 03:25:05 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 03:25:05 INFO - Using env: {'DISPLAY': ':0', 03:25:05 INFO - 'HOME': '/home/cltbld', 03:25:05 INFO - 'LANG': 'en_US.UTF-8', 03:25:05 INFO - 'LANGUAGE': 'en_US:en', 03:25:05 INFO - 'LOGNAME': 'cltbld', 03:25:05 INFO - 'MAIL': '/var/mail/cltbld', 03:25:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:25:05 INFO - 'MOZ_NO_REMOTE': '1', 03:25:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:25:05 INFO - 'NO_EM_RESTART': '1', 03:25:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:25:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:25:05 INFO - 'PWD': '/builds/slave/test', 03:25:05 INFO - 'SHELL': '/bin/bash', 03:25:05 INFO - 'SHLVL': '1', 03:25:05 INFO - 'TERM': 'linux', 03:25:05 INFO - 'TMOUT': '86400', 03:25:05 INFO - 'USER': 'cltbld', 03:25:05 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1505728324.77209-1688482940', 03:25:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:25:05 INFO - '_': '/tools/buildbot/bin/python'} 03:25:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:25:05 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:25:05 INFO - Cleaning up... 03:25:05 INFO - Return code: 0 03:25:05 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:25: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')]} 03:25:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:25: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')]} 03:25:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:25:05 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 0xd551f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf1ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c1a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5090>, '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 0x10c5d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bff90>, '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': 'df17445c3590ff7897617a0e00000225-1505728324.77209-1688482940', '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 03:25:05 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 03:25:05 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 03:25:05 INFO - Using env: {'DISPLAY': ':0', 03:25:05 INFO - 'HOME': '/home/cltbld', 03:25:05 INFO - 'LANG': 'en_US.UTF-8', 03:25:05 INFO - 'LANGUAGE': 'en_US:en', 03:25:05 INFO - 'LOGNAME': 'cltbld', 03:25:05 INFO - 'MAIL': '/var/mail/cltbld', 03:25:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:25:05 INFO - 'MOZ_NO_REMOTE': '1', 03:25:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:25:05 INFO - 'NO_EM_RESTART': '1', 03:25:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:25:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:25:05 INFO - 'PWD': '/builds/slave/test', 03:25:05 INFO - 'SHELL': '/bin/bash', 03:25:05 INFO - 'SHLVL': '1', 03:25:05 INFO - 'TERM': 'linux', 03:25:05 INFO - 'TMOUT': '86400', 03:25:05 INFO - 'USER': 'cltbld', 03:25:05 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1505728324.77209-1688482940', 03:25:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:25:05 INFO - '_': '/tools/buildbot/bin/python'} 03:25:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:25:05 INFO - Downloading/unpacking blobuploader==1.2.4 03:25: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 03:25: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 03:25: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 03:25: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 03:25:06 INFO - Downloading blobuploader-1.2.4.tar.gz 03:25:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:25:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:25:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:25:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:25:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:25:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:25:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:25:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:25:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:25:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:25:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:25:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:25:09 INFO - Downloading docopt-0.6.1.tar.gz 03:25:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:25:09 INFO - Installing collected packages: blobuploader, requests, docopt 03:25:09 INFO - Running setup.py install for blobuploader 03:25:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:25:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:25:09 INFO - Running setup.py install for requests 03:25:10 INFO - Running setup.py install for docopt 03:25:10 INFO - Successfully installed blobuploader requests docopt 03:25:10 INFO - Cleaning up... 03:25:10 INFO - Return code: 0 03:25:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:25:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:25:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:25:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:25:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:25:10 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 0xd551f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf1ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c1a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5090>, '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 0x10c5d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bff90>, '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': 'df17445c3590ff7897617a0e00000225-1505728324.77209-1688482940', '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 03:25:10 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 03:25:10 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 03:25:10 INFO - Using env: {'DISPLAY': ':0', 03:25:10 INFO - 'HOME': '/home/cltbld', 03:25:10 INFO - 'LANG': 'en_US.UTF-8', 03:25:10 INFO - 'LANGUAGE': 'en_US:en', 03:25:10 INFO - 'LOGNAME': 'cltbld', 03:25:10 INFO - 'MAIL': '/var/mail/cltbld', 03:25:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:25:10 INFO - 'MOZ_NO_REMOTE': '1', 03:25:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:25:10 INFO - 'NO_EM_RESTART': '1', 03:25:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:25:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:25:10 INFO - 'PWD': '/builds/slave/test', 03:25:10 INFO - 'SHELL': '/bin/bash', 03:25:10 INFO - 'SHLVL': '1', 03:25:10 INFO - 'TERM': 'linux', 03:25:10 INFO - 'TMOUT': '86400', 03:25:10 INFO - 'USER': 'cltbld', 03:25:10 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1505728324.77209-1688482940', 03:25:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:25:10 INFO - '_': '/tools/buildbot/bin/python'} 03:25:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:25:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:25:10 INFO - Running setup.py (path:/tmp/pip-viNOih-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:25:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:25:10 INFO - Running setup.py (path:/tmp/pip-J2NanT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:25:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:25:10 INFO - Running setup.py (path:/tmp/pip-X3WaZ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:25:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:25:10 INFO - Running setup.py (path:/tmp/pip-mMrVZg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:25:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:25:10 INFO - Running setup.py (path:/tmp/pip-nRhXvT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:25:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:25:10 INFO - Running setup.py (path:/tmp/pip-5Xb080-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:25:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:25:10 INFO - Running setup.py (path:/tmp/pip-BFrV2M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:25:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:25:10 INFO - Running setup.py (path:/tmp/pip-jN5rKs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:25:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:25:11 INFO - Running setup.py (path:/tmp/pip-bi4Voq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:25:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:25:11 INFO - Running setup.py (path:/tmp/pip-sRMXWS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:25:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:25:11 INFO - Running setup.py (path:/tmp/pip-dWxIol-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:25:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:25:11 INFO - Running setup.py (path:/tmp/pip-LTXhXe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:25:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:25:11 INFO - Running setup.py (path:/tmp/pip-9DnWpJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:25:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:25:11 INFO - Running setup.py (path:/tmp/pip-riMBCY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:25:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:25:11 INFO - Running setup.py (path:/tmp/pip-ePOLgB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:25:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:25:11 INFO - Running setup.py (path:/tmp/pip-6TghjX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:25:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:25:11 INFO - Running setup.py (path:/tmp/pip-p7VZbK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:25:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:25:11 INFO - Running setup.py install for manifestparser 03:25:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:25:11 INFO - Running setup.py install for mozcrash 03:25:11 INFO - Running setup.py install for mozdebug 03:25:11 INFO - Running setup.py install for mozdevice 03:25:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:25:12 INFO - Running setup.py install for mozfile 03:25:12 INFO - Running setup.py install for mozhttpd 03:25:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:25:12 INFO - Running setup.py install for mozinfo 03:25:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:25:12 INFO - Running setup.py install for mozInstall 03:25:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:25:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:25:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:25:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:25:12 INFO - Running setup.py install for mozleak 03:25:12 INFO - Running setup.py install for mozlog 03:25:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:25:12 INFO - Running setup.py install for moznetwork 03:25:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:25:12 INFO - Running setup.py install for mozprocess 03:25:12 INFO - Running setup.py install for mozprofile 03:25:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:25:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:25:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:25:13 INFO - Running setup.py install for mozrunner 03:25:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:25:13 INFO - Running setup.py install for mozscreenshot 03:25:13 INFO - Running setup.py install for moztest 03:25:13 INFO - Running setup.py install for mozversion 03:25:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:25:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:25:13 INFO - Cleaning up... 03:25:13 INFO - Return code: 0 03:25:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:25:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:25:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:25:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:25:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:25:13 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 0xd551f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf1ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c1a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5090>, '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 0x10c5d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bff90>, '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': 'df17445c3590ff7897617a0e00000225-1505728324.77209-1688482940', '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 03:25:13 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 03:25:13 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 03:25:13 INFO - Using env: {'DISPLAY': ':0', 03:25:13 INFO - 'HOME': '/home/cltbld', 03:25:13 INFO - 'LANG': 'en_US.UTF-8', 03:25:13 INFO - 'LANGUAGE': 'en_US:en', 03:25:13 INFO - 'LOGNAME': 'cltbld', 03:25:13 INFO - 'MAIL': '/var/mail/cltbld', 03:25:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:25:13 INFO - 'MOZ_NO_REMOTE': '1', 03:25:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:25:13 INFO - 'NO_EM_RESTART': '1', 03:25:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:25:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:25:13 INFO - 'PWD': '/builds/slave/test', 03:25:13 INFO - 'SHELL': '/bin/bash', 03:25:13 INFO - 'SHLVL': '1', 03:25:13 INFO - 'TERM': 'linux', 03:25:13 INFO - 'TMOUT': '86400', 03:25:13 INFO - 'USER': 'cltbld', 03:25:13 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1505728324.77209-1688482940', 03:25:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:25:13 INFO - '_': '/tools/buildbot/bin/python'} 03:25:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:25:13 INFO - Running setup.py (path:/tmp/pip-pZwIQG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:25:13 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)) 03:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:25:13 INFO - Running setup.py (path:/tmp/pip-D4aoYt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:25:13 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)) 03:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:25:13 INFO - Running setup.py (path:/tmp/pip-opgCnd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:25:13 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)) 03:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:25:13 INFO - Running setup.py (path:/tmp/pip-yPpiAu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:25:13 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)) 03:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:25:13 INFO - Running setup.py (path:/tmp/pip-5hitgT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:25:14 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)) 03:25:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:25:14 INFO - Running setup.py (path:/tmp/pip-wYM2QH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:25:14 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)) 03:25:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:25:14 INFO - Running setup.py (path:/tmp/pip-2PNX45-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:25:14 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)) 03:25:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:25:14 INFO - Running setup.py (path:/tmp/pip-uZHcWo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:25:14 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)) 03:25:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:25:14 INFO - Running setup.py (path:/tmp/pip-wCp6cU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:25:14 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)) 03:25:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:25:14 INFO - Running setup.py (path:/tmp/pip-FFnPw2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:25:14 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)) 03:25:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:25:14 INFO - Running setup.py (path:/tmp/pip-IL9Cv2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:25:14 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)) 03:25:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:25:14 INFO - Running setup.py (path:/tmp/pip-HQsN6k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:25:14 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)) 03:25:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:25:14 INFO - Running setup.py (path:/tmp/pip-8mimMQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:25:14 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)) 03:25:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:25:14 INFO - Running setup.py (path:/tmp/pip-FJI_yx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:25:14 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)) 03:25:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:25:14 INFO - Running setup.py (path:/tmp/pip-04iouo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:25:14 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)) 03:25:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:25:14 INFO - Running setup.py (path:/tmp/pip-RNlQQ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:25:14 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)) 03:25:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:25:14 INFO - Running setup.py (path:/tmp/pip-JW2msH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:25:14 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)) 03:25:14 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)) 03:25:14 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)) 03:25:14 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)) 03:25:14 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)) 03:25:14 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)) 03:25:14 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)) 03:25:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:25:14 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 03:25:14 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 03:25:14 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 03:25:14 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 03:25:16 INFO - Downloading blessings-1.6.tar.gz 03:25:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:25:16 INFO - Installing collected packages: blessings 03:25:16 INFO - Running setup.py install for blessings 03:25:16 INFO - Successfully installed blessings 03:25:16 INFO - Cleaning up... 03:25:16 INFO - Return code: 0 03:25:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:25:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:25:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:25:16 INFO - Reading from file tmpfile_stdout 03:25:16 INFO - Current package versions: 03:25:16 INFO - argparse == 1.2.1 03:25:16 INFO - blessings == 1.6 03:25:16 INFO - blobuploader == 1.2.4 03:25:16 INFO - docopt == 0.6.1 03:25:16 INFO - functools32 == 3.2.3-2 03:25:16 INFO - jsonschema == 2.5.1 03:25:16 INFO - manifestparser == 1.1 03:25:16 INFO - mozInstall == 1.13 03:25:16 INFO - mozcrash == 1.0 03:25:16 INFO - mozdebug == 0.1 03:25:16 INFO - mozdevice == 0.51 03:25:16 INFO - mozfile == 1.2 03:25:16 INFO - mozhttpd == 0.7 03:25:16 INFO - mozinfo == 0.10 03:25:16 INFO - mozleak == 0.1 03:25:16 INFO - mozlog == 3.5 03:25:16 INFO - moznetwork == 0.27 03:25:16 INFO - mozprocess == 0.25 03:25:16 INFO - mozprofile == 0.28 03:25:16 INFO - mozrunner == 6.13 03:25:16 INFO - mozscreenshot == 0.1 03:25:16 INFO - mozsystemmonitor == 0.3 03:25:16 INFO - moztest == 0.8 03:25:16 INFO - mozversion == 1.4 03:25:16 INFO - psutil == 3.1.1 03:25:16 INFO - requests == 1.2.3 03:25:16 INFO - wsgiref == 0.1.2 03:25:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:25:16 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')]} 03:25:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:25:16 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')]} 03:25:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:25:16 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 0xd551f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf1ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c1a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5090>, '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 0x10c5d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bff90>, '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': 'df17445c3590ff7897617a0e00000225-1505728324.77209-1688482940', '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 03:25:16 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 03:25:16 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 03:25:16 INFO - Using env: {'DISPLAY': ':0', 03:25:16 INFO - 'HOME': '/home/cltbld', 03:25:16 INFO - 'LANG': 'en_US.UTF-8', 03:25:16 INFO - 'LANGUAGE': 'en_US:en', 03:25:16 INFO - 'LOGNAME': 'cltbld', 03:25:16 INFO - 'MAIL': '/var/mail/cltbld', 03:25:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:25:16 INFO - 'MOZ_NO_REMOTE': '1', 03:25:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:25:16 INFO - 'NO_EM_RESTART': '1', 03:25:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:25:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:25:16 INFO - 'PWD': '/builds/slave/test', 03:25:16 INFO - 'SHELL': '/bin/bash', 03:25:16 INFO - 'SHLVL': '1', 03:25:16 INFO - 'TERM': 'linux', 03:25:16 INFO - 'TMOUT': '86400', 03:25:16 INFO - 'USER': 'cltbld', 03:25:16 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1505728324.77209-1688482940', 03:25:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:25:16 INFO - '_': '/tools/buildbot/bin/python'} 03:25:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:25:16 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)) 03:25:16 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)) 03:25:16 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)) 03:25:16 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)) 03:25:16 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)) 03:25:16 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)) 03:25:16 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)) 03:25:16 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)) 03:25:16 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)) 03:25:16 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:25:16 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 03:25:16 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 03:25:16 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 03:25:16 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 03:25:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 03:25:17 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)) 03:25:17 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)) 03:25:17 INFO - Installing collected packages: simplejson 03:25:17 INFO - Running setup.py install for simplejson 03:25:17 INFO - building 'simplejson._speedups' extension 03:25:17 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 03:25:18 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 03:25:18 INFO - Successfully installed simplejson 03:25:18 INFO - Cleaning up... 03:25:18 INFO - Return code: 0 03:25:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:25:18 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')]} 03:25:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:25:18 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')]} 03:25:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:25:18 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 0xd551f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf1ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c1a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c5090>, '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 0x10c5d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bff90>, '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': 'df17445c3590ff7897617a0e00000225-1505728324.77209-1688482940', '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 03:25:18 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 03:25:18 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 03:25:18 INFO - Using env: {'DISPLAY': ':0', 03:25:18 INFO - 'HOME': '/home/cltbld', 03:25:18 INFO - 'LANG': 'en_US.UTF-8', 03:25:18 INFO - 'LANGUAGE': 'en_US:en', 03:25:18 INFO - 'LOGNAME': 'cltbld', 03:25:18 INFO - 'MAIL': '/var/mail/cltbld', 03:25:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:25:18 INFO - 'MOZ_NO_REMOTE': '1', 03:25:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:25:18 INFO - 'NO_EM_RESTART': '1', 03:25:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:25:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:25:18 INFO - 'PWD': '/builds/slave/test', 03:25:18 INFO - 'SHELL': '/bin/bash', 03:25:18 INFO - 'SHLVL': '1', 03:25:18 INFO - 'TERM': 'linux', 03:25:18 INFO - 'TMOUT': '86400', 03:25:18 INFO - 'USER': 'cltbld', 03:25:18 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1505728324.77209-1688482940', 03:25:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:25:18 INFO - '_': '/tools/buildbot/bin/python'} 03:25:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:25:19 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:25:19 INFO - Cleaning up... 03:25:19 INFO - Return code: 0 03:25:19 INFO - Running post-action listener: _resource_record_post_action 03:25:19 INFO - Running post-action listener: _start_resource_monitoring 03:25:19 INFO - Starting resource monitoring. 03:25:19 INFO - [mozharness: 2017-09-18 10:25:19.178009Z] Finished create-virtualenv step (success) 03:25:19 INFO - [mozharness: 2017-09-18 10:25:19.178518Z] Running install step. 03:25:19 INFO - Running pre-action listener: _resource_record_pre_action 03:25:19 INFO - Running main action method: install 03:25:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:25:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:25:19 INFO - Reading from file tmpfile_stdout 03:25:19 INFO - Detecting whether we're running mozinstall >=1.0... 03:25:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:25:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:25:19 INFO - Reading from file tmpfile_stdout 03:25:19 INFO - Output received: 03:25:19 INFO - Usage: mozinstall [options] installer 03:25:19 INFO - Options: 03:25:19 INFO - -h, --help show this help message and exit 03:25:19 INFO - -d DEST, --destination=DEST 03:25:19 INFO - Directory to install application into. [default: 03:25:19 INFO - "/builds/slave/test"] 03:25:19 INFO - --app=APP Application being installed. [default: firefox] 03:25:19 INFO - mkdir: /builds/slave/test/build/application 03:25:19 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'] 03:25:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 03:25:37 INFO - Reading from file tmpfile_stdout 03:25:37 INFO - Output received: 03:25:37 INFO - /builds/slave/test/build/application/firefox/firefox 03:25:37 INFO - Running post-action listener: _resource_record_post_action 03:25:37 INFO - [mozharness: 2017-09-18 10:25:37.479181Z] Finished install step (success) 03:25:37 INFO - [mozharness: 2017-09-18 10:25:37.479430Z] Running setup-mitmproxy step. 03:25:37 INFO - Running pre-action listener: _resource_record_pre_action 03:25:37 INFO - Running main action method: setup_mitmproxy 03:25:37 INFO - Skipping: mitmproxy is not required 03:25:37 INFO - Running post-action listener: _resource_record_post_action 03:25:37 INFO - [mozharness: 2017-09-18 10:25:37.480551Z] Finished setup-mitmproxy step (success) 03:25:37 INFO - [mozharness: 2017-09-18 10:25:37.480771Z] Running run-tests step. 03:25:37 INFO - Running pre-action listener: _resource_record_pre_action 03:25:37 INFO - Running pre-action listener: _set_gcov_prefix 03:25:37 INFO - Running main action method: run_tests 03:25:37 WARNING - Try message not found. 03:25:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:25:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:25:37 INFO - Python 2.7.3 03:25:37 INFO - Return code: 0 03:25:37 INFO - grabbing minidump binary from tooltool 03:25:37 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')]} 03:25:37 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 0x10c5090>, '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 0x10c5d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bff90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:25:37 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 03:25:37 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 03:25:37 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 03:25:37 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:25:37 INFO - Return code: 0 03:25:37 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:25:37 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:25:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:25:37 INFO - ENV: RUST_BACKTRACE is now full 03:25:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:25:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:25:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/L5VTY1pKRgOdH0QnkhRnug/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-021', '--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 03:25:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/L5VTY1pKRgOdH0QnkhRnug/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-021 --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 03:25:37 INFO - Using env: {'DISPLAY': ':0', 03:25:37 INFO - 'HOME': '/home/cltbld', 03:25:37 INFO - 'LANG': 'en_US.UTF-8', 03:25:37 INFO - 'LANGUAGE': 'en_US:en', 03:25:37 INFO - 'LOGNAME': 'cltbld', 03:25:37 INFO - 'MAIL': '/var/mail/cltbld', 03:25:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:25:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:25:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:25:37 INFO - 'MOZ_NO_REMOTE': '1', 03:25:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:25:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:25:37 INFO - 'NO_EM_RESTART': '1', 03:25:37 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:25:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:25:37 INFO - 'PWD': '/builds/slave/test', 03:25:37 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:25:37 INFO - 'RUST_BACKTRACE': 'full', 03:25:37 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:25:37 INFO - 'SHELL': '/bin/bash', 03:25:37 INFO - 'SHLVL': '1', 03:25:37 INFO - 'TERM': 'linux', 03:25:37 INFO - 'TMOUT': '86400', 03:25:37 INFO - 'USER': 'cltbld', 03:25:37 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1505728324.77209-1688482940', 03:25:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:25:37 INFO - '_': '/tools/buildbot/bin/python'} 03:25:37 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/L5VTY1pKRgOdH0QnkhRnug/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-021', '--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 03:25:37 INFO - mozversion application_buildid: 20170918093516 03:25:37 INFO - mozversion application_changeset: ffe6cc09ccf38cca6f0e727837bbc6cb722d1e71 03:25:37 INFO - mozversion application_display_name: Nightly 03:25:37 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:25:37 INFO - mozversion application_name: Firefox 03:25:37 INFO - mozversion application_remotingname: firefox 03:25:37 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:25:37 INFO - mozversion application_vendor: Mozilla 03:25:37 INFO - mozversion application_version: 57.0a1 03:25:37 INFO - mozversion platform_buildid: 20170918093516 03:25:37 INFO - mozversion platform_changeset: ffe6cc09ccf38cca6f0e727837bbc6cb722d1e71 03:25:37 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:25:37 INFO - mozversion platform_version: 57.0a1 03:25:37 INFO - using testdate: 1505730337 03:25:37 INFO - actual date: 1505730337 03:25:37 INFO - starting webserver on 'localhost:32831' 03:25:37 INFO - SUITE-START | Running 2 tests 03:25:37 INFO - TEST-START | dromaeo_css 03:25:37 INFO - Initialising browser for dromaeo_css test... 03:25:37 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:32831/getInfo.html -profile /tmp/tmpsUFs7C/profile 03:25:37 INFO - TEST-INFO | started process 26181 (/builds/slave/test/build/application/firefox/firefox http://localhost:32831/getInfo.html) 03:25:44 INFO - TEST-INFO | 26181: exit 0 03:25:44 INFO - Browser initialized. 03:25:44 INFO - Running cycle 1/1 for dromaeo_css test... 03:25:44 INFO - Using env: {'DISPLAY': ':0', 03:25:44 INFO - 'HOME': '/home/cltbld', 03:25:44 INFO - 'JSGC_DISABLE_POISONING': '1', 03:25:44 INFO - 'LANG': 'en_US.UTF-8', 03:25:44 INFO - 'LANGUAGE': 'en_US:en', 03:25:44 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:25:44 INFO - 'LOGNAME': 'cltbld', 03:25:44 INFO - 'MAIL': '/var/mail/cltbld', 03:25:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:25:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:25:44 INFO - 'MOZ_CRASHREPORTER': '1', 03:25:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:25:44 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:25:44 INFO - 'MOZ_NO_REMOTE': '1', 03:25:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:25:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:25:44 INFO - 'NO_EM_RESTART': '1', 03:25:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:25:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:25:44 INFO - 'PWD': '/builds/slave/test', 03:25:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:25:44 INFO - 'RUST_BACKTRACE': 'full', 03:25:44 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:25:44 INFO - 'SHELL': '/bin/bash', 03:25:44 INFO - 'SHLVL': '1', 03:25:44 INFO - 'TERM': 'linux', 03:25:44 INFO - 'TMOUT': '86400', 03:25:44 INFO - 'USER': 'cltbld', 03:25:44 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1505728324.77209-1688482940', 03:25:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:25:44 INFO - '_': '/tools/buildbot/bin/python'} 03:25:44 INFO - TEST-INFO | started process 26415 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsUFs7C/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:25:45 INFO - PID 26415 | 03:25:45 INFO - PID 26415 | (/builds/slave/test/build/application/firefox/firefox:26464): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:25:45 INFO - PID 26415 | 03:25:55 INFO - PID 26415 | RSS: Main: 155344896 03:25:55 INFO - PID 26415 | 03:28:53 INFO - PID 26415 | Cycle 1(1): loaded http://localhost:32831/tests/dromaeo/cssquery-dojo.html (next: http://localhost:32831/tests/dromaeo/cssquery-ext.html) 03:28:54 INFO - PID 26415 | RSS: Main: 155635712 03:28:54 INFO - PID 26415 | 03:31:56 INFO - PID 26415 | Cycle 1(1): loaded http://localhost:32831/tests/dromaeo/cssquery-ext.html (next: http://localhost:32831/tests/dromaeo/cssquery-jquery.html) 03:31:56 INFO - PID 26415 | RSS: Main: 155697152 03:31:56 INFO - PID 26415 | 03:34:58 INFO - PID 26415 | Cycle 1(1): loaded http://localhost:32831/tests/dromaeo/cssquery-jquery.html (next: http://localhost:32831/tests/dromaeo/cssquery-mootools.html) 03:34:59 INFO - PID 26415 | RSS: Main: 157622272 03:34:59 INFO - PID 26415 | 03:38:01 INFO - PID 26415 | Cycle 1(1): loaded http://localhost:32831/tests/dromaeo/cssquery-mootools.html (next: http://localhost:32831/tests/dromaeo/cssquery-prototype.html) 03:38:01 INFO - PID 26415 | RSS: Main: 156975104 03:38:01 INFO - PID 26415 | 03:41:05 INFO - PID 26415 | Cycle 1(1): loaded http://localhost:32831/tests/dromaeo/cssquery-prototype.html (next: http://localhost:32831/tests/dromaeo/cssquery-yui.html) 03:41:05 INFO - PID 26415 | RSS: Main: 157048832 03:41:05 INFO - PID 26415 | 03:44:07 INFO - PID 26415 | Cycle 1(1): loaded http://localhost:32831/tests/dromaeo/cssquery-yui.html (next: http://localhost:32831/tests/dromaeo/cssquery-dojo.html) 03:44:07 INFO - PID 26415 | RSS: Main: 159625216 03:44:07 INFO - PID 26415 | 03:44:07 INFO - PID 26415 | __start_tp_report 03:44:07 INFO - PID 26415 | _x_x_mozilla_page_load 03:44:07 INFO - PID 26415 | _x_x_mozilla_page_load_details 03:44:07 INFO - PID 26415 | |i|pagename|runs| 03:44:07 INFO - PID 26415 | |0;dojo.html;3144.5597616333716;3165.414398336308;3180.6024246969127;3181.0138856954345;3191.8563664635103;6234.719437625306;6299.685015749205;6320.968395158024;6370.617762934227;6424.1648585683915;6299.748010079603;6317.33667964864;6319.905201421982;6329.272133704625;6368.713407896645;5780.710964451781;5906.26171728534;5926.496247818941;5948.940510594882;5949.85125371867;5706.087026075828;5844.766209351621;5931.258592675916;6001.2498437695285;6052.031674932012;5443.782248710047;5768;5849.795257165979;5861.150133230679;5908.172855800191;5813.09896965971;5857.648541087548;5905.911411328833;5917.970410147965;5934.673592952386;5689.089745640677;5762.1068734346245;5764.337101233328;5819.156222347779;5875.38308477608;6286.18279623646;6335;6353.5870168438905;6393.808185754435;6418.839529011812;6770.390664840139;6784.932150678479;6876.828079298008;6890.37986581205;6894.24163342032;6852.314768523158;6880.208775990725;6945.2707465716385;6963.268856770017;6970.825729356756;6205;6276.027215781562;6277.8744425111245;6278.1210630511305;6310.400511951403;6990.930090699129;7027.929720702829;7057.36483716468;7058;7098.467614928901;6667.499937504706;6718.630475323904;6740.62926539045;6747.426468750211;6755.1556055493065;6757.8648427031185;6772.966135169293;6801.795946121624;6860;6876.209235937931;6627.237867645186;6734.3602357775935;6759.324067593201;6781.423578995742;6826.658667066627;6693.297203793629;6762.594244345355;6788.796336109925;6792.0830687856505;6794.762183323659;5306.416294207634;5316.069687804618;5324.068288049576;5326.334208223972;5329.200619907045;4695.319178719066;4716.787744551503;4731.077439899186;4772.689775164603;4776.47458779534;5766.654000759968;5775;5859.794907178229;5865.530757539386;5872.265966754156;6747.089142965637;6786.524943253925;6813.727450902007;6888.173419189729;6916.757913473004;5759.366469688331;5784.94215057844;5794.82615521535;5794.88410231793;5853;5745.511631511285;5752.539796816244;5804.6807425592;5833.241678581842;5851.882962340815;6483.513736469784;6498.187726534184;6575.210974682973;6579.901301480481;6676.031975363642;6589.571677840925;6776.28848970851;6777.356151165632;6779.423748981392;6788.321167883172;6535.934640653532;6552.63960482159;6557.082008518716;6558.672066396565;6597.043428702716;6662.666866656551;6697.9953007049335;6758.662066896733;6769.221539523018;6781.254062053072;6822;6824.965875170592;6886.48351373639;6887.965560172167;6900.068490753684;6448.774292899726;6564.048213009182;6564.081028656087;6576.868462630816;6652.2349929756765;6578;6593.109930159367;6601.50488713358;6605.207375114825;6666.866662666816;6467.900456922241;6541.051547525677;6612.537122401386;6626.27111017778;6630.038644396632;6621.27166011748;6659.833504162435;6689.966550167218;6738.629375384401;6764.019217213378;6244.469220116341;6345.270293916259;6351.745930162927;6364.77723279683;6518.902216466662 03:44:07 INFO - PID 26415 | |1;ext.html;844.3329769482109;870.3733312015347;872.7687162901832;873.0222151190667;879.2087121590572;41358.37962430566;42329;42415.78792106035;42447.36328955068;42457.150856982844;38547;38633.80683096588;38684.226315473745;39007.219855602874;39057;7144.0712707347975;7226.891596626055;7234.60209688467;7268.3458488736005;7278.454115941326;7121.572705637653;7213.134423869117;7214.134303883541;7215.278472152769;7216.891746623779;7504.54972701637;7651.885221721679;7662.272084151997;7667.923320766805;7691.077070751518;7532.849343013137;7562.054743157106;7610.315071643551;7624.961875190644;7629.923700762977;7635.694572217105;7829.451938364316;7835.373170146431;7850.293473587404;7853.8036549086155;37322;37580.62419375826;37776.24447511035;37782.24435511274;37849.62150378516;3514.1390359361862;3534;3553.022918697353;3554.289142171576;3557.857685692595;31222.53166202509;31501.36997260042;31577.526337104962;31608.683913160803;31618.683813162035;5186;5200.947990520084;5222.63441559091;5246.685198888041;5264.2893209416625;186476;186769;186949;186950;186976;41518;42614.78692606579;46810.29784553235;48369;48929.510704893204;49884.750576246886;50065;50214;50281.24578131331;50395.496045039814;15972.840271597135;16001.199940003185;16006.11966341839;16077;16086.034837909529;13023.348832558335;13074.084814062924;13099.476020959055;13263.801042984363;13296.401661925043;13506.797398038841;13599.59201223965;13696.109752866032;13850.584482465541;13912.37394317258;5584.720763961867;5637.887242255214;5658.292713410824;5686.459786320293;5723;1008.3596915958419;1019.918406527476;1022.9846552301722;1028.8456731490337;1034.8706411698538;22887.771122288897;23094;23124.190653327052;23292.883535582212;23322.65016024761;1111.127764704703;1112.5660992213043;1115.2249186815086;1126.1159989408275;1149.844770955927;12053.81919271194;12129.332886691316;12329.321887296284;12338.383080845922;12342.259464432163;221340;222679;222956;223169;223629;54011.729941350044;54032;54046.18930716042;54061.189082163015;54070.459295406545;1458.1324112153252;1462.8464011278663;1477.5567329801017;1483.1101339196396;1485.6805786755683;22259.109635614393;22322;22442.887785560968;22446.551068978857;22662.093516259825;37859.432108517845;37892.24215515729;37892.62107379001;37951.24097517979;38027;197856;198489;198877;199030;199127;33928.15179620529;34061;35215.47176792299;36061.0984725384;36167.27665446729;45340;46289.3056604158;46379.76810115928;46652.300215496114;46809;33218.833905831285;33718.49422258619;33812.492812608325;33859;34585.135371615914;33139.50290745593;33627.982321060816;33751.15622109467;33763;34234.82882585571;37421.4386784193;37569.62430375771;37772.24455510819;37783.433248501846;37833.243335133695;32392.83803581061;32756.50865237071;32814.34371312608;32981.83509082535;33052.17369565684;24404.51190976135;24453.51092978166;24712.629310560715;24799.752002479745;24868.875655621607 03:44:07 INFO - PID 26415 | |2;jquery.html;553.6927005511942;556.8385168301193;561.1190431023296;571.0520535910389;581.7498475655469;21657.891710541444;22603.547929041408;22630;22651.20720774773;22693;23292.650610240817;24195.51608967824;24364.51270974584;24525.264242072768;24645.876770616167;6463.7737679181355;6574.112494813211;6614.0740296358545;6615.33846615337;6637.867242655168;6507.609543427386;6604.735810567571;6606;6611.603303801763;6803.149606299213;7115.750948716796;7155.785326440189;7158.713651453935;7160.391366733807;7165.176004759442;7117.217106118323;7120.252373500785;7120.679569419389;7121.501494895385;7146.714131434736;6480.092787009822;6774.458043356569;6793.932060679429;6798.354156355138;6815.2162501312;29112.8544357279;29682;29796.702032979825;29940.251493712614;30017.549736753965;2079.334612923865;2083.7707852136255;2096.067250073718;2102.9474263143543;2103.2743703422293;18441.631167376712;18974.81025189758;19235.13441895118;19247.807521924882;19809.9009504953;2529.4941011797714;2591.0672158023285;2613.4381108061857;2616.136674897279;2631.7499837515597;124077;127749;132210;137391;143682.28158859242;19194.328198513263;20012.799232046124;20266.59466810685;20654.690179647318;20946.47633809167;41282.79358603248;41292;41422.37866432006;41483;41539.58460415417;10995.175361847894;11053.392063436588;11204.607838725606;11291.661250162344;11291.661250162344;9328.300377471716;9376.718698439057;9393.20157786582;9404.435733855991;9425.104615061558;10725.356478611307;10839.807621161666;10870.673879783619;10945.23383363157;11143.665690029313;3722.3299806034634;3722.6091260417807;4077.9796101019306;4086.9182616347507;4088.4889388826396;918.4856480371013;942.561708805395;963.3641796414333;968.8498282766183;970.3062310448064;18416.35542755997;18698.252069917053;18950.52623684419;19159.904200478908;19209.80790192108;1020.2807021050141;1030.4744580264119;1036.1710631494775;1044.3577200021932;1045.0542259255385;10249.282550221413;10294;10417.947910260551;10439.739006524898;10731.678049658523;494277;495730;503250;503825;504835;112344;113007.43496282466;114177;114629;114687.42656286832;1138.6089212653362;1154.3131836557068;1171.437709899236;1172.267332916927;1180.9232399893979;16814.495565133064;16859.904106233054;17071.402500912904;17227.913860430617;17347.739783903504;52318.43044708513;54112.729436352565;54880.72559637176;55502.44497555134;55947.160792588955;66548.33451665421;67854.3214567848;67893.66053169702;68271.3172868265;68447.31552684409;18664;19101.617967640846;19250.614987700446;19288.517787055436;19289;21346.252881149085;21403.46491337729;21554.461138471037;21577.460563486038;21961.121555137615;16012.599685007503;17375.56561085983;17732.29070837152;17926;17946.641067178844;14844.777328339867;15535.922320388325;15666.686666266838;16165;16244.918775406048;17668.38160664422;17821.10894455298;17860.55348616243;17975.191116400056;17993.640127197643;18540.536486587403;18564.44306670802;18755;18790.342338018105;18831.90584047071;17086.658266834842;17203.967761934324;17324.220410081827;17352.132393380532;17366.21852016637 03:44:07 INFO - PID 26415 | |3;mootools.html;1366.5831921264016;1420.9076410033347;1444.4799872046062;1445.9927700361498;1446.0672866001437;14560.635984100403;15273.312700928456;15378.53864384069;15399.461018864327;15428.074315541075;15050.698986020274;15188.392464301414;15195.54413367598;15207.163606001666;15238.619034524143;2721.7958653100995;2858.3568697043206;2859.599656048155;2861.1130549529607;2862.341661417875;2654.4956458272964;2777.4445110977786;2797.580362945564;2800.7759379249715;2801.145650576573;2282.8858557072163;2357.5167090746354;2714.1043455659583;2811.12855014945;2814.521531339677;2741.451709658076;2749.5738160585142;2773.209635253953;2777.0558010276604;2783.2067860659718;2689.9058532951253;2719.2114286856986;2722.1561315992108;2724.986375068132;2738.7945904057274;14623.63440913975;14883.255837208097;14916.62708432287;14937.477188298466;14987.850121498754;7940.1662825403655;8233.423660343778;8233.670653173866;8237.176282371775;8277.592809222446;5194.610404219661;5319.760610772486;5524.944750552483;5533.142362933751;5549.361823390322;1938.6995015772436;1943.494691380237;1970.0642194957425;1978.099964516156;2009.6884982827685;26394.868025660133;26416;26617.866910665518;26635.86682066577;26734.197974060808;7154.713811447588;7229.783106506813;7460.179380268166;7512.661930213044;7516.736914207976;17642.911785440992;17770.111494425226;17780.3776867809;17782.377616783608;17820.55448613795;7843.568603726736;7887.132415434297;7928.8810667840025;7954.801129971681;8218.589070546568;7182.102237220348;7259;7347.816304592321;7510.812229694192;7614.885776713354;4884.242942343943;4991.850244492671;5090.643654944119;5098.286239926413;5112.463191364853;1335.2922950836073;1358.151155527795;1423.288355822089;1424.8646378594017;1442.6393401649589;831.9542425166674;832.7501749475133;837.8868852704807;840.2983508770124;845.8350621628846;8674.522901240367;8690.391672582859;8713.956430217808;8767.079456656958;8826.646934122691;717.6196615793638;735.2537174767615;786.6420778545748;913.1644545241163;987.775158803079;4995.750212489361;5055.570276526537;5179.8446046618665;5250.553702935294;5283.471652834763;20697.896510517352;20731.689024664644;21541.67687484689;21572.46068848291;21598.676019859715;20785;21142.894285528473;21240.150393984066;21529.67705484419;21581.24465643695;11318;11385.37380444067;11502.367369794742;11513.769724605461;11515.712107197387;8583.828323433621;8586.184312490303;8649.308055355683;8657.61040753155;8691.826163476822;18159.546011349823;18449.800762950366;18834.811651883305;18906.43280701581;18915.716264256323;18815.435536933914;18864.434066977465;18991.33530326432;19055.714164287823;19234.71147932754;10754.139668826632;10975.83536246941;10984.945075274572;11040.723981900517;11042.723931901766;13755;13799.2410417428;13911;14021.228832414316;14062.859371406155;11373.090152787923;11448.427578621202;11667;11749.353785541878;11829.231099978473;10932.781344372908;11139.832902506307;11270.94364528172;11315;11581.7104572383;10050;10131;10179.592816287563;10189.592416303265;10282.07461328484;7287.672054757442;7362.042934418706;7367.742129025459;7378.815529611803;7405.814854628462;7551.282628150317;7622.085349758065;7682.231776822273;7726.227377262228;7786.221377862168 03:44:07 INFO - PID 26415 | |4;prototype.html;494.54748904752154;509.419262041273;513.1328055586062;523.7783465269924;532.7549327309438;6159.353267906872;6199.504039676821;6218.191635087438;6307.62154270744;6325.620462772237;6165.969170154143;6201.224846894138;6217.316095229521;6228.321821616703;6247.406496382836;5157.200633901752;5238.5023422774875;5254.369475662926;5272.789088436458;5327;5359.785608575652;5362.632528705175;5375.43557926418;5380.515753582177;5413.431589683086;5329.120695085307;5331.120365139747;5342.893142137155;5355.169948657965;5368.62419630626;5300.628955973083;5328.520433161015;5331.786728530854;5354.9196762048605;5360.678359298455;5019.52314530119;5020.799168033274;5028.823991160329;5037.11850426174;5053.722045287508;6229.190205273331;6322.241331040259;6351.301356850743;6357.237131544198;6395.87208255837;6041.184440100574;6112.083187521863;6116.938830611682;6146.969265153645;6154.415330543591;6705.564138330993;6713.194416669982;6717.361850624183;6721.4958878084335;6737.326267373223;5397.676139431646;5411.702356370398;5412.91880621791;5432.483914028161;5444.257837491965;7089.680964356565;7140.571885622896;7152.4278057755255;7193.676284567207;7237.457190710665;5501.367342755634;5560.638558493685;5578.972105139448;5589.469000444951;5604.6843930174355;5644.717764111778;5683.26117604717;5688.857778555569;5704.372519022904;5711.143328500675;6088.995315772848;6121.510279177655;6156.107364432221;6176.289726681399;6195.380461953769;6157.384261573807;6484.870302593922;6717.261101278856;6723.361280678328;6846.520743547904;1909.9140538675792;1917.6548221320018;1940.8738432001874;1947.4839167620592;1950.6683863743199;1431.1484666623342;1435.1174027972722;1440.3590402271097;1451.1873350923518;1480.6668499587722;5627.593101724568;5647.237622920935;5699.629524080921;5737.426257374229;5758.452946970031;6628.933710662832;6672.366125218096;6673.432758215606;6793.150856142982;6806.6256355900905;5663.207150998864;5688.573356998241;5753.7054162813865;5775.393583673654;5780.132980052942;3521.841517131735;3604.8738294160103;3610.4403817408343;3634.47300141478;3644.4351125575577;6510.414062734377;6539.836504087436;6593.2088149421415;6639.734410623521;6647.806223946086;6828.190529509684;6843.418309443753;6873.725050998004;6939.8202305608165;6968.3728464438445;6398.456131228898;6433.067205255305;6545.639989800607;6551.312112228243;6563.113979612691;6757.797266082025;6779.288174741779;6781.321867813179;6787.898181527183;6885.311468853075;6699.363560461748;6740.359665831738;6769.729210831511;6792.558483698544;6816.182058153054;6494.4804415645685;6597.208334999831;6610.702518386588;6627.867442651216;6673.466122710074;5004.899902001866;5028.949710502994;5033.748312584283;5040.84877453677;5065.544101030925;6548.705308261235;6740.66296685154;6852.965735171292;6881.827954301182;6915.896261556181;6278.372162783685;6469.4824414045825;6553.543590358411;6637.2699003108655;6729.360710732472;6584.703688333941;6700.430463410665;6701.0953521274005;6707.396334329934;6727.899081513683;6338.447080465131;6462.256840463407;6499.995003847106;6500.479961602966;6524.804255872332;6588.868222635616;6607.207135143622;6628.76799312041;6718.563293385914;6751.426128779109;6379.979203327445;6414.903776443264;6446.548741588232;6474;6493.480521558358 03:44:07 INFO - PID 26415 | |5;yui.html;886.769439945614;894.3515950935571;910.9180173784363;912.498126030683;922.8892532896053;18451;19709.113089910952;19856.60286794265;19868.40394788155;19884.304049358274;17822.554436139104;18634.90682546589;18892.905535472302;19330.51673708158;19644.214231430724;938.4228699349902;944.5088553951941;947.1428357336587;947.2185447006214;947.2469386837481;939.2486011191054;954.9140577348037;955.9378640388387;960.231814548362;972.2027937091588;1023.6212601337515;1026.6663334416307;1026.8870424253325;1027.3117011602208;1028.511457057899;995.2088089968456;999.435319044741;1011.049613363436;1011.1253765492869;1011.6762635956534;735.9632018399112;736.2674139231445;737.9077615298088;738.2433006168667;738.3281214095201;18627;18700.812991870043;18740.812591874044;18780.90609546957;18847.62304753897;21364.359069227947;21726.782732172633;21874.343769686933;21892.343229702976;21894.562108757735;15797.21013949298;16331.673366532603;16358.509244722678;16410.261538230807;16423.83576164235;3766.133789228493;3804.524434445694;3808.314503389389;3808.6572208501366;3809.8666546670734;141179.29410352986;141931.29034354867;141970.29014854963;142027.99999999895;145789;16008.519744407686;16401.343946242017;16423.260953257133;16486.17569121563;16519.6696066078;28693.713062869523;30924.845375773428;30947.381052379278;31041.223969400944;31189;8705.738827835175;8746.125387461203;8764.430312029697;8814.162654547808;8870.600822962982;4189.120284740177;4272.743635381887;4277.358396240589;4283.186194623012;4295.591918767712;5395.21769343443;5461.2627295315415;5463.9180412293845;5481.040817856859;5487.6158668893595;985.3201291109111;985.522021819417;992.503748125937;1018.1752780248023;1019.23557332001;672.8418821576926;674.20780582815;679.0527214535695;684.8219462939572;690.1373283395756;11148.108151347873;11207.271527350696;11546.249493782878;12214.145009849226;12516.3741812909;648.9870202596016;654.2051407539767;662.3740565165969;667.7863083813133;670.6043434373743;5441.972790136103;5474.288342515448;5494.697791621499;5532.640378375393;5624.6625202487985;130065;135563;136011.31994340164;136363;137164.07953561057;112160;113283.43358283321;114039.42980285212;116422.41788791171;118534;99934.5003274979;100921.49539252257;101230;102496.48751756194;104034;10184.287099903231;10470.738231544272;10476.319039262728;10553.261271711213;10570;44108;44731.552684472736;45384;46678.06643867172;47004;58676;59565.7021714906;59820;61062.69468652628;61809;14560.417583296974;14888.776668349767;15098.622534436727;15122.243887805345;15137.697246054797;17154.228059737092;17311.48065558035;17337.826621734126;17444.12779361052;17492.91253543775;14706.411743530567;14786.112833230041;14975.026623269889;15109.39562417491;15213.543593692208;14856.40574377056;15099.245037747849;15147.394104236148;15198.696026079637;15202.239888005777;14271.500497482539;14399.136051836924;14481.710365792836;14603.269836508345;14632.634184145056;12145.574904878287;12253.816192756938;12298.077644176543;12339.383030848601;12672.936635316764;10984.450777460936;11017.11863050938;11096.33421994683;11173.162012849232;11174.05020573228 03:44:07 INFO - PID 26415 | __end_tp_report 03:44:07 INFO - PID 26415 | __start_cc_report 03:44:07 INFO - PID 26415 | _x_x_mozilla_cycle_collect,284 03:44:07 INFO - PID 26415 | __end_cc_report 03:44:07 INFO - PID 26415 | __startTimestamp1505731447459__endTimestamp 03:44:07 INFO - PID 26415 | 03:44:07 INFO - PID 26415 | ------- Summary: start ------- 03:44:07 INFO - PID 26415 | Number of tests: 6 03:44:07 INFO - PID 26415 | 03:44:07 INFO - PID 26415 | [#0] dojo.html Cycles:165 Average:6246.82 Median:6498.19 stddev:752.99 (11.6%) stddev-sans-first:714.89 03:44:07 INFO - PID 26415 | Values: 3144.6 3165.4 3180.6 3181.0 3191.9 6234.7 6299.7 6321.0 6370.6 6424.2 6299.7 6317.3 6319.9 6329.3 6368.7 5780.7 5906.3 5926.5 5948.9 5949.9 5706.1 5844.8 5931.3 6001.2 6052.0 5443.8 5768.0 5849.8 5861.2 5908.2 5813.1 5857.6 5905.9 5918.0 5934.7 5689.1 5762.1 5764.3 5819.2 5875.4 6286.2 6335.0 6353.6 6393.8 6418.8 6770.4 6784.9 6876.8 6890.4 6894.2 6852.3 6880.2 6945.3 6963.3 6970.8 6205.0 6276.0 6277.9 6278.1 6310.4 6990.9 7027.9 7057.4 7058.0 7098.5 6667.5 6718.6 6740.6 6747.4 6755.2 6757.9 6773.0 6801.8 6860.0 6876.2 6627.2 6734.4 6759.3 6781.4 6826.7 6693.3 6762.6 6788.8 6792.1 6794.8 5306.4 5316.1 5324.1 5326.3 5329.2 4695.3 4716.8 4731.1 4772.7 4776.5 5766.7 5775.0 5859.8 5865.5 5872.3 6747.1 6786.5 6813.7 6888.2 6916.8 5759.4 5784.9 5794.8 5794.9 5853.0 5745.5 5752.5 5804.7 5833.2 5851.9 6483.5 6498.2 6575.2 6579.9 6676.0 6589.6 6776.3 6777.4 6779.4 6788.3 6535.9 6552.6 6557.1 6558.7 6597.0 6662.7 6698.0 6758.7 6769.2 6781.3 6822.0 6825.0 6886.5 6888.0 6900.1 6448.8 6564.0 6564.1 6576.9 6652.2 6578.0 6593.1 6601.5 6605.2 6666.9 6467.9 6541.1 6612.5 6626.3 6630.0 6621.3 6659.8 6690.0 6738.6 6764.0 6244.5 6345.3 6351.7 6364.8 6518.9 03:44:07 INFO - PID 26415 | 03:44:07 INFO - PID 26415 | [#1] ext.html Cycles:180 Average:37425.31 Median:3553.66 stddev:52629.64 (1481.0%) stddev-sans-first:52705.60 03:44:07 INFO - PID 26415 | Values: 844.3 870.4 872.8 873.0 879.2 41358.4 42329.0 42415.8 42447.4 42457.2 38547.0 38633.8 38684.2 39007.2 39057.0 7144.1 7226.9 7234.6 7268.3 7278.5 7121.6 7213.1 7214.1 7215.3 7216.9 7504.5 7651.9 7662.3 7667.9 7691.1 7532.8 7562.1 7610.3 7625.0 7629.9 7635.7 7829.5 7835.4 7850.3 7853.8 37322.0 37580.6 37776.2 37782.2 37849.6 3514.1 3534.0 3553.0 3554.3 3557.9 31222.5 31501.4 31577.5 31608.7 31618.7 5186.0 5200.9 5222.6 5246.7 5264.3 186476.0 186769.0 186949.0 186950.0 186976.0 41518.0 42614.8 46810.3 48369.0 48929.5 49884.8 50065.0 50214.0 50281.2 50395.5 15972.8 16001.2 16006.1 16077.0 16086.0 13023.3 13074.1 13099.5 13263.8 13296.4 13506.8 13599.6 13696.1 13850.6 13912.4 5584.7 5637.9 5658.3 5686.5 5723.0 1008.4 1019.9 1023.0 1028.8 1034.9 22887.8 23094.0 23124.2 23292.9 23322.7 1111.1 1112.6 1115.2 1126.1 1149.8 12053.8 12129.3 12329.3 12338.4 12342.3 221340.0 222679.0 222956.0 223169.0 223629.0 54011.7 54032.0 54046.2 54061.2 54070.5 1458.1 1462.8 1477.6 1483.1 1485.7 22259.1 22322.0 22442.9 22446.6 22662.1 37859.4 37892.2 37892.6 37951.2 38027.0 197856.0 198489.0 198877.0 199030.0 199127.0 33928.2 34061.0 35215.5 36061.1 36167.3 45340.0 46289.3 46379.8 46652.3 46809.0 33218.8 33718.5 33812.5 33859.0 34585.1 33139.5 33628.0 33751.2 33763.0 34234.8 37421.4 37569.6 37772.2 37783.4 37833.2 32392.8 32756.5 32814.3 32981.8 33052.2 24404.5 24453.5 24712.6 24799.8 24868.9 03:44:07 INFO - PID 26415 | 03:44:07 INFO - PID 26415 | [#2] jquery.html Cycles:180 Average:35518.16 Median:21374.86 stddev:83801.78 (392.1%) stddev-sans-first:83995.74 03:44:07 INFO - PID 26415 | Values: 553.7 556.8 561.1 571.1 581.7 21657.9 22603.5 22630.0 22651.2 22693.0 23292.7 24195.5 24364.5 24525.3 24645.9 6463.8 6574.1 6614.1 6615.3 6637.9 6507.6 6604.7 6606.0 6611.6 6803.1 7115.8 7155.8 7158.7 7160.4 7165.2 7117.2 7120.3 7120.7 7121.5 7146.7 6480.1 6774.5 6793.9 6798.4 6815.2 29112.9 29682.0 29796.7 29940.3 30017.5 2079.3 2083.8 2096.1 2102.9 2103.3 18441.6 18974.8 19235.1 19247.8 19809.9 2529.5 2591.1 2613.4 2616.1 2631.7 124077.0 127749.0 132210.0 137391.0 143682.3 19194.3 20012.8 20266.6 20654.7 20946.5 41282.8 41292.0 41422.4 41483.0 41539.6 10995.2 11053.4 11204.6 11291.7 11291.7 9328.3 9376.7 9393.2 9404.4 9425.1 10725.4 10839.8 10870.7 10945.2 11143.7 3722.3 3722.6 4078.0 4086.9 4088.5 918.5 942.6 963.4 968.8 970.3 18416.4 18698.3 18950.5 19159.9 19209.8 1020.3 1030.5 1036.2 1044.4 1045.1 10249.3 10294.0 10417.9 10439.7 10731.7 494277.0 495730.0 503250.0 503825.0 504835.0 112344.0 113007.4 114177.0 114629.0 114687.4 1138.6 1154.3 1171.4 1172.3 1180.9 16814.5 16859.9 17071.4 17227.9 17347.7 52318.4 54112.7 54880.7 55502.4 55947.2 66548.3 67854.3 67893.7 68271.3 68447.3 18664.0 19101.6 19250.6 19288.5 19289.0 21346.3 21403.5 21554.5 21577.5 21961.1 16012.6 17375.6 17732.3 17926.0 17946.6 14844.8 15535.9 15666.7 16165.0 16244.9 17668.4 17821.1 17860.6 17975.2 17993.6 18540.5 18564.4 18755.0 18790.3 18831.9 17086.7 17204.0 17324.2 17352.1 17366.2 03:44:07 INFO - PID 26415 | 03:44:07 INFO - PID 26415 | [#3] mootools.html Cycles:180 Average:9391.82 Median:26626.87 stddev:6644.81 (25.0%) stddev-sans-first:6636.09 03:44:07 INFO - PID 26415 | Values: 1366.6 1420.9 1444.5 1446.0 1446.1 14560.6 15273.3 15378.5 15399.5 15428.1 15050.7 15188.4 15195.5 15207.2 15238.6 2721.8 2858.4 2859.6 2861.1 2862.3 2654.5 2777.4 2797.6 2800.8 2801.1 2282.9 2357.5 2714.1 2811.1 2814.5 2741.5 2749.6 2773.2 2777.1 2783.2 2689.9 2719.2 2722.2 2725.0 2738.8 14623.6 14883.3 14916.6 14937.5 14987.9 7940.2 8233.4 8233.7 8237.2 8277.6 5194.6 5319.8 5524.9 5533.1 5549.4 1938.7 1943.5 1970.1 1978.1 2009.7 26394.9 26416.0 26617.9 26635.9 26734.2 7154.7 7229.8 7460.2 7512.7 7516.7 17642.9 17770.1 17780.4 17782.4 17820.6 7843.6 7887.1 7928.9 7954.8 8218.6 7182.1 7259.0 7347.8 7510.8 7614.9 4884.2 4991.9 5090.6 5098.3 5112.5 1335.3 1358.2 1423.3 1424.9 1442.6 832.0 832.8 837.9 840.3 845.8 8674.5 8690.4 8714.0 8767.1 8826.6 717.6 735.3 786.6 913.2 987.8 4995.8 5055.6 5179.8 5250.6 5283.5 20697.9 20731.7 21541.7 21572.5 21598.7 20785.0 21142.9 21240.2 21529.7 21581.2 11318.0 11385.4 11502.4 11513.8 11515.7 8583.8 8586.2 8649.3 8657.6 8691.8 18159.5 18449.8 18834.8 18906.4 18915.7 18815.4 18864.4 18991.3 19055.7 19234.7 10754.1 10975.8 10984.9 11040.7 11042.7 13755.0 13799.2 13911.0 14021.2 14062.9 11373.1 11448.4 11667.0 11749.4 11829.2 10932.8 11139.8 11270.9 11315.0 11581.7 10050.0 10131.0 10179.6 10189.6 10282.1 7287.7 7362.0 7367.7 7378.8 7405.8 7551.3 7622.1 7682.2 7726.2 7786.2 03:44:07 INFO - PID 26415 | 03:44:07 INFO - PID 26415 | [#4] prototype.html Cycles:180 Average:5692.05 Median:6217.75 stddev:1517.85 (24.4%) stddev-sans-first:1471.12 03:44:07 INFO - PID 26415 | Values: 494.5 509.4 513.1 523.8 532.8 6159.4 6199.5 6218.2 6307.6 6325.6 6166.0 6201.2 6217.3 6228.3 6247.4 5157.2 5238.5 5254.4 5272.8 5327.0 5359.8 5362.6 5375.4 5380.5 5413.4 5329.1 5331.1 5342.9 5355.2 5368.6 5300.6 5328.5 5331.8 5354.9 5360.7 5019.5 5020.8 5028.8 5037.1 5053.7 6229.2 6322.2 6351.3 6357.2 6395.9 6041.2 6112.1 6116.9 6147.0 6154.4 6705.6 6713.2 6717.4 6721.5 6737.3 5397.7 5411.7 5412.9 5432.5 5444.3 7089.7 7140.6 7152.4 7193.7 7237.5 5501.4 5560.6 5579.0 5589.5 5604.7 5644.7 5683.3 5688.9 5704.4 5711.1 6089.0 6121.5 6156.1 6176.3 6195.4 6157.4 6484.9 6717.3 6723.4 6846.5 1909.9 1917.7 1940.9 1947.5 1950.7 1431.1 1435.1 1440.4 1451.2 1480.7 5627.6 5647.2 5699.6 5737.4 5758.5 6628.9 6672.4 6673.4 6793.2 6806.6 5663.2 5688.6 5753.7 5775.4 5780.1 3521.8 3604.9 3610.4 3634.5 3644.4 6510.4 6539.8 6593.2 6639.7 6647.8 6828.2 6843.4 6873.7 6939.8 6968.4 6398.5 6433.1 6545.6 6551.3 6563.1 6757.8 6779.3 6781.3 6787.9 6885.3 6699.4 6740.4 6769.7 6792.6 6816.2 6494.5 6597.2 6610.7 6627.9 6673.5 5004.9 5028.9 5033.7 5040.8 5065.5 6548.7 6740.7 6853.0 6881.8 6915.9 6278.4 6469.5 6553.5 6637.3 6729.4 6584.7 6700.4 6701.1 6707.4 6727.9 6338.4 6462.3 6500.0 6500.5 6524.8 6588.9 6607.2 6628.8 6718.6 6751.4 6380.0 6414.9 6446.5 6474.0 6493.5 03:44:07 INFO - PID 26415 | 03:44:07 INFO - PID 26415 | [#5] yui.html Cycles:180 Average:25007.75 Median:18814.26 stddev:37457.99 (199.1%) stddev-sans-first:37519.28 03:44:07 INFO - PID 26415 | Values: 886.8 894.4 910.9 912.5 922.9 18451.0 19709.1 19856.6 19868.4 19884.3 17822.6 18634.9 18892.9 19330.5 19644.2 938.4 944.5 947.1 947.2 947.2 939.2 954.9 955.9 960.2 972.2 1023.6 1026.7 1026.9 1027.3 1028.5 995.2 999.4 1011.0 1011.1 1011.7 736.0 736.3 737.9 738.2 738.3 18627.0 18700.8 18740.8 18780.9 18847.6 21364.4 21726.8 21874.3 21892.3 21894.6 15797.2 16331.7 16358.5 16410.3 16423.8 3766.1 3804.5 3808.3 3808.7 3809.9 141179.3 141931.3 141970.3 142028.0 145789.0 16008.5 16401.3 16423.3 16486.2 16519.7 28693.7 30924.8 30947.4 31041.2 31189.0 8705.7 8746.1 8764.4 8814.2 8870.6 4189.1 4272.7 4277.4 4283.2 4295.6 5395.2 5461.3 5463.9 5481.0 5487.6 985.3 985.5 992.5 1018.2 1019.2 672.8 674.2 679.1 684.8 690.1 11148.1 11207.3 11546.2 12214.1 12516.4 649.0 654.2 662.4 667.8 670.6 5442.0 5474.3 5494.7 5532.6 5624.7 130065.0 135563.0 136011.3 136363.0 137164.1 112160.0 113283.4 114039.4 116422.4 118534.0 99934.5 100921.5 101230.0 102496.5 104034.0 10184.3 10470.7 10476.3 10553.3 10570.0 44108.0 44731.6 45384.0 46678.1 47004.0 58676.0 59565.7 59820.0 61062.7 61809.0 14560.4 14888.8 15098.6 15122.2 15137.7 17154.2 17311.5 17337.8 17444.1 17492.9 14706.4 14786.1 14975.0 15109.4 15213.5 14856.4 15099.2 15147.4 15198.7 15202.2 14271.5 14399.1 14481.7 14603.3 14632.6 12145.6 12253.8 12298.1 12339.4 12672.9 10984.5 11017.1 11096.3 11173.2 11174.1 03:44:07 INFO - PID 26415 | -------- Summary: end -------- 03:44:07 INFO - PID 26415 | 03:44:08 INFO - TEST-INFO | 26415: exit 0 03:44:08 INFO - TEST-OK | dromaeo_css | took 1110383ms 03:44:08 INFO - TEST-START | kraken 03:44:08 INFO - Initialising browser for kraken test... 03:44:08 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:32831/getInfo.html -profile /tmp/tmp8yEMnU/profile 03:44:08 INFO - TEST-INFO | started process 26791 (/builds/slave/test/build/application/firefox/firefox http://localhost:32831/getInfo.html) 03:44:14 INFO - TEST-INFO | 26791: exit 0 03:44:14 INFO - Browser initialized. 03:44:14 INFO - Running cycle 1/1 for kraken test... 03:44:14 INFO - Using env: {'DISPLAY': ':0', 03:44:14 INFO - 'HOME': '/home/cltbld', 03:44:14 INFO - 'JSGC_DISABLE_POISONING': '1', 03:44:14 INFO - 'LANG': 'en_US.UTF-8', 03:44:14 INFO - 'LANGUAGE': 'en_US:en', 03:44:14 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:44:14 INFO - 'LOGNAME': 'cltbld', 03:44:14 INFO - 'MAIL': '/var/mail/cltbld', 03:44:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:44:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:44:14 INFO - 'MOZ_CRASHREPORTER': '1', 03:44:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:44:14 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:44:14 INFO - 'MOZ_NO_REMOTE': '1', 03:44:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:44:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:44:14 INFO - 'NO_EM_RESTART': '1', 03:44:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:44:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:44:14 INFO - 'PWD': '/builds/slave/test', 03:44:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:44:14 INFO - 'RUST_BACKTRACE': 'full', 03:44:14 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:44:14 INFO - 'SHELL': '/bin/bash', 03:44:14 INFO - 'SHLVL': '1', 03:44:14 INFO - 'TERM': 'linux', 03:44:14 INFO - 'TMOUT': '86400', 03:44:14 INFO - 'USER': 'cltbld', 03:44:14 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1505728324.77209-1688482940', 03:44:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:44:14 INFO - '_': '/tools/buildbot/bin/python'} 03:44:14 INFO - TEST-INFO | started process 27027 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8yEMnU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:44:15 INFO - PID 27027 | 03:44:15 INFO - PID 27027 | (/builds/slave/test/build/application/firefox/firefox:27075): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:44:15 INFO - PID 27027 | 03:44:25 INFO - PID 27027 | RSS: Main: 154411008 03:44:25 INFO - PID 27027 | 03:44:28 INFO - PID 27027 | Cycle 1(1): loaded http://localhost:32831/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:32831/tests/kraken/driver.html?testName=audio-beat-detection) 03:44:29 INFO - PID 27027 | RSS: Main: 160546816 03:44:29 INFO - PID 27027 | 03:44:31 INFO - PID 27027 | Cycle 1(1): loaded http://localhost:32831/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:32831/tests/kraken/driver.html?testName=audio-dft) 03:44:32 INFO - PID 27027 | RSS: Main: 160669696 03:44:32 INFO - PID 27027 | 03:44:35 INFO - PID 27027 | Cycle 1(1): loaded http://localhost:32831/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:32831/tests/kraken/driver.html?testName=audio-fft) 03:44:35 INFO - PID 27027 | RSS: Main: 156344320 03:44:35 INFO - PID 27027 | 03:44:38 INFO - PID 27027 | Cycle 1(1): loaded http://localhost:32831/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:32831/tests/kraken/driver.html?testName=audio-oscillator) 03:44:38 INFO - PID 27027 | RSS: Main: 157077504 03:44:38 INFO - PID 27027 | 03:44:40 INFO - PID 27027 | Cycle 1(1): loaded http://localhost:32831/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:32831/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:44:41 INFO - PID 27027 | RSS: Main: 157274112 03:44:41 INFO - PID 27027 | 03:44:44 INFO - PID 27027 | Cycle 1(1): loaded http://localhost:32831/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:32831/tests/kraken/driver.html?testName=imaging-darkroom) 03:44:45 INFO - PID 27027 | RSS: Main: 159571968 03:44:45 INFO - PID 27027 | 03:44:48 INFO - PID 27027 | Cycle 1(1): loaded http://localhost:32831/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:32831/tests/kraken/driver.html?testName=imaging-desaturate) 03:44:49 INFO - PID 27027 | RSS: Main: 160325632 03:44:49 INFO - PID 27027 | 03:44:52 INFO - PID 27027 | Cycle 1(1): loaded http://localhost:32831/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:32831/tests/kraken/driver.html?testName=json-parse-financial) 03:44:53 INFO - PID 27027 | RSS: Main: 159940608 03:44:53 INFO - PID 27027 | 03:44:54 INFO - PID 27027 | Cycle 1(1): loaded http://localhost:32831/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:32831/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:44:55 INFO - PID 27027 | RSS: Main: 159825920 03:44:55 INFO - PID 27027 | 03:44:56 INFO - PID 27027 | Cycle 1(1): loaded http://localhost:32831/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:32831/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:44:57 INFO - PID 27027 | RSS: Main: 157437952 03:44:57 INFO - PID 27027 | 03:44:59 INFO - PID 27027 | Cycle 1(1): loaded http://localhost:32831/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:32831/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:45:00 INFO - PID 27027 | RSS: Main: 157089792 03:45:00 INFO - PID 27027 | 03:45:02 INFO - PID 27027 | Cycle 1(1): loaded http://localhost:32831/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:32831/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:45:03 INFO - PID 27027 | RSS: Main: 157347840 03:45:03 INFO - PID 27027 | 03:45:06 INFO - PID 27027 | Cycle 1(1): loaded http://localhost:32831/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:32831/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:45:07 INFO - PID 27027 | RSS: Main: 157818880 03:45:07 INFO - PID 27027 | 03:45:08 INFO - PID 27027 | Cycle 1(1): loaded http://localhost:32831/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:32831/tests/kraken/driver.html?testName=ai-astar) 03:45:09 INFO - PID 27027 | RSS: Main: 159109120 03:45:09 INFO - PID 27027 | 03:45:09 INFO - PID 27027 | __start_tp_report 03:45:09 INFO - PID 27027 | _x_x_mozilla_page_load 03:45:09 INFO - PID 27027 | _x_x_mozilla_page_load_details 03:45:09 INFO - PID 27027 | |i|pagename|runs| 03:45:09 INFO - PID 27027 | |0;ai-astar;107;106;107;107;108;109;108;109;107;108 03:45:09 INFO - PID 27027 | |1;audio-beat-detection;117;119;124;115;124;126;118;120;117;145 03:45:09 INFO - PID 27027 | |2;audio-dft;165;164;164;164;161;163;167;165;170;165 03:45:09 INFO - PID 27027 | |3;audio-fft;77;73;74;73;76;74;74;84;75;76 03:45:09 INFO - PID 27027 | |4;audio-oscillator;79;80;86;79;78;79;79;78;82;84 03:45:09 INFO - PID 27027 | |5;imaging-gaussian-blur;115;114;113;114;113;113;114;114;119;111 03:45:09 INFO - PID 27027 | |6;imaging-darkroom;148;151;148;149;148;149;148;151;148;148 03:45:09 INFO - PID 27027 | |7;imaging-desaturate;94;94;94;94;95;93;94;94;94;93 03:45:09 INFO - PID 27027 | |8;json-parse-financial;53;58;56;55;56;57;55;55;54;53 03:45:09 INFO - PID 27027 | |9;json-stringify-tinderbox;38;38;37;38;48;38;37;37;38;38 03:45:09 INFO - PID 27027 | |10;stanford-crypto-aes;73;72;74;82;76;74;77;77;72;74 03:45:09 INFO - PID 27027 | |11;stanford-crypto-ccm;111;116;112;110;111;112;114;112;114;119 03:45:09 INFO - PID 27027 | |12;stanford-crypto-pbkdf2;178;169;171;172;172;170;174;170;175;171 03:45:09 INFO - PID 27027 | |13;stanford-crypto-sha256-iterative;69;67;66;69;66;68;70;66;65;66 03:45:09 INFO - PID 27027 | __end_tp_report 03:45:09 INFO - PID 27027 | __start_cc_report 03:45:09 INFO - PID 27027 | _x_x_mozilla_cycle_collect,744 03:45:09 INFO - PID 27027 | __end_cc_report 03:45:09 INFO - PID 27027 | __startTimestamp1505731509092__endTimestamp 03:45:09 INFO - PID 27027 | 03:45:09 INFO - PID 27027 | ------- Summary: start ------- 03:45:09 INFO - PID 27027 | Number of tests: 14 03:45:09 INFO - PID 27027 | 03:45:09 INFO - PID 27027 | [#0] ai-astar Cycles:10 Average:107.60 Median:108.00 stddev:0.97 (0.9%) stddev-sans-first:1.00 03:45:09 INFO - PID 27027 | Values: 107.0 106.0 107.0 107.0 108.0 109.0 108.0 109.0 107.0 108.0 03:45:09 INFO - PID 27027 | 03:45:09 INFO - PID 27027 | [#1] audio-beat-detection Cycles:10 Average:122.50 Median:122.00 stddev:8.68 (7.1%) stddev-sans-first:8.98 03:45:09 INFO - PID 27027 | Values: 117.0 119.0 124.0 115.0 124.0 126.0 118.0 120.0 117.0 145.0 03:45:09 INFO - PID 27027 | 03:45:09 INFO - PID 27027 | [#2] audio-dft Cycles:10 Average:164.80 Median:165.00 stddev:2.39 (1.5%) stddev-sans-first:2.54 03:45:09 INFO - PID 27027 | Values: 165.0 164.0 164.0 164.0 161.0 163.0 167.0 165.0 170.0 165.0 03:45:09 INFO - PID 27027 | 03:45:09 INFO - PID 27027 | [#3] audio-fft Cycles:10 Average:75.60 Median:75.50 stddev:3.24 (4.3%) stddev-sans-first:3.40 03:45:09 INFO - PID 27027 | Values: 77.0 73.0 74.0 73.0 76.0 74.0 74.0 84.0 75.0 76.0 03:45:09 INFO - PID 27027 | 03:45:09 INFO - PID 27027 | [#4] audio-oscillator Cycles:10 Average:80.40 Median:79.50 stddev:2.72 (3.4%) stddev-sans-first:2.83 03:45:09 INFO - PID 27027 | Values: 79.0 80.0 86.0 79.0 78.0 79.0 79.0 78.0 82.0 84.0 03:45:09 INFO - PID 27027 | 03:45:09 INFO - PID 27027 | [#5] imaging-gaussian-blur Cycles:10 Average:114.00 Median:114.00 stddev:2.05 (1.8%) stddev-sans-first:2.15 03:45:09 INFO - PID 27027 | Values: 115.0 114.0 113.0 114.0 113.0 113.0 114.0 114.0 119.0 111.0 03:45:09 INFO - PID 27027 | 03:45:09 INFO - PID 27027 | [#6] imaging-darkroom Cycles:10 Average:148.80 Median:148.50 stddev:1.23 (0.8%) stddev-sans-first:1.27 03:45:09 INFO - PID 27027 | Values: 148.0 151.0 148.0 149.0 148.0 149.0 148.0 151.0 148.0 148.0 03:45:09 INFO - PID 27027 | 03:45:09 INFO - PID 27027 | [#7] imaging-desaturate Cycles:10 Average:93.90 Median:94.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 03:45:09 INFO - PID 27027 | Values: 94.0 94.0 94.0 94.0 95.0 93.0 94.0 94.0 94.0 93.0 03:45:09 INFO - PID 27027 | 03:45:09 INFO - PID 27027 | [#8] json-parse-financial Cycles:10 Average:55.20 Median:55.50 stddev:1.62 (2.9%) stddev-sans-first:1.51 03:45:09 INFO - PID 27027 | Values: 53.0 58.0 56.0 55.0 56.0 57.0 55.0 55.0 54.0 53.0 03:45:09 INFO - PID 27027 | 03:45:09 INFO - PID 27027 | [#9] json-stringify-tinderbox Cycles:10 Average:38.70 Median:38.00 stddev:3.30 (8.7%) stddev-sans-first:3.49 03:45:09 INFO - PID 27027 | Values: 38.0 38.0 37.0 38.0 48.0 38.0 37.0 37.0 38.0 38.0 03:45:09 INFO - PID 27027 | 03:45:09 INFO - PID 27027 | [#10] stanford-crypto-aes Cycles:10 Average:75.10 Median:75.00 stddev:3.03 (4.0%) stddev-sans-first:3.12 03:45:09 INFO - PID 27027 | Values: 73.0 72.0 74.0 82.0 76.0 74.0 77.0 77.0 72.0 74.0 03:45:09 INFO - PID 27027 | 03:45:09 INFO - PID 27027 | [#11] stanford-crypto-ccm Cycles:10 Average:113.10 Median:113.00 stddev:2.73 (2.4%) stddev-sans-first:2.78 03:45:09 INFO - PID 27027 | Values: 111.0 116.0 112.0 110.0 111.0 112.0 114.0 112.0 114.0 119.0 03:45:09 INFO - PID 27027 | 03:45:09 INFO - PID 27027 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.20 Median:172.00 stddev:2.74 (1.6%) stddev-sans-first:1.94 03:45:09 INFO - PID 27027 | Values: 178.0 169.0 171.0 172.0 172.0 170.0 174.0 170.0 175.0 171.0 03:45:09 INFO - PID 27027 | 03:45:09 INFO - PID 27027 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.20 Median:67.50 stddev:1.69 (2.5%) stddev-sans-first:1.66 03:45:09 INFO - PID 27027 | Values: 69.0 67.0 66.0 69.0 66.0 68.0 70.0 66.0 65.0 66.0 03:45:09 INFO - PID 27027 | -------- Summary: end -------- 03:45:09 INFO - PID 27027 | 03:45:09 INFO - PID 27027 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 03:45:09 INFO - TEST-INFO | 27027: exit 0 03:45:09 INFO - TEST-OK | kraken | took 61592ms 03:45:09 INFO - SUITE-END | took 1171s 03:45:10 INFO - Completed test suite (00:19:32) 03:45:10 INFO - javascript benchmark 03:45:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8641.449210321849, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3144.5597616333716, 3165.414398336308, 3180.6024246969127, 3181.0138856954345, 3191.8563664635103, 6234.719437625306, 6299.685015749205, 6320.968395158024, 6370.617762934227, 6424.1648585683915, 6299.748010079603, 6317.33667964864, 6319.905201421982, 6329.272133704625, 6368.713407896645, 5780.710964451781, 5906.26171728534, 5926.496247818941, 5948.940510594882, 5949.85125371867, 5706.087026075828, 5844.766209351621, 5931.258592675916, 6001.2498437695285, 6052.031674932012, 5443.782248710047, 5768.0, 5849.795257165979, 5861.150133230679, 5908.172855800191, 5813.09896965971, 5857.648541087548, 5905.911411328833, 5917.970410147965, 5934.673592952386, 5689.089745640677, 5762.1068734346245, 5764.337101233328, 5819.156222347779, 5875.38308477608, 6286.18279623646, 6335.0, 6353.5870168438905, 6393.808185754435, 6418.839529011812, 6770.390664840139, 6784.932150678479, 6876.828079298008, 6890.37986581205, 6894.24163342032, 6852.314768523158, 6880.208775990725, 6945.2707465716385, 6963.268856770017, 6970.825729356756, 6205.0, 6276.027215781562, 6277.8744425111245, 6278.1210630511305, 6310.400511951403, 6990.930090699129, 7027.929720702829, 7057.36483716468, 7058.0, 7098.467614928901, 6667.499937504706, 6718.630475323904, 6740.62926539045, 6747.426468750211, 6755.1556055493065, 6757.8648427031185, 6772.966135169293, 6801.795946121624, 6860.0, 6876.209235937931, 6627.237867645186, 6734.3602357775935, 6759.324067593201, 6781.423578995742, 6826.658667066627, 6693.297203793629, 6762.594244345355, 6788.796336109925, 6792.0830687856505, 6794.762183323659, 5306.416294207634, 5316.069687804618, 5324.068288049576, 5326.334208223972, 5329.200619907045, 4695.319178719066, 4716.787744551503, 4731.077439899186, 4772.689775164603, 4776.47458779534, 5766.654000759968, 5775.0, 5859.794907178229, 5865.530757539386, 5872.265966754156, 6747.089142965637, 6786.524943253925, 6813.727450902007, 6888.173419189729, 6916.757913473004, 5759.366469688331, 5784.94215057844, 5794.82615521535, 5794.88410231793, 5853.0, 5745.511631511285, 5752.539796816244, 5804.6807425592, 5833.241678581842, 5851.882962340815, 6483.513736469784, 6498.187726534184, 6575.210974682973, 6579.901301480481, 6676.031975363642, 6589.571677840925, 6776.28848970851, 6777.356151165632, 6779.423748981392, 6788.321167883172, 6535.934640653532, 6552.63960482159, 6557.082008518716, 6558.672066396565, 6597.043428702716, 6662.666866656551, 6697.9953007049335, 6758.662066896733, 6769.221539523018, 6781.254062053072, 6822.0, 6824.965875170592, 6886.48351373639, 6887.965560172167, 6900.068490753684, 6448.774292899726, 6564.048213009182, 6564.081028656087, 6576.868462630816, 6652.2349929756765, 6578.0, 6593.109930159367, 6601.50488713358, 6605.207375114825, 6666.866662666816, 6467.900456922241, 6541.051547525677, 6612.537122401386, 6626.27111017778, 6630.038644396632, 6621.27166011748, 6659.833504162435, 6689.966550167218, 6738.629375384401, 6764.019217213378, 6244.469220116341, 6345.270293916259, 6351.745930162927, 6364.77723279683, 6518.902216466662], "value": 6189.247022466595, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [844.3329769482109, 870.3733312015347, 872.7687162901832, 873.0222151190667, 879.2087121590572, 41358.37962430566, 42329.0, 42415.78792106035, 42447.36328955068, 42457.150856982844, 38547.0, 38633.80683096588, 38684.226315473745, 39007.219855602874, 39057.0, 7144.0712707347975, 7226.891596626055, 7234.60209688467, 7268.3458488736005, 7278.454115941326, 7121.572705637653, 7213.134423869117, 7214.134303883541, 7215.278472152769, 7216.891746623779, 7504.54972701637, 7651.885221721679, 7662.272084151997, 7667.923320766805, 7691.077070751518, 7532.849343013137, 7562.054743157106, 7610.315071643551, 7624.961875190644, 7629.923700762977, 7635.694572217105, 7829.451938364316, 7835.373170146431, 7850.293473587404, 7853.8036549086155, 37322.0, 37580.62419375826, 37776.24447511035, 37782.24435511274, 37849.62150378516, 3514.1390359361862, 3534.0, 3553.022918697353, 3554.289142171576, 3557.857685692595, 31222.53166202509, 31501.36997260042, 31577.526337104962, 31608.683913160803, 31618.683813162035, 5186.0, 5200.947990520084, 5222.63441559091, 5246.685198888041, 5264.2893209416625, 186476.0, 186769.0, 186949.0, 186950.0, 186976.0, 41518.0, 42614.78692606579, 46810.29784553235, 48369.0, 48929.510704893204, 49884.750576246886, 50065.0, 50214.0, 50281.24578131331, 50395.496045039814, 15972.840271597135, 16001.199940003185, 16006.11966341839, 16077.0, 16086.034837909529, 13023.348832558335, 13074.084814062924, 13099.476020959055, 13263.801042984363, 13296.401661925043, 13506.797398038841, 13599.59201223965, 13696.109752866032, 13850.584482465541, 13912.37394317258, 5584.720763961867, 5637.887242255214, 5658.292713410824, 5686.459786320293, 5723.0, 1008.3596915958419, 1019.918406527476, 1022.9846552301722, 1028.8456731490337, 1034.8706411698538, 22887.771122288897, 23094.0, 23124.190653327052, 23292.883535582212, 23322.65016024761, 1111.127764704703, 1112.5660992213043, 1115.2249186815086, 1126.1159989408275, 1149.844770955927, 12053.81919271194, 12129.332886691316, 12329.321887296284, 12338.383080845922, 12342.259464432163, 221340.0, 222679.0, 222956.0, 223169.0, 223629.0, 54011.729941350044, 54032.0, 54046.18930716042, 54061.189082163015, 54070.459295406545, 1458.1324112153252, 1462.8464011278663, 1477.5567329801017, 1483.1101339196396, 1485.6805786755683, 22259.109635614393, 22322.0, 22442.887785560968, 22446.551068978857, 22662.093516259825, 37859.432108517845, 37892.24215515729, 37892.62107379001, 37951.24097517979, 38027.0, 197856.0, 198489.0, 198877.0, 199030.0, 199127.0, 33928.15179620529, 34061.0, 35215.47176792299, 36061.0984725384, 36167.27665446729, 45340.0, 46289.3056604158, 46379.76810115928, 46652.300215496114, 46809.0, 33218.833905831285, 33718.49422258619, 33812.492812608325, 33859.0, 34585.135371615914, 33139.50290745593, 33627.982321060816, 33751.15622109467, 33763.0, 34234.82882585571, 37421.4386784193, 37569.62430375771, 37772.24455510819, 37783.433248501846, 37833.243335133695, 32392.83803581061, 32756.50865237071, 32814.34371312608, 32981.83509082535, 33052.17369565684, 24404.51190976135, 24453.51092978166, 24712.629310560715, 24799.752002479745, 24868.875655621607], "value": 17171.333209531032, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [553.6927005511942, 556.8385168301193, 561.1190431023296, 571.0520535910389, 581.7498475655469, 21657.891710541444, 22603.547929041408, 22630.0, 22651.20720774773, 22693.0, 23292.650610240817, 24195.51608967824, 24364.51270974584, 24525.264242072768, 24645.876770616167, 6463.7737679181355, 6574.112494813211, 6614.0740296358545, 6615.33846615337, 6637.867242655168, 6507.609543427386, 6604.735810567571, 6606.0, 6611.603303801763, 6803.149606299213, 7115.750948716796, 7155.785326440189, 7158.713651453935, 7160.391366733807, 7165.176004759442, 7117.217106118323, 7120.252373500785, 7120.679569419389, 7121.501494895385, 7146.714131434736, 6480.092787009822, 6774.458043356569, 6793.932060679429, 6798.354156355138, 6815.2162501312, 29112.8544357279, 29682.0, 29796.702032979825, 29940.251493712614, 30017.549736753965, 2079.334612923865, 2083.7707852136255, 2096.067250073718, 2102.9474263143543, 2103.2743703422293, 18441.631167376712, 18974.81025189758, 19235.13441895118, 19247.807521924882, 19809.9009504953, 2529.4941011797714, 2591.0672158023285, 2613.4381108061857, 2616.136674897279, 2631.7499837515597, 124077.0, 127749.0, 132210.0, 137391.0, 143682.28158859242, 19194.328198513263, 20012.799232046124, 20266.59466810685, 20654.690179647318, 20946.47633809167, 41282.79358603248, 41292.0, 41422.37866432006, 41483.0, 41539.58460415417, 10995.175361847894, 11053.392063436588, 11204.607838725606, 11291.661250162344, 11291.661250162344, 9328.300377471716, 9376.718698439057, 9393.20157786582, 9404.435733855991, 9425.104615061558, 10725.356478611307, 10839.807621161666, 10870.673879783619, 10945.23383363157, 11143.665690029313, 3722.3299806034634, 3722.6091260417807, 4077.9796101019306, 4086.9182616347507, 4088.4889388826396, 918.4856480371013, 942.561708805395, 963.3641796414333, 968.8498282766183, 970.3062310448064, 18416.35542755997, 18698.252069917053, 18950.52623684419, 19159.904200478908, 19209.80790192108, 1020.2807021050141, 1030.4744580264119, 1036.1710631494775, 1044.3577200021932, 1045.0542259255385, 10249.282550221413, 10294.0, 10417.947910260551, 10439.739006524898, 10731.678049658523, 494277.0, 495730.0, 503250.0, 503825.0, 504835.0, 112344.0, 113007.43496282466, 114177.0, 114629.0, 114687.42656286832, 1138.6089212653362, 1154.3131836557068, 1171.437709899236, 1172.267332916927, 1180.9232399893979, 16814.495565133064, 16859.904106233054, 17071.402500912904, 17227.913860430617, 17347.739783903504, 52318.43044708513, 54112.729436352565, 54880.72559637176, 55502.44497555134, 55947.160792588955, 66548.33451665421, 67854.3214567848, 67893.66053169702, 68271.3172868265, 68447.31552684409, 18664.0, 19101.617967640846, 19250.614987700446, 19288.517787055436, 19289.0, 21346.252881149085, 21403.46491337729, 21554.461138471037, 21577.460563486038, 21961.121555137615, 16012.599685007503, 17375.56561085983, 17732.29070837152, 17926.0, 17946.641067178844, 14844.777328339867, 15535.922320388325, 15666.686666266838, 16165.0, 16244.918775406048, 17668.38160664422, 17821.10894455298, 17860.55348616243, 17975.191116400056, 17993.640127197643, 18540.536486587403, 18564.44306670802, 18755.0, 18790.342338018105, 18831.90584047071, 17086.658266834842, 17203.967761934324, 17324.220410081827, 17352.132393380532, 17366.21852016637], "value": 12570.08678348714, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1366.5831921264016, 1420.9076410033347, 1444.4799872046062, 1445.9927700361498, 1446.0672866001437, 14560.635984100403, 15273.312700928456, 15378.53864384069, 15399.461018864327, 15428.074315541075, 15050.698986020274, 15188.392464301414, 15195.54413367598, 15207.163606001666, 15238.619034524143, 2721.7958653100995, 2858.3568697043206, 2859.599656048155, 2861.1130549529607, 2862.341661417875, 2654.4956458272964, 2777.4445110977786, 2797.580362945564, 2800.7759379249715, 2801.145650576573, 2282.8858557072163, 2357.5167090746354, 2714.1043455659583, 2811.12855014945, 2814.521531339677, 2741.451709658076, 2749.5738160585142, 2773.209635253953, 2777.0558010276604, 2783.2067860659718, 2689.9058532951253, 2719.2114286856986, 2722.1561315992108, 2724.986375068132, 2738.7945904057274, 14623.63440913975, 14883.255837208097, 14916.62708432287, 14937.477188298466, 14987.850121498754, 7940.1662825403655, 8233.423660343778, 8233.670653173866, 8237.176282371775, 8277.592809222446, 5194.610404219661, 5319.760610772486, 5524.944750552483, 5533.142362933751, 5549.361823390322, 1938.6995015772436, 1943.494691380237, 1970.0642194957425, 1978.099964516156, 2009.6884982827685, 26394.868025660133, 26416.0, 26617.866910665518, 26635.86682066577, 26734.197974060808, 7154.713811447588, 7229.783106506813, 7460.179380268166, 7512.661930213044, 7516.736914207976, 17642.911785440992, 17770.111494425226, 17780.3776867809, 17782.377616783608, 17820.55448613795, 7843.568603726736, 7887.132415434297, 7928.8810667840025, 7954.801129971681, 8218.589070546568, 7182.102237220348, 7259.0, 7347.816304592321, 7510.812229694192, 7614.885776713354, 4884.242942343943, 4991.850244492671, 5090.643654944119, 5098.286239926413, 5112.463191364853, 1335.2922950836073, 1358.151155527795, 1423.288355822089, 1424.8646378594017, 1442.6393401649589, 831.9542425166674, 832.7501749475133, 837.8868852704807, 840.2983508770124, 845.8350621628846, 8674.522901240367, 8690.391672582859, 8713.956430217808, 8767.079456656958, 8826.646934122691, 717.6196615793638, 735.2537174767615, 786.6420778545748, 913.1644545241163, 987.775158803079, 4995.750212489361, 5055.570276526537, 5179.8446046618665, 5250.553702935294, 5283.471652834763, 20697.896510517352, 20731.689024664644, 21541.67687484689, 21572.46068848291, 21598.676019859715, 20785.0, 21142.894285528473, 21240.150393984066, 21529.67705484419, 21581.24465643695, 11318.0, 11385.37380444067, 11502.367369794742, 11513.769724605461, 11515.712107197387, 8583.828323433621, 8586.184312490303, 8649.308055355683, 8657.61040753155, 8691.826163476822, 18159.546011349823, 18449.800762950366, 18834.811651883305, 18906.43280701581, 18915.716264256323, 18815.435536933914, 18864.434066977465, 18991.33530326432, 19055.714164287823, 19234.71147932754, 10754.139668826632, 10975.83536246941, 10984.945075274572, 11040.723981900517, 11042.723931901766, 13755.0, 13799.2410417428, 13911.0, 14021.228832414316, 14062.859371406155, 11373.090152787923, 11448.427578621202, 11667.0, 11749.353785541878, 11829.231099978473, 10932.781344372908, 11139.832902506307, 11270.94364528172, 11315.0, 11581.7104572383, 10050.0, 10131.0, 10179.592816287563, 10189.592416303265, 10282.07461328484, 7287.672054757442, 7362.042934418706, 7367.742129025459, 7378.815529611803, 7405.814854628462, 7551.282628150317, 7622.085349758065, 7682.231776822273, 7726.227377262228, 7786.221377862168], "value": 6694.584918915989, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [494.54748904752154, 509.419262041273, 513.1328055586062, 523.7783465269924, 532.7549327309438, 6159.353267906872, 6199.504039676821, 6218.191635087438, 6307.62154270744, 6325.620462772237, 6165.969170154143, 6201.224846894138, 6217.316095229521, 6228.321821616703, 6247.406496382836, 5157.200633901752, 5238.5023422774875, 5254.369475662926, 5272.789088436458, 5327.0, 5359.785608575652, 5362.632528705175, 5375.43557926418, 5380.515753582177, 5413.431589683086, 5329.120695085307, 5331.120365139747, 5342.893142137155, 5355.169948657965, 5368.62419630626, 5300.628955973083, 5328.520433161015, 5331.786728530854, 5354.9196762048605, 5360.678359298455, 5019.52314530119, 5020.799168033274, 5028.823991160329, 5037.11850426174, 5053.722045287508, 6229.190205273331, 6322.241331040259, 6351.301356850743, 6357.237131544198, 6395.87208255837, 6041.184440100574, 6112.083187521863, 6116.938830611682, 6146.969265153645, 6154.415330543591, 6705.564138330993, 6713.194416669982, 6717.361850624183, 6721.4958878084335, 6737.326267373223, 5397.676139431646, 5411.702356370398, 5412.91880621791, 5432.483914028161, 5444.257837491965, 7089.680964356565, 7140.571885622896, 7152.4278057755255, 7193.676284567207, 7237.457190710665, 5501.367342755634, 5560.638558493685, 5578.972105139448, 5589.469000444951, 5604.6843930174355, 5644.717764111778, 5683.26117604717, 5688.857778555569, 5704.372519022904, 5711.143328500675, 6088.995315772848, 6121.510279177655, 6156.107364432221, 6176.289726681399, 6195.380461953769, 6157.384261573807, 6484.870302593922, 6717.261101278856, 6723.361280678328, 6846.520743547904, 1909.9140538675792, 1917.6548221320018, 1940.8738432001874, 1947.4839167620592, 1950.6683863743199, 1431.1484666623342, 1435.1174027972722, 1440.3590402271097, 1451.1873350923518, 1480.6668499587722, 5627.593101724568, 5647.237622920935, 5699.629524080921, 5737.426257374229, 5758.452946970031, 6628.933710662832, 6672.366125218096, 6673.432758215606, 6793.150856142982, 6806.6256355900905, 5663.207150998864, 5688.573356998241, 5753.7054162813865, 5775.393583673654, 5780.132980052942, 3521.841517131735, 3604.8738294160103, 3610.4403817408343, 3634.47300141478, 3644.4351125575577, 6510.414062734377, 6539.836504087436, 6593.2088149421415, 6639.734410623521, 6647.806223946086, 6828.190529509684, 6843.418309443753, 6873.725050998004, 6939.8202305608165, 6968.3728464438445, 6398.456131228898, 6433.067205255305, 6545.639989800607, 6551.312112228243, 6563.113979612691, 6757.797266082025, 6779.288174741779, 6781.321867813179, 6787.898181527183, 6885.311468853075, 6699.363560461748, 6740.359665831738, 6769.729210831511, 6792.558483698544, 6816.182058153054, 6494.4804415645685, 6597.208334999831, 6610.702518386588, 6627.867442651216, 6673.466122710074, 5004.899902001866, 5028.949710502994, 5033.748312584283, 5040.84877453677, 5065.544101030925, 6548.705308261235, 6740.66296685154, 6852.965735171292, 6881.827954301182, 6915.896261556181, 6278.372162783685, 6469.4824414045825, 6553.543590358411, 6637.2699003108655, 6729.360710732472, 6584.703688333941, 6700.430463410665, 6701.0953521274005, 6707.396334329934, 6727.899081513683, 6338.447080465131, 6462.256840463407, 6499.995003847106, 6500.479961602966, 6524.804255872332, 6588.868222635616, 6607.207135143622, 6628.76799312041, 6718.563293385914, 6751.426128779109, 6379.979203327445, 6414.903776443264, 6446.548741588232, 6474.0, 6493.480521558358], "value": 5252.728959469574, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [886.769439945614, 894.3515950935571, 910.9180173784363, 912.498126030683, 922.8892532896053, 18451.0, 19709.113089910952, 19856.60286794265, 19868.40394788155, 19884.304049358274, 17822.554436139104, 18634.90682546589, 18892.905535472302, 19330.51673708158, 19644.214231430724, 938.4228699349902, 944.5088553951941, 947.1428357336587, 947.2185447006214, 947.2469386837481, 939.2486011191054, 954.9140577348037, 955.9378640388387, 960.231814548362, 972.2027937091588, 1023.6212601337515, 1026.6663334416307, 1026.8870424253325, 1027.3117011602208, 1028.511457057899, 995.2088089968456, 999.435319044741, 1011.049613363436, 1011.1253765492869, 1011.6762635956534, 735.9632018399112, 736.2674139231445, 737.9077615298088, 738.2433006168667, 738.3281214095201, 18627.0, 18700.812991870043, 18740.812591874044, 18780.90609546957, 18847.62304753897, 21364.359069227947, 21726.782732172633, 21874.343769686933, 21892.343229702976, 21894.562108757735, 15797.21013949298, 16331.673366532603, 16358.509244722678, 16410.261538230807, 16423.83576164235, 3766.133789228493, 3804.524434445694, 3808.314503389389, 3808.6572208501366, 3809.8666546670734, 141179.29410352986, 141931.29034354867, 141970.29014854963, 142027.99999999895, 145789.0, 16008.519744407686, 16401.343946242017, 16423.260953257133, 16486.17569121563, 16519.6696066078, 28693.713062869523, 30924.845375773428, 30947.381052379278, 31041.223969400944, 31189.0, 8705.738827835175, 8746.125387461203, 8764.430312029697, 8814.162654547808, 8870.600822962982, 4189.120284740177, 4272.743635381887, 4277.358396240589, 4283.186194623012, 4295.591918767712, 5395.21769343443, 5461.2627295315415, 5463.9180412293845, 5481.040817856859, 5487.6158668893595, 985.3201291109111, 985.522021819417, 992.503748125937, 1018.1752780248023, 1019.23557332001, 672.8418821576926, 674.20780582815, 679.0527214535695, 684.8219462939572, 690.1373283395756, 11148.108151347873, 11207.271527350696, 11546.249493782878, 12214.145009849226, 12516.3741812909, 648.9870202596016, 654.2051407539767, 662.3740565165969, 667.7863083813133, 670.6043434373743, 5441.972790136103, 5474.288342515448, 5494.697791621499, 5532.640378375393, 5624.6625202487985, 130065.0, 135563.0, 136011.31994340164, 136363.0, 137164.07953561057, 112160.0, 113283.43358283321, 114039.42980285212, 116422.41788791171, 118534.0, 99934.5003274979, 100921.49539252257, 101230.0, 102496.48751756194, 104034.0, 10184.287099903231, 10470.738231544272, 10476.319039262728, 10553.261271711213, 10570.0, 44108.0, 44731.552684472736, 45384.0, 46678.06643867172, 47004.0, 58676.0, 59565.7021714906, 59820.0, 61062.69468652628, 61809.0, 14560.417583296974, 14888.776668349767, 15098.622534436727, 15122.243887805345, 15137.697246054797, 17154.228059737092, 17311.48065558035, 17337.826621734126, 17444.12779361052, 17492.91253543775, 14706.411743530567, 14786.112833230041, 14975.026623269889, 15109.39562417491, 15213.543593692208, 14856.40574377056, 15099.245037747849, 15147.394104236148, 15198.696026079637, 15202.239888005777, 14271.500497482539, 14399.136051836924, 14481.710365792836, 14603.269836508345, 14632.634184145056, 12145.574904878287, 12253.816192756938, 12298.077644176543, 12339.383030848601, 12672.936635316764, 10984.450777460936, 11017.11863050938, 11096.33421994683, 11173.162012849232, 11174.05020573228], "value": 8863.509673866678, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1429.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 106.0, 107.0, 107.0, 108.0, 109.0, 108.0, 109.0, 107.0, 108.0], "value": 107.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 119.0, 124.0, 115.0, 124.0, 126.0, 118.0, 120.0, 117.0, 145.0], "value": 122.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 164.0, 164.0, 164.0, 161.0, 163.0, 167.0, 165.0, 170.0, 165.0], "value": 164.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 73.0, 74.0, 73.0, 76.0, 74.0, 74.0, 84.0, 75.0, 76.0], "value": 75.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 80.0, 86.0, 79.0, 78.0, 79.0, 79.0, 78.0, 82.0, 84.0], "value": 80.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 113.0, 114.0, 113.0, 113.0, 114.0, 114.0, 119.0, 111.0], "value": 114.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 151.0, 148.0, 149.0, 148.0, 149.0, 148.0, 151.0, 148.0, 148.0], "value": 148.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 95.0, 93.0, 94.0, 94.0, 94.0, 93.0], "value": 93.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 58.0, 56.0, 55.0, 56.0, 57.0, 55.0, 55.0, 54.0, 53.0], "value": 55.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 38.0, 37.0, 38.0, 48.0, 38.0, 37.0, 37.0, 38.0, 38.0], "value": 38.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 72.0, 74.0, 82.0, 76.0, 74.0, 77.0, 77.0, 72.0, 74.0], "value": 75.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 116.0, 112.0, 110.0, 111.0, 112.0, 114.0, 112.0, 114.0, 119.0], "value": 113.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 169.0, 171.0, 172.0, 172.0, 170.0, 174.0, 170.0, 175.0, 171.0], "value": 172.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 67.0, 66.0, 69.0, 66.0, 68.0, 70.0, 66.0, 65.0, 66.0], "value": 67.2, "unit": "score"}]}]} 03:45:10 INFO - Return code: 0 03:45:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:45:10 INFO - # TBPL SUCCESS # 03:45:10 INFO - Running post-action listener: _package_coverage_data 03:45:10 INFO - Running post-action listener: _resource_record_post_action 03:45:10 INFO - [mozharness: 2017-09-18 10:45:10.369681Z] Finished run-tests step (success) 03:45:10 INFO - Running post-run listener: _resource_record_post_run 03:45:10 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 12288; Write bytes: 339562496; Read time: 568; Write time: 398788 03:45:10 INFO - TinderboxPrint: CPU usage
13.1% 03:45:10 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 568 03:45:10 INFO - TinderboxPrint: I/O write bytes / time
339,562,496 / 398,788 03:45:10 INFO - TinderboxPrint: CPU idle
8,246.8 (86.8%) 03:45:10 INFO - TinderboxPrint: CPU user
1,194.1 (12.6%) 03:45:10 INFO - TinderboxPrint: Swap in / out
0 / 0 03:45:10 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 13594624; Read time: 0; Write time: 2048 03:45:10 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 03:45:10 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 8192; Write bytes: 325967872; Read time: 560; Write time: 396740 03:45:10 INFO - Running post-run listener: _upload_blobber_files 03:45:10 INFO - Blob upload gear active. 03:45:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:45:10 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 03:45:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:45:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:45:11 INFO - (blobuploader) - INFO - Open directory for files ... 03:45:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:45:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:45:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:45:12 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:45:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:45:12 INFO - (blobuploader) - INFO - Done attempting. 03:45:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:45:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:45:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:45:18 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:45:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:45:18 INFO - (blobuploader) - INFO - Done attempting. 03:45:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:45:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:45:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:45:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:45:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:45:19 INFO - (blobuploader) - INFO - Done attempting. 03:45:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:45:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:45:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:45:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:45:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:45:20 INFO - (blobuploader) - INFO - Done attempting. 03:45:20 INFO - (blobuploader) - INFO - Iteration through files over. 03:45:20 INFO - Return code: 0 03:45:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:45:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:45:20 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6a07f9722288aa7fca794e9f5fe22d38b65be04732cfecdbd6be13fbefde5ae8bb5fecfdbde49d7c716f6d8e148d14fff5aff1ba0f4db3416501faa6a47145a7", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/77502cd05517ceee3ec69b8c08d69714090215804d586e66df588a5d38fe3d70d35adeb9df3382e9aed40880b42aa86caf8f419f19ce7e6eb83810f775871832", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c5ea4d0317ea28b2c4abd9cd91fcdf87f624020e783b474b07f4ff62d5d286622b19b1254d4c61c8d10aef090d9e46f9a433f82b61007b6a2b56058ee84f2dde", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b2f197894e722dab450aceb6067f291799c89ba78c41247d8cfb3bc35d49843ce8426d7a83e5bdaea084e72f3107efa9b64f9f55509d4015b8cc25606df6a4eb"} 03:45:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:45:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:45:20 INFO - Contents: 03:45:20 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6a07f9722288aa7fca794e9f5fe22d38b65be04732cfecdbd6be13fbefde5ae8bb5fecfdbde49d7c716f6d8e148d14fff5aff1ba0f4db3416501faa6a47145a7", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/77502cd05517ceee3ec69b8c08d69714090215804d586e66df588a5d38fe3d70d35adeb9df3382e9aed40880b42aa86caf8f419f19ce7e6eb83810f775871832", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c5ea4d0317ea28b2c4abd9cd91fcdf87f624020e783b474b07f4ff62d5d286622b19b1254d4c61c8d10aef090d9e46f9a433f82b61007b6a2b56058ee84f2dde", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b2f197894e722dab450aceb6067f291799c89ba78c41247d8cfb3bc35d49843ce8426d7a83e5bdaea084e72f3107efa9b64f9f55509d4015b8cc25606df6a4eb"} 03:45:20 INFO - Running post-run listener: copy_logs_to_upload_dir 03:45:20 INFO - Copying logs to upload dir... 03:45:20 INFO - mkdir: /builds/slave/test/build/upload/logs 03:45:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1243.041665 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2017-09-18 03:45:21.138867) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-18 03:45:21.144498) ========= 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=df17445c3590ff7897617a0e00000225-1505728324.77209-1688482940 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-Non-PGO/sha512/6a07f9722288aa7fca794e9f5fe22d38b65be04732cfecdbd6be13fbefde5ae8bb5fecfdbde49d7c716f6d8e148d14fff5aff1ba0f4db3416501faa6a47145a7", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/77502cd05517ceee3ec69b8c08d69714090215804d586e66df588a5d38fe3d70d35adeb9df3382e9aed40880b42aa86caf8f419f19ce7e6eb83810f775871832", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c5ea4d0317ea28b2c4abd9cd91fcdf87f624020e783b474b07f4ff62d5d286622b19b1254d4c61c8d10aef090d9e46f9a433f82b61007b6a2b56058ee84f2dde", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b2f197894e722dab450aceb6067f291799c89ba78c41247d8cfb3bc35d49843ce8426d7a83e5bdaea084e72f3107efa9b64f9f55509d4015b8cc25606df6a4eb"} build_url:https://queue.taskcluster.net/v1/task/L5VTY1pKRgOdH0QnkhRnug/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011350 build_url: 'https://queue.taskcluster.net/v1/task/L5VTY1pKRgOdH0QnkhRnug/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6a07f9722288aa7fca794e9f5fe22d38b65be04732cfecdbd6be13fbefde5ae8bb5fecfdbde49d7c716f6d8e148d14fff5aff1ba0f4db3416501faa6a47145a7", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/77502cd05517ceee3ec69b8c08d69714090215804d586e66df588a5d38fe3d70d35adeb9df3382e9aed40880b42aa86caf8f419f19ce7e6eb83810f775871832", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c5ea4d0317ea28b2c4abd9cd91fcdf87f624020e783b474b07f4ff62d5d286622b19b1254d4c61c8d10aef090d9e46f9a433f82b61007b6a2b56058ee84f2dde", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b2f197894e722dab450aceb6067f291799c89ba78c41247d8cfb3bc35d49843ce8426d7a83e5bdaea084e72f3107efa9b64f9f55509d4015b8cc25606df6a4eb"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-18 03:45:21.182150) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-18 03:45:21.182483) ========= 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=df17445c3590ff7897617a0e00000225-1505728324.77209-1688482940 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004497 ========= master_lag: 0.07 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-18 03:45:21.261843) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-18 03:45:21.266183) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-18 03:45:21.266492) ========= ========= Total master_lag: 0.84 =========