builder: mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s slave: talos-linux64-ix-082 starttime: 1507065505.4 results: success (0) revision: 65a5054a1f922b83929c80658062f441ca3da6a0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-10-03 14:18:25.401926) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-10-03 14:18:25.402618) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-03 14:18:25.402888) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-03 14:18:25.623764) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 14:18:25.624030) ========= 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=51fc86400edbde9650d2781a000001d0-1507062476.832498-1090344465 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-10-03 14:18:25-- 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% 104M=0s 2017-10-03 14:18:26 (104 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.425962 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 14:18:26.080944) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 14:18:26.081227) ========= 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=51fc86400edbde9650d2781a000001d0-1507062476.832498-1090344465 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020277 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 14:18:26.134814) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 14:18:26.135112) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 65a5054a1f922b83929c80658062f441ca3da6a0 --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 65a5054a1f922b83929c80658062f441ca3da6a0 --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=51fc86400edbde9650d2781a000001d0-1507062476.832498-1090344465 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-10-03 14:18:26,496 truncating revision to first 12 chars 2017-10-03 14:18:26,496 Setting DEBUG logging. 2017-10-03 14:18:26,496 attempt 1/10 2017-10-03 14:18:26,496 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/65a5054a1f92?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-10-03 14:18:26,741 unpacking tar archive at: mozilla-central-65a5054a1f92/testing/mozharness/ program finished with exit code 0 elapsedTime=0.578056 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 14:18:26.751143) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-03 14:18:26.754425) ========= script_repo_revision: 65a5054a1f922b83929c80658062f441ca3da6a0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-03 14:18:26.754768) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-03 14:18:26.755020) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-03 14:18:26.776268) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2017-10-03 14:18:26.776529) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=51fc86400edbde9650d2781a000001d0-1507062476.832498-1090344465 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:18:27 INFO - MultiFileLogger online at 20171003 14:18:27 in /builds/slave/test 14:18:27 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 14:18:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:18:27 INFO - {'append_to_log': False, 14:18:27 INFO - 'base_work_dir': '/builds/slave/test', 14:18:27 INFO - 'blob_upload_branch': 'Firefox', 14:18:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:18:27 INFO - 'branch': 'Firefox', 14:18:27 INFO - 'buildbot_json_path': 'buildprops.json', 14:18:27 INFO - 'code_coverage': False, 14:18:27 INFO - 'config_files': ('talos/linux_config.py',), 14:18:27 INFO - 'default_actions': ('clobber', 14:18:27 INFO - 'read-buildbot-config', 14:18:27 INFO - 'download-and-extract', 14:18:27 INFO - 'populate-webroot', 14:18:27 INFO - 'create-virtualenv', 14:18:27 INFO - 'install', 14:18:27 INFO - 'setup-mitmproxy', 14:18:27 INFO - 'run-tests'), 14:18:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:18:27 INFO - 'disable_ccov_upload': False, 14:18:27 INFO - 'disable_stylo': False, 14:18:27 INFO - 'download_minidump_stackwalk': True, 14:18:27 INFO - 'download_symbols': 'ondemand', 14:18:27 INFO - 'enable_stylo': False, 14:18:27 INFO - 'enable_webrender': False, 14:18:27 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 14:18:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:18:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:18:27 INFO - 'gecko_profile': False, 14:18:27 INFO - 'gecko_profile_interval': 0, 14:18:27 INFO - 'installer_path': 'installer.exe', 14:18:27 INFO - 'log_level': 'info', 14:18:27 INFO - 'log_name': 'talos', 14:18:27 INFO - 'log_to_console': True, 14:18:27 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:18:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:18:27 INFO - 'opt_config_files': (), 14:18:27 INFO - 'pip_index': False, 14:18:27 INFO - 'suite': 'dromaeojs-e10s', 14:18:27 INFO - 'system_bits': '32', 14:18:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:18:27 INFO - 'title': 'talos-linux64-ix-082', 14:18:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:18:27 INFO - 'use_talos_json': True, 14:18:27 INFO - 'verify': 'False', 14:18:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:18:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:18:27 INFO - 'work_dir': 'build'} 14:18:27 INFO - [mozharness: 2017-10-03 21:18:27.284063Z] Running clobber step. 14:18:27 INFO - Running pre-action listener: _resource_record_pre_action 14:18:27 INFO - Running main action method: clobber 14:18:27 INFO - rmtree: /builds/slave/test/build 14:18:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:18:28 INFO - Running post-action listener: _resource_record_post_action 14:18:28 INFO - [mozharness: 2017-10-03 21:18:28.272947Z] Finished clobber step (success) 14:18:28 INFO - [mozharness: 2017-10-03 21:18:28.273044Z] Running read-buildbot-config step. 14:18:28 INFO - Running pre-action listener: _resource_record_pre_action 14:18:28 INFO - Running main action method: read_buildbot_config 14:18:28 INFO - Using buildbot properties: 14:18:28 INFO - { 14:18:28 INFO - "project": "", 14:18:28 INFO - "product": "firefox", 14:18:28 INFO - "who": "kwierso@gmail.com", 14:18:28 INFO - "installer_path": "public/build/target.tar.bz2", 14:18:28 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s", 14:18:28 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 14:18:28 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos dromaeojs-e10s", 14:18:28 INFO - "stage_platform": "linux64-qr", 14:18:28 INFO - "basedir": "/builds/slave/test", 14:18:28 INFO - "buildnumber": 118, 14:18:28 INFO - "platform": "ubuntu64_hw_qr", 14:18:28 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 14:18:28 INFO - "slavebuilddir": "test", 14:18:28 INFO - "taskId": "AwjAEzh2Td2nFCtMasc2Rw", 14:18:28 INFO - "branch": "mozilla-central", 14:18:28 INFO - "script_repo_revision": "production", 14:18:28 INFO - "revision": "65a5054a1f922b83929c80658062f441ca3da6a0", 14:18:28 INFO - "slavename": "talos-linux64-ix-082", 14:18:28 INFO - "repo_path": "mozilla-central" 14:18:28 INFO - } 14:18:28 INFO - Finding installer, test and symbols from parent task. 14:18:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AwjAEzh2Td2nFCtMasc2Rw'}, attempt #1 14:18:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AwjAEzh2Td2nFCtMasc2Rw'}, attempt #1 14:18:29 INFO - Task dependencies: V7ZwxuDyRX-_fu7gAE5ohQ 14:18:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/V7ZwxuDyRX-_fu7gAE5ohQ'}, attempt #1 14:18:29 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/V7ZwxuDyRX-_fu7gAE5ohQ/artifacts/public/build/target.tar.bz2 14:18:29 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/V7ZwxuDyRX-_fu7gAE5ohQ/artifacts/public/build/target.test_packages.json 14:18:29 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/V7ZwxuDyRX-_fu7gAE5ohQ/artifacts/public/build/target.crashreporter-symbols.zip 14:18:29 INFO - Running post-action listener: _resource_record_post_action 14:18:29 INFO - [mozharness: 2017-10-03 21:18:29.670326Z] Finished read-buildbot-config step (success) 14:18:29 INFO - [mozharness: 2017-10-03 21:18:29.670544Z] Running download-and-extract step. 14:18:29 INFO - Running pre-action listener: _resource_record_pre_action 14:18:29 INFO - Running main action method: download_and_extract 14:18:29 INFO - mkdir: /builds/slave/test/build/tests 14:18:29 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')]} 14:18:29 INFO - trying https://queue.taskcluster.net/v1/task/V7ZwxuDyRX-_fu7gAE5ohQ/artifacts/public/build/target.test_packages.json 14:18:29 INFO - Downloading https://queue.taskcluster.net/v1/task/V7ZwxuDyRX-_fu7gAE5ohQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 14:18:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/V7ZwxuDyRX-_fu7gAE5ohQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 14:18:30 INFO - Downloaded 989 bytes. 14:18:31 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 14:18:31 INFO - Using the following test package requirements: 14:18:31 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 14:18:31 INFO - u'common': [u'target.common.tests.zip'], 14:18:31 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 14:18:31 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 14:18:31 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 14:18:31 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 14:18:31 INFO - u'mozbase': [u'target.common.tests.zip'], 14:18:31 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 14:18:31 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 14:18:31 INFO - u'web-platform': [u'target.common.tests.zip', 14:18:31 INFO - u'target.web-platform.tests.tar.gz'], 14:18:31 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 14:18:31 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 14:18:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/V7ZwxuDyRX-_fu7gAE5ohQ/artifacts/public/build/target.common.tests.zip 14:18:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/V7ZwxuDyRX-_fu7gAE5ohQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 14:18:31 INFO - Fetch https://queue.taskcluster.net/v1/task/V7ZwxuDyRX-_fu7gAE5ohQ/artifacts/public/build/target.common.tests.zip into memory 14:18:34 INFO - Content-Length response header: 55304183 14:18:34 INFO - Bytes received: 55304183 14:18:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/V7ZwxuDyRX-_fu7gAE5ohQ/artifacts/public/build/target.talos.tests.zip 14:18:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/V7ZwxuDyRX-_fu7gAE5ohQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 14:18:44 INFO - Fetch https://queue.taskcluster.net/v1/task/V7ZwxuDyRX-_fu7gAE5ohQ/artifacts/public/build/target.talos.tests.zip into memory 14:18:46 INFO - Content-Length response header: 14533844 14:18:46 INFO - Bytes received: 14533844 14:18:47 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')]} 14:18:47 INFO - trying https://queue.taskcluster.net/v1/task/V7ZwxuDyRX-_fu7gAE5ohQ/artifacts/public/build/target.tar.bz2 14:18:47 INFO - Downloading https://queue.taskcluster.net/v1/task/V7ZwxuDyRX-_fu7gAE5ohQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 14:18:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/V7ZwxuDyRX-_fu7gAE5ohQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 14:18:50 INFO - Downloaded 61572871 bytes. 14:18:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/V7ZwxuDyRX-_fu7gAE5ohQ/artifacts/public/build/target.tar.bz2 14:18:50 INFO - mkdir: /builds/slave/test/properties 14:18:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:18:50 INFO - Writing to file /builds/slave/test/properties/build_url 14:18:50 INFO - Contents: 14:18:50 INFO - build_url:https://queue.taskcluster.net/v1/task/V7ZwxuDyRX-_fu7gAE5ohQ/artifacts/public/build/target.tar.bz2 14:18:50 INFO - Running post-action listener: _resource_record_post_action 14:18:50 INFO - Running post-action listener: find_tests_for_verification 14:18:50 INFO - Running post-action listener: set_extra_try_arguments 14:18:50 INFO - [mozharness: 2017-10-03 21:18:50.309654Z] Finished download-and-extract step (success) 14:18:50 INFO - [mozharness: 2017-10-03 21:18:50.309883Z] Running populate-webroot step. 14:18:50 INFO - Running pre-action listener: _resource_record_pre_action 14:18:50 INFO - Running main action method: populate_webroot 14:18:50 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:18:50 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 14:18:50 INFO - 'tests': ['tresize', 14:18:50 INFO - 'tcanvasmark']}, 14:18:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:18:50 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 14:18:50 INFO - 'tests': ['dromaeo_css', 14:18:50 INFO - 'kraken']}, 14:18:50 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 14:18:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:18:50 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 14:18:50 INFO - 'talos_options': ['--disable-stylo'], 14:18:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:18:50 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 14:18:50 INFO - 'tests': ['damp', 'tps']}, 14:18:50 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 14:18:50 INFO - 'talos_options': ['--disable-stylo'], 14:18:50 INFO - 'tests': ['damp', 'tps']}, 14:18:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:18:50 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 14:18:50 INFO - 'tests': ['dromaeo_dom']}, 14:18:50 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 14:18:50 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 14:18:50 INFO - 'tests': ['basic_compositor_video', 14:18:50 INFO - 'glvideo']}, 14:18:50 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 14:18:50 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 14:18:50 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 14:18:50 INFO - 'talos_options': ['--disable-stylo'], 14:18:50 INFO - 'tests': ['ts_paint_webext', 14:18:50 INFO - 'tp5o_webext']}, 14:18:50 INFO - 'other-e10s': {'tests': ['a11yr', 14:18:50 INFO - 'ts_paint', 14:18:50 INFO - 'tpaint', 14:18:50 INFO - 'sessionrestore', 14:18:50 INFO - 'sessionrestore_many_windows', 14:18:50 INFO - 'sessionrestore_no_auto_restore', 14:18:50 INFO - 'tabpaint', 14:18:50 INFO - 'cpstartup']}, 14:18:50 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 14:18:50 INFO - 'tests': ['a11yr', 14:18:50 INFO - 'ts_paint', 14:18:50 INFO - 'tpaint', 14:18:50 INFO - 'sessionrestore', 14:18:50 INFO - 'sessionrestore_many_windows', 14:18:50 INFO - 'sessionrestore_no_auto_restore', 14:18:50 INFO - 'tabpaint', 14:18:50 INFO - 'cpstartup']}, 14:18:50 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 14:18:50 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 14:18:50 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 14:18:50 INFO - 'tests': ['perf_reftest_singletons']}, 14:18:50 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 14:18:50 INFO - 'tests': ['perf_reftest']}, 14:18:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:18:50 INFO - 'tsvgr_opacity', 14:18:50 INFO - 'tart', 14:18:50 INFO - 'tscrollx', 14:18:50 INFO - 'tsvg_static']}, 14:18:50 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 14:18:50 INFO - 'tests': ['tsvgx', 14:18:50 INFO - 'tsvgr_opacity', 14:18:50 INFO - 'tart', 14:18:50 INFO - 'tscrollx', 14:18:50 INFO - 'tsvg_static']}, 14:18:50 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 14:18:50 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 14:18:50 INFO - 'talos_options': ['--disable-stylo'], 14:18:50 INFO - 'tests': ['tp5o']}, 14:18:50 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 14:18:50 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 14:18:50 INFO - 'talos_options': ['--mitmproxy', 14:18:50 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 14:18:50 INFO - '--firstNonBlankPaint'], 14:18:50 INFO - 'tests': ['tp6_google', 14:18:50 INFO - 'tp6_youtube', 14:18:50 INFO - 'tp6_amazon', 14:18:50 INFO - 'tp6_facebook']}, 14:18:50 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 14:18:50 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 14:18:50 INFO - 'talos_options': ['--disable-stylo', 14:18:50 INFO - '--mitmproxy', 14:18:50 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 14:18:50 INFO - '--firstNonBlankPaint'], 14:18:50 INFO - 'tests': ['tp6_google', 14:18:50 INFO - 'tp6_youtube', 14:18:50 INFO - 'tp6_amazon', 14:18:50 INFO - 'tp6_facebook']}, 14:18:50 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 14:18:50 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 14:18:50 INFO - 'talos_options': ['--stylo-threads=1', 14:18:50 INFO - '--mitmproxy', 14:18:50 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 14:18:50 INFO - '--firstNonBlankPaint'], 14:18:50 INFO - 'tests': ['tp6_google', 14:18:50 INFO - 'tp6_youtube', 14:18:50 INFO - 'tp6_amazon', 14:18:50 INFO - 'tp6_facebook']}, 14:18:50 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 14:18:50 INFO - 'talos_options': ['--xperf_path', 14:18:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:18:50 INFO - 'tests': ['tp5n']}, 14:18:50 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 14:18:50 INFO - 'talos_options': ['--disable-stylo', 14:18:50 INFO - '--xperf_path', 14:18:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:18:50 INFO - 'tests': ['tp5n']}}} 14:18:50 INFO - Running post-action listener: _resource_record_post_action 14:18:50 INFO - [mozharness: 2017-10-03 21:18:50.328822Z] Finished populate-webroot step (success) 14:18:50 INFO - [mozharness: 2017-10-03 21:18:50.328911Z] Running create-virtualenv step. 14:18:50 INFO - Running pre-action listener: _resource_record_pre_action 14:18:50 INFO - Running main action method: create_virtualenv 14:18:50 INFO - Creating virtualenv /builds/slave/test/build/venv 14:18:50 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 14:18:50 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 14:18:50 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 14:18:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:18:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:18:50 INFO - Installing distribute.............................................................................................................................................................................................done. 14:18:51 INFO - Installing pip...............done. 14:18:51 INFO - Return code: 0 14:18:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:18:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:18:51 INFO - Reading from file tmpfile_stdout 14:18:51 INFO - Output received: 14:18:51 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 14:18:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:18:51 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')]} 14:18:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:18:51 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')]} 14:18:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:18:51 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 0xe861f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11f39c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11f0d60>, '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 0x11f1a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11f1ef0>, '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': '51fc86400edbde9650d2781a000001d0-1507062476.832498-1090344465', '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 14:18:51 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 14:18:51 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 14:18:51 INFO - Using env: {'DISPLAY': ':0', 14:18:51 INFO - 'HOME': '/home/cltbld', 14:18:51 INFO - 'LANG': 'en_US.UTF-8', 14:18:51 INFO - 'LANGUAGE': 'en_US:en', 14:18:51 INFO - 'LOGNAME': 'cltbld', 14:18:51 INFO - 'MAIL': '/var/mail/cltbld', 14:18:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:51 INFO - 'MOZ_NO_REMOTE': '1', 14:18:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:18:51 INFO - 'NO_EM_RESTART': '1', 14:18:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:18:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:51 INFO - 'PWD': '/builds/slave/test', 14:18:51 INFO - 'SHELL': '/bin/bash', 14:18:51 INFO - 'SHLVL': '1', 14:18:51 INFO - 'TERM': 'linux', 14:18:51 INFO - 'TMOUT': '86400', 14:18:51 INFO - 'USER': 'cltbld', 14:18:51 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1507062476.832498-1090344465', 14:18:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:51 INFO - '_': '/tools/buildbot/bin/python'} 14:18:53 INFO - Ignoring indexes: http://pypi.python.org/simple/ 14:18:53 INFO - Downloading/unpacking pip>=1.5 14:18:53 INFO - Running setup.py egg_info for package pip 14:18:53 INFO - warning: no files found matching 'pip/cacert.pem' 14:18:53 INFO - warning: no files found matching '*.html' under directory 'docs' 14:18:53 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 14:18:53 INFO - no previously-included directories found matching 'docs/_build/_sources' 14:18:53 INFO - Installing collected packages: pip 14:18:53 INFO - Found existing installation: pip 1.1 14:18:53 INFO - Uninstalling pip: 14:18:53 INFO - Successfully uninstalled pip 14:18:53 INFO - Running setup.py install for pip 14:18:53 INFO - warning: no files found matching 'pip/cacert.pem' 14:18:53 INFO - warning: no files found matching '*.html' under directory 'docs' 14:18:53 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 14:18:53 INFO - no previously-included directories found matching 'docs/_build/_sources' 14:18:53 INFO - Installing pip script to /builds/slave/test/build/venv/bin 14:18:53 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 14:18:53 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 14:18:53 INFO - Successfully installed pip 14:18:53 INFO - Cleaning up... 14:18:53 INFO - Return code: 0 14:18:53 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:18:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:18:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:18:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:18:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:18:53 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 0xe861f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11f39c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11f0d60>, '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 0x11f1a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11f1ef0>, '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': '51fc86400edbde9650d2781a000001d0-1507062476.832498-1090344465', '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 14:18:53 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 14:18:53 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 14:18:53 INFO - Using env: {'DISPLAY': ':0', 14:18:53 INFO - 'HOME': '/home/cltbld', 14:18:53 INFO - 'LANG': 'en_US.UTF-8', 14:18:53 INFO - 'LANGUAGE': 'en_US:en', 14:18:53 INFO - 'LOGNAME': 'cltbld', 14:18:53 INFO - 'MAIL': '/var/mail/cltbld', 14:18:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:53 INFO - 'MOZ_NO_REMOTE': '1', 14:18:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:18:53 INFO - 'NO_EM_RESTART': '1', 14:18:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:18:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:53 INFO - 'PWD': '/builds/slave/test', 14:18:53 INFO - 'SHELL': '/bin/bash', 14:18:53 INFO - 'SHLVL': '1', 14:18:53 INFO - 'TERM': 'linux', 14:18:53 INFO - 'TMOUT': '86400', 14:18:53 INFO - 'USER': 'cltbld', 14:18:53 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1507062476.832498-1090344465', 14:18:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:53 INFO - '_': '/tools/buildbot/bin/python'} 14:18:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:18:53 INFO - Downloading/unpacking psutil>=3.1.1 14:18:53 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 14:18:53 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 14:18:53 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 14:18:53 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 14:18:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:18:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:18:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:18:54 INFO - Installing collected packages: psutil 14:18:54 INFO - Running setup.py install for psutil 14:18:54 INFO - building 'psutil._psutil_linux' extension 14:18:54 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 14:18:54 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 14:18:54 INFO - building 'psutil._psutil_posix' extension 14:18:54 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 14:18:55 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 14:18:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:18:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:18:55 INFO - Successfully installed psutil 14:18:55 INFO - Cleaning up... 14:18:55 INFO - Return code: 0 14:18:55 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:18:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:18:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:18:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:18:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:18:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe861f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11f39c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11f0d60>, '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 0x11f1a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11f1ef0>, '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': '51fc86400edbde9650d2781a000001d0-1507062476.832498-1090344465', '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 14:18:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:18:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 14:18:55 INFO - Using env: {'DISPLAY': ':0', 14:18:55 INFO - 'HOME': '/home/cltbld', 14:18:55 INFO - 'LANG': 'en_US.UTF-8', 14:18:55 INFO - 'LANGUAGE': 'en_US:en', 14:18:55 INFO - 'LOGNAME': 'cltbld', 14:18:55 INFO - 'MAIL': '/var/mail/cltbld', 14:18:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:55 INFO - 'MOZ_NO_REMOTE': '1', 14:18:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:18:55 INFO - 'NO_EM_RESTART': '1', 14:18:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:18:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:55 INFO - 'PWD': '/builds/slave/test', 14:18:55 INFO - 'SHELL': '/bin/bash', 14:18:55 INFO - 'SHLVL': '1', 14:18:55 INFO - 'TERM': 'linux', 14:18:55 INFO - 'TMOUT': '86400', 14:18:55 INFO - 'USER': 'cltbld', 14:18:55 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1507062476.832498-1090344465', 14:18:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:55 INFO - '_': '/tools/buildbot/bin/python'} 14:18:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:18:55 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:18:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:18:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:18:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:18:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:18:56 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:18:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:18:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:18:56 INFO - Installing collected packages: mozsystemmonitor 14:18:56 INFO - Running setup.py install for mozsystemmonitor 14:18:56 INFO - Successfully installed mozsystemmonitor 14:18:56 INFO - Cleaning up... 14:18:56 INFO - Return code: 0 14:18:56 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:18:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:18:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:18:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:18:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:18:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xe861f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11f39c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11f0d60>, '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 0x11f1a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11f1ef0>, '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': '51fc86400edbde9650d2781a000001d0-1507062476.832498-1090344465', '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 14:18:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 14:18:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 14:18:56 INFO - Using env: {'DISPLAY': ':0', 14:18:56 INFO - 'HOME': '/home/cltbld', 14:18:56 INFO - 'LANG': 'en_US.UTF-8', 14:18:56 INFO - 'LANGUAGE': 'en_US:en', 14:18:56 INFO - 'LOGNAME': 'cltbld', 14:18:56 INFO - 'MAIL': '/var/mail/cltbld', 14:18:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:56 INFO - 'MOZ_NO_REMOTE': '1', 14:18:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:18:56 INFO - 'NO_EM_RESTART': '1', 14:18:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:18:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:56 INFO - 'PWD': '/builds/slave/test', 14:18:56 INFO - 'SHELL': '/bin/bash', 14:18:56 INFO - 'SHLVL': '1', 14:18:56 INFO - 'TERM': 'linux', 14:18:56 INFO - 'TMOUT': '86400', 14:18:56 INFO - 'USER': 'cltbld', 14:18:56 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1507062476.832498-1090344465', 14:18:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:56 INFO - '_': '/tools/buildbot/bin/python'} 14:18:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:18:56 INFO - Downloading/unpacking jsonschema==2.5.1 14:18:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:18:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:18:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:18:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:18:58 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:18:58 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:18:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:18:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:18:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:18:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:18:58 INFO - Downloading functools32-3.2.3-2.tar.gz 14:18:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:18:58 INFO - warning: no files found matching '*.txt' 14:18:58 INFO - no previously-included directories found matching 'build' 14:18:58 INFO - no previously-included directories found matching 'dist' 14:18:58 INFO - no previously-included directories found matching '.git*' 14:18:58 INFO - Installing collected packages: jsonschema, functools32 14:18:58 INFO - Running setup.py install for functools32 14:18:58 INFO - warning: no files found matching '*.txt' 14:18:58 INFO - no previously-included directories found matching 'build' 14:18:58 INFO - no previously-included directories found matching 'dist' 14:18:58 INFO - no previously-included directories found matching '.git*' 14:18:58 INFO - Successfully installed jsonschema functools32 14:18:58 INFO - Cleaning up... 14:18:58 INFO - Return code: 0 14:18:58 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:18:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:18:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:18:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:18:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:18:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xe861f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11f39c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11f0d60>, '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 0x11f1a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11f1ef0>, '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': '51fc86400edbde9650d2781a000001d0-1507062476.832498-1090344465', '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 14:18:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 14:18:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 14:18:58 INFO - Using env: {'DISPLAY': ':0', 14:18:58 INFO - 'HOME': '/home/cltbld', 14:18:58 INFO - 'LANG': 'en_US.UTF-8', 14:18:58 INFO - 'LANGUAGE': 'en_US:en', 14:18:58 INFO - 'LOGNAME': 'cltbld', 14:18:58 INFO - 'MAIL': '/var/mail/cltbld', 14:18:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:58 INFO - 'MOZ_NO_REMOTE': '1', 14:18:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:18:58 INFO - 'NO_EM_RESTART': '1', 14:18:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:18:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:58 INFO - 'PWD': '/builds/slave/test', 14:18:58 INFO - 'SHELL': '/bin/bash', 14:18:58 INFO - 'SHLVL': '1', 14:18:58 INFO - 'TERM': 'linux', 14:18:58 INFO - 'TMOUT': '86400', 14:18:58 INFO - 'USER': 'cltbld', 14:18:58 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1507062476.832498-1090344465', 14:18:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:58 INFO - '_': '/tools/buildbot/bin/python'} 14:18:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:18:58 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:18:58 INFO - Cleaning up... 14:18:58 INFO - Return code: 0 14:18:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:18:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:18:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:18:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:18:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:18:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xe861f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11f39c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11f0d60>, '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 0x11f1a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11f1ef0>, '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': '51fc86400edbde9650d2781a000001d0-1507062476.832498-1090344465', '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 14:18:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 14:18:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 14:18:58 INFO - Using env: {'DISPLAY': ':0', 14:18:58 INFO - 'HOME': '/home/cltbld', 14:18:58 INFO - 'LANG': 'en_US.UTF-8', 14:18:58 INFO - 'LANGUAGE': 'en_US:en', 14:18:58 INFO - 'LOGNAME': 'cltbld', 14:18:58 INFO - 'MAIL': '/var/mail/cltbld', 14:18:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:58 INFO - 'MOZ_NO_REMOTE': '1', 14:18:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:18:58 INFO - 'NO_EM_RESTART': '1', 14:18:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:18:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:58 INFO - 'PWD': '/builds/slave/test', 14:18:58 INFO - 'SHELL': '/bin/bash', 14:18:58 INFO - 'SHLVL': '1', 14:18:58 INFO - 'TERM': 'linux', 14:18:58 INFO - 'TMOUT': '86400', 14:18:58 INFO - 'USER': 'cltbld', 14:18:58 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1507062476.832498-1090344465', 14:18:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:58 INFO - '_': '/tools/buildbot/bin/python'} 14:18:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:18:58 INFO - Downloading/unpacking blobuploader==1.2.4 14:18:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:18:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:18:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:18:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:18:59 INFO - Downloading blobuploader-1.2.4.tar.gz 14:18:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:19:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:19:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:19:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:19:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:00 INFO - Downloading docopt-0.6.1.tar.gz 14:19:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:19:00 INFO - Installing collected packages: blobuploader, requests, docopt 14:19:00 INFO - Running setup.py install for blobuploader 14:19:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:19:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:19:00 INFO - Running setup.py install for requests 14:19:00 INFO - Running setup.py install for docopt 14:19:01 INFO - Successfully installed blobuploader requests docopt 14:19:01 INFO - Cleaning up... 14:19:01 INFO - Return code: 0 14:19:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:19: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')]} 14:19:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:19: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')]} 14:19:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:19:01 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 0xe861f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11f39c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11f0d60>, '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 0x11f1a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11f1ef0>, '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': '51fc86400edbde9650d2781a000001d0-1507062476.832498-1090344465', '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 14:19:01 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 14:19:01 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 14:19:01 INFO - Using env: {'DISPLAY': ':0', 14:19:01 INFO - 'HOME': '/home/cltbld', 14:19:01 INFO - 'LANG': 'en_US.UTF-8', 14:19:01 INFO - 'LANGUAGE': 'en_US:en', 14:19:01 INFO - 'LOGNAME': 'cltbld', 14:19:01 INFO - 'MAIL': '/var/mail/cltbld', 14:19:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:01 INFO - 'MOZ_NO_REMOTE': '1', 14:19:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:19:01 INFO - 'NO_EM_RESTART': '1', 14:19:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:19:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:19:01 INFO - 'PWD': '/builds/slave/test', 14:19:01 INFO - 'SHELL': '/bin/bash', 14:19:01 INFO - 'SHLVL': '1', 14:19:01 INFO - 'TERM': 'linux', 14:19:01 INFO - 'TMOUT': '86400', 14:19:01 INFO - 'USER': 'cltbld', 14:19:01 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1507062476.832498-1090344465', 14:19:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:01 INFO - '_': '/tools/buildbot/bin/python'} 14:19:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:19:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:19:01 INFO - Running setup.py (path:/tmp/pip-VY4gyX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:19:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:19:01 INFO - Running setup.py (path:/tmp/pip-I8Hc7O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:19:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:19:01 INFO - Running setup.py (path:/tmp/pip-SvmdMh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:19:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:19:01 INFO - Running setup.py (path:/tmp/pip-oeoazj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:19:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:19:01 INFO - Running setup.py (path:/tmp/pip-KVart0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:19:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:19:01 INFO - Running setup.py (path:/tmp/pip-WIb855-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:19:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:19:01 INFO - Running setup.py (path:/tmp/pip-fmrR2T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:19:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:19:01 INFO - Running setup.py (path:/tmp/pip-KbCZ6h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:19:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:19:01 INFO - Running setup.py (path:/tmp/pip-LINNyf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:19:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:19:02 INFO - Running setup.py (path:/tmp/pip-ibjRul-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:19:02 INFO - Running setup.py (path:/tmp/pip-oPBtJd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:19:02 INFO - Running setup.py (path:/tmp/pip-eeXmIB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:19:02 INFO - Running setup.py (path:/tmp/pip-cMoFo8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:19:02 INFO - Running setup.py (path:/tmp/pip-S4euEB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:19:02 INFO - Running setup.py (path:/tmp/pip-Fm34fb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:19:02 INFO - Running setup.py (path:/tmp/pip-X6t_G_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:19:02 INFO - Running setup.py (path:/tmp/pip-5Z73LY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:19:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:19:02 INFO - Running setup.py install for manifestparser 14:19:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:19:02 INFO - Running setup.py install for mozcrash 14:19:02 INFO - Running setup.py install for mozdebug 14:19:02 INFO - Running setup.py install for mozdevice 14:19:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:19:02 INFO - Running setup.py install for mozfile 14:19:03 INFO - Running setup.py install for mozhttpd 14:19:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:19:03 INFO - Running setup.py install for mozinfo 14:19:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:19:03 INFO - Running setup.py install for mozInstall 14:19:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:19:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:19:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:19:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:19:03 INFO - Running setup.py install for mozleak 14:19:03 INFO - Running setup.py install for mozlog 14:19:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:19:03 INFO - Running setup.py install for moznetwork 14:19:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:19:03 INFO - Running setup.py install for mozprocess 14:19:03 INFO - Running setup.py install for mozprofile 14:19:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:19:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:19:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:19:03 INFO - Running setup.py install for mozrunner 14:19:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:19:03 INFO - Running setup.py install for mozscreenshot 14:19:04 INFO - Running setup.py install for moztest 14:19:04 INFO - Running setup.py install for mozversion 14:19:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:19:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:19:04 INFO - Cleaning up... 14:19:04 INFO - Return code: 0 14:19:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:19:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:19:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:19:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:19:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:19:04 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 0xe861f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11f39c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11f0d60>, '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 0x11f1a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11f1ef0>, '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': '51fc86400edbde9650d2781a000001d0-1507062476.832498-1090344465', '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 14:19:04 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 14:19:04 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 14:19:04 INFO - Using env: {'DISPLAY': ':0', 14:19:04 INFO - 'HOME': '/home/cltbld', 14:19:04 INFO - 'LANG': 'en_US.UTF-8', 14:19:04 INFO - 'LANGUAGE': 'en_US:en', 14:19:04 INFO - 'LOGNAME': 'cltbld', 14:19:04 INFO - 'MAIL': '/var/mail/cltbld', 14:19:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:04 INFO - 'MOZ_NO_REMOTE': '1', 14:19:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:19:04 INFO - 'NO_EM_RESTART': '1', 14:19:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:19:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:19:04 INFO - 'PWD': '/builds/slave/test', 14:19:04 INFO - 'SHELL': '/bin/bash', 14:19:04 INFO - 'SHLVL': '1', 14:19:04 INFO - 'TERM': 'linux', 14:19:04 INFO - 'TMOUT': '86400', 14:19:04 INFO - 'USER': 'cltbld', 14:19:04 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1507062476.832498-1090344465', 14:19:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:04 INFO - '_': '/tools/buildbot/bin/python'} 14:19:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:19:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:19:04 INFO - Running setup.py (path:/tmp/pip-S4FuTP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:19:04 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)) 14:19:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:19:04 INFO - Running setup.py (path:/tmp/pip-04bnpS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:19:04 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)) 14:19:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:19:04 INFO - Running setup.py (path:/tmp/pip-BeJrT2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:19:04 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)) 14:19:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:19:04 INFO - Running setup.py (path:/tmp/pip-hD_GJC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:19:04 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)) 14:19:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:19:04 INFO - Running setup.py (path:/tmp/pip-vSVYdS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:19:04 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)) 14:19:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:19:04 INFO - Running setup.py (path:/tmp/pip-ygK2q6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:19:04 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)) 14:19:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:19:04 INFO - Running setup.py (path:/tmp/pip-2xesSX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:19:05 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)) 14:19:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:19:05 INFO - Running setup.py (path:/tmp/pip-iBIDLR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:19:05 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)) 14:19:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:19:05 INFO - Running setup.py (path:/tmp/pip-xf7d0j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:19:05 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)) 14:19:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:19:05 INFO - Running setup.py (path:/tmp/pip-CM1mq6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:19:05 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)) 14:19:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:19:05 INFO - Running setup.py (path:/tmp/pip-bgHx2Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:19:05 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)) 14:19:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:19:05 INFO - Running setup.py (path:/tmp/pip-PlS1FG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:19:05 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)) 14:19:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:19:05 INFO - Running setup.py (path:/tmp/pip-j9ex0h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:19:05 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)) 14:19:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:19:05 INFO - Running setup.py (path:/tmp/pip-rxwd5_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:19:05 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)) 14:19:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:19:05 INFO - Running setup.py (path:/tmp/pip-WElpdc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:19:05 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)) 14:19:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:19:05 INFO - Running setup.py (path:/tmp/pip-2kesS2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:19:05 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)) 14:19:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:19:05 INFO - Running setup.py (path:/tmp/pip-pdpOxd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:19:05 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)) 14:19:05 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)) 14:19:05 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)) 14:19:05 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)) 14:19:05 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)) 14:19:05 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)) 14:19:05 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)) 14:19:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:19: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 14:19: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 14:19: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 14:19: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 14:19:06 INFO - Downloading blessings-1.6.tar.gz 14:19:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:19:06 INFO - Installing collected packages: blessings 14:19:06 INFO - Running setup.py install for blessings 14:19:07 INFO - Successfully installed blessings 14:19:07 INFO - Cleaning up... 14:19:07 INFO - Return code: 0 14:19:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:19:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:19:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:19:07 INFO - Reading from file tmpfile_stdout 14:19:07 INFO - Current package versions: 14:19:07 INFO - argparse == 1.2.1 14:19:07 INFO - blessings == 1.6 14:19:07 INFO - blobuploader == 1.2.4 14:19:07 INFO - docopt == 0.6.1 14:19:07 INFO - functools32 == 3.2.3-2 14:19:07 INFO - jsonschema == 2.5.1 14:19:07 INFO - manifestparser == 1.1 14:19:07 INFO - mozInstall == 1.13 14:19:07 INFO - mozcrash == 1.0 14:19:07 INFO - mozdebug == 0.1 14:19:07 INFO - mozdevice == 0.51 14:19:07 INFO - mozfile == 1.2 14:19:07 INFO - mozhttpd == 0.7 14:19:07 INFO - mozinfo == 0.10 14:19:07 INFO - mozleak == 0.1 14:19:07 INFO - mozlog == 3.5 14:19:07 INFO - moznetwork == 0.27 14:19:07 INFO - mozprocess == 0.25 14:19:07 INFO - mozprofile == 0.28 14:19:07 INFO - mozrunner == 6.13 14:19:07 INFO - mozscreenshot == 0.1 14:19:07 INFO - mozsystemmonitor == 0.3 14:19:07 INFO - moztest == 0.8 14:19:07 INFO - mozversion == 1.4 14:19:07 INFO - psutil == 3.1.1 14:19:07 INFO - requests == 1.2.3 14:19:07 INFO - wsgiref == 0.1.2 14:19:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:19:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:19:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:19:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:19:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:19:07 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 0xe861f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11f39c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11f0d60>, '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 0x11f1a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11f1ef0>, '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': '51fc86400edbde9650d2781a000001d0-1507062476.832498-1090344465', '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 14:19:07 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 14:19:07 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 14:19:07 INFO - Using env: {'DISPLAY': ':0', 14:19:07 INFO - 'HOME': '/home/cltbld', 14:19:07 INFO - 'LANG': 'en_US.UTF-8', 14:19:07 INFO - 'LANGUAGE': 'en_US:en', 14:19:07 INFO - 'LOGNAME': 'cltbld', 14:19:07 INFO - 'MAIL': '/var/mail/cltbld', 14:19:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:07 INFO - 'MOZ_NO_REMOTE': '1', 14:19:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:19:07 INFO - 'NO_EM_RESTART': '1', 14:19:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:19:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:19:07 INFO - 'PWD': '/builds/slave/test', 14:19:07 INFO - 'SHELL': '/bin/bash', 14:19:07 INFO - 'SHLVL': '1', 14:19:07 INFO - 'TERM': 'linux', 14:19:07 INFO - 'TMOUT': '86400', 14:19:07 INFO - 'USER': 'cltbld', 14:19:07 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1507062476.832498-1090344465', 14:19:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:07 INFO - '_': '/tools/buildbot/bin/python'} 14:19:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:19:07 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)) 14:19:07 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)) 14:19:07 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)) 14:19:07 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)) 14:19:07 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)) 14:19:07 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)) 14:19:07 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)) 14:19:07 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)) 14:19:07 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)) 14:19:07 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 14:19:07 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 14:19:07 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 14:19:07 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 14:19:07 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 14:19:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 14:19:08 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)) 14:19:08 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)) 14:19:08 INFO - Installing collected packages: simplejson 14:19:08 INFO - Running setup.py install for simplejson 14:19:08 INFO - building 'simplejson._speedups' extension 14:19:08 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 14:19:09 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 14:19:09 INFO - Successfully installed simplejson 14:19:09 INFO - Cleaning up... 14:19:09 INFO - Return code: 0 14:19:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:19:09 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')]} 14:19:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:19:09 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')]} 14:19:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:19:09 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 0xe861f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11f39c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11f0d60>, '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 0x11f1a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11f1ef0>, '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': '51fc86400edbde9650d2781a000001d0-1507062476.832498-1090344465', '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 14:19:09 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 14:19:09 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 14:19:09 INFO - Using env: {'DISPLAY': ':0', 14:19:09 INFO - 'HOME': '/home/cltbld', 14:19:09 INFO - 'LANG': 'en_US.UTF-8', 14:19:09 INFO - 'LANGUAGE': 'en_US:en', 14:19:09 INFO - 'LOGNAME': 'cltbld', 14:19:09 INFO - 'MAIL': '/var/mail/cltbld', 14:19:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:09 INFO - 'MOZ_NO_REMOTE': '1', 14:19:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:19:09 INFO - 'NO_EM_RESTART': '1', 14:19:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:19:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:19:09 INFO - 'PWD': '/builds/slave/test', 14:19:09 INFO - 'SHELL': '/bin/bash', 14:19:09 INFO - 'SHLVL': '1', 14:19:09 INFO - 'TERM': 'linux', 14:19:09 INFO - 'TMOUT': '86400', 14:19:09 INFO - 'USER': 'cltbld', 14:19:09 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1507062476.832498-1090344465', 14:19:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:09 INFO - '_': '/tools/buildbot/bin/python'} 14:19:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:19:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:19:09 INFO - Cleaning up... 14:19:10 INFO - Return code: 0 14:19:10 INFO - Running post-action listener: _resource_record_post_action 14:19:10 INFO - Running post-action listener: _start_resource_monitoring 14:19:10 INFO - Starting resource monitoring. 14:19:10 INFO - [mozharness: 2017-10-03 21:19:10.034004Z] Finished create-virtualenv step (success) 14:19:10 INFO - [mozharness: 2017-10-03 21:19:10.034522Z] Running install step. 14:19:10 INFO - Running pre-action listener: _resource_record_pre_action 14:19:10 INFO - Running main action method: install 14:19:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:19:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:19:10 INFO - Reading from file tmpfile_stdout 14:19:10 INFO - Detecting whether we're running mozinstall >=1.0... 14:19:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:19:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:19:10 INFO - Reading from file tmpfile_stdout 14:19:10 INFO - Output received: 14:19:10 INFO - Usage: mozinstall [options] installer 14:19:10 INFO - Options: 14:19:10 INFO - -h, --help show this help message and exit 14:19:10 INFO - -d DEST, --destination=DEST 14:19:10 INFO - Directory to install application into. [default: 14:19:10 INFO - "/builds/slave/test"] 14:19:10 INFO - --app=APP Application being installed. [default: firefox] 14:19:10 INFO - mkdir: /builds/slave/test/build/application 14:19:10 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'] 14:19:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 14:19:28 INFO - Reading from file tmpfile_stdout 14:19:28 INFO - Output received: 14:19:28 INFO - /builds/slave/test/build/application/firefox/firefox 14:19:28 INFO - Running post-action listener: _resource_record_post_action 14:19:28 INFO - [mozharness: 2017-10-03 21:19:28.194085Z] Finished install step (success) 14:19:28 INFO - [mozharness: 2017-10-03 21:19:28.194336Z] Running setup-mitmproxy step. 14:19:28 INFO - Running pre-action listener: _resource_record_pre_action 14:19:28 INFO - Running main action method: setup_mitmproxy 14:19:28 INFO - Skipping: mitmproxy is not required 14:19:28 INFO - Running post-action listener: _resource_record_post_action 14:19:28 INFO - [mozharness: 2017-10-03 21:19:28.195512Z] Finished setup-mitmproxy step (success) 14:19:28 INFO - [mozharness: 2017-10-03 21:19:28.195753Z] Running run-tests step. 14:19:28 INFO - Running pre-action listener: _resource_record_pre_action 14:19:28 INFO - Running pre-action listener: _set_gcov_prefix 14:19:28 INFO - Running main action method: run_tests 14:19:28 WARNING - Try message not found. 14:19:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:19:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:19:28 INFO - Python 2.7.3 14:19:28 INFO - Return code: 0 14:19:28 INFO - grabbing minidump binary from tooltool 14:19:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:19:28 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11f0d60>, '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 0x11f1a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11f1ef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:19:28 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 14:19:28 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 14:19:28 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 14:19:28 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:19:28 INFO - Return code: 0 14:19:28 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 14:19:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:19:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:19:28 INFO - ENV: RUST_BACKTRACE is now full 14:19:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 14:19:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:19:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/V7ZwxuDyRX-_fu7gAE5ohQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-082', '--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 14:19:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/V7ZwxuDyRX-_fu7gAE5ohQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-082 --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 14:19:28 INFO - Using env: {'DISPLAY': ':0', 14:19:28 INFO - 'HOME': '/home/cltbld', 14:19:28 INFO - 'LANG': 'en_US.UTF-8', 14:19:28 INFO - 'LANGUAGE': 'en_US:en', 14:19:28 INFO - 'LOGNAME': 'cltbld', 14:19:28 INFO - 'MAIL': '/var/mail/cltbld', 14:19:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:19:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:19:28 INFO - 'MOZ_ACCELERATED': '1', 14:19:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:28 INFO - 'MOZ_NO_REMOTE': '1', 14:19:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:19:28 INFO - 'MOZ_WEBRENDER': '1', 14:19:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:19:28 INFO - 'NO_EM_RESTART': '1', 14:19:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:19:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:19:28 INFO - 'PWD': '/builds/slave/test', 14:19:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:19:28 INFO - 'RUST_BACKTRACE': 'full', 14:19:28 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 14:19:28 INFO - 'SHELL': '/bin/bash', 14:19:28 INFO - 'SHLVL': '1', 14:19:28 INFO - 'TERM': 'linux', 14:19:28 INFO - 'TMOUT': '86400', 14:19:28 INFO - 'USER': 'cltbld', 14:19:28 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1507062476.832498-1090344465', 14:19:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:28 INFO - '_': '/tools/buildbot/bin/python'} 14:19:28 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/V7ZwxuDyRX-_fu7gAE5ohQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-082', '--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 14:19:28 INFO - mozversion application_buildid: 20171003202555 14:19:28 INFO - mozversion application_changeset: 65a5054a1f922b83929c80658062f441ca3da6a0 14:19:28 INFO - mozversion application_display_name: Nightly 14:19:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:19:28 INFO - mozversion application_name: Firefox 14:19:28 INFO - mozversion application_remotingname: firefox 14:19:28 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 14:19:28 INFO - mozversion application_vendor: Mozilla 14:19:28 INFO - mozversion application_version: 58.0a1 14:19:28 INFO - mozversion platform_buildid: 20171003202555 14:19:28 INFO - mozversion platform_changeset: 65a5054a1f922b83929c80658062f441ca3da6a0 14:19:28 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 14:19:28 INFO - mozversion platform_version: 58.0a1 14:19:28 INFO - using testdate: 1507065568 14:19:28 INFO - actual date: 1507065568 14:19:28 INFO - starting webserver on 'localhost:52794' 14:19:28 INFO - SUITE-START | Running 2 tests 14:19:28 INFO - TEST-START | dromaeo_css 14:19:28 INFO - Initialising browser for dromaeo_css test... 14:19:28 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:52794/getInfo.html -profile /tmp/tmpHVh6Xi/profile 14:19:28 INFO - TEST-INFO | started process 4578 (/builds/slave/test/build/application/firefox/firefox http://localhost:52794/getInfo.html) 14:19:36 INFO - TEST-INFO | 4578: exit 0 14:19:36 INFO - Browser initialized. 14:19:36 INFO - Running cycle 1/1 for dromaeo_css test... 14:19:36 INFO - Using env: {'DISPLAY': ':0', 14:19:36 INFO - 'HOME': '/home/cltbld', 14:19:36 INFO - 'JSGC_DISABLE_POISONING': '1', 14:19:36 INFO - 'LANG': 'en_US.UTF-8', 14:19:36 INFO - 'LANGUAGE': 'en_US:en', 14:19:36 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 14:19:36 INFO - 'LOGNAME': 'cltbld', 14:19:36 INFO - 'MAIL': '/var/mail/cltbld', 14:19:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:19:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:19:36 INFO - 'MOZ_ACCELERATED': '1', 14:19:36 INFO - 'MOZ_CRASHREPORTER': '1', 14:19:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:36 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 14:19:36 INFO - 'MOZ_NO_REMOTE': '1', 14:19:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:19:36 INFO - 'MOZ_WEBRENDER': '1', 14:19:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:19:36 INFO - 'NO_EM_RESTART': '1', 14:19:36 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:19:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:19:36 INFO - 'PWD': '/builds/slave/test', 14:19:36 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:19:36 INFO - 'RUST_BACKTRACE': 'full', 14:19:36 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 14:19:36 INFO - 'SHELL': '/bin/bash', 14:19:36 INFO - 'SHLVL': '1', 14:19:36 INFO - 'TERM': 'linux', 14:19:36 INFO - 'TMOUT': '86400', 14:19:36 INFO - 'USER': 'cltbld', 14:19:36 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1507062476.832498-1090344465', 14:19:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:36 INFO - '_': '/tools/buildbot/bin/python'} 14:19:36 INFO - TEST-INFO | started process 4824 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHVh6Xi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 14:19:37 INFO - PID 4824 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 14:19:37 INFO - PID 4824 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 14:19:37 INFO - PID 4824 | 14:19:37 INFO - PID 4824 | (/builds/slave/test/build/application/firefox/firefox:4884): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 14:19:37 INFO - PID 4824 | 14:19:48 INFO - PID 4824 | RSS: Main: 190443520 14:19:48 INFO - PID 4824 | 14:22:45 INFO - PID 4824 | Cycle 1(1): loaded http://localhost:52794/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52794/tests/dromaeo/cssquery-ext.html) 14:22:46 INFO - PID 4824 | RSS: Main: 185655296 14:22:46 INFO - PID 4824 | 14:25:49 INFO - PID 4824 | Cycle 1(1): loaded http://localhost:52794/tests/dromaeo/cssquery-ext.html (next: http://localhost:52794/tests/dromaeo/cssquery-jquery.html) 14:25:49 INFO - PID 4824 | RSS: Main: 184725504 14:25:49 INFO - PID 4824 | 14:28:51 INFO - PID 4824 | Cycle 1(1): loaded http://localhost:52794/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52794/tests/dromaeo/cssquery-mootools.html) 14:28:52 INFO - PID 4824 | RSS: Main: 185683968 14:28:52 INFO - PID 4824 | 14:31:54 INFO - PID 4824 | Cycle 1(1): loaded http://localhost:52794/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52794/tests/dromaeo/cssquery-prototype.html) 14:31:55 INFO - PID 4824 | RSS: Main: 185233408 14:31:55 INFO - PID 4824 | 14:34:59 INFO - PID 4824 | Cycle 1(1): loaded http://localhost:52794/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52794/tests/dromaeo/cssquery-yui.html) 14:34:59 INFO - PID 4824 | RSS: Main: 188080128 14:34:59 INFO - PID 4824 | 14:38:01 INFO - PID 4824 | Cycle 1(1): loaded http://localhost:52794/tests/dromaeo/cssquery-yui.html (next: http://localhost:52794/tests/dromaeo/cssquery-dojo.html) 14:38:01 INFO - PID 4824 | RSS: Main: 189276160 14:38:01 INFO - PID 4824 | 14:38:01 INFO - PID 4824 | __start_tp_report 14:38:01 INFO - PID 4824 | _x_x_mozilla_page_load 14:38:01 INFO - PID 4824 | _x_x_mozilla_page_load_details 14:38:01 INFO - PID 4824 | |i|pagename|runs| 14:38:01 INFO - PID 4824 | |0;dojo.html;3110.673379295173;3125.9531107033386;3136.1845920060778;3144.072498612909;3157.652658207598;6177.84555386115;6179.536534759888;6187.5049996000325;6234.594751341159;6364.904526432108;6236.84407889803;6270.028145637433;6285.402886725765;6289.842753931154;6329.683515824214;5732.0828667413225;5757.1364295355825;5805.954928112938;5815.505682017012;5835.9787037268525;5887.8528036799;5911;5950.464458198761;5987.191729116579;5991.191189189447;5832.329282132567;5845.532357411397;5921.496672782819;5926.377730338317;5950.851228719273;696.2912818529079;5701.6864072476;5711.942880571224;5712.885742285131;5874.295084589877;5710.885782284331;5781.277340332458;5788.855278618026;5806.44838740319;5818.709064546798;6285.308616052231;6330.936690633127;6337.904931426032;6400.7119679614525;6423.710933008026;6717.66411679419;6826.351496607815;6868.210155832094;6869;6872.896906546406;6755.2231493378395;6863.485238607124;6865.107536020286;6918.135233095863;6965.895511567331;6201.937980620181;6248.531360147962;6254.0931564923285;6275.592086514362;6429.646369449679;6787;6828.180618325832;6886;6921.688524016431;6930.272321406281;6290.654014029273;6568.277489476154;6594.472442204612;6610.305917878553;6674.699638516278;6763.492738044666;6818.249992500821;6847.589144651337;6890.689918953659;6905;6750.662466876636;6759.121314229217;6768.796936091925;6828.897566536506;6837.487188460886;6566.3105373936005;6773.525853190229;6826.385625293748;6843.555168914005;6858.3141685831015;4987.925181122286;4990.251462280615;4995.400551933792;5030.245463180479;5033.974830125899;4343.261645520269;4369.71596846204;4399.428074350314;4408.515063343029;4421.690481666253;5724.25584673998;5792.565557583114;5807.448292412214;5816.534677225896;5824.825255242349;6706.497012724065;6771.187457505032;6807.795766127024;6869.725210991604;6869.862602747917;5648.435156484319;5729.684867332254;5759.424057594207;5791.247137872134;5822.621529600562;5841.211436456109;5885.970570147122;5906.143609176645;5910.083936989773;5932.8813423732145;5953.166556682068;6507.8698426031215;6644.767433139914;6647.468202543785;6658.400743933069;6836.555623884432;6870.862582748217;6875.278095799869;6920.861582768316;6922.723091076502;6321.841953951188;6501.414872661483;6530.053142294436;6602.537822352389;6639.435647969976;6528.314526974599;6534.987077003072;6538.934610653832;6685.866282674417;6755.560888542229;6856.142982127234;6901.516893817385;6947.68735406918;6967.09427774386;6983.9650801745665;6716.126903502513;6717.05961165447;6750;6754.763583274562;6773.830654233683;6575.24384695747;6587.9670601646685;6616.603003819594;6672.932330827044;6709.127813384229;6317.273513546001;6485.967570162119;6529.804105876831;6619.569727967667;6637.568558043712;6539.411452969255;6571.277159512354;6579.835504112435;6633.369829866155;6640.335966403321;6291.842703932438;6425.614463132227;6447;6469.353064693493;6492.253390860112 14:38:01 INFO - PID 4824 | |1;ext.html;852.6078004118106;883.3639779358863;890.421226202968;891.942023768455;893.4058850864176;39529.80235098824;42039.789801051025;42121.789391053004;42132.15735685285;42305.576944230546;38278.4258236126;38311.61688383116;38646.420303695464;38762.612373876254;38832.22335553287;7068.999999999987;7187.748428804993;7195.532290401134;7198.316159964821;7199.244079371643;7012.333828286304;7074.893876591856;7158.319959603856;7172.103487064117;7215.062041934568;7198.100237470317;7771.494852834548;7878.290953814156;7928.682852685914;7988.320992715626;7834.56909869957;7846.293833554951;8017.078036025815;8033.919660803375;8036.276735093869;7834.804129896741;7851.332636725885;7853.960730196312;7872.645730942121;7918.0498340198965;38378.232435351136;38662;38686.80656596727;38816;38838.41742373867;3493.5283736695474;3588.784672919633;3595.712343012579;3596.8021758803256;3597.226596281812;31335.84332078348;31565.36869262625;31575.36849263025;31684.366312673847;31943.680563194303;5013.874653133665;5038.244263360489;5113.079645663779;5130.204818253177;5152.072626927152;173114.13442932832;174556.12721936437;177581;177843;178791;47753.761231193625;48550.5144948546;48846;48905.755471222416;49163.262551061765;50671.493285067416;51409.74295128501;51454.48545514572;51726;51745.48254517407;16477.83522164787;16547.586310342096;16548.83451165497;16646.08446535452;16687.666246675242;13483.258420786951;13508.189508629514;13614.931925340508;13628.93185534066;13631.931840340734;14005.229712365917;14073.929630351782;14107.576772696835;14179.50371737005;14321.35553900077;5543.6396634218645;5642.266505354278;5642.971785141048;5737.540996720336;5773.134029895549;994.7015895231401;998.5905778630872;1006.6325791086201;1015.8933312002281;1021.0555236406294;22454.550908981728;22674.773252267267;22678.206262780725;22872.542549148922;23121.53756924852;1104.0946423932298;1114.3648120571359;1116.0569318925495;1127.0926903842321;1134.8524691790014;11884.40577971098;12007.819882701591;12008.159428839897;12074.732153123796;12134.271943683407;217031;223443;232430;232698;233379;52857.73571132197;52917.2062419064;53102.20346694803;53372;53483.46516534863;1434.1968497641146;1471.5953112893826;1474.1670955909876;1484.6288427893028;1492.0525466328743;22267.77732222657;22410.663840042085;22575.322740317817;22622.321330360115;22737.431564211027;36805.63194368022;36886.26227475489;36980.81509592435;37076.44385334276;37313.81343093267;182340;182629;182934;183278;185013;30282;30539.694603054573;30850.69149308478;31149.377012460078;33180.66819331776;37217.4417383734;41743;41754.58245417507;42982.570174297856;43169.568304316555;31124.53313200349;31318;31571.36857262796;31798.682013179572;32382;30575.023892959223;31134.844325778227;31318.68681313158;31414.900478483145;31610;35801.28397432089;36239.094022649646;36307;36360;36444;30566;31070.844645776626;31155.221119472193;31163;31659.84170079135;22503.662445063008;22606.77393226047;22742.65886011744;22850.31449056531;22922.885385572965 14:38:01 INFO - PID 4824 | |2;jquery.html;562.1483452569358;568.9630174038684;570.7488704969816;590.7902694543437;610.4688920639044;21278.89360553199;22671.54656906863;22772.430689232777;22802;22805;24038.63942040871;24803.62794558083;25161.119360822377;25285.620715689278;25512.362190945234;6411.5191360647905;6451.419372256496;6480.481561475094;6486.61080335179;6504.739810407602;6430.356964303579;6494.155759751226;6567.47460203185;6569.146011018561;6569.737210511574;6189.2572891252885;6504.479641628682;6547.181602299713;6572.835679108012;6573;6412.711427985705;6470.838229044264;6498.155239818817;6545.0509676096935;6548.770793022221;5837.678927658977;6006.909896351558;6016.909746353808;6074.5747797654185;6103.847403814896;26998.5950210747;27436.862815685792;27462.725372746216;27477.31306717348;27490;2057.238821635989;2059.227789578908;2063.0819285417997;2065.308121779206;2076.3148161106797;17862.821371786245;18779.34272300463;19019.61960760777;19045;19069.427917162506;2437;2448.2532827487607;2491.0409492345398;2496.213692686816;2498.8625625590585;109703.16038938599;114902;115171.42414287875;130923.6907630921;131120;14483.309344006782;14633.634159146106;14758.694446248961;14795.41440929085;14937.954343195872;23257.7674223259;23482.178123765927;23546.293611191693;23550.351475396557;23657.645135322982;9306.208972237435;9868.802623947624;9961.701148965543;9970.451625160686;10033.09702126812;8922.86615700765;8957.731268061969;9066.138716821892;9103.772405689911;9198.816023679488;8901.910980890238;8929.285657147413;8982.775430614156;9119.407238529475;9171.495567743705;2696.8112232143562;2717.2120085175475;2723.9182824515296;2767.9446411071667;2791.4277573097465;950.2493030505962;970.96601618943;981.215027977615;987.6345752071779;993.8012397520525;18804.34184803553;18813;18864.811351886307;19065.142068606947;19272.1327540261;1020.3928662445982;1026.471367245869;1038.1072277841051;1041.671873359889;1043.9112675422675;10436.2694611378;10498.265121441578;10544.367337959748;10613.84079238812;10734.838977415346;422483;446730;449114;451395;472211;108015;108298.45850770854;109299;109886.45056774665;110187;1008.5814387029448;1062.5749700120014;1119.361963680694;1130.3556972525746;1179.292424545266;19068.904655476636;19314.420567383;19565.8043419564;19704;19939.501512462306;54217;55057;55437;56134.1579876294;56758.71620641871;69161.30838691548;69505;70094.94857577242;70308;70414.64792676004;13920.234387108807;13951.232682202577;14057.437702491785;14116.78824817608;14177.22025288619;21857;22100.889495553063;22101.226457073924;22155.224567140718;22168.88915555412;17487.912560437115;17642.5589360267;17783.28866845312;17927.73108403349;18014;15561.455349062728;15667.608309792346;15847.128407937675;15857.524274271791;16019.439319623758;17833;17871.28514859391;17957.910210448863;18023.279068837623;18398.724019139456;13753.931230343784;13863.514776982756;13905.930470347583;13930.442782288595;14033.859661403256;17197.74203386925;17202.569935751708;17594.20826062856;17700.46898593044;17873 14:38:01 INFO - PID 4824 | |3;mootools.html;1323.3317174826714;1404.9999999999993;1405.9226742529156;1408.97886531702;1418.2625034981809;14553.126812391263;15131.773023404658;15208.391664333414;15226.619334516643;15234.238288085578;14809.259537023132;14820.703585928275;14856.628584285398;14856.777148342784;14933.402663893432;2671.919842404731;2675.4247836715103;2729.3586007288272;2741.0954385052883;2743.1222008957143;2653.962235913293;2655.296346468178;2690.4888071266496;2692.67687877455;2698.689650690176;2570.575854983925;2574.4979728952862;2670.2790246633494;2780.9721902781016;2782.8886844526196;2757.2831063923322;2780.110364683302;2784.192584150594;2792.4694308081503;2792.48339057273;2601.6747906511687;2613.9869300653563;2631.7499837515406;2634.7628713415884;2649.5893136563864;14237.928810355985;14521.709565808731;14528;14922.62693432662;15005.924970375187;7799.3760499159935;8107;8148.837023259561;8157.061937877161;8159.75520734373;5285.154375299933;5422.186671999192;5466.496713403806;5534.4742249486635;5551.333839939233;1807.2951548895942;1815.963680726378;1821.5537193387704;1867.8972656504022;1885.1139964216934;25417.237482875542;25629.48741025169;25674.48651026969;25701;25729.099481518057;6710.496712746563;6710.932890671128;6780.966095169492;6884.65576721162;6918.93081069193;17154.742678859828;17262.05058721757;17268.913655431643;17286.22212000463;17316.220770065378;7677.846443071107;7807.453478256468;7813.882791758128;7814.570198639016;7830.060392752792;6849.691763870638;7040.190378106583;7181.784546463615;7188.964055179691;7212.639368031578;5011.799528018912;5224.294720212799;5269.235960785664;5317.760700768475;5343.732813359316;1540.1529158962526;1549.1866769945868;1552.7127481415973;1559.6412825050074;1562.0861795849555;865.7446053414258;894.190757364586;909.2544113826731;910.1944778870615;915.2540679346312;7201.8094396429515;7317.743878964211;7414.14737305206;7416.40668746499;7490.101187857492;721.0626185958233;737.4321772235348;744.2037020388133;746.2537462537463;759.1497522774528;4764.7611620978105;4857.1742803723455;4963.8014479421145;5234.502722241381;5396.811111611153;19724.90137549332;20455.28406505765;20477.897610511853;21172;21188.682169767464;20207.393778186677;20895.373138805557;20974.685379719318;21138.89430552868;21139;10459.790804183875;11181.161412894066;11254.662360129209;11256.437178141225;11264.71838204035;8022.679092836222;8096.433249672466;8125.187481252064;8223.424360294723;8247.711330103417;17461.825381746527;17492.91253543724;17682.64634707273;17710.645787084442;17970.281188752302;18258.817411826243;18313.725294120333;18867.622647546697;18884;19062.61874762525;10987.780244394908;11032.944835276094;11116.777664446827;11219.831702524305;11242.325460472397;13155.802662959872;13405;13528.59414217575;13530.255835929118;13548.525801596898;10854.67435976922;11204;11220.55117795279;11283.887161128283;11429.657110286706;10623;10640.680779576625;10735.89264107349;10901.618443354444;11019.118470522179;9509.809803804024;9514.333996620448;9587.856182157133;9596.712098637052;9635.903640963501;7024.613646249505;7104.254053324326;7119.252478489684;7164.749233776793;7177.641117943977;7128.821779455555;7197.748078817216;7306.1963184048645;7325.41396688253;7450.515716478411 14:38:01 INFO - PID 4824 | |4;prototype.html;505.31782094172894;509.1929292072064;539.2315949771576;542.1217627443538;542.7069382533432;6111.205543279368;6138.355472675372;6210.565260431772;6250.374962503753;6317;6118.357572454895;6145.907811382833;6146.2009938708015;6147.323794382625;6218.84452888678;5142.07442660321;5147.974260128713;5149.716765577892;5170.2503137045105;5217.817376391827;5044.2937988681615;5116.260471439709;5182.274481572583;5208;5224.346956630421;4809.831655892044;4972;4974.925376119361;5005.6496045276845;5014.974925125369;4904.82284251777;4947.6784009039475;4974.751262436882;4983.526564976358;4997.350344455198;4374.365716971053;4451.310046942729;4468.8882777930485;4486.349479325512;4549.499555048942;6080.604760690585;6090.594215284982;6133.877322453571;6139.2325959255095;6147.631142131441;5675.148727690838;5687.544996400278;5712.285964254468;5752.338481074647;5754.884902301972;6460.321666225026;6503.626176445563;6519.674016299166;6532.6733663316645;6537.705803238818;4646.883827904329;4872.561469467765;4879.512048795127;4897.191963326047;4898;6388.297287298394;6505.251896031969;6682.665866706694;6692.077511402395;6781.559198652072;4932.654714169974;4952.777125029383;4961.280614310905;4979;4995.750212489361;5007.524285192973;5049.2426136079885;5065.11360511909;5086.694798312114;5112.463191364927;5588.485181668266;5632.943670563324;5656.547476201894;5674.957015474426;5693.202951586698;6429.292777794439;6483.4164925156965;6551.836204094936;6566.3105373936005;6630.138082049302;1754.5964428181335;1761.8942863428235;1765.4174122539532;1767.469759072273;1770.3184274054581;1164.6839071848926;1212.7938250497436;1239.2626387299542;1243.7263801963554;1245.3026305268897;5567;5604.719764011783;5621.775128994795;5624.353199382042;5643.379228284967;6537.803865883936;6620.2386725527185;6669.699863506154;6703.162104736908;6709.6980635871505;5661.952538780339;5694.857628559319;5697.9430205698245;5701.4298570142655;5733.3406658234;3314.9337013260083;3480.2946556187794;3552.1119720069983;3552.2540266543797;3572.089117275078;6252.656103914237;6297.362685701659;6301.6219026858535;6321.922360077724;6390.45681117101;6703.530752847351;6761.830954226085;6780.966095169492;6788.321167883271;6817.965910170417;6275.811725648238;6319.304876463585;6333.8733225336155;6360.809175724736;6539.049464986839;6566.737330506726;6647.567908085959;6704.195496540447;6741.966290168518;6768.898466523105;6607.240167380621;6649.168853893263;6652.966735166293;6668.66656667155;6713.261541230363;6364.681765911593;6411.230652321565;6461.64460954652;6506.837329066811;6550.333961123689;5011.248312753116;5041.873953151054;5044.8991020178655;5053;5072.82245121419;6431.903521447089;6579.309172536815;6600.029986506187;6640.468762499085;6653.201615806134;6183.732334871431;6431.903521447089;6455.515836312389;6475.287718351072;6499.545031847725;6371.858292110165;6378.266499352634;6445.677716114269;6499.075416062885;6525.771597994048;6224.595401298901;6290.559660823698;6424.807255782334;6446.935530644633;6448.645324507197;6410.61536307823;6454.967725161344;6514;6531.150950376421;6564.901526477011;6179.859494138848;6249.312575616587;6301.495880329471;6325.209348831396;6352.841178970378 14:38:01 INFO - PID 4824 | |5;yui.html;898.6001229452892;899.1907283444895;912.3795818843186;931.986020209697;949.1552518258749;19683.60632787345;19842.40472785817;19933;19972.800271997276;20033;16356.591085222875;18343.816561834377;18610.069496525186;18721.34475293365;18747;951.3483263964172;965.932384733069;969.7963427680196;971.7570607348163;979.4123525884484;961.4952150121208;966.2608104799833;968.2060710217589;969.7721035556639;970.2092794372563;983.8179936711696;1008.349614498648;1015.7562185075602;1022.0597050713325;1023.738946568624;1008.2690049713972;1018.0277834667875;1022.3814592171741;1022.6114076650899;1031.0720351683508;736.1129838544541;742.406075139886;745.697992313114;748.790338705158;748.9775306740806;19927.402177934684;20282.391528254175;20525.486862828548;20581.4854628634;20600.175992960263;20330.79669203304;20887.5822483551;21811;21867.343979680474;21998.120075197134;17176;17382.391616293367;17465.38871139504;17467.301307947666;17679.292828286852;3824.9043773905873;3829.3681542545446;3868.032991752062;3868.7485313454536;3869.9032524186837;98943;137110;138732;140037;140817;17291.827081729272;17691.823081769275;17900.731489027676;17909.641807163785;17933.7309940351;30807;32142.517862232085;32321.51517727236;32335;32477.675223247938;9356.95321523388;9393.248540116774;9468.857967130498;9500;9503.049695030442;4621.976890115528;4652.534746525388;4682.227432473604;4683.063387322549;4712.69367491112;5409.24270602116;5419.485148910847;5454.91817622736;5517.337919449692;5527.309086364205;999.2954966748425;1049.6483677967813;1107.1309022417363;1120.3054106454051;1142.0520967596874;680.496432639843;708.0229283588615;708.1431467923767;710.7938697777587;713.2546488919093;12346.64186939436;12387.08000259943;12515.123941324202;12957.546485872948;13045.282509462071;693.1543516909362;699.422976044765;706.219627311813;708.4297140801704;712.3837880233664;5734.283214598176;5740.339860916049;5781.430794398093;5785.450382213683;5883.794067207627;120404.3979780113;123770;125027.37486312693;126298;129276.35361823319;79723.83793437178;106936.46531767448;110890;111903.44048279869;113949.43025284821;91733;92127;93296;94865.051349487;96255.51872240735;12431.627051188118;12945.54690585825;12996.545120920722;12999.96000319991;13010.544630937871;41421.378679320434;42173.789131054145;42611.7869410651;43117.3532397008;43675;57145.71427142838;57158;57785.13322300085;57912;59235.40764592299;18469.630607388044;18720.625587488445;18847.24611015544;18926.621467570847;19229.134688938753;20768.48078798042;20872.47818804542;21039.8948005259;21123.894380528;21532.89233553822;17646.735298970783;17653.293868245128;17708.032252598558;17732.468025959242;17801.910990444965;16812.495625131265;16855.578610534834;17000.40498582593;17058.744118837978;17248.396306129227;15531.689366212839;15704.76442853381;15774.60563485876;15870.7619385707;16036.679266414372;13613.38739756733;13988.58034258974;14240.92879535637;14373.425062997363;14507.1295722257;13449.798253026016;13482.393292301675;13509.662258443224;13522.256275904527;13666.18002919828 14:38:01 INFO - PID 4824 | __end_tp_report 14:38:01 INFO - PID 4824 | __start_cc_report 14:38:01 INFO - PID 4824 | _x_x_mozilla_cycle_collect,305 14:38:01 INFO - PID 4824 | __end_cc_report 14:38:01 INFO - PID 4824 | __startTimestamp1507066681944__endTimestamp 14:38:01 INFO - PID 4824 | 14:38:01 INFO - PID 4824 | ------- Summary: start ------- 14:38:01 INFO - PID 4824 | Number of tests: 6 14:38:01 INFO - PID 4824 | 14:38:01 INFO - PID 4824 | [#0] dojo.html Cycles:165 Average:6180.07 Median:6485.97 stddev:899.32 (13.9%) stddev-sans-first:869.24 14:38:01 INFO - PID 4824 | Values: 3110.7 3126.0 3136.2 3144.1 3157.7 6177.8 6179.5 6187.5 6234.6 6364.9 6236.8 6270.0 6285.4 6289.8 6329.7 5732.1 5757.1 5806.0 5815.5 5836.0 5887.9 5911.0 5950.5 5987.2 5991.2 5832.3 5845.5 5921.5 5926.4 5950.9 696.3 5701.7 5711.9 5712.9 5874.3 5710.9 5781.3 5788.9 5806.4 5818.7 6285.3 6330.9 6337.9 6400.7 6423.7 6717.7 6826.4 6868.2 6869.0 6872.9 6755.2 6863.5 6865.1 6918.1 6965.9 6201.9 6248.5 6254.1 6275.6 6429.6 6787.0 6828.2 6886.0 6921.7 6930.3 6290.7 6568.3 6594.5 6610.3 6674.7 6763.5 6818.2 6847.6 6890.7 6905.0 6750.7 6759.1 6768.8 6828.9 6837.5 6566.3 6773.5 6826.4 6843.6 6858.3 4987.9 4990.3 4995.4 5030.2 5034.0 4343.3 4369.7 4399.4 4408.5 4421.7 5724.3 5792.6 5807.4 5816.5 5824.8 6706.5 6771.2 6807.8 6869.7 6869.9 5648.4 5729.7 5759.4 5791.2 5822.6 5841.2 5886.0 5906.1 5910.1 5932.9 5953.2 6507.9 6644.8 6647.5 6658.4 6836.6 6870.9 6875.3 6920.9 6922.7 6321.8 6501.4 6530.1 6602.5 6639.4 6528.3 6535.0 6538.9 6685.9 6755.6 6856.1 6901.5 6947.7 6967.1 6984.0 6716.1 6717.1 6750.0 6754.8 6773.8 6575.2 6588.0 6616.6 6672.9 6709.1 6317.3 6486.0 6529.8 6619.6 6637.6 6539.4 6571.3 6579.8 6633.4 6640.3 6291.8 6425.6 6447.0 6469.4 6492.3 14:38:01 INFO - PID 4824 | 14:38:01 INFO - PID 4824 | [#1] ext.html Cycles:180 Average:36441.30 Median:3592.25 stddev:51076.31 (1421.8%) stddev-sans-first:51149.68 14:38:01 INFO - PID 4824 | Values: 852.6 883.4 890.4 891.9 893.4 39529.8 42039.8 42121.8 42132.2 42305.6 38278.4 38311.6 38646.4 38762.6 38832.2 7069.0 7187.7 7195.5 7198.3 7199.2 7012.3 7074.9 7158.3 7172.1 7215.1 7198.1 7771.5 7878.3 7928.7 7988.3 7834.6 7846.3 8017.1 8033.9 8036.3 7834.8 7851.3 7854.0 7872.6 7918.0 38378.2 38662.0 38686.8 38816.0 38838.4 3493.5 3588.8 3595.7 3596.8 3597.2 31335.8 31565.4 31575.4 31684.4 31943.7 5013.9 5038.2 5113.1 5130.2 5152.1 173114.1 174556.1 177581.0 177843.0 178791.0 47753.8 48550.5 48846.0 48905.8 49163.3 50671.5 51409.7 51454.5 51726.0 51745.5 16477.8 16547.6 16548.8 16646.1 16687.7 13483.3 13508.2 13614.9 13628.9 13631.9 14005.2 14073.9 14107.6 14179.5 14321.4 5543.6 5642.3 5643.0 5737.5 5773.1 994.7 998.6 1006.6 1015.9 1021.1 22454.6 22674.8 22678.2 22872.5 23121.5 1104.1 1114.4 1116.1 1127.1 1134.9 11884.4 12007.8 12008.2 12074.7 12134.3 217031.0 223443.0 232430.0 232698.0 233379.0 52857.7 52917.2 53102.2 53372.0 53483.5 1434.2 1471.6 1474.2 1484.6 1492.1 22267.8 22410.7 22575.3 22622.3 22737.4 36805.6 36886.3 36980.8 37076.4 37313.8 182340.0 182629.0 182934.0 183278.0 185013.0 30282.0 30539.7 30850.7 31149.4 33180.7 37217.4 41743.0 41754.6 42982.6 43169.6 31124.5 31318.0 31571.4 31798.7 32382.0 30575.0 31134.8 31318.7 31414.9 31610.0 35801.3 36239.1 36307.0 36360.0 36444.0 30566.0 31070.8 31155.2 31163.0 31659.8 22503.7 22606.8 22742.7 22850.3 22922.9 14:38:01 INFO - PID 4824 | 14:38:01 INFO - PID 4824 | [#2] jquery.html Cycles:180 Average:32613.37 Median:23031.38 stddev:75463.07 (327.7%) stddev-sans-first:75636.39 14:38:01 INFO - PID 4824 | Values: 562.1 569.0 570.7 590.8 610.5 21278.9 22671.5 22772.4 22802.0 22805.0 24038.6 24803.6 25161.1 25285.6 25512.4 6411.5 6451.4 6480.5 6486.6 6504.7 6430.4 6494.2 6567.5 6569.1 6569.7 6189.3 6504.5 6547.2 6572.8 6573.0 6412.7 6470.8 6498.2 6545.1 6548.8 5837.7 6006.9 6016.9 6074.6 6103.8 26998.6 27436.9 27462.7 27477.3 27490.0 2057.2 2059.2 2063.1 2065.3 2076.3 17862.8 18779.3 19019.6 19045.0 19069.4 2437.0 2448.3 2491.0 2496.2 2498.9 109703.2 114902.0 115171.4 130923.7 131120.0 14483.3 14633.6 14758.7 14795.4 14938.0 23257.8 23482.2 23546.3 23550.4 23657.6 9306.2 9868.8 9961.7 9970.5 10033.1 8922.9 8957.7 9066.1 9103.8 9198.8 8901.9 8929.3 8982.8 9119.4 9171.5 2696.8 2717.2 2723.9 2767.9 2791.4 950.2 971.0 981.2 987.6 993.8 18804.3 18813.0 18864.8 19065.1 19272.1 1020.4 1026.5 1038.1 1041.7 1043.9 10436.3 10498.3 10544.4 10613.8 10734.8 422483.0 446730.0 449114.0 451395.0 472211.0 108015.0 108298.5 109299.0 109886.5 110187.0 1008.6 1062.6 1119.4 1130.4 1179.3 19068.9 19314.4 19565.8 19704.0 19939.5 54217.0 55057.0 55437.0 56134.2 56758.7 69161.3 69505.0 70094.9 70308.0 70414.6 13920.2 13951.2 14057.4 14116.8 14177.2 21857.0 22100.9 22101.2 22155.2 22168.9 17487.9 17642.6 17783.3 17927.7 18014.0 15561.5 15667.6 15847.1 15857.5 16019.4 17833.0 17871.3 17957.9 18023.3 18398.7 13753.9 13863.5 13905.9 13930.4 14033.9 17197.7 17202.6 17594.2 17700.5 17873.0 14:38:01 INFO - PID 4824 | 14:38:01 INFO - PID 4824 | [#3] mootools.html Cycles:180 Average:9104.43 Median:2642.18 stddev:6451.12 (244.2%) stddev-sans-first:6442.72 14:38:01 INFO - PID 4824 | Values: 1323.3 1405.0 1405.9 1409.0 1418.3 14553.1 15131.8 15208.4 15226.6 15234.2 14809.3 14820.7 14856.6 14856.8 14933.4 2671.9 2675.4 2729.4 2741.1 2743.1 2654.0 2655.3 2690.5 2692.7 2698.7 2570.6 2574.5 2670.3 2781.0 2782.9 2757.3 2780.1 2784.2 2792.5 2792.5 2601.7 2614.0 2631.7 2634.8 2649.6 14237.9 14521.7 14528.0 14922.6 15005.9 7799.4 8107.0 8148.8 8157.1 8159.8 5285.2 5422.2 5466.5 5534.5 5551.3 1807.3 1816.0 1821.6 1867.9 1885.1 25417.2 25629.5 25674.5 25701.0 25729.1 6710.5 6710.9 6781.0 6884.7 6918.9 17154.7 17262.1 17268.9 17286.2 17316.2 7677.8 7807.5 7813.9 7814.6 7830.1 6849.7 7040.2 7181.8 7189.0 7212.6 5011.8 5224.3 5269.2 5317.8 5343.7 1540.2 1549.2 1552.7 1559.6 1562.1 865.7 894.2 909.3 910.2 915.3 7201.8 7317.7 7414.1 7416.4 7490.1 721.1 737.4 744.2 746.3 759.1 4764.8 4857.2 4963.8 5234.5 5396.8 19724.9 20455.3 20477.9 21172.0 21188.7 20207.4 20895.4 20974.7 21138.9 21139.0 10459.8 11181.2 11254.7 11256.4 11264.7 8022.7 8096.4 8125.2 8223.4 8247.7 17461.8 17492.9 17682.6 17710.6 17970.3 18258.8 18313.7 18867.6 18884.0 19062.6 10987.8 11032.9 11116.8 11219.8 11242.3 13155.8 13405.0 13528.6 13530.3 13548.5 10854.7 11204.0 11220.6 11283.9 11429.7 10623.0 10640.7 10735.9 10901.6 11019.1 9509.8 9514.3 9587.9 9596.7 9635.9 7024.6 7104.3 7119.3 7164.7 7177.6 7128.8 7197.7 7306.2 7325.4 7450.5 14:38:01 INFO - PID 4824 | 14:38:01 INFO - PID 4824 | [#4] prototype.html Cycles:180 Average:5469.71 Median:6138.79 stddev:1514.60 (24.7%) stddev-sans-first:1472.30 14:38:01 INFO - PID 4824 | Values: 505.3 509.2 539.2 542.1 542.7 6111.2 6138.4 6210.6 6250.4 6317.0 6118.4 6145.9 6146.2 6147.3 6218.8 5142.1 5148.0 5149.7 5170.3 5217.8 5044.3 5116.3 5182.3 5208.0 5224.3 4809.8 4972.0 4974.9 5005.6 5015.0 4904.8 4947.7 4974.8 4983.5 4997.4 4374.4 4451.3 4468.9 4486.3 4549.5 6080.6 6090.6 6133.9 6139.2 6147.6 5675.1 5687.5 5712.3 5752.3 5754.9 6460.3 6503.6 6519.7 6532.7 6537.7 4646.9 4872.6 4879.5 4897.2 4898.0 6388.3 6505.3 6682.7 6692.1 6781.6 4932.7 4952.8 4961.3 4979.0 4995.8 5007.5 5049.2 5065.1 5086.7 5112.5 5588.5 5632.9 5656.5 5675.0 5693.2 6429.3 6483.4 6551.8 6566.3 6630.1 1754.6 1761.9 1765.4 1767.5 1770.3 1164.7 1212.8 1239.3 1243.7 1245.3 5567.0 5604.7 5621.8 5624.4 5643.4 6537.8 6620.2 6669.7 6703.2 6709.7 5662.0 5694.9 5697.9 5701.4 5733.3 3314.9 3480.3 3552.1 3552.3 3572.1 6252.7 6297.4 6301.6 6321.9 6390.5 6703.5 6761.8 6781.0 6788.3 6818.0 6275.8 6319.3 6333.9 6360.8 6539.0 6566.7 6647.6 6704.2 6742.0 6768.9 6607.2 6649.2 6653.0 6668.7 6713.3 6364.7 6411.2 6461.6 6506.8 6550.3 5011.2 5041.9 5044.9 5053.0 5072.8 6431.9 6579.3 6600.0 6640.5 6653.2 6183.7 6431.9 6455.5 6475.3 6499.5 6371.9 6378.3 6445.7 6499.1 6525.8 6224.6 6290.6 6424.8 6446.9 6448.6 6410.6 6455.0 6514.0 6531.2 6564.9 6179.9 6249.3 6301.5 6325.2 6352.8 14:38:01 INFO - PID 4824 | 14:38:01 INFO - PID 4824 | [#5] yui.html Cycles:180 Average:24522.31 Median:20590.83 stddev:34367.50 (166.9%) stddev-sans-first:34418.14 14:38:01 INFO - PID 4824 | Values: 898.6 899.2 912.4 932.0 949.2 19683.6 19842.4 19933.0 19972.8 20033.0 16356.6 18343.8 18610.1 18721.3 18747.0 951.3 965.9 969.8 971.8 979.4 961.5 966.3 968.2 969.8 970.2 983.8 1008.3 1015.8 1022.1 1023.7 1008.3 1018.0 1022.4 1022.6 1031.1 736.1 742.4 745.7 748.8 749.0 19927.4 20282.4 20525.5 20581.5 20600.2 20330.8 20887.6 21811.0 21867.3 21998.1 17176.0 17382.4 17465.4 17467.3 17679.3 3824.9 3829.4 3868.0 3868.7 3869.9 98943.0 137110.0 138732.0 140037.0 140817.0 17291.8 17691.8 17900.7 17909.6 17933.7 30807.0 32142.5 32321.5 32335.0 32477.7 9357.0 9393.2 9468.9 9500.0 9503.0 4622.0 4652.5 4682.2 4683.1 4712.7 5409.2 5419.5 5454.9 5517.3 5527.3 999.3 1049.6 1107.1 1120.3 1142.1 680.5 708.0 708.1 710.8 713.3 12346.6 12387.1 12515.1 12957.5 13045.3 693.2 699.4 706.2 708.4 712.4 5734.3 5740.3 5781.4 5785.5 5883.8 120404.4 123770.0 125027.4 126298.0 129276.4 79723.8 106936.5 110890.0 111903.4 113949.4 91733.0 92127.0 93296.0 94865.1 96255.5 12431.6 12945.5 12996.5 13000.0 13010.5 41421.4 42173.8 42611.8 43117.4 43675.0 57145.7 57158.0 57785.1 57912.0 59235.4 18469.6 18720.6 18847.2 18926.6 19229.1 20768.5 20872.5 21039.9 21123.9 21532.9 17646.7 17653.3 17708.0 17732.5 17801.9 16812.5 16855.6 17000.4 17058.7 17248.4 15531.7 15704.8 15774.6 15870.8 16036.7 13613.4 13988.6 14240.9 14373.4 14507.1 13449.8 13482.4 13509.7 13522.3 13666.2 14:38:01 INFO - PID 4824 | -------- Summary: end -------- 14:38:01 INFO - PID 4824 | 14:38:02 INFO - TEST-INFO | 4824: exit 0 14:38:02 INFO - TEST-OK | dromaeo_css | took 1114084ms 14:38:02 INFO - TEST-START | kraken 14:38:02 INFO - Initialising browser for kraken test... 14:38:02 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:52794/getInfo.html -profile /tmp/tmpIB5x87/profile 14:38:02 INFO - TEST-INFO | started process 5243 (/builds/slave/test/build/application/firefox/firefox http://localhost:52794/getInfo.html) 14:38:08 INFO - TEST-INFO | 5243: exit 0 14:38:08 INFO - Browser initialized. 14:38:08 INFO - Running cycle 1/1 for kraken test... 14:38:08 INFO - Using env: {'DISPLAY': ':0', 14:38:08 INFO - 'HOME': '/home/cltbld', 14:38:08 INFO - 'JSGC_DISABLE_POISONING': '1', 14:38:08 INFO - 'LANG': 'en_US.UTF-8', 14:38:08 INFO - 'LANGUAGE': 'en_US:en', 14:38:08 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 14:38:08 INFO - 'LOGNAME': 'cltbld', 14:38:08 INFO - 'MAIL': '/var/mail/cltbld', 14:38:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:38:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:38:08 INFO - 'MOZ_ACCELERATED': '1', 14:38:08 INFO - 'MOZ_CRASHREPORTER': '1', 14:38:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:38:08 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 14:38:08 INFO - 'MOZ_NO_REMOTE': '1', 14:38:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:38:08 INFO - 'MOZ_WEBRENDER': '1', 14:38:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:38:08 INFO - 'NO_EM_RESTART': '1', 14:38:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:38:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:38:08 INFO - 'PWD': '/builds/slave/test', 14:38:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:38:08 INFO - 'RUST_BACKTRACE': 'full', 14:38:08 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 14:38:08 INFO - 'SHELL': '/bin/bash', 14:38:08 INFO - 'SHLVL': '1', 14:38:08 INFO - 'TERM': 'linux', 14:38:08 INFO - 'TMOUT': '86400', 14:38:08 INFO - 'USER': 'cltbld', 14:38:08 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1507062476.832498-1090344465', 14:38:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:38:08 INFO - '_': '/tools/buildbot/bin/python'} 14:38:08 INFO - TEST-INFO | started process 5525 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIB5x87/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 14:38:09 INFO - PID 5525 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 14:38:09 INFO - PID 5525 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 14:38:09 INFO - PID 5525 | 14:38:09 INFO - PID 5525 | (/builds/slave/test/build/application/firefox/firefox:5584): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 14:38:09 INFO - PID 5525 | 14:38:19 INFO - PID 5525 | RSS: Main: 190476288 14:38:19 INFO - PID 5525 | 14:38:22 INFO - PID 5525 | Cycle 1(1): loaded http://localhost:52794/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52794/tests/kraken/driver.html?testName=audio-beat-detection) 14:38:23 INFO - PID 5525 | RSS: Main: 188162048 14:38:23 INFO - PID 5525 | 14:38:26 INFO - PID 5525 | Cycle 1(1): loaded http://localhost:52794/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52794/tests/kraken/driver.html?testName=audio-dft) 14:38:26 INFO - PID 5525 | RSS: Main: 188600320 14:38:26 INFO - PID 5525 | 14:38:29 INFO - PID 5525 | Cycle 1(1): loaded http://localhost:52794/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52794/tests/kraken/driver.html?testName=audio-fft) 14:38:30 INFO - PID 5525 | RSS: Main: 184188928 14:38:30 INFO - PID 5525 | 14:38:32 INFO - PID 5525 | Cycle 1(1): loaded http://localhost:52794/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52794/tests/kraken/driver.html?testName=audio-oscillator) 14:38:33 INFO - PID 5525 | RSS: Main: 184827904 14:38:33 INFO - PID 5525 | 14:38:35 INFO - PID 5525 | Cycle 1(1): loaded http://localhost:52794/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52794/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:38:36 INFO - PID 5525 | RSS: Main: 184717312 14:38:36 INFO - PID 5525 | 14:38:39 INFO - PID 5525 | Cycle 1(1): loaded http://localhost:52794/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52794/tests/kraken/driver.html?testName=imaging-darkroom) 14:38:40 INFO - PID 5525 | RSS: Main: 187113472 14:38:40 INFO - PID 5525 | 14:38:43 INFO - PID 5525 | Cycle 1(1): loaded http://localhost:52794/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52794/tests/kraken/driver.html?testName=imaging-desaturate) 14:38:44 INFO - PID 5525 | RSS: Main: 186527744 14:38:44 INFO - PID 5525 | 14:38:47 INFO - PID 5525 | Cycle 1(1): loaded http://localhost:52794/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52794/tests/kraken/driver.html?testName=json-parse-financial) 14:38:48 INFO - PID 5525 | RSS: Main: 187682816 14:38:48 INFO - PID 5525 | 14:38:49 INFO - PID 5525 | Cycle 1(1): loaded http://localhost:52794/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52794/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:38:50 INFO - PID 5525 | RSS: Main: 187236352 14:38:50 INFO - PID 5525 | 14:38:51 INFO - PID 5525 | Cycle 1(1): loaded http://localhost:52794/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52794/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:38:52 INFO - PID 5525 | RSS: Main: 187318272 14:38:52 INFO - PID 5525 | 14:38:54 INFO - PID 5525 | Cycle 1(1): loaded http://localhost:52794/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52794/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:38:55 INFO - PID 5525 | RSS: Main: 187228160 14:38:55 INFO - PID 5525 | 14:38:58 INFO - PID 5525 | Cycle 1(1): loaded http://localhost:52794/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52794/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:38:58 INFO - PID 5525 | RSS: Main: 187834368 14:38:58 INFO - PID 5525 | 14:39:01 INFO - PID 5525 | Cycle 1(1): loaded http://localhost:52794/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52794/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:39:02 INFO - PID 5525 | RSS: Main: 187797504 14:39:02 INFO - PID 5525 | 14:39:03 INFO - PID 5525 | Cycle 1(1): loaded http://localhost:52794/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52794/tests/kraken/driver.html?testName=ai-astar) 14:39:04 INFO - PID 5525 | RSS: Main: 189145088 14:39:04 INFO - PID 5525 | 14:39:04 INFO - PID 5525 | __start_tp_report 14:39:04 INFO - PID 5525 | _x_x_mozilla_page_load 14:39:04 INFO - PID 5525 | _x_x_mozilla_page_load_details 14:39:04 INFO - PID 5525 | |i|pagename|runs| 14:39:04 INFO - PID 5525 | |0;ai-astar;111;120;109;109;112;107;114;110;119;110 14:39:04 INFO - PID 5525 | |1;audio-beat-detection;124;126;119;131;119;118;134;117;116;147 14:39:04 INFO - PID 5525 | |2;audio-dft;170;170;163;169;173;164;167;163;166;165 14:39:04 INFO - PID 5525 | |3;audio-fft;75;75;76;79;83;75;80;81;86;81 14:39:04 INFO - PID 5525 | |4;audio-oscillator;82;82;79;80;88;84;80;86;88;84 14:39:04 INFO - PID 5525 | |5;imaging-gaussian-blur;111;115;117;110;118;121;115;123;123;115 14:39:04 INFO - PID 5525 | |6;imaging-darkroom;154;151;157;149;154;149;150;148;151;148 14:39:04 INFO - PID 5525 | |7;imaging-desaturate;98;104;97;98;95;95;94;94;98;95 14:39:04 INFO - PID 5525 | |8;json-parse-financial;52;51;52;54;54;53;52;52;53;50 14:39:04 INFO - PID 5525 | |9;json-stringify-tinderbox;37;37;36;40;44;37;37;41;49;45 14:39:04 INFO - PID 5525 | |10;stanford-crypto-aes;83;78;76;74;79;74;83;77;79;78 14:39:04 INFO - PID 5525 | |11;stanford-crypto-ccm;111;118;111;117;114;113;121;113;115;126 14:39:04 INFO - PID 5525 | |12;stanford-crypto-pbkdf2;169;158;157;160;161;162;162;166;175;164 14:39:04 INFO - PID 5525 | |13;stanford-crypto-sha256-iterative;65;65;63;65;72;64;62;67;69;63 14:39:04 INFO - PID 5525 | __end_tp_report 14:39:04 INFO - PID 5525 | __start_cc_report 14:39:04 INFO - PID 5525 | _x_x_mozilla_cycle_collect,784 14:39:04 INFO - PID 5525 | __end_cc_report 14:39:04 INFO - PID 5525 | __startTimestamp1507066744178__endTimestamp 14:39:04 INFO - PID 5525 | 14:39:04 INFO - PID 5525 | ------- Summary: start ------- 14:39:04 INFO - PID 5525 | Number of tests: 14 14:39:04 INFO - PID 5525 | 14:39:04 INFO - PID 5525 | [#0] ai-astar Cycles:10 Average:112.10 Median:111.50 stddev:4.33 (3.9%) stddev-sans-first:4.58 14:39:04 INFO - PID 5525 | Values: 111.0 120.0 109.0 109.0 112.0 107.0 114.0 110.0 119.0 110.0 14:39:04 INFO - PID 5525 | 14:39:04 INFO - PID 5525 | [#1] audio-beat-detection Cycles:10 Average:125.10 Median:125.00 stddev:9.83 (7.9%) stddev-sans-first:10.41 14:39:04 INFO - PID 5525 | Values: 124.0 126.0 119.0 131.0 119.0 118.0 134.0 117.0 116.0 147.0 14:39:04 INFO - PID 5525 | 14:39:04 INFO - PID 5525 | [#2] audio-dft Cycles:10 Average:167.00 Median:168.00 stddev:3.40 (2.0%) stddev-sans-first:3.43 14:39:04 INFO - PID 5525 | Values: 170.0 170.0 163.0 169.0 173.0 164.0 167.0 163.0 166.0 165.0 14:39:04 INFO - PID 5525 | 14:39:04 INFO - PID 5525 | [#3] audio-fft Cycles:10 Average:79.10 Median:80.50 stddev:3.81 (4.7%) stddev-sans-first:3.75 14:39:04 INFO - PID 5525 | Values: 75.0 75.0 76.0 79.0 83.0 75.0 80.0 81.0 86.0 81.0 14:39:04 INFO - PID 5525 | 14:39:04 INFO - PID 5525 | [#4] audio-oscillator Cycles:10 Average:83.30 Median:84.00 stddev:3.27 (3.9%) stddev-sans-first:3.43 14:39:04 INFO - PID 5525 | Values: 82.0 82.0 79.0 80.0 88.0 84.0 80.0 86.0 88.0 84.0 14:39:04 INFO - PID 5525 | 14:39:04 INFO - PID 5525 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.50 stddev:4.54 (3.9%) stddev-sans-first:4.30 14:39:04 INFO - PID 5525 | Values: 111.0 115.0 117.0 110.0 118.0 121.0 115.0 123.0 123.0 115.0 14:39:04 INFO - PID 5525 | 14:39:04 INFO - PID 5525 | [#6] imaging-darkroom Cycles:10 Average:151.10 Median:151.00 stddev:3.00 (2.0%) stddev-sans-first:2.99 14:39:04 INFO - PID 5525 | Values: 154.0 151.0 157.0 149.0 154.0 149.0 150.0 148.0 151.0 148.0 14:39:04 INFO - PID 5525 | 14:39:04 INFO - PID 5525 | [#7] imaging-desaturate Cycles:10 Average:96.80 Median:96.00 stddev:3.01 (3.1%) stddev-sans-first:3.16 14:39:04 INFO - PID 5525 | Values: 98.0 104.0 97.0 98.0 95.0 95.0 94.0 94.0 98.0 95.0 14:39:04 INFO - PID 5525 | 14:39:04 INFO - PID 5525 | [#8] json-parse-financial Cycles:10 Average:52.30 Median:52.50 stddev:1.25 (2.4%) stddev-sans-first:1.32 14:39:04 INFO - PID 5525 | Values: 52.0 51.0 52.0 54.0 54.0 53.0 52.0 52.0 53.0 50.0 14:39:04 INFO - PID 5525 | 14:39:04 INFO - PID 5525 | [#9] json-stringify-tinderbox Cycles:10 Average:40.30 Median:40.50 stddev:4.40 (10.9%) stddev-sans-first:4.50 14:39:04 INFO - PID 5525 | Values: 37.0 37.0 36.0 40.0 44.0 37.0 37.0 41.0 49.0 45.0 14:39:04 INFO - PID 5525 | 14:39:04 INFO - PID 5525 | [#10] stanford-crypto-aes Cycles:10 Average:78.10 Median:78.50 stddev:3.14 (4.0%) stddev-sans-first:2.79 14:39:04 INFO - PID 5525 | Values: 83.0 78.0 76.0 74.0 79.0 74.0 83.0 77.0 79.0 78.0 14:39:04 INFO - PID 5525 | 14:39:04 INFO - PID 5525 | [#11] stanford-crypto-ccm Cycles:10 Average:115.90 Median:116.00 stddev:4.75 (4.1%) stddev-sans-first:4.69 14:39:04 INFO - PID 5525 | Values: 111.0 118.0 111.0 117.0 114.0 113.0 121.0 113.0 115.0 126.0 14:39:04 INFO - PID 5525 | 14:39:04 INFO - PID 5525 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.40 Median:163.00 stddev:5.42 (3.3%) stddev-sans-first:5.36 14:39:04 INFO - PID 5525 | Values: 169.0 158.0 157.0 160.0 161.0 162.0 162.0 166.0 175.0 164.0 14:39:04 INFO - PID 5525 | 14:39:04 INFO - PID 5525 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.50 Median:65.00 stddev:3.06 (4.7%) stddev-sans-first:3.24 14:39:04 INFO - PID 5525 | Values: 65.0 65.0 63.0 65.0 72.0 64.0 62.0 67.0 69.0 63.0 14:39:04 INFO - PID 5525 | -------- Summary: end -------- 14:39:04 INFO - PID 5525 | 14:39:04 INFO - PID 5525 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 14:39:04 INFO - TEST-INFO | 5525: exit 0 14:39:05 INFO - TEST-OK | kraken | took 62384ms 14:39:05 INFO - SUITE-END | took 1176s 14:39:05 INFO - Completed test suite (00:19:36) 14:39:05 INFO - javascript benchmark 14:39:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8463.749059431693, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3110.673379295173, 3125.9531107033386, 3136.1845920060778, 3144.072498612909, 3157.652658207598, 6177.84555386115, 6179.536534759888, 6187.5049996000325, 6234.594751341159, 6364.904526432108, 6236.84407889803, 6270.028145637433, 6285.402886725765, 6289.842753931154, 6329.683515824214, 5732.0828667413225, 5757.1364295355825, 5805.954928112938, 5815.505682017012, 5835.9787037268525, 5887.8528036799, 5911.0, 5950.464458198761, 5987.191729116579, 5991.191189189447, 5832.329282132567, 5845.532357411397, 5921.496672782819, 5926.377730338317, 5950.851228719273, 696.2912818529079, 5701.6864072476, 5711.942880571224, 5712.885742285131, 5874.295084589877, 5710.885782284331, 5781.277340332458, 5788.855278618026, 5806.44838740319, 5818.709064546798, 6285.308616052231, 6330.936690633127, 6337.904931426032, 6400.7119679614525, 6423.710933008026, 6717.66411679419, 6826.351496607815, 6868.210155832094, 6869.0, 6872.896906546406, 6755.2231493378395, 6863.485238607124, 6865.107536020286, 6918.135233095863, 6965.895511567331, 6201.937980620181, 6248.531360147962, 6254.0931564923285, 6275.592086514362, 6429.646369449679, 6787.0, 6828.180618325832, 6886.0, 6921.688524016431, 6930.272321406281, 6290.654014029273, 6568.277489476154, 6594.472442204612, 6610.305917878553, 6674.699638516278, 6763.492738044666, 6818.249992500821, 6847.589144651337, 6890.689918953659, 6905.0, 6750.662466876636, 6759.121314229217, 6768.796936091925, 6828.897566536506, 6837.487188460886, 6566.3105373936005, 6773.525853190229, 6826.385625293748, 6843.555168914005, 6858.3141685831015, 4987.925181122286, 4990.251462280615, 4995.400551933792, 5030.245463180479, 5033.974830125899, 4343.261645520269, 4369.71596846204, 4399.428074350314, 4408.515063343029, 4421.690481666253, 5724.25584673998, 5792.565557583114, 5807.448292412214, 5816.534677225896, 5824.825255242349, 6706.497012724065, 6771.187457505032, 6807.795766127024, 6869.725210991604, 6869.862602747917, 5648.435156484319, 5729.684867332254, 5759.424057594207, 5791.247137872134, 5822.621529600562, 5841.211436456109, 5885.970570147122, 5906.143609176645, 5910.083936989773, 5932.8813423732145, 5953.166556682068, 6507.8698426031215, 6644.767433139914, 6647.468202543785, 6658.400743933069, 6836.555623884432, 6870.862582748217, 6875.278095799869, 6920.861582768316, 6922.723091076502, 6321.841953951188, 6501.414872661483, 6530.053142294436, 6602.537822352389, 6639.435647969976, 6528.314526974599, 6534.987077003072, 6538.934610653832, 6685.866282674417, 6755.560888542229, 6856.142982127234, 6901.516893817385, 6947.68735406918, 6967.09427774386, 6983.9650801745665, 6716.126903502513, 6717.05961165447, 6750.0, 6754.763583274562, 6773.830654233683, 6575.24384695747, 6587.9670601646685, 6616.603003819594, 6672.932330827044, 6709.127813384229, 6317.273513546001, 6485.967570162119, 6529.804105876831, 6619.569727967667, 6637.568558043712, 6539.411452969255, 6571.277159512354, 6579.835504112435, 6633.369829866155, 6640.335966403321, 6291.842703932438, 6425.614463132227, 6447.0, 6469.353064693493, 6492.253390860112], "value": 6111.154663764688, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [852.6078004118106, 883.3639779358863, 890.421226202968, 891.942023768455, 893.4058850864176, 39529.80235098824, 42039.789801051025, 42121.789391053004, 42132.15735685285, 42305.576944230546, 38278.4258236126, 38311.61688383116, 38646.420303695464, 38762.612373876254, 38832.22335553287, 7068.999999999987, 7187.748428804993, 7195.532290401134, 7198.316159964821, 7199.244079371643, 7012.333828286304, 7074.893876591856, 7158.319959603856, 7172.103487064117, 7215.062041934568, 7198.100237470317, 7771.494852834548, 7878.290953814156, 7928.682852685914, 7988.320992715626, 7834.56909869957, 7846.293833554951, 8017.078036025815, 8033.919660803375, 8036.276735093869, 7834.804129896741, 7851.332636725885, 7853.960730196312, 7872.645730942121, 7918.0498340198965, 38378.232435351136, 38662.0, 38686.80656596727, 38816.0, 38838.41742373867, 3493.5283736695474, 3588.784672919633, 3595.712343012579, 3596.8021758803256, 3597.226596281812, 31335.84332078348, 31565.36869262625, 31575.36849263025, 31684.366312673847, 31943.680563194303, 5013.874653133665, 5038.244263360489, 5113.079645663779, 5130.204818253177, 5152.072626927152, 173114.13442932832, 174556.12721936437, 177581.0, 177843.0, 178791.0, 47753.761231193625, 48550.5144948546, 48846.0, 48905.755471222416, 49163.262551061765, 50671.493285067416, 51409.74295128501, 51454.48545514572, 51726.0, 51745.48254517407, 16477.83522164787, 16547.586310342096, 16548.83451165497, 16646.08446535452, 16687.666246675242, 13483.258420786951, 13508.189508629514, 13614.931925340508, 13628.93185534066, 13631.931840340734, 14005.229712365917, 14073.929630351782, 14107.576772696835, 14179.50371737005, 14321.35553900077, 5543.6396634218645, 5642.266505354278, 5642.971785141048, 5737.540996720336, 5773.134029895549, 994.7015895231401, 998.5905778630872, 1006.6325791086201, 1015.8933312002281, 1021.0555236406294, 22454.550908981728, 22674.773252267267, 22678.206262780725, 22872.542549148922, 23121.53756924852, 1104.0946423932298, 1114.3648120571359, 1116.0569318925495, 1127.0926903842321, 1134.8524691790014, 11884.40577971098, 12007.819882701591, 12008.159428839897, 12074.732153123796, 12134.271943683407, 217031.0, 223443.0, 232430.0, 232698.0, 233379.0, 52857.73571132197, 52917.2062419064, 53102.20346694803, 53372.0, 53483.46516534863, 1434.1968497641146, 1471.5953112893826, 1474.1670955909876, 1484.6288427893028, 1492.0525466328743, 22267.77732222657, 22410.663840042085, 22575.322740317817, 22622.321330360115, 22737.431564211027, 36805.63194368022, 36886.26227475489, 36980.81509592435, 37076.44385334276, 37313.81343093267, 182340.0, 182629.0, 182934.0, 183278.0, 185013.0, 30282.0, 30539.694603054573, 30850.69149308478, 31149.377012460078, 33180.66819331776, 37217.4417383734, 41743.0, 41754.58245417507, 42982.570174297856, 43169.568304316555, 31124.53313200349, 31318.0, 31571.36857262796, 31798.682013179572, 32382.0, 30575.023892959223, 31134.844325778227, 31318.68681313158, 31414.900478483145, 31610.0, 35801.28397432089, 36239.094022649646, 36307.0, 36360.0, 36444.0, 30566.0, 31070.844645776626, 31155.221119472193, 31163.0, 31659.84170079135, 22503.662445063008, 22606.77393226047, 22742.65886011744, 22850.31449056531, 22922.885385572965], "value": 16931.39345618312, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [562.1483452569358, 568.9630174038684, 570.7488704969816, 590.7902694543437, 610.4688920639044, 21278.89360553199, 22671.54656906863, 22772.430689232777, 22802.0, 22805.0, 24038.63942040871, 24803.62794558083, 25161.119360822377, 25285.620715689278, 25512.362190945234, 6411.5191360647905, 6451.419372256496, 6480.481561475094, 6486.61080335179, 6504.739810407602, 6430.356964303579, 6494.155759751226, 6567.47460203185, 6569.146011018561, 6569.737210511574, 6189.2572891252885, 6504.479641628682, 6547.181602299713, 6572.835679108012, 6573.0, 6412.711427985705, 6470.838229044264, 6498.155239818817, 6545.0509676096935, 6548.770793022221, 5837.678927658977, 6006.909896351558, 6016.909746353808, 6074.5747797654185, 6103.847403814896, 26998.5950210747, 27436.862815685792, 27462.725372746216, 27477.31306717348, 27490.0, 2057.238821635989, 2059.227789578908, 2063.0819285417997, 2065.308121779206, 2076.3148161106797, 17862.821371786245, 18779.34272300463, 19019.61960760777, 19045.0, 19069.427917162506, 2437.0, 2448.2532827487607, 2491.0409492345398, 2496.213692686816, 2498.8625625590585, 109703.16038938599, 114902.0, 115171.42414287875, 130923.6907630921, 131120.0, 14483.309344006782, 14633.634159146106, 14758.694446248961, 14795.41440929085, 14937.954343195872, 23257.7674223259, 23482.178123765927, 23546.293611191693, 23550.351475396557, 23657.645135322982, 9306.208972237435, 9868.802623947624, 9961.701148965543, 9970.451625160686, 10033.09702126812, 8922.86615700765, 8957.731268061969, 9066.138716821892, 9103.772405689911, 9198.816023679488, 8901.910980890238, 8929.285657147413, 8982.775430614156, 9119.407238529475, 9171.495567743705, 2696.8112232143562, 2717.2120085175475, 2723.9182824515296, 2767.9446411071667, 2791.4277573097465, 950.2493030505962, 970.96601618943, 981.215027977615, 987.6345752071779, 993.8012397520525, 18804.34184803553, 18813.0, 18864.811351886307, 19065.142068606947, 19272.1327540261, 1020.3928662445982, 1026.471367245869, 1038.1072277841051, 1041.671873359889, 1043.9112675422675, 10436.2694611378, 10498.265121441578, 10544.367337959748, 10613.84079238812, 10734.838977415346, 422483.0, 446730.0, 449114.0, 451395.0, 472211.0, 108015.0, 108298.45850770854, 109299.0, 109886.45056774665, 110187.0, 1008.5814387029448, 1062.5749700120014, 1119.361963680694, 1130.3556972525746, 1179.292424545266, 19068.904655476636, 19314.420567383, 19565.8043419564, 19704.0, 19939.501512462306, 54217.0, 55057.0, 55437.0, 56134.1579876294, 56758.71620641871, 69161.30838691548, 69505.0, 70094.94857577242, 70308.0, 70414.64792676004, 13920.234387108807, 13951.232682202577, 14057.437702491785, 14116.78824817608, 14177.22025288619, 21857.0, 22100.889495553063, 22101.226457073924, 22155.224567140718, 22168.88915555412, 17487.912560437115, 17642.5589360267, 17783.28866845312, 17927.73108403349, 18014.0, 15561.455349062728, 15667.608309792346, 15847.128407937675, 15857.524274271791, 16019.439319623758, 17833.0, 17871.28514859391, 17957.910210448863, 18023.279068837623, 18398.724019139456, 13753.931230343784, 13863.514776982756, 13905.930470347583, 13930.442782288595, 14033.859661403256, 17197.74203386925, 17202.569935751708, 17594.20826062856, 17700.46898593044, 17873.0], "value": 11674.92728874236, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1323.3317174826714, 1404.9999999999993, 1405.9226742529156, 1408.97886531702, 1418.2625034981809, 14553.126812391263, 15131.773023404658, 15208.391664333414, 15226.619334516643, 15234.238288085578, 14809.259537023132, 14820.703585928275, 14856.628584285398, 14856.777148342784, 14933.402663893432, 2671.919842404731, 2675.4247836715103, 2729.3586007288272, 2741.0954385052883, 2743.1222008957143, 2653.962235913293, 2655.296346468178, 2690.4888071266496, 2692.67687877455, 2698.689650690176, 2570.575854983925, 2574.4979728952862, 2670.2790246633494, 2780.9721902781016, 2782.8886844526196, 2757.2831063923322, 2780.110364683302, 2784.192584150594, 2792.4694308081503, 2792.48339057273, 2601.6747906511687, 2613.9869300653563, 2631.7499837515406, 2634.7628713415884, 2649.5893136563864, 14237.928810355985, 14521.709565808731, 14528.0, 14922.62693432662, 15005.924970375187, 7799.3760499159935, 8107.0, 8148.837023259561, 8157.061937877161, 8159.75520734373, 5285.154375299933, 5422.186671999192, 5466.496713403806, 5534.4742249486635, 5551.333839939233, 1807.2951548895942, 1815.963680726378, 1821.5537193387704, 1867.8972656504022, 1885.1139964216934, 25417.237482875542, 25629.48741025169, 25674.48651026969, 25701.0, 25729.099481518057, 6710.496712746563, 6710.932890671128, 6780.966095169492, 6884.65576721162, 6918.93081069193, 17154.742678859828, 17262.05058721757, 17268.913655431643, 17286.22212000463, 17316.220770065378, 7677.846443071107, 7807.453478256468, 7813.882791758128, 7814.570198639016, 7830.060392752792, 6849.691763870638, 7040.190378106583, 7181.784546463615, 7188.964055179691, 7212.639368031578, 5011.799528018912, 5224.294720212799, 5269.235960785664, 5317.760700768475, 5343.732813359316, 1540.1529158962526, 1549.1866769945868, 1552.7127481415973, 1559.6412825050074, 1562.0861795849555, 865.7446053414258, 894.190757364586, 909.2544113826731, 910.1944778870615, 915.2540679346312, 7201.8094396429515, 7317.743878964211, 7414.14737305206, 7416.40668746499, 7490.101187857492, 721.0626185958233, 737.4321772235348, 744.2037020388133, 746.2537462537463, 759.1497522774528, 4764.7611620978105, 4857.1742803723455, 4963.8014479421145, 5234.502722241381, 5396.811111611153, 19724.90137549332, 20455.28406505765, 20477.897610511853, 21172.0, 21188.682169767464, 20207.393778186677, 20895.373138805557, 20974.685379719318, 21138.89430552868, 21139.0, 10459.790804183875, 11181.161412894066, 11254.662360129209, 11256.437178141225, 11264.71838204035, 8022.679092836222, 8096.433249672466, 8125.187481252064, 8223.424360294723, 8247.711330103417, 17461.825381746527, 17492.91253543724, 17682.64634707273, 17710.645787084442, 17970.281188752302, 18258.817411826243, 18313.725294120333, 18867.622647546697, 18884.0, 19062.61874762525, 10987.780244394908, 11032.944835276094, 11116.777664446827, 11219.831702524305, 11242.325460472397, 13155.802662959872, 13405.0, 13528.59414217575, 13530.255835929118, 13548.525801596898, 10854.67435976922, 11204.0, 11220.55117795279, 11283.887161128283, 11429.657110286706, 10623.0, 10640.680779576625, 10735.89264107349, 10901.618443354444, 11019.118470522179, 9509.809803804024, 9514.333996620448, 9587.856182157133, 9596.712098637052, 9635.903640963501, 7024.613646249505, 7104.254053324326, 7119.252478489684, 7164.749233776793, 7177.641117943977, 7128.821779455555, 7197.748078817216, 7306.1963184048645, 7325.41396688253, 7450.515716478411], "value": 6516.743960174009, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [505.31782094172894, 509.1929292072064, 539.2315949771576, 542.1217627443538, 542.7069382533432, 6111.205543279368, 6138.355472675372, 6210.565260431772, 6250.374962503753, 6317.0, 6118.357572454895, 6145.907811382833, 6146.2009938708015, 6147.323794382625, 6218.84452888678, 5142.07442660321, 5147.974260128713, 5149.716765577892, 5170.2503137045105, 5217.817376391827, 5044.2937988681615, 5116.260471439709, 5182.274481572583, 5208.0, 5224.346956630421, 4809.831655892044, 4972.0, 4974.925376119361, 5005.6496045276845, 5014.974925125369, 4904.82284251777, 4947.6784009039475, 4974.751262436882, 4983.526564976358, 4997.350344455198, 4374.365716971053, 4451.310046942729, 4468.8882777930485, 4486.349479325512, 4549.499555048942, 6080.604760690585, 6090.594215284982, 6133.877322453571, 6139.2325959255095, 6147.631142131441, 5675.148727690838, 5687.544996400278, 5712.285964254468, 5752.338481074647, 5754.884902301972, 6460.321666225026, 6503.626176445563, 6519.674016299166, 6532.6733663316645, 6537.705803238818, 4646.883827904329, 4872.561469467765, 4879.512048795127, 4897.191963326047, 4898.0, 6388.297287298394, 6505.251896031969, 6682.665866706694, 6692.077511402395, 6781.559198652072, 4932.654714169974, 4952.777125029383, 4961.280614310905, 4979.0, 4995.750212489361, 5007.524285192973, 5049.2426136079885, 5065.11360511909, 5086.694798312114, 5112.463191364927, 5588.485181668266, 5632.943670563324, 5656.547476201894, 5674.957015474426, 5693.202951586698, 6429.292777794439, 6483.4164925156965, 6551.836204094936, 6566.3105373936005, 6630.138082049302, 1754.5964428181335, 1761.8942863428235, 1765.4174122539532, 1767.469759072273, 1770.3184274054581, 1164.6839071848926, 1212.7938250497436, 1239.2626387299542, 1243.7263801963554, 1245.3026305268897, 5567.0, 5604.719764011783, 5621.775128994795, 5624.353199382042, 5643.379228284967, 6537.803865883936, 6620.2386725527185, 6669.699863506154, 6703.162104736908, 6709.6980635871505, 5661.952538780339, 5694.857628559319, 5697.9430205698245, 5701.4298570142655, 5733.3406658234, 3314.9337013260083, 3480.2946556187794, 3552.1119720069983, 3552.2540266543797, 3572.089117275078, 6252.656103914237, 6297.362685701659, 6301.6219026858535, 6321.922360077724, 6390.45681117101, 6703.530752847351, 6761.830954226085, 6780.966095169492, 6788.321167883271, 6817.965910170417, 6275.811725648238, 6319.304876463585, 6333.8733225336155, 6360.809175724736, 6539.049464986839, 6566.737330506726, 6647.567908085959, 6704.195496540447, 6741.966290168518, 6768.898466523105, 6607.240167380621, 6649.168853893263, 6652.966735166293, 6668.66656667155, 6713.261541230363, 6364.681765911593, 6411.230652321565, 6461.64460954652, 6506.837329066811, 6550.333961123689, 5011.248312753116, 5041.873953151054, 5044.8991020178655, 5053.0, 5072.82245121419, 6431.903521447089, 6579.309172536815, 6600.029986506187, 6640.468762499085, 6653.201615806134, 6183.732334871431, 6431.903521447089, 6455.515836312389, 6475.287718351072, 6499.545031847725, 6371.858292110165, 6378.266499352634, 6445.677716114269, 6499.075416062885, 6525.771597994048, 6224.595401298901, 6290.559660823698, 6424.807255782334, 6446.935530644633, 6448.645324507197, 6410.61536307823, 6454.967725161344, 6514.0, 6531.150950376421, 6564.901526477011, 6179.859494138848, 6249.312575616587, 6301.495880329471, 6325.209348831396, 6352.841178970378], "value": 5028.002326678418, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [898.6001229452892, 899.1907283444895, 912.3795818843186, 931.986020209697, 949.1552518258749, 19683.60632787345, 19842.40472785817, 19933.0, 19972.800271997276, 20033.0, 16356.591085222875, 18343.816561834377, 18610.069496525186, 18721.34475293365, 18747.0, 951.3483263964172, 965.932384733069, 969.7963427680196, 971.7570607348163, 979.4123525884484, 961.4952150121208, 966.2608104799833, 968.2060710217589, 969.7721035556639, 970.2092794372563, 983.8179936711696, 1008.349614498648, 1015.7562185075602, 1022.0597050713325, 1023.738946568624, 1008.2690049713972, 1018.0277834667875, 1022.3814592171741, 1022.6114076650899, 1031.0720351683508, 736.1129838544541, 742.406075139886, 745.697992313114, 748.790338705158, 748.9775306740806, 19927.402177934684, 20282.391528254175, 20525.486862828548, 20581.4854628634, 20600.175992960263, 20330.79669203304, 20887.5822483551, 21811.0, 21867.343979680474, 21998.120075197134, 17176.0, 17382.391616293367, 17465.38871139504, 17467.301307947666, 17679.292828286852, 3824.9043773905873, 3829.3681542545446, 3868.032991752062, 3868.7485313454536, 3869.9032524186837, 98943.0, 137110.0, 138732.0, 140037.0, 140817.0, 17291.827081729272, 17691.823081769275, 17900.731489027676, 17909.641807163785, 17933.7309940351, 30807.0, 32142.517862232085, 32321.51517727236, 32335.0, 32477.675223247938, 9356.95321523388, 9393.248540116774, 9468.857967130498, 9500.0, 9503.049695030442, 4621.976890115528, 4652.534746525388, 4682.227432473604, 4683.063387322549, 4712.69367491112, 5409.24270602116, 5419.485148910847, 5454.91817622736, 5517.337919449692, 5527.309086364205, 999.2954966748425, 1049.6483677967813, 1107.1309022417363, 1120.3054106454051, 1142.0520967596874, 680.496432639843, 708.0229283588615, 708.1431467923767, 710.7938697777587, 713.2546488919093, 12346.64186939436, 12387.08000259943, 12515.123941324202, 12957.546485872948, 13045.282509462071, 693.1543516909362, 699.422976044765, 706.219627311813, 708.4297140801704, 712.3837880233664, 5734.283214598176, 5740.339860916049, 5781.430794398093, 5785.450382213683, 5883.794067207627, 120404.3979780113, 123770.0, 125027.37486312693, 126298.0, 129276.35361823319, 79723.83793437178, 106936.46531767448, 110890.0, 111903.44048279869, 113949.43025284821, 91733.0, 92127.0, 93296.0, 94865.051349487, 96255.51872240735, 12431.627051188118, 12945.54690585825, 12996.545120920722, 12999.96000319991, 13010.544630937871, 41421.378679320434, 42173.789131054145, 42611.7869410651, 43117.3532397008, 43675.0, 57145.71427142838, 57158.0, 57785.13322300085, 57912.0, 59235.40764592299, 18469.630607388044, 18720.625587488445, 18847.24611015544, 18926.621467570847, 19229.134688938753, 20768.48078798042, 20872.47818804542, 21039.8948005259, 21123.894380528, 21532.89233553822, 17646.735298970783, 17653.293868245128, 17708.032252598558, 17732.468025959242, 17801.910990444965, 16812.495625131265, 16855.578610534834, 17000.40498582593, 17058.744118837978, 17248.396306129227, 15531.689366212839, 15704.76442853381, 15774.60563485876, 15870.7619385707, 16036.679266414372, 13613.38739756733, 13988.58034258974, 14240.92879535637, 14373.425062997363, 14507.1295722257, 13449.798253026016, 13482.393292301675, 13509.662258443224, 13522.256275904527, 13666.18002919828], "value": 9286.553445389009, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1446.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 120.0, 109.0, 109.0, 112.0, 107.0, 114.0, 110.0, 119.0, 110.0], "value": 112.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 126.0, 119.0, 131.0, 119.0, 118.0, 134.0, 117.0, 116.0, 147.0], "value": 125.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 170.0, 163.0, 169.0, 173.0, 164.0, 167.0, 163.0, 166.0, 165.0], "value": 167.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 76.0, 79.0, 83.0, 75.0, 80.0, 81.0, 86.0, 81.0], "value": 79.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 79.0, 80.0, 88.0, 84.0, 80.0, 86.0, 88.0, 84.0], "value": 83.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 115.0, 117.0, 110.0, 118.0, 121.0, 115.0, 123.0, 123.0, 115.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 151.0, 157.0, 149.0, 154.0, 149.0, 150.0, 148.0, 151.0, 148.0], "value": 151.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 104.0, 97.0, 98.0, 95.0, 95.0, 94.0, 94.0, 98.0, 95.0], "value": 96.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 52.0, 54.0, 54.0, 53.0, 52.0, 52.0, 53.0, 50.0], "value": 52.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [37.0, 37.0, 36.0, 40.0, 44.0, 37.0, 37.0, 41.0, 49.0, 45.0], "value": 40.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 78.0, 76.0, 74.0, 79.0, 74.0, 83.0, 77.0, 79.0, 78.0], "value": 78.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 118.0, 111.0, 117.0, 114.0, 113.0, 121.0, 113.0, 115.0, 126.0], "value": 115.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 158.0, 157.0, 160.0, 161.0, 162.0, 162.0, 166.0, 175.0, 164.0], "value": 163.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 63.0, 65.0, 72.0, 64.0, 62.0, 67.0, 69.0, 63.0], "value": 65.5, "unit": "score"}]}]} 14:39:05 INFO - Return code: 0 14:39:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:39:05 INFO - # TBPL SUCCESS # 14:39:05 INFO - Running post-action listener: _package_coverage_data 14:39:05 INFO - Running post-action listener: _resource_record_post_action 14:39:05 INFO - [mozharness: 2017-10-03 21:39:05.474947Z] Finished run-tests step (success) 14:39:05 INFO - Running post-run listener: _resource_record_post_run 14:39:05 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 5189632; Write bytes: 434114560; Read time: 2068; Write time: 272732 14:39:05 INFO - TinderboxPrint: CPU usage
13.2% 14:39:05 INFO - TinderboxPrint: I/O read bytes / time
5,189,632 / 2,068 14:39:05 INFO - TinderboxPrint: I/O write bytes / time
434,114,560 / 272,732 14:39:05 INFO - TinderboxPrint: CPU idle
8,261.5 (86.8%) 14:39:05 INFO - TinderboxPrint: CPU user
1,198.3 (12.6%) 14:39:05 INFO - TinderboxPrint: Swap in / out
0 / 0 14:39:05 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 110526464; Read time: 0; Write time: 63440 14:39:05 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 14:39:05 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 5185536; Write bytes: 323588096; Read time: 2052; Write time: 209292 14:39:06 INFO - Running post-run listener: _upload_blobber_files 14:39:06 INFO - Blob upload gear active. 14:39:06 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:39:06 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 14:39:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:39:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:39:06 INFO - (blobuploader) - INFO - Open directory for files ... 14:39:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:39:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:39:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:39:09 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:39:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:39:09 INFO - (blobuploader) - INFO - Done attempting. 14:39:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 14:39:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:39:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:39:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 14:39:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:39:10 INFO - (blobuploader) - INFO - Done attempting. 14:39:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 14:39:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:39:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:39:11 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 14:39:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:39:11 INFO - (blobuploader) - INFO - Done attempting. 14:39:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 14:39:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:39:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:39:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 14:39:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:39:12 INFO - (blobuploader) - INFO - Done attempting. 14:39:12 INFO - (blobuploader) - INFO - Iteration through files over. 14:39:12 INFO - Return code: 0 14:39:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:39:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:39:12 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e7cf41e801c95d23c654eced85027bcca8da1ea2007bf4f8c9a7aba0f83490d3d56a186a813ff3412e9988a51ab167a89d3399848e42370199687a7901561bfd", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c6441d0009097a140bdf5659c7d971a451a84194e7f439728ef56c01f5bdb4d2184699e664e26f22db6b2c306f75c05e8efeb1589101579379e7812618640df4", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0de4c74e3268c758d0d627cdc3eaed25cfef6c0af4af20f082d6cb0d48bbe81c7248859bb85f69128698abaa81a24bd0672f735af953ad057d13b30516c72be4", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9fa544cd9b09a1d2af90bdb1dfa7ca1f47f5990239bbd9ac28609f1c98713ba040f9d3197449a31f95b54b9aebd8f468b490564895f253264fd23e58e9f4f1f1"} 14:39:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:39:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:39:12 INFO - Contents: 14:39:12 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e7cf41e801c95d23c654eced85027bcca8da1ea2007bf4f8c9a7aba0f83490d3d56a186a813ff3412e9988a51ab167a89d3399848e42370199687a7901561bfd", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c6441d0009097a140bdf5659c7d971a451a84194e7f439728ef56c01f5bdb4d2184699e664e26f22db6b2c306f75c05e8efeb1589101579379e7812618640df4", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0de4c74e3268c758d0d627cdc3eaed25cfef6c0af4af20f082d6cb0d48bbe81c7248859bb85f69128698abaa81a24bd0672f735af953ad057d13b30516c72be4", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9fa544cd9b09a1d2af90bdb1dfa7ca1f47f5990239bbd9ac28609f1c98713ba040f9d3197449a31f95b54b9aebd8f468b490564895f253264fd23e58e9f4f1f1"} 14:39:12 INFO - Running post-run listener: copy_logs_to_upload_dir 14:39:12 INFO - Copying logs to upload dir... 14:39:12 INFO - mkdir: /builds/slave/test/build/upload/logs 14:39:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1245.902885 ========= master_lag: 0.12 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2017-10-03 14:39:12.798340) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-03 14:39:12.802334) ========= 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=51fc86400edbde9650d2781a000001d0-1507062476.832498-1090344465 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e7cf41e801c95d23c654eced85027bcca8da1ea2007bf4f8c9a7aba0f83490d3d56a186a813ff3412e9988a51ab167a89d3399848e42370199687a7901561bfd", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c6441d0009097a140bdf5659c7d971a451a84194e7f439728ef56c01f5bdb4d2184699e664e26f22db6b2c306f75c05e8efeb1589101579379e7812618640df4", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0de4c74e3268c758d0d627cdc3eaed25cfef6c0af4af20f082d6cb0d48bbe81c7248859bb85f69128698abaa81a24bd0672f735af953ad057d13b30516c72be4", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9fa544cd9b09a1d2af90bdb1dfa7ca1f47f5990239bbd9ac28609f1c98713ba040f9d3197449a31f95b54b9aebd8f468b490564895f253264fd23e58e9f4f1f1"} build_url:https://queue.taskcluster.net/v1/task/V7ZwxuDyRX-_fu7gAE5ohQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011457 build_url: 'https://queue.taskcluster.net/v1/task/V7ZwxuDyRX-_fu7gAE5ohQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e7cf41e801c95d23c654eced85027bcca8da1ea2007bf4f8c9a7aba0f83490d3d56a186a813ff3412e9988a51ab167a89d3399848e42370199687a7901561bfd", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c6441d0009097a140bdf5659c7d971a451a84194e7f439728ef56c01f5bdb4d2184699e664e26f22db6b2c306f75c05e8efeb1589101579379e7812618640df4", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0de4c74e3268c758d0d627cdc3eaed25cfef6c0af4af20f082d6cb0d48bbe81c7248859bb85f69128698abaa81a24bd0672f735af953ad057d13b30516c72be4", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9fa544cd9b09a1d2af90bdb1dfa7ca1f47f5990239bbd9ac28609f1c98713ba040f9d3197449a31f95b54b9aebd8f468b490564895f253264fd23e58e9f4f1f1"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-03 14:39:12.836249) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 14:39:12.836771) ========= 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=51fc86400edbde9650d2781a000001d0-1507062476.832498-1090344465 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004557 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 14:39:12.889806) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-03 14:39:12.893148) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-03 14:39:12.893476) ========= ========= Total master_lag: 0.29 =========