builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-057 starttime: 1504036377.56 results: success (0) revision: 9957a25209908aaea4bfd4915c3201f006b29b6e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-29 12:52:57.560691) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-29 12:52:57.561132) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-29 12:52:57.561402) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-29 12:52:57.579331) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-29 12:52:57.579662) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1504036376.908797-1903792368 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-29 12:52:57-- 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% 45.9M=0s 2017-08-29 12:52:57 (45.9 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.098253 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-29 12:52:57.693790) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-29 12:52:57.694130) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1504036376.908797-1903792368 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021394 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-29 12:52:57.743769) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-29 12:52:57.744123) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 9957a25209908aaea4bfd4915c3201f006b29b6e --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 9957a25209908aaea4bfd4915c3201f006b29b6e --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=934fc3100db09dcb9af4d4b6000001ab-1504036376.908797-1903792368 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-29 12:52:57,795 truncating revision to first 12 chars 2017-08-29 12:52:57,795 Setting DEBUG logging. 2017-08-29 12:52:57,795 attempt 1/10 2017-08-29 12:52:57,795 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/9957a2520990?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-29 12:52:58,461 unpacking tar archive at: mozilla-central-9957a2520990/testing/mozharness/ program finished with exit code 0 elapsedTime=1.071459 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-29 12:52:58.830168) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-29 12:52:58.830486) ========= script_repo_revision: 9957a25209908aaea4bfd4915c3201f006b29b6e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-29 12:52:58.830863) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-29 12:52:58.831131) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-29 12:52:58.843448) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2017-08-29 12:52:58.843815) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=934fc3100db09dcb9af4d4b6000001ab-1504036376.908797-1903792368 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:52:59 INFO - MultiFileLogger online at 20170829 12:52:59 in /builds/slave/test 12:52:59 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 12:52:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:52:59 INFO - {'append_to_log': False, 12:52:59 INFO - 'base_work_dir': '/builds/slave/test', 12:52:59 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 12:52:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:52:59 INFO - 'branch': 'Firefox-Non-PGO', 12:52:59 INFO - 'buildbot_json_path': 'buildprops.json', 12:52:59 INFO - 'code_coverage': False, 12:52:59 INFO - 'config_files': ('talos/linux_config.py',), 12:52:59 INFO - 'default_actions': ('clobber', 12:52:59 INFO - 'read-buildbot-config', 12:52:59 INFO - 'download-and-extract', 12:52:59 INFO - 'populate-webroot', 12:52:59 INFO - 'create-virtualenv', 12:52:59 INFO - 'install', 12:52:59 INFO - 'setup-mitmproxy', 12:52:59 INFO - 'run-tests'), 12:52:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:52:59 INFO - 'disable_ccov_upload': False, 12:52:59 INFO - 'disable_stylo': False, 12:52:59 INFO - 'download_minidump_stackwalk': True, 12:52:59 INFO - 'download_symbols': 'ondemand', 12:52:59 INFO - 'enable_stylo': False, 12:52:59 INFO - 'enable_webrender': False, 12:52:59 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 12:52:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:52:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:52:59 INFO - 'gecko_profile': False, 12:52:59 INFO - 'gecko_profile_interval': 0, 12:52:59 INFO - 'installer_path': 'installer.exe', 12:52:59 INFO - 'log_level': 'info', 12:52:59 INFO - 'log_name': 'talos', 12:52:59 INFO - 'log_to_console': True, 12:52:59 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:52:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:52:59 INFO - 'opt_config_files': (), 12:52:59 INFO - 'pip_index': False, 12:52:59 INFO - 'suite': 'dromaeojs-e10s', 12:52:59 INFO - 'system_bits': '32', 12:52:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:52:59 INFO - 'title': 'talos-linux64-ix-057', 12:52:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:52:59 INFO - 'use_talos_json': True, 12:52:59 INFO - 'verify': 'False', 12:52:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:52:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:52:59 INFO - 'work_dir': 'build'} 12:52:59 INFO - [mozharness: 2017-08-29 19:52:59.035654Z] Running clobber step. 12:52:59 INFO - Running pre-action listener: _resource_record_pre_action 12:52:59 INFO - Running main action method: clobber 12:52:59 INFO - rmtree: /builds/slave/test/build 12:52:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:53:00 INFO - Running post-action listener: _resource_record_post_action 12:53:00 INFO - [mozharness: 2017-08-29 19:53:00.681096Z] Finished clobber step (success) 12:53:00 INFO - [mozharness: 2017-08-29 19:53:00.681207Z] Running read-buildbot-config step. 12:53:00 INFO - Running pre-action listener: _resource_record_pre_action 12:53:00 INFO - Running main action method: read_buildbot_config 12:53:00 INFO - Using buildbot properties: 12:53:00 INFO - { 12:53:00 INFO - "project": "", 12:53:00 INFO - "product": "firefox", 12:53:00 INFO - "who": "ffxbld@noreply.mozilla.org", 12:53:00 INFO - "installer_path": "public/build/target.tar.bz2", 12:53:00 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 12:53:00 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 12:53:00 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 12:53:00 INFO - "stage_platform": "linux64", 12:53:00 INFO - "basedir": "/builds/slave/test", 12:53:00 INFO - "buildnumber": 1104, 12:53:00 INFO - "platform": "ubuntu64_hw", 12:53:00 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 12:53:00 INFO - "slavebuilddir": "test", 12:53:00 INFO - "taskId": "W1NqsJ_SRTOAqJqfF3qq2Q", 12:53:00 INFO - "branch": "mozilla-central", 12:53:00 INFO - "script_repo_revision": "production", 12:53:00 INFO - "revision": "9957a25209908aaea4bfd4915c3201f006b29b6e", 12:53:00 INFO - "slavename": "talos-linux64-ix-057", 12:53:00 INFO - "repo_path": "mozilla-central" 12:53:00 INFO - } 12:53:00 INFO - Finding installer, test and symbols from parent task. 12:53:00 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/W1NqsJ_SRTOAqJqfF3qq2Q'}, attempt #1 12:53:01 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/W1NqsJ_SRTOAqJqfF3qq2Q'}, attempt #1 12:53:01 INFO - Task dependencies: I8xynoCOTwiPbft2MqBxhQ 12:53:01 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/I8xynoCOTwiPbft2MqBxhQ'}, attempt #1 12:53:02 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/I8xynoCOTwiPbft2MqBxhQ/artifacts/public/build/target.tar.bz2 12:53:02 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/I8xynoCOTwiPbft2MqBxhQ/artifacts/public/build/target.test_packages.json 12:53:02 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/I8xynoCOTwiPbft2MqBxhQ/artifacts/public/build/target.crashreporter-symbols.zip 12:53:02 INFO - Running post-action listener: _resource_record_post_action 12:53:02 INFO - [mozharness: 2017-08-29 19:53:02.182878Z] Finished read-buildbot-config step (success) 12:53:02 INFO - [mozharness: 2017-08-29 19:53:02.183095Z] Running download-and-extract step. 12:53:02 INFO - Running pre-action listener: _resource_record_pre_action 12:53:02 INFO - Running main action method: download_and_extract 12:53:02 INFO - mkdir: /builds/slave/test/build/tests 12:53:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:53:02 INFO - trying https://queue.taskcluster.net/v1/task/I8xynoCOTwiPbft2MqBxhQ/artifacts/public/build/target.test_packages.json 12:53:02 INFO - Downloading https://queue.taskcluster.net/v1/task/I8xynoCOTwiPbft2MqBxhQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 12:53:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/I8xynoCOTwiPbft2MqBxhQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 12:53:02 INFO - Downloaded 989 bytes. 12:53:02 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 12:53:02 INFO - Using the following test package requirements: 12:53:02 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 12:53:02 INFO - u'common': [u'target.common.tests.zip'], 12:53:02 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 12:53:02 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 12:53:02 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 12:53:02 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 12:53:02 INFO - u'mozbase': [u'target.common.tests.zip'], 12:53:02 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 12:53:02 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 12:53:02 INFO - u'web-platform': [u'target.common.tests.zip', 12:53:02 INFO - u'target.web-platform.tests.tar.gz'], 12:53:02 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 12:53:02 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 12:53:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/I8xynoCOTwiPbft2MqBxhQ/artifacts/public/build/target.common.tests.zip 12:53:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/I8xynoCOTwiPbft2MqBxhQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 12:53:02 INFO - Fetch https://queue.taskcluster.net/v1/task/I8xynoCOTwiPbft2MqBxhQ/artifacts/public/build/target.common.tests.zip into memory 12:53:03 INFO - Content-Length response header: 54980756 12:53:03 INFO - Bytes received: 54980756 12:53:14 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 12:53:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/I8xynoCOTwiPbft2MqBxhQ/artifacts/public/build/target.common.tests.zip 12:53:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/I8xynoCOTwiPbft2MqBxhQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 12:53:14 INFO - Fetch https://queue.taskcluster.net/v1/task/I8xynoCOTwiPbft2MqBxhQ/artifacts/public/build/target.common.tests.zip into memory 12:53:15 INFO - Content-Length response header: 54980756 12:53:15 INFO - Bytes received: 54980756 12:53:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/I8xynoCOTwiPbft2MqBxhQ/artifacts/public/build/target.talos.tests.zip 12:53:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/I8xynoCOTwiPbft2MqBxhQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 12:53:27 INFO - Fetch https://queue.taskcluster.net/v1/task/I8xynoCOTwiPbft2MqBxhQ/artifacts/public/build/target.talos.tests.zip into memory 12:53:28 INFO - Content-Length response header: 14496610 12:53:28 INFO - Bytes received: 14496610 12:53: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')]} 12:53:28 INFO - trying https://queue.taskcluster.net/v1/task/I8xynoCOTwiPbft2MqBxhQ/artifacts/public/build/target.tar.bz2 12:53:28 INFO - Downloading https://queue.taskcluster.net/v1/task/I8xynoCOTwiPbft2MqBxhQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 12:53:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/I8xynoCOTwiPbft2MqBxhQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 12:53:29 INFO - Downloaded 62681709 bytes. 12:53:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/I8xynoCOTwiPbft2MqBxhQ/artifacts/public/build/target.tar.bz2 12:53:29 INFO - mkdir: /builds/slave/test/properties 12:53:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:53:29 INFO - Writing to file /builds/slave/test/properties/build_url 12:53:29 INFO - Contents: 12:53:29 INFO - build_url:https://queue.taskcluster.net/v1/task/I8xynoCOTwiPbft2MqBxhQ/artifacts/public/build/target.tar.bz2 12:53:29 INFO - Running post-action listener: _resource_record_post_action 12:53:29 INFO - Running post-action listener: find_tests_for_verification 12:53:29 INFO - Running post-action listener: set_extra_try_arguments 12:53:29 INFO - [mozharness: 2017-08-29 19:53:29.633084Z] Finished download-and-extract step (success) 12:53:29 INFO - [mozharness: 2017-08-29 19:53:29.633201Z] Running populate-webroot step. 12:53:29 INFO - Running pre-action listener: _resource_record_pre_action 12:53:29 INFO - Running main action method: populate_webroot 12:53:29 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:53:29 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 12:53:29 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:53:29 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:53:29 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 12:53:29 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:53:29 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:53:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:53:29 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 12:53:29 INFO - 'talos_options': ['--enable-stylo'], 12:53:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:53:29 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:53:29 INFO - 'tests': ['damp', 'tps']}, 12:53:29 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 12:53:29 INFO - 'talos_options': ['--enable-stylo'], 12:53:29 INFO - 'tests': ['damp', 'tps']}, 12:53:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:53:29 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 12:53:29 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 12:53:29 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 12:53:29 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 12:53:29 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 12:53:29 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 12:53:29 INFO - 'talos_options': ['--enable-stylo'], 12:53:29 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 12:53:29 INFO - 'other-e10s': {'tests': ['a11yr', 12:53:29 INFO - 'ts_paint', 12:53:29 INFO - 'tpaint', 12:53:29 INFO - 'sessionrestore', 12:53:29 INFO - 'sessionrestore_many_windows', 12:53:29 INFO - 'sessionrestore_no_auto_restore', 12:53:29 INFO - 'tabpaint']}, 12:53:29 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 12:53:29 INFO - 'tests': ['a11yr', 12:53:29 INFO - 'ts_paint', 12:53:29 INFO - 'tpaint', 12:53:29 INFO - 'sessionrestore', 12:53:29 INFO - 'sessionrestore_many_windows', 12:53:29 INFO - 'sessionrestore_no_auto_restore', 12:53:29 INFO - 'tabpaint']}, 12:53:29 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 12:53:29 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 12:53:29 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 12:53:29 INFO - 'tests': ['bloom_basic_singleton']}, 12:53:29 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 12:53:29 INFO - 'tests': ['bloom_basic']}, 12:53:29 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:53:29 INFO - 'tsvgr_opacity', 12:53:29 INFO - 'tart', 12:53:29 INFO - 'tscrollx', 12:53:29 INFO - 'tsvg_static']}, 12:53:29 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 12:53:29 INFO - 'tests': ['tsvgx', 12:53:29 INFO - 'tsvgr_opacity', 12:53:29 INFO - 'tart', 12:53:29 INFO - 'tscrollx', 12:53:29 INFO - 'tsvg_static']}, 12:53:29 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:53:29 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 12:53:29 INFO - 'talos_options': ['--enable-stylo'], 12:53:29 INFO - 'tests': ['tp5o']}, 12:53:29 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:53:29 INFO - 'talos_options': ['--mitmproxy', 12:53:29 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:53:29 INFO - '--firstNonBlankPaint'], 12:53:29 INFO - 'tests': ['tp6_google', 12:53:29 INFO - 'tp6_youtube', 12:53:29 INFO - 'tp6_amazon', 12:53:29 INFO - 'tp6_facebook']}, 12:53:29 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:53:29 INFO - 'talos_options': ['--enable-stylo', 12:53:29 INFO - '--mitmproxy', 12:53:29 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:53:29 INFO - '--firstNonBlankPaint'], 12:53:29 INFO - 'tests': ['tp6_google', 12:53:29 INFO - 'tp6_youtube', 12:53:29 INFO - 'tp6_amazon', 12:53:29 INFO - 'tp6_facebook']}, 12:53:29 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:53:29 INFO - 'talos_options': ['--enable-stylo', 12:53:29 INFO - '--stylo-threads=1', 12:53:29 INFO - '--mitmproxy', 12:53:29 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:53:29 INFO - '--firstNonBlankPaint'], 12:53:29 INFO - 'tests': ['tp6_google', 12:53:29 INFO - 'tp6_youtube', 12:53:29 INFO - 'tp6_amazon', 12:53:29 INFO - 'tp6_facebook']}, 12:53:29 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:53:29 INFO - 'talos_options': ['--xperf_path', 12:53:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:53:29 INFO - 'tests': ['tp5n']}, 12:53:29 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 12:53:29 INFO - 'talos_options': ['--enable-stylo', 12:53:29 INFO - '--xperf_path', 12:53:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:53:29 INFO - 'tests': ['tp5n']}}} 12:53:29 INFO - Running post-action listener: _resource_record_post_action 12:53:29 INFO - [mozharness: 2017-08-29 19:53:29.647403Z] Finished populate-webroot step (success) 12:53:29 INFO - [mozharness: 2017-08-29 19:53:29.647492Z] Running create-virtualenv step. 12:53:29 INFO - Running pre-action listener: _resource_record_pre_action 12:53:29 INFO - Running main action method: create_virtualenv 12:53:29 INFO - Creating virtualenv /builds/slave/test/build/venv 12:53:29 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 12:53:29 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 12:53:29 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 12:53:29 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:53:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:53:30 INFO - Installing distribute.............................................................................................................................................................................................done. 12:53:30 INFO - Installing pip...............done. 12:53:30 INFO - Return code: 0 12:53:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:53:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:53:30 INFO - Reading from file tmpfile_stdout 12:53:30 INFO - Output received: 12:53:30 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 12:53:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:53:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:53:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:53:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:53:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:53:30 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 0xd141f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xedca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1083b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1080580>, '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 0x1081480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1081910>, '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': '934fc3100db09dcb9af4d4b6000001ab-1504036376.908797-1903792368', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:53:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 12:53:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:53:30 INFO - Using env: {'DISPLAY': ':0', 12:53:30 INFO - 'HOME': '/home/cltbld', 12:53:30 INFO - 'LANG': 'en_US.UTF-8', 12:53:30 INFO - 'LANGUAGE': 'en_US:en', 12:53:30 INFO - 'LOGNAME': 'cltbld', 12:53:30 INFO - 'MAIL': '/var/mail/cltbld', 12:53:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:53:30 INFO - 'MOZ_NO_REMOTE': '1', 12:53:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:53:30 INFO - 'NO_EM_RESTART': '1', 12:53:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:53:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:53:30 INFO - 'PWD': '/builds/slave/test', 12:53:30 INFO - 'SHELL': '/bin/bash', 12:53:30 INFO - 'SHLVL': '1', 12:53:30 INFO - 'TERM': 'linux', 12:53:30 INFO - 'TMOUT': '86400', 12:53:30 INFO - 'USER': 'cltbld', 12:53:30 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504036376.908797-1903792368', 12:53:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:53:30 INFO - '_': '/tools/buildbot/bin/python'} 12:53:32 INFO - Ignoring indexes: http://pypi.python.org/simple/ 12:53:32 INFO - Downloading/unpacking pip>=1.5 12:53:32 INFO - Running setup.py egg_info for package pip 12:53:32 INFO - warning: no files found matching 'pip/cacert.pem' 12:53:32 INFO - warning: no files found matching '*.html' under directory 'docs' 12:53:32 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 12:53:32 INFO - no previously-included directories found matching 'docs/_build/_sources' 12:53:32 INFO - Installing collected packages: pip 12:53:32 INFO - Found existing installation: pip 1.1 12:53:32 INFO - Uninstalling pip: 12:53:32 INFO - Successfully uninstalled pip 12:53:32 INFO - Running setup.py install for pip 12:53:32 INFO - warning: no files found matching 'pip/cacert.pem' 12:53:32 INFO - warning: no files found matching '*.html' under directory 'docs' 12:53:32 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 12:53:32 INFO - no previously-included directories found matching 'docs/_build/_sources' 12:53:32 INFO - Installing pip script to /builds/slave/test/build/venv/bin 12:53:32 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 12:53:32 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 12:53:32 INFO - Successfully installed pip 12:53:32 INFO - Cleaning up... 12:53:32 INFO - Return code: 0 12:53:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:53:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:53:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:53:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:53:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:53:32 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 0xd141f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xedca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1083b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1080580>, '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 0x1081480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1081910>, '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': '934fc3100db09dcb9af4d4b6000001ab-1504036376.908797-1903792368', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:53:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 12:53:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 12:53:32 INFO - Using env: {'DISPLAY': ':0', 12:53:32 INFO - 'HOME': '/home/cltbld', 12:53:32 INFO - 'LANG': 'en_US.UTF-8', 12:53:32 INFO - 'LANGUAGE': 'en_US:en', 12:53:32 INFO - 'LOGNAME': 'cltbld', 12:53:32 INFO - 'MAIL': '/var/mail/cltbld', 12:53:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:53:32 INFO - 'MOZ_NO_REMOTE': '1', 12:53:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:53:32 INFO - 'NO_EM_RESTART': '1', 12:53:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:53:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:53:32 INFO - 'PWD': '/builds/slave/test', 12:53:32 INFO - 'SHELL': '/bin/bash', 12:53:32 INFO - 'SHLVL': '1', 12:53:32 INFO - 'TERM': 'linux', 12:53:32 INFO - 'TMOUT': '86400', 12:53:32 INFO - 'USER': 'cltbld', 12:53:32 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504036376.908797-1903792368', 12:53:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:53:32 INFO - '_': '/tools/buildbot/bin/python'} 12:53:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:53:33 INFO - Downloading/unpacking psutil>=3.1.1 12:53:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:53:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:53:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:53:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:53:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:53:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:53:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:53:34 INFO - Installing collected packages: psutil 12:53:34 INFO - Running setup.py install for psutil 12:53:34 INFO - building 'psutil._psutil_linux' extension 12:53:34 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 12:53:34 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 12:53:34 INFO - building 'psutil._psutil_posix' extension 12:53:34 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 12:53:34 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 12:53:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:53:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:53:34 INFO - Successfully installed psutil 12:53:34 INFO - Cleaning up... 12:53:34 INFO - Return code: 0 12:53:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:53:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:53:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:53:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:53:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:53:34 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 0xd141f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xedca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1083b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1080580>, '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 0x1081480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1081910>, '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': '934fc3100db09dcb9af4d4b6000001ab-1504036376.908797-1903792368', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:53:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:53:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 12:53:34 INFO - Using env: {'DISPLAY': ':0', 12:53:34 INFO - 'HOME': '/home/cltbld', 12:53:34 INFO - 'LANG': 'en_US.UTF-8', 12:53:34 INFO - 'LANGUAGE': 'en_US:en', 12:53:34 INFO - 'LOGNAME': 'cltbld', 12:53:34 INFO - 'MAIL': '/var/mail/cltbld', 12:53:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:53:34 INFO - 'MOZ_NO_REMOTE': '1', 12:53:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:53:34 INFO - 'NO_EM_RESTART': '1', 12:53:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:53:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:53:34 INFO - 'PWD': '/builds/slave/test', 12:53:34 INFO - 'SHELL': '/bin/bash', 12:53:34 INFO - 'SHLVL': '1', 12:53:34 INFO - 'TERM': 'linux', 12:53:34 INFO - 'TMOUT': '86400', 12:53:34 INFO - 'USER': 'cltbld', 12:53:34 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504036376.908797-1903792368', 12:53:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:53:34 INFO - '_': '/tools/buildbot/bin/python'} 12:53:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:53:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:53:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:53:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:53:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:53:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:53:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:53:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:53:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:53:36 INFO - Installing collected packages: mozsystemmonitor 12:53:36 INFO - Running setup.py install for mozsystemmonitor 12:53:36 INFO - Successfully installed mozsystemmonitor 12:53:36 INFO - Cleaning up... 12:53:36 INFO - Return code: 0 12:53:36 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:53:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:53:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:53:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:53:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:53:36 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 0xd141f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xedca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1083b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1080580>, '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 0x1081480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1081910>, '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': '934fc3100db09dcb9af4d4b6000001ab-1504036376.908797-1903792368', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:53:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 12:53:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 12:53:36 INFO - Using env: {'DISPLAY': ':0', 12:53:36 INFO - 'HOME': '/home/cltbld', 12:53:36 INFO - 'LANG': 'en_US.UTF-8', 12:53:36 INFO - 'LANGUAGE': 'en_US:en', 12:53:36 INFO - 'LOGNAME': 'cltbld', 12:53:36 INFO - 'MAIL': '/var/mail/cltbld', 12:53:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:53:36 INFO - 'MOZ_NO_REMOTE': '1', 12:53:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:53:36 INFO - 'NO_EM_RESTART': '1', 12:53:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:53:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:53:36 INFO - 'PWD': '/builds/slave/test', 12:53:36 INFO - 'SHELL': '/bin/bash', 12:53:36 INFO - 'SHLVL': '1', 12:53:36 INFO - 'TERM': 'linux', 12:53:36 INFO - 'TMOUT': '86400', 12:53:36 INFO - 'USER': 'cltbld', 12:53:36 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504036376.908797-1903792368', 12:53:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:53:36 INFO - '_': '/tools/buildbot/bin/python'} 12:53:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:53:36 INFO - Downloading/unpacking jsonschema==2.5.1 12:53:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:53:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:53:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:53:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:53:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:53:37 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:53:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:53:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:53:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:53:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:53:37 INFO - Downloading functools32-3.2.3-2.tar.gz 12:53:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:53:37 INFO - warning: no files found matching '*.txt' 12:53:37 INFO - no previously-included directories found matching 'build' 12:53:37 INFO - no previously-included directories found matching 'dist' 12:53:37 INFO - no previously-included directories found matching '.git*' 12:53:37 INFO - Installing collected packages: jsonschema, functools32 12:53:37 INFO - Running setup.py install for functools32 12:53:37 INFO - warning: no files found matching '*.txt' 12:53:37 INFO - no previously-included directories found matching 'build' 12:53:37 INFO - no previously-included directories found matching 'dist' 12:53:37 INFO - no previously-included directories found matching '.git*' 12:53:37 INFO - Successfully installed jsonschema functools32 12:53:37 INFO - Cleaning up... 12:53:37 INFO - Return code: 0 12:53:37 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:53:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:53:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:53:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:53:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:53:37 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 0xd141f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xedca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1083b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1080580>, '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 0x1081480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1081910>, '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': '934fc3100db09dcb9af4d4b6000001ab-1504036376.908797-1903792368', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:53:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:53:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 12:53:37 INFO - Using env: {'DISPLAY': ':0', 12:53:37 INFO - 'HOME': '/home/cltbld', 12:53:37 INFO - 'LANG': 'en_US.UTF-8', 12:53:37 INFO - 'LANGUAGE': 'en_US:en', 12:53:37 INFO - 'LOGNAME': 'cltbld', 12:53:37 INFO - 'MAIL': '/var/mail/cltbld', 12:53:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:53:37 INFO - 'MOZ_NO_REMOTE': '1', 12:53:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:53:37 INFO - 'NO_EM_RESTART': '1', 12:53:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:53:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:53:37 INFO - 'PWD': '/builds/slave/test', 12:53:37 INFO - 'SHELL': '/bin/bash', 12:53:37 INFO - 'SHLVL': '1', 12:53:37 INFO - 'TERM': 'linux', 12:53:37 INFO - 'TMOUT': '86400', 12:53:37 INFO - 'USER': 'cltbld', 12:53:37 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504036376.908797-1903792368', 12:53:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:53:37 INFO - '_': '/tools/buildbot/bin/python'} 12:53:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:53:38 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:53:38 INFO - Cleaning up... 12:53:38 INFO - Return code: 0 12:53:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:53:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:53:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:53:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:53:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:53:38 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 0xd141f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xedca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1083b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1080580>, '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 0x1081480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1081910>, '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': '934fc3100db09dcb9af4d4b6000001ab-1504036376.908797-1903792368', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:53:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:53:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:53:38 INFO - Using env: {'DISPLAY': ':0', 12:53:38 INFO - 'HOME': '/home/cltbld', 12:53:38 INFO - 'LANG': 'en_US.UTF-8', 12:53:38 INFO - 'LANGUAGE': 'en_US:en', 12:53:38 INFO - 'LOGNAME': 'cltbld', 12:53:38 INFO - 'MAIL': '/var/mail/cltbld', 12:53:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:53:38 INFO - 'MOZ_NO_REMOTE': '1', 12:53:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:53:38 INFO - 'NO_EM_RESTART': '1', 12:53:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:53:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:53:38 INFO - 'PWD': '/builds/slave/test', 12:53:38 INFO - 'SHELL': '/bin/bash', 12:53:38 INFO - 'SHLVL': '1', 12:53:38 INFO - 'TERM': 'linux', 12:53:38 INFO - 'TMOUT': '86400', 12:53:38 INFO - 'USER': 'cltbld', 12:53:38 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504036376.908797-1903792368', 12:53:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:53:38 INFO - '_': '/tools/buildbot/bin/python'} 12:53:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:53:38 INFO - Downloading/unpacking blobuploader==1.2.4 12:53:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:53:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:53:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:53:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:53:39 INFO - Downloading blobuploader-1.2.4.tar.gz 12:53:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:53:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:53:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:53:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:53:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:53:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:53:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:53:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:53:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:53:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:53:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:53:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:53:39 INFO - Downloading docopt-0.6.1.tar.gz 12:53:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:53:40 INFO - Installing collected packages: blobuploader, requests, docopt 12:53:40 INFO - Running setup.py install for blobuploader 12:53:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:53:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:53:40 INFO - Running setup.py install for requests 12:53:40 INFO - Running setup.py install for docopt 12:53:40 INFO - Successfully installed blobuploader requests docopt 12:53:40 INFO - Cleaning up... 12:53:40 INFO - Return code: 0 12:53:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:53:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:53:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:53:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:53:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:53:40 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 0xd141f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xedca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1083b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1080580>, '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 0x1081480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1081910>, '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': '934fc3100db09dcb9af4d4b6000001ab-1504036376.908797-1903792368', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:53:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:53:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:53:40 INFO - Using env: {'DISPLAY': ':0', 12:53:40 INFO - 'HOME': '/home/cltbld', 12:53:40 INFO - 'LANG': 'en_US.UTF-8', 12:53:40 INFO - 'LANGUAGE': 'en_US:en', 12:53:40 INFO - 'LOGNAME': 'cltbld', 12:53:40 INFO - 'MAIL': '/var/mail/cltbld', 12:53:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:53:40 INFO - 'MOZ_NO_REMOTE': '1', 12:53:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:53:40 INFO - 'NO_EM_RESTART': '1', 12:53:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:53:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:53:40 INFO - 'PWD': '/builds/slave/test', 12:53:40 INFO - 'SHELL': '/bin/bash', 12:53:40 INFO - 'SHLVL': '1', 12:53:40 INFO - 'TERM': 'linux', 12:53:40 INFO - 'TMOUT': '86400', 12:53:40 INFO - 'USER': 'cltbld', 12:53:40 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504036376.908797-1903792368', 12:53:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:53:40 INFO - '_': '/tools/buildbot/bin/python'} 12:53:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:53:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:53:40 INFO - Running setup.py (path:/tmp/pip-8I6ytI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:53:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:53:40 INFO - Running setup.py (path:/tmp/pip-hNjA8V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:53:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:53:41 INFO - Running setup.py (path:/tmp/pip-SxgeWv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:53:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:53:41 INFO - Running setup.py (path:/tmp/pip-PWyEWp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:53:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:53:41 INFO - Running setup.py (path:/tmp/pip-kHGs7a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:53:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:53:41 INFO - Running setup.py (path:/tmp/pip-nGZwJP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:53:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:53:41 INFO - Running setup.py (path:/tmp/pip-0_eN8k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:53:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:53:41 INFO - Running setup.py (path:/tmp/pip-75UsJC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:53:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:53:41 INFO - Running setup.py (path:/tmp/pip-Xiqo5U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:53:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:53:41 INFO - Running setup.py (path:/tmp/pip-Xt3Gwi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:53:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:53:41 INFO - Running setup.py (path:/tmp/pip-go3Jpd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:53:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:53:41 INFO - Running setup.py (path:/tmp/pip-x1jBej-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:53:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:53:41 INFO - Running setup.py (path:/tmp/pip-CFFXCM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:53:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:53:41 INFO - Running setup.py (path:/tmp/pip-Fq1Xft-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:53:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:53:41 INFO - Running setup.py (path:/tmp/pip-ZjlPv6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:53:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:53:41 INFO - Running setup.py (path:/tmp/pip-lxU_nV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:53:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:53:42 INFO - Running setup.py (path:/tmp/pip-0W7fVx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:53:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:53:42 INFO - Running setup.py install for manifestparser 12:53:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:53:42 INFO - Running setup.py install for mozcrash 12:53:42 INFO - Running setup.py install for mozdebug 12:53:42 INFO - Running setup.py install for mozdevice 12:53:42 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:53:42 INFO - Running setup.py install for mozfile 12:53:42 INFO - Running setup.py install for mozhttpd 12:53:42 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:53:42 INFO - Running setup.py install for mozinfo 12:53:42 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:53:42 INFO - Running setup.py install for mozInstall 12:53:42 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:53:42 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:53:42 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:53:42 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:53:42 INFO - Running setup.py install for mozleak 12:53:42 INFO - Running setup.py install for mozlog 12:53:43 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:53:43 INFO - Running setup.py install for moznetwork 12:53:43 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:53:43 INFO - Running setup.py install for mozprocess 12:53:43 INFO - Running setup.py install for mozprofile 12:53:43 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:53:43 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:53:43 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:53:43 INFO - Running setup.py install for mozrunner 12:53:43 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:53:43 INFO - Running setup.py install for mozscreenshot 12:53:43 INFO - Running setup.py install for moztest 12:53:43 INFO - Running setup.py install for mozversion 12:53:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:53:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:53:43 INFO - Cleaning up... 12:53:43 INFO - Return code: 0 12:53:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:53:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:53:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:53:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:53:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:53:43 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 0xd141f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xedca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1083b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1080580>, '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 0x1081480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1081910>, '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': '934fc3100db09dcb9af4d4b6000001ab-1504036376.908797-1903792368', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:53:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:53:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:53:43 INFO - Using env: {'DISPLAY': ':0', 12:53:43 INFO - 'HOME': '/home/cltbld', 12:53:43 INFO - 'LANG': 'en_US.UTF-8', 12:53:43 INFO - 'LANGUAGE': 'en_US:en', 12:53:43 INFO - 'LOGNAME': 'cltbld', 12:53:43 INFO - 'MAIL': '/var/mail/cltbld', 12:53:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:53:43 INFO - 'MOZ_NO_REMOTE': '1', 12:53:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:53:43 INFO - 'NO_EM_RESTART': '1', 12:53:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:53:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:53:43 INFO - 'PWD': '/builds/slave/test', 12:53:43 INFO - 'SHELL': '/bin/bash', 12:53:43 INFO - 'SHLVL': '1', 12:53:43 INFO - 'TERM': 'linux', 12:53:43 INFO - 'TMOUT': '86400', 12:53:43 INFO - 'USER': 'cltbld', 12:53:43 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504036376.908797-1903792368', 12:53:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:53:43 INFO - '_': '/tools/buildbot/bin/python'} 12:53:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:53:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:53:44 INFO - Running setup.py (path:/tmp/pip-azAPCK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:53:44 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 12:53:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:53:44 INFO - Running setup.py (path:/tmp/pip-aYN0fz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:53:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:53:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:53:44 INFO - Running setup.py (path:/tmp/pip-gN2yps-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:53:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 12:53:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:53:44 INFO - Running setup.py (path:/tmp/pip-j_VPmk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:53:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:53:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:53:44 INFO - Running setup.py (path:/tmp/pip-o53Bmt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:53:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 12:53:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:53:44 INFO - Running setup.py (path:/tmp/pip-u8Qw1m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:53:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 12:53:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:53:44 INFO - Running setup.py (path:/tmp/pip-7rJacH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:53:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 12:53:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:53:44 INFO - Running setup.py (path:/tmp/pip-6xeItx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:53:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 12:53:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:53:44 INFO - Running setup.py (path:/tmp/pip-yL3vnd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:53:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 12:53:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:53:44 INFO - Running setup.py (path:/tmp/pip-Cq0zDK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:53:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:53:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:53:44 INFO - Running setup.py (path:/tmp/pip-p90X6O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:53:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 12:53:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:53:44 INFO - Running setup.py (path:/tmp/pip-U_NVWd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:53:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 12:53:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:53:44 INFO - Running setup.py (path:/tmp/pip-1AkWvL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:53:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 12:53:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:53:44 INFO - Running setup.py (path:/tmp/pip-8dG_WI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:53:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 12:53:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:53:45 INFO - Running setup.py (path:/tmp/pip-F4IFic-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:53:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 12:53:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:53:45 INFO - Running setup.py (path:/tmp/pip-90paJb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:53:45 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 12:53:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:53:45 INFO - Running setup.py (path:/tmp/pip-sIR5yn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:53:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 12:53:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:53:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:53:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 12:53:45 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:53:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:53:45 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 12:53:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:53:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:53:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:53:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:53:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:53:46 INFO - Downloading blessings-1.6.tar.gz 12:53:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:53:46 INFO - Installing collected packages: blessings 12:53:46 INFO - Running setup.py install for blessings 12:53:46 INFO - Successfully installed blessings 12:53:46 INFO - Cleaning up... 12:53:46 INFO - Return code: 0 12:53:46 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:53:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:53:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:53:46 INFO - Reading from file tmpfile_stdout 12:53:46 INFO - Current package versions: 12:53:46 INFO - argparse == 1.2.1 12:53:46 INFO - blessings == 1.6 12:53:46 INFO - blobuploader == 1.2.4 12:53:46 INFO - docopt == 0.6.1 12:53:46 INFO - functools32 == 3.2.3-2 12:53:46 INFO - jsonschema == 2.5.1 12:53:46 INFO - manifestparser == 1.1 12:53:46 INFO - mozInstall == 1.13 12:53:46 INFO - mozcrash == 1.0 12:53:46 INFO - mozdebug == 0.1 12:53:46 INFO - mozdevice == 0.51 12:53:46 INFO - mozfile == 1.2 12:53:46 INFO - mozhttpd == 0.7 12:53:46 INFO - mozinfo == 0.10 12:53:46 INFO - mozleak == 0.1 12:53:46 INFO - mozlog == 3.5 12:53:46 INFO - moznetwork == 0.27 12:53:46 INFO - mozprocess == 0.25 12:53:46 INFO - mozprofile == 0.28 12:53:46 INFO - mozrunner == 6.13 12:53:46 INFO - mozscreenshot == 0.1 12:53:46 INFO - mozsystemmonitor == 0.3 12:53:46 INFO - moztest == 0.8 12:53:46 INFO - mozversion == 1.4 12:53:46 INFO - psutil == 3.1.1 12:53:46 INFO - requests == 1.2.3 12:53:46 INFO - wsgiref == 0.1.2 12:53:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:53:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:53:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:53:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:53:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:53:46 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 0xd141f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xedca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1083b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1080580>, '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 0x1081480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1081910>, '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': '934fc3100db09dcb9af4d4b6000001ab-1504036376.908797-1903792368', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:53:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 12:53:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:53:46 INFO - Using env: {'DISPLAY': ':0', 12:53:46 INFO - 'HOME': '/home/cltbld', 12:53:46 INFO - 'LANG': 'en_US.UTF-8', 12:53:46 INFO - 'LANGUAGE': 'en_US:en', 12:53:46 INFO - 'LOGNAME': 'cltbld', 12:53:46 INFO - 'MAIL': '/var/mail/cltbld', 12:53:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:53:46 INFO - 'MOZ_NO_REMOTE': '1', 12:53:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:53:46 INFO - 'NO_EM_RESTART': '1', 12:53:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:53:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:53:46 INFO - 'PWD': '/builds/slave/test', 12:53:46 INFO - 'SHELL': '/bin/bash', 12:53:46 INFO - 'SHLVL': '1', 12:53:46 INFO - 'TERM': 'linux', 12:53:46 INFO - 'TMOUT': '86400', 12:53:46 INFO - 'USER': 'cltbld', 12:53:46 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504036376.908797-1903792368', 12:53:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:53:46 INFO - '_': '/tools/buildbot/bin/python'} 12:53:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:53:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:53:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 12:53:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 12:53:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:53:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 12:53:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 12:53:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 12:53:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 12:53:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 12:53:47 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 12:53:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:53:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:53:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:53:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:53:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 12:53:48 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:53:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:53:48 INFO - Installing collected packages: simplejson 12:53:48 INFO - Running setup.py install for simplejson 12:53:48 INFO - building 'simplejson._speedups' extension 12:53:48 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 12:53:49 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 12:53:49 INFO - Successfully installed simplejson 12:53:49 INFO - Cleaning up... 12:53:49 INFO - Return code: 0 12:53:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:53:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:53:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:53:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:53:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:53:49 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 0xd141f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xedca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1083b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1080580>, '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 0x1081480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1081910>, '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': '934fc3100db09dcb9af4d4b6000001ab-1504036376.908797-1903792368', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:53:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:53:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:53:49 INFO - Using env: {'DISPLAY': ':0', 12:53:49 INFO - 'HOME': '/home/cltbld', 12:53:49 INFO - 'LANG': 'en_US.UTF-8', 12:53:49 INFO - 'LANGUAGE': 'en_US:en', 12:53:49 INFO - 'LOGNAME': 'cltbld', 12:53:49 INFO - 'MAIL': '/var/mail/cltbld', 12:53:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:53:49 INFO - 'MOZ_NO_REMOTE': '1', 12:53:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:53:49 INFO - 'NO_EM_RESTART': '1', 12:53:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:53:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:53:49 INFO - 'PWD': '/builds/slave/test', 12:53:49 INFO - 'SHELL': '/bin/bash', 12:53:49 INFO - 'SHLVL': '1', 12:53:49 INFO - 'TERM': 'linux', 12:53:49 INFO - 'TMOUT': '86400', 12:53:49 INFO - 'USER': 'cltbld', 12:53:49 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504036376.908797-1903792368', 12:53:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:53:49 INFO - '_': '/tools/buildbot/bin/python'} 12:53:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:53:49 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:53:49 INFO - Cleaning up... 12:53:49 INFO - Return code: 0 12:53:49 INFO - Running post-action listener: _resource_record_post_action 12:53:49 INFO - Running post-action listener: _start_resource_monitoring 12:53:49 INFO - Starting resource monitoring. 12:53:49 INFO - [mozharness: 2017-08-29 19:53:49.510559Z] Finished create-virtualenv step (success) 12:53:49 INFO - [mozharness: 2017-08-29 19:53:49.511030Z] Running install step. 12:53:49 INFO - Running pre-action listener: _resource_record_pre_action 12:53:49 INFO - Running main action method: install 12:53:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:53:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:53:49 INFO - Reading from file tmpfile_stdout 12:53:49 INFO - Detecting whether we're running mozinstall >=1.0... 12:53:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:53:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:53:49 INFO - Reading from file tmpfile_stdout 12:53:49 INFO - Output received: 12:53:49 INFO - Usage: mozinstall [options] installer 12:53:49 INFO - Options: 12:53:49 INFO - -h, --help show this help message and exit 12:53:49 INFO - -d DEST, --destination=DEST 12:53:49 INFO - Directory to install application into. [default: 12:53:49 INFO - "/builds/slave/test"] 12:53:49 INFO - --app=APP Application being installed. [default: firefox] 12:53:49 INFO - mkdir: /builds/slave/test/build/application 12:53:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 12:53:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 12:54:06 INFO - Reading from file tmpfile_stdout 12:54:06 INFO - Output received: 12:54:06 INFO - /builds/slave/test/build/application/firefox/firefox 12:54:06 INFO - Running post-action listener: _resource_record_post_action 12:54:06 INFO - [mozharness: 2017-08-29 19:54:06.315649Z] Finished install step (success) 12:54:06 INFO - [mozharness: 2017-08-29 19:54:06.315805Z] Running setup-mitmproxy step. 12:54:06 INFO - Running pre-action listener: _resource_record_pre_action 12:54:06 INFO - Running main action method: setup_mitmproxy 12:54:06 INFO - Skipping: mitmproxy is not required 12:54:06 INFO - Running post-action listener: _resource_record_post_action 12:54:06 INFO - [mozharness: 2017-08-29 19:54:06.316325Z] Finished setup-mitmproxy step (success) 12:54:06 INFO - [mozharness: 2017-08-29 19:54:06.316426Z] Running run-tests step. 12:54:06 INFO - Running pre-action listener: _resource_record_pre_action 12:54:06 INFO - Running pre-action listener: _set_gcov_prefix 12:54:06 INFO - Running main action method: run_tests 12:54:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:54:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:54:06 INFO - Python 2.7.3 12:54:06 INFO - Return code: 0 12:54:06 INFO - grabbing minidump binary from tooltool 12:54:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:54:06 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 0x1080580>, '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 0x1081480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1081910>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:54:06 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 12:54:06 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 12:54:06 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 12:54:06 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:54:06 INFO - Return code: 0 12:54:06 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:54:06 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:54:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:54:06 INFO - ENV: RUST_BACKTRACE is now full 12:54:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:54:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:54:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/I8xynoCOTwiPbft2MqBxhQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-057', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 12:54:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/I8xynoCOTwiPbft2MqBxhQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-057 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 12:54:06 INFO - Using env: {'DISPLAY': ':0', 12:54:06 INFO - 'HOME': '/home/cltbld', 12:54:06 INFO - 'LANG': 'en_US.UTF-8', 12:54:06 INFO - 'LANGUAGE': 'en_US:en', 12:54:06 INFO - 'LOGNAME': 'cltbld', 12:54:06 INFO - 'MAIL': '/var/mail/cltbld', 12:54:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:54:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:54:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:54:06 INFO - 'MOZ_NO_REMOTE': '1', 12:54:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:54:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:54:06 INFO - 'NO_EM_RESTART': '1', 12:54:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:54:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:54:06 INFO - 'PWD': '/builds/slave/test', 12:54:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:54:06 INFO - 'RUST_BACKTRACE': 'full', 12:54:06 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:54:06 INFO - 'SHELL': '/bin/bash', 12:54:06 INFO - 'SHLVL': '1', 12:54:06 INFO - 'TERM': 'linux', 12:54:06 INFO - 'TMOUT': '86400', 12:54:06 INFO - 'USER': 'cltbld', 12:54:06 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504036376.908797-1903792368', 12:54:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:54:06 INFO - '_': '/tools/buildbot/bin/python'} 12:54:06 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/I8xynoCOTwiPbft2MqBxhQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-057', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 12:54:06 INFO - mozversion application_buildid: 20170829190058 12:54:06 INFO - mozversion application_changeset: 9957a25209908aaea4bfd4915c3201f006b29b6e 12:54:06 INFO - mozversion application_display_name: Nightly 12:54:06 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:54:06 INFO - mozversion application_name: Firefox 12:54:06 INFO - mozversion application_remotingname: firefox 12:54:06 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 12:54:06 INFO - mozversion application_vendor: Mozilla 12:54:06 INFO - mozversion application_version: 57.0a1 12:54:06 INFO - mozversion platform_buildid: 20170829190058 12:54:06 INFO - mozversion platform_changeset: 9957a25209908aaea4bfd4915c3201f006b29b6e 12:54:06 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 12:54:06 INFO - mozversion platform_version: 57.0a1 12:54:06 INFO - using testdate: 1504036446 12:54:06 INFO - actual date: 1504036446 12:54:06 INFO - starting webserver on 'localhost:38233' 12:54:06 INFO - SUITE-START | Running 2 tests 12:54:06 INFO - TEST-START | dromaeo_css 12:54:06 INFO - Initialising browser for dromaeo_css test... 12:54:06 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:38233/getInfo.html -profile /tmp/tmpHnqwf4/profile 12:54:06 INFO - TEST-INFO | started process 6751 (/builds/slave/test/build/application/firefox/firefox http://localhost:38233/getInfo.html) 12:54:12 INFO - TEST-INFO | 6751: exit 0 12:54:12 INFO - Browser initialized. 12:54:12 INFO - Running cycle 1/1 for dromaeo_css test... 12:54:12 INFO - Using env: {'DISPLAY': ':0', 12:54:12 INFO - 'HOME': '/home/cltbld', 12:54:12 INFO - 'JSGC_DISABLE_POISONING': '1', 12:54:12 INFO - 'LANG': 'en_US.UTF-8', 12:54:12 INFO - 'LANGUAGE': 'en_US:en', 12:54:12 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 12:54:12 INFO - 'LOGNAME': 'cltbld', 12:54:12 INFO - 'MAIL': '/var/mail/cltbld', 12:54:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:54:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:54:12 INFO - 'MOZ_CRASHREPORTER': '1', 12:54:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:54:12 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 12:54:12 INFO - 'MOZ_NO_REMOTE': '1', 12:54:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:54:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:54:12 INFO - 'NO_EM_RESTART': '1', 12:54:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:54:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:54:12 INFO - 'PWD': '/builds/slave/test', 12:54:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:54:12 INFO - 'RUST_BACKTRACE': 'full', 12:54:12 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:54:12 INFO - 'SHELL': '/bin/bash', 12:54:12 INFO - 'SHLVL': '1', 12:54:12 INFO - 'TERM': 'linux', 12:54:12 INFO - 'TMOUT': '86400', 12:54:12 INFO - 'USER': 'cltbld', 12:54:12 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504036376.908797-1903792368', 12:54:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:54:12 INFO - '_': '/tools/buildbot/bin/python'} 12:54:12 INFO - TEST-INFO | started process 6933 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHnqwf4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 12:54:13 INFO - PID 6933 | 12:54:13 INFO - PID 6933 | (/builds/slave/test/build/application/firefox/firefox:6984): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 12:54:13 INFO - PID 6933 | 12:54:23 INFO - PID 6933 | RSS: Main: 170078208 12:54:23 INFO - PID 6933 | 12:57:22 INFO - PID 6933 | Cycle 1(1): loaded http://localhost:38233/tests/dromaeo/cssquery-dojo.html (next: http://localhost:38233/tests/dromaeo/cssquery-ext.html) 12:57:23 INFO - PID 6933 | RSS: Main: 171995136 12:57:23 INFO - PID 6933 | 13:00:25 INFO - PID 6933 | Cycle 1(1): loaded http://localhost:38233/tests/dromaeo/cssquery-ext.html (next: http://localhost:38233/tests/dromaeo/cssquery-jquery.html) 13:00:25 INFO - PID 6933 | RSS: Main: 168349696 13:00:25 INFO - PID 6933 | 13:03:27 INFO - PID 6933 | Cycle 1(1): loaded http://localhost:38233/tests/dromaeo/cssquery-jquery.html (next: http://localhost:38233/tests/dromaeo/cssquery-mootools.html) 13:03:28 INFO - PID 6933 | RSS: Main: 171241472 13:03:28 INFO - PID 6933 | 13:06:29 INFO - PID 6933 | Cycle 1(1): loaded http://localhost:38233/tests/dromaeo/cssquery-mootools.html (next: http://localhost:38233/tests/dromaeo/cssquery-prototype.html) 13:06:30 INFO - PID 6933 | RSS: Main: 169431040 13:06:30 INFO - PID 6933 | 13:09:34 INFO - PID 6933 | Cycle 1(1): loaded http://localhost:38233/tests/dromaeo/cssquery-prototype.html (next: http://localhost:38233/tests/dromaeo/cssquery-yui.html) 13:09:34 INFO - PID 6933 | RSS: Main: 170389504 13:09:34 INFO - PID 6933 | 13:12:36 INFO - PID 6933 | Cycle 1(1): loaded http://localhost:38233/tests/dromaeo/cssquery-yui.html (next: http://localhost:38233/tests/dromaeo/cssquery-dojo.html) 13:12:36 INFO - PID 6933 | RSS: Main: 171413504 13:12:36 INFO - PID 6933 | 13:12:36 INFO - PID 6933 | __start_tp_report 13:12:36 INFO - PID 6933 | _x_x_mozilla_page_load 13:12:36 INFO - PID 6933 | _x_x_mozilla_page_load_details 13:12:36 INFO - PID 6933 | |i|pagename|runs| 13:12:36 INFO - PID 6933 | |0;dojo.html;3308.9172770680743;3315.386653469108;3325.7671962962586;3360.7479439042077;3360.8823691170815;6300.086487459315;6375.840603984902;6415.58298710584;6444.097826304315;6452.161219041518;6204.472619827309;6303.0545418187185;6322.114903913456;6340.144080549125;6342.112104305401;5844;5851.79518716845;5859.44335288147;5871.178035075082;5897.823065308047;5731.018205678131;5763.942360576382;5850.648961062328;5886.88226235475;5959.284885813709;5738.885222295552;5795.681237531934;5841.240638716961;5873.060310350366;5904.143899134601;5822.9417705822825;5831.620944638585;5878.265216847894;5891.440313170242;5904.143899134644;5680.6591604503865;5740.397258287861;5755.827325180251;5762.279715035621;5838.503727183194;6284.4344009039405;6305.3379395163765;6347.460465860361;6369.4585960193435;6388.425041746219;6754.966225168842;6761.0196521504595;6798.422134118606;6848.554843935129;6857.588544687334;6830;6868.896966545506;6952.304769523058;6959.965200173967;6992.650367481657;6304.621722696653;6359.713812878385;6379.106925030499;6413.102165696806;6424.678766061772;6958.826029349307;6962.617056061864;6965.442764578822;7043.8239044023285;7069.681864316118;6626.7017984190825;6705.893527567994;6720.9663951680905;6727.495437842182;6739.629320387327;6842.692078856364;6866.313368663194;6931.480138989596;6938.27148149437;6941.548799328028;6711.765088221987;6758.831029224309;6780.389764921082;6821.249662537117;6840.897386539206;6636.104125942936;6745.089412929192;6765.391114799691;6849.972504124421;6902.827429314207;5349.705766182819;5383.488568585978;5409.945900541023;5415.783368665209;5439.891202175934;4672.742999135012;4680;4716.198246298138;4721.3390125382475;4739.668223224341;5827.883442331215;5894.970525147432;5949.405059494016;6036.15493830864;6045.486133678599;6805.591664500146;6889.483288753263;6898.1377327834025;6924.065251191126;6946.5137440378685;5938.940610593925;5942.58401911862;6008.09878518217;6051.971164901889;6068.848278792977;5804.680742559115;5846.064629659234;5910.970445147746;5929.199558059693;5947;6427.935720642828;6538.1827271591055;6548.27968923418;6649.767258145942;6664.633445160563;6917.377436030782;6965.33829286217;6967.79096627102;6970.128733908262;6972;6516.609003459807;6599.043138744951;6668.6665666717445;6687.164104486939;6698.56459330142;6475.902861456988;6551.737930482727;6719.294474080151;6736.730530778714;6839.452843772586;6713.899291510534;6789.049533065276;6802.727890884309;6805.183377994672;6807.047013418027;6463.353664633499;6534.771283005072;6572.671366431564;6575.178102737158;6644.767433139817;6649.966750166218;6671;6684.231313399021;6693.899591506229;6717.026031225542;6502.122213501117;6521.119648847345;6582.8354291143105;6599.9010014848855;6614.305497922649;6577.835554111186;6656.900146497709;6676.49926255523;6702.731890724316;6718.193816742022;6469.77355792545;6500.739970401131;6503.089567460464;6558.1802274715665;6560.803175904731 13:12:36 INFO - PID 6933 | |1;ext.html;821.7863355527564;860.8751730999005;892.3129190523293;893.1782759860926;1151.0963893343726;29718.405631887348;30335.696643033563;30336.54495182574;40122.799386003106;40748.185036299255;40447;40836.59163408365;40882;40887;40913;7672.309492145706;7696.4227682924;7837.059552853666;7849.254320839511;7860.803479912991;7659.310662040415;7760.883586746203;7764.495307804975;7777.8055548611455;7783.610819459004;7727.72952946647;7791.493552919071;7802.8439431211345;7803.8049048773955;7814.257645523695;7750.922490775105;7774.105977812511;7782.610869456505;7786.571738554378;7792.064952205772;7679.57762323072;7725.806854828618;7749.186335434754;7751.263629955145;7752.806179845493;36462.8176859114;36654.450183247274;36671.63328366709;36674.44988325177;36699;3575.0704816747834;3602.6397360264027;3602.873899413508;3603.279344131158;3604.6395360463744;30811.537826932614;30812.8459357704;30897;30945.845270773727;30979.690203097907;5022.096022715909;5039.496050394968;5060.1903695408555;5101;5123.718195499246;188886;188969;189218;189722;190301.04849475803;46846.76576617095;46999.06001880012;47041.764791175825;47119.76440117846;47165;49168;49189.50810491921;51241;51911;51971;15999.920000400156;16056.197190140447;16072.598185045234;16082.839171608133;16175.191240438166;11518.36648984297;11708.824367634492;11714.824277635842;11718.121140914294;13212.207267563977;15028.323725432381;15105.47130850437;15110.773338399933;15187.240637968058;15257.313420896086;6113.663748493876;6116.785912493041;6120.571559990847;6179.16581261533;6247.531435142291;1010.5048526222098;1028.228828378716;1041;1042.3276986343917;1045.3153184664056;23567.7643223569;23907.521849562912;24205.21640011386;24276.271711848673;24413.38966525851;1083.9891601084046;1132.1226049811462;1136.4261048170622;1137.5336112194127;1149.109440183864;11902.464389102512;12548.623541293775;12589.685257868627;12600.11799174049;12662.430190641444;224676;225726;226042;226477;226697;48473.75763121162;48606;48657.5134248653;48797.51202488001;48837;1435.1891181482429;1446.026174272111;1454.2583282525989;1455.7015811758565;1458.9781153282922;22739.65890511677;22848.086076556752;22874.88562557243;22894.198703045313;23155.3053408398;37107.07232319214;37296.44055339118;37388.252234954605;37390.439143413416;37401.81299093596;204344;204405;204677;204867;204938;33554;33930.66069339374;34931.82534087415;36503.26993460063;36637.450438242915;43214.35178472388;43598.782006089765;43795.34306985334;44188.33717494176;44332.77833610812;32772.67227327696;32832.83583582067;32996.84511042102;33237.16907077342;33370.499442508866;31181.285029323604;32368.83815580907;32500.512492312162;32954.17614559655;33120.1719957003;35273.47089793604;36046.27907441784;36126.63873361233;36269.81865090658;36618.45072323864;31610.103393796446;32129.196770080936;32507.512387313735;32531.512027319135;32971.340573188885;23115.190968316027;24836.00655973741;24837.62743558884;24871.378215544755;25031.374215644755 13:12:36 INFO - PID 6933 | |2;jquery.html;561.287165300069;563.5688698145916;571.3943220186602;571.6277448588355;580.5587753307485;21653.783462165393;22350.441238969015;22354.10583576657;22453.775462245412;22498.775012249873;25579.488410231785;25993.48013039738;26093.217203483924;26158.346041348977;26177.73822261777;6323.24121105468;6448.838779030515;6457.225132984025;6460.483161347104;6463.773767918124;5769.682667453289;6297.559170858019;6298.212723409574;6309.6845157742155;6420.967895160517;6270;6272.811815645515;6284.088807122965;6288.402601752826;6289.402506761873;6259.7496100156395;6273.309935907047;6295.622262664232;6298.244210694723;6301.432871041606;5839.153322768175;5854;5854.590178687494;5857.355690874;5875.236219291507;28901.855490722413;29519.55720664192;30012.099637010924;30340.393192136253;30408.695913040807;1999.6400647883374;2024.0284663361522;2025.9290924817708;2028.3407892435018;2028.4725971247435;19370.4188874334;19844;19938;19954;19992.10035548404;2334.6498025296173;2363.5272945410816;2407.518496300747;2420.7579242075826;2452.276578409356;130942.34528827391;135721;135808.32095839558;135861.848289102;135981.3200933989;13633.590992270249;14190.148591084568;14336.21150836693;14346.282685865874;14510.347034383478;22780.658290125662;22829.885850570972;22955.196568120034;23014.884925575265;23018.309450716504;9990.650327238513;10299.279050466395;10311.401732731438;10407.53166107527;10593.152547796159;8279.172082791672;9842.901570984342;9871.654492092886;9886.357386769838;9904;7879.763607091796;8946.37375383717;8980.191782739585;9023.729288121367;9276.323322231927;2571.2543362425063;2595.5976823592373;2607.6610040694586;2710.105665130502;2715.891364345404;938.7981583959481;943.6697155995485;976.2531663277598;988.5353883674661;989.2580564576567;19331;19445.416637500897;19851.503712407306;19872.70190947137;20220.595588088156;1048.4128887822844;1062.2776511971933;1064.669952314785;1067.9679609611724;1069.1927594665976;10870.18473614482;10926.617568385227;10978.286411383235;11017.008469237648;11047.171462140372;534682;549152;550782;553160;556954;113707.43146284382;114871;115165;116417;116453;1186.0807873897797;1224.2715584227371;1228.520876858026;1238.5603110895684;1243.5274595653775;17607.72030813543;17698.380556680455;17790;17828.643427131643;17900;54397.18404223861;55038.724806377315;55186.448135518134;55865.16202256888;56362.15456768234;72517;72675.27324726686;72884;74126.62936685282;74944;12210.328431936328;12745.10784245131;12776.872231277941;14074.366653500412;14634.634134146732;23975.640365394185;24472;24844.50310993806;25379.238622840607;25697.357566060997;19276.518087047934;19828.206871724968;19838.702419463432;20047.899760501103;20105.6984145235;17079.829201707824;17143;17947.820521795136;17984.2806287747;18286;19813.306534271233;19869.205231790565;19945.501362465475;20376.898115509328;20399.082041308473;20648.380548583566;21209.15163393447;21224;21410.357689269345;21483.570328593654;18608.44174674762;19207.8079219206;19247.13387897576;19253.133608987348;19441.31955381555 13:12:36 INFO - PID 6933 | |3;mootools.html;1323.7881938889777;1406.9648258793536;1418.2412409360993;1423.402171088143;1437.302908089577;14299.356528956196;15506.534803955885;15513.301901414436;15551.144687042208;15555.455559055436;15228;15466.845331546681;15475.613109672264;15506.379744810194;15514.146721930289;2747.285705716518;2811.63448751662;2816.0284701777855;2823.153054083777;2824.4351129774022;2640.326716687242;2737.110439107298;2742.492638861807;2765.5575107982727;2779.5135851226055;2613.4642398308397;2804.383035732136;2838.843863587502;2840.034388307975;2847.7152284771564;2758.600002999574;2793.0922450203766;2828.5474324108145;2841.843698596587;2848.672402673688;2694.1513423271804;2714.4028313770937;2732;2735.3571910600995;2737.54830452975;14699.55901322962;14746;14930.178840163786;14945.103293802407;14996.925015374962;7984.9201507985335;8289.917100829036;8297.460665056813;8307.83384332316;8317.085120636724;4971.279164521125;5156.252343410222;5467.45325467454;5486.561075113981;5658.207850900836;1843.6773564626137;1854.842338401224;1877.5869308752053;1890.5557194059386;1906.323255244396;25196;25580.488390232276;25583.360415989562;25610.48779024409;25633.359166021;6455.9677201613695;6520.18497687789;6743.932560674429;6809.114815074008;6903.75836845708;16967;16970.490885273462;16997;17013;17013.659726805396;7825.256600622932;7958.880616790752;7959.8408031839035;7965.960170199228;7988.720394786154;7318.158411782713;7370.6314684266645;7417.072865891763;7500.399968002546;7512.436567257478;5185.144451165515;5313.335833020872;5322.2282768998275;5356.91964620531;5387.138057910715;1381.1851007905184;1412.7315809996069;1609.5251900689325;1610.2029495399852;1627.3246602659763;891.4250308550949;909.6225066597289;934.6027938126269;935.209747763139;957.0381766324799;8345.749627511184;8545.060043395222;9066.365354425126;9079.54602269897;9182.86225706615;1025.2259544044198;1032.9586816527255;1038.2213339995003;1042.6819819954987;1058.1746237934424;5087.109755792795;5146.125158723026;5279.1289437243195;5314.096603577401;5340.519353258225;20197.394078177676;20420.183192672128;20739.481512961996;20923.05846236923;21298.893505532684;20744.170233190805;20835.583288334452;20855.165793368094;20923.79076209249;20928.581428371654;10831.187660925461;10975.847536008605;11230.943845280884;11328.999999999834;11351.545938162546;8570.914290857012;8585.82828343442;8605.741827745178;8726.171013753683;8845.823083538164;16861;17444.651106977537;17532.561685957953;17906.28374864991;18042.0076895772;16659;17123.828761712222;17312.740308895125;17345.856914599648;17802.46592602224;10488;10902.345859248471;10933.343999359746;10935.83596246041;10937.179711521827;13216.074874758675;13333.266670333225;13334.733305334034;13335.86664133385;13337;10407.115395191493;10497.16022718196;10633.627823026158;10677.14582833356;10802.135829133807;10191.694249172537;10238.078572928473;10248.692539223835;10305.587776488857;10602.522886469973;8317.500949943023;8989;9049.140331668481;9057.320700947323;9126.863097053416;6823.624700641512;6867.381935625817;6873.621950792754;6894.103766510321;6969.745445819801;6578.868422631616;6752.729890804313;6795.150606174228;6800.897986530305;7004.754833580801 13:12:36 INFO - PID 6933 | |4;prototype.html;471.09784762180425;478.2419864514744;490.76933841094683;495.91321518734225;502.1137691973667;6448.903266451001;6460.773872914449;6464.224293084831;6466.450351720109;6493.8376540586505;6052.727627256773;6129.785457508977;6211.298085332872;6283.717232724527;6307.873842523159;4960.305557221992;4962.478939711333;4997.4003119625695;5063.139266324715;5077.771500282496;4984.3021976923255;5030.220315851049;5032.6477146599755;5042.546170844624;5043.546080852722;4743.169945259583;4909.410870695521;4917.508249175072;4978.950210497903;4992.327570263966;4973.925391119136;4983.526564976321;4984.700917944935;5003.149464591028;5015.7492125393765;4379.1022840317655;4402;4429.889252768674;4431.689781715281;4451.198784218839;6154.5076393888385;6161.691915404257;6185.628862268279;6191.721372538246;6238.812835614939;5675.177099320616;5681.261436013333;5715.285414375712;5762.250907382014;5798.53562236048;6491.93508064918;6581.40034686301;6588.957423198775;6616.834579135512;6659.40065394112;4801.855944321641;4805.303231031481;4805.9094293982225;4831.4926932671915;4837.782299796518;7034.472414568928;7053.612051337174;7062.329078737512;7109.680064397115;7146.499745017903;4890.168671325883;4939.703617782872;4965.598564982998;4966.652334336634;5007.39911210657;4938.9012219755405;4981.800727970913;4990.6257030722845;4997.700137991732;4999.700017998932;5723.399043100415;5736.4224838169;5764.279465066867;5781.624194427349;5790.594658373873;6677.399034086859;6730.394264516217;6731.124953755981;6735.25912149673;6777.152855893013;1654.1729135432283;1684.3683618643008;1694.940677076321;1703.5570751604423;1752.4217008387202;1176.100283283289;1191.2733232728028;1193.1051711216587;1196.7486827766268;1203.2720204276286;5753.309602847601;5763.423657634287;5818.2436283282905;5822.359540450547;5916.940830591639;6617.966910165418;6622.761432683086;6643.368879956396;6667.6666166691475;6748.763793267212;5820.214271073435;5929.436703513159;5949.553783466257;5962.821115366546;5972.67065844321;3621.728370372233;3627.764195327295;3675.650813172744;3675.889723308305;3703.777773333609;6508.544401891917;6527.967360163168;6540.50946179029;6652;6712.39588437043;6988.545744526589;6993.2657071007825;7022.438204943593;7075.39859111971;7081.150261968597;6554.246261679968;6604.282886449461;6630.668466576748;6639.203295604461;6707.765228216989;6760.391564759196;6783.728650853911;6865.210500792473;6873.793786186422;6984.79045628632;6790.064831522992;6811;6906.240313565403;6918.654067296515;6926.792196234121;6694;6696.698648560729;6740.595564265964;6741.2921643226755;6766.15423072116;4938.135826230324;4947.183714687037;4950.45544990058;4963.106640804545;4998.875028124326;6918.411934985583;6956.860862782817;6964.860702785814;6976.4767642426;6998.930010699828;6630.933690663224;6659.667016649245;6734.898976515454;6751.426128779109;6760.695768690519;6612.900806488003;6693.732250709917;6744.22441419243;6775;6788.86422271542;6249.093881387083;6486.318936511787;6498.610083395011;6629.171353580802;6640.236372816995;6345.318490599902;6567.671616419065;6585.769498067544;6648.836453620694;6663.300353462817;6419.807405777834;6447.677616119082;6448.709808058555;6474.125992990886;6501.772437964649 13:12:36 INFO - PID 6933 | |5;yui.html;898.2364989758705;901.5897766516235;918.7565295196772;920.5259291464893;924.1497822003756;19236.519087022833;19906.601867962632;19957.201711931506;19976.900115499422;20487.795122048814;19908.402747917586;20027.198912043536;20114.49713757157;20160.59678806423;20341.389758307236;961.5240455974302;970.0978090375947;971.8785151856018;972.2367941166186;973.9853902191472;944.0135058863469;993.64228877604;996.8604395384616;998.3061772068415;1000.164862339947;1045.142982754142;1051.0645525482328;1059.973500662482;1061.3843970497094;1063.2291588598243;1051.952662130202;1052.0426411965113;1053.4311471805215;1053.984190237147;1054.6150655010904;775.464929198851;775.7013549783318;775.8991331126917;776.646625785272;776.7980325115454;19945.401637950883;19962.90018549898;20092.79907200924;20113.29603463872;20374.592508149755;20781.16875324985;20956.476088097766;21088.156473741034;21185.364439066852;21214;16925.3229870805;17049.40327088546;17170.48488545333;17184.656306873916;17295.74056389155;3625.23869987303;3638.7998660073686;3640.3993341098685;3641.7086633069557;3642.417213245868;127175;127466;128142;129605.99999999905;130715;16119.67760644804;16137.838621613868;16141.757873631905;16528.504144875675;16532.173391330387;30097.548536771967;30183.396332073673;30231.848840755654;30589.847050764605;31133;7960.641771120314;8384.538850363166;8680.30557555394;8698.95650521756;8759.562021898879;4363.345498175236;4529.8641040768825;4535.977320113444;4540.296254080623;4553.2942393929;5210.713410762369;5302.840914772563;5325.653832500875;5713;5887.735051922674;1112.7663190729875;1142.7485953090306;1153.9653810385703;1154.1978325064;1156.4217891054473;691.5435812363816;710.2861624067779;717.9676914538858;721.1598487761804;731.2139450091067;11598.826017609743;12524.937375313066;12582.685432864251;12649.810252846215;13085.738285234242;682.0621645237798;688.8071340024801;690.1097584116534;694.6630884021347;695.58265040975;5846.064629659234;5949.732262048132;5969.313528944227;6002.939970600238;6058.182145410313;108571.45714271536;109722.45138774415;109885;110164;111070;92616.53691731498;95991;98155.50922245343;100720;102091.48954255182;81324;82643.58678206691;83224;84267.15732842716;86473.5676321627;11529.827052594386;12821.294828784507;12938.417771200508;13142.60572182836;13334.799978000532;38009.87489474943;40054.998625034605;40317.193656126125;40358.1928361437;40876;70349;71750.64124679343;72145;72289;74264.25735742574;16373.672526549164;16642.417515387388;16679;17798.822011779717;18041.909790450965;20432.387028389174;20916.686249705963;21168.894155529124;21287.148514059263;21312.36062918115;18722;19294.035298235314;19562.315318963767;19603.215871364984;19689.704654430483;18393.72409413833;18443;18756.906215468836;18945.810541894403;18975.90512047431;17988.55028624295;18773.812261877207;19438.4168474946;19492.31776887802;19513.414597562096;13405.128666636638;13464.596062118153;13798.17210967345;13940.721185576434;13943.860561394256;14147.575572732423;14408.783868242195;14529.34617942174;14690.485832995793;15034.473793417177 13:12:36 INFO - PID 6933 | __end_tp_report 13:12:36 INFO - PID 6933 | __start_cc_report 13:12:36 INFO - PID 6933 | _x_x_mozilla_cycle_collect,362 13:12:36 INFO - PID 6933 | __end_cc_report 13:12:36 INFO - PID 6933 | __startTimestamp1504037556769__endTimestamp 13:12:36 INFO - PID 6933 | 13:12:36 INFO - PID 6933 | ------- Summary: start ------- 13:12:36 INFO - PID 6933 | Number of tests: 6 13:12:36 INFO - PID 6933 | 13:12:36 INFO - PID 6933 | [#0] dojo.html Cycles:165 Average:6279.88 Median:6516.61 stddev:733.80 (11.3%) stddev-sans-first:698.06 13:12:36 INFO - PID 6933 | Values: 3308.9 3315.4 3325.8 3360.7 3360.9 6300.1 6375.8 6415.6 6444.1 6452.2 6204.5 6303.1 6322.1 6340.1 6342.1 5844.0 5851.8 5859.4 5871.2 5897.8 5731.0 5763.9 5850.6 5886.9 5959.3 5738.9 5795.7 5841.2 5873.1 5904.1 5822.9 5831.6 5878.3 5891.4 5904.1 5680.7 5740.4 5755.8 5762.3 5838.5 6284.4 6305.3 6347.5 6369.5 6388.4 6755.0 6761.0 6798.4 6848.6 6857.6 6830.0 6868.9 6952.3 6960.0 6992.7 6304.6 6359.7 6379.1 6413.1 6424.7 6958.8 6962.6 6965.4 7043.8 7069.7 6626.7 6705.9 6721.0 6727.5 6739.6 6842.7 6866.3 6931.5 6938.3 6941.5 6711.8 6758.8 6780.4 6821.2 6840.9 6636.1 6745.1 6765.4 6850.0 6902.8 5349.7 5383.5 5409.9 5415.8 5439.9 4672.7 4680.0 4716.2 4721.3 4739.7 5827.9 5895.0 5949.4 6036.2 6045.5 6805.6 6889.5 6898.1 6924.1 6946.5 5938.9 5942.6 6008.1 6052.0 6068.8 5804.7 5846.1 5911.0 5929.2 5947.0 6427.9 6538.2 6548.3 6649.8 6664.6 6917.4 6965.3 6967.8 6970.1 6972.0 6516.6 6599.0 6668.7 6687.2 6698.6 6475.9 6551.7 6719.3 6736.7 6839.5 6713.9 6789.0 6802.7 6805.2 6807.0 6463.4 6534.8 6572.7 6575.2 6644.8 6650.0 6671.0 6684.2 6693.9 6717.0 6502.1 6521.1 6582.8 6599.9 6614.3 6577.8 6656.9 6676.5 6702.7 6718.2 6469.8 6500.7 6503.1 6558.2 6560.8 13:12:36 INFO - PID 6933 | 13:12:36 INFO - PID 6933 | [#1] ext.html Cycles:180 Average:37345.87 Median:34431.24 stddev:53591.87 (155.6%) stddev-sans-first:53672.03 13:12:36 INFO - PID 6933 | Values: 821.8 860.9 892.3 893.2 1151.1 29718.4 30335.7 30336.5 40122.8 40748.2 40447.0 40836.6 40882.0 40887.0 40913.0 7672.3 7696.4 7837.1 7849.3 7860.8 7659.3 7760.9 7764.5 7777.8 7783.6 7727.7 7791.5 7802.8 7803.8 7814.3 7750.9 7774.1 7782.6 7786.6 7792.1 7679.6 7725.8 7749.2 7751.3 7752.8 36462.8 36654.5 36671.6 36674.4 36699.0 3575.1 3602.6 3602.9 3603.3 3604.6 30811.5 30812.8 30897.0 30945.8 30979.7 5022.1 5039.5 5060.2 5101.0 5123.7 188886.0 188969.0 189218.0 189722.0 190301.0 46846.8 46999.1 47041.8 47119.8 47165.0 49168.0 49189.5 51241.0 51911.0 51971.0 15999.9 16056.2 16072.6 16082.8 16175.2 11518.4 11708.8 11714.8 11718.1 13212.2 15028.3 15105.5 15110.8 15187.2 15257.3 6113.7 6116.8 6120.6 6179.2 6247.5 1010.5 1028.2 1041.0 1042.3 1045.3 23567.8 23907.5 24205.2 24276.3 24413.4 1084.0 1132.1 1136.4 1137.5 1149.1 11902.5 12548.6 12589.7 12600.1 12662.4 224676.0 225726.0 226042.0 226477.0 226697.0 48473.8 48606.0 48657.5 48797.5 48837.0 1435.2 1446.0 1454.3 1455.7 1459.0 22739.7 22848.1 22874.9 22894.2 23155.3 37107.1 37296.4 37388.3 37390.4 37401.8 204344.0 204405.0 204677.0 204867.0 204938.0 33554.0 33930.7 34931.8 36503.3 36637.5 43214.4 43598.8 43795.3 44188.3 44332.8 32772.7 32832.8 32996.8 33237.2 33370.5 31181.3 32368.8 32500.5 32954.2 33120.2 35273.5 36046.3 36126.6 36269.8 36618.5 31610.1 32129.2 32507.5 32531.5 32971.3 23115.2 24836.0 24837.6 24871.4 25031.4 13:12:36 INFO - PID 6933 | 13:12:36 INFO - PID 6933 | [#2] jquery.html Cycles:180 Average:36616.64 Median:22892.54 stddev:91587.99 (400.1%) stddev-sans-first:91804.91 13:12:36 INFO - PID 6933 | Values: 561.3 563.6 571.4 571.6 580.6 21653.8 22350.4 22354.1 22453.8 22498.8 25579.5 25993.5 26093.2 26158.3 26177.7 6323.2 6448.8 6457.2 6460.5 6463.8 5769.7 6297.6 6298.2 6309.7 6421.0 6270.0 6272.8 6284.1 6288.4 6289.4 6259.7 6273.3 6295.6 6298.2 6301.4 5839.2 5854.0 5854.6 5857.4 5875.2 28901.9 29519.6 30012.1 30340.4 30408.7 1999.6 2024.0 2025.9 2028.3 2028.5 19370.4 19844.0 19938.0 19954.0 19992.1 2334.6 2363.5 2407.5 2420.8 2452.3 130942.3 135721.0 135808.3 135861.8 135981.3 13633.6 14190.1 14336.2 14346.3 14510.3 22780.7 22829.9 22955.2 23014.9 23018.3 9990.7 10299.3 10311.4 10407.5 10593.2 8279.2 9842.9 9871.7 9886.4 9904.0 7879.8 8946.4 8980.2 9023.7 9276.3 2571.3 2595.6 2607.7 2710.1 2715.9 938.8 943.7 976.3 988.5 989.3 19331.0 19445.4 19851.5 19872.7 20220.6 1048.4 1062.3 1064.7 1068.0 1069.2 10870.2 10926.6 10978.3 11017.0 11047.2 534682.0 549152.0 550782.0 553160.0 556954.0 113707.4 114871.0 115165.0 116417.0 116453.0 1186.1 1224.3 1228.5 1238.6 1243.5 17607.7 17698.4 17790.0 17828.6 17900.0 54397.2 55038.7 55186.4 55865.2 56362.2 72517.0 72675.3 72884.0 74126.6 74944.0 12210.3 12745.1 12776.9 14074.4 14634.6 23975.6 24472.0 24844.5 25379.2 25697.4 19276.5 19828.2 19838.7 20047.9 20105.7 17079.8 17143.0 17947.8 17984.3 18286.0 19813.3 19869.2 19945.5 20376.9 20399.1 20648.4 21209.2 21224.0 21410.4 21483.6 18608.4 19207.8 19247.1 19253.1 19441.3 13:12:36 INFO - PID 6933 | 13:12:36 INFO - PID 6933 | [#3] mootools.html Cycles:180 Average:9100.06 Median:2626.90 stddev:6364.23 (242.3%) stddev-sans-first:6355.27 13:12:36 INFO - PID 6933 | Values: 1323.8 1407.0 1418.2 1423.4 1437.3 14299.4 15506.5 15513.3 15551.1 15555.5 15228.0 15466.8 15475.6 15506.4 15514.1 2747.3 2811.6 2816.0 2823.2 2824.4 2640.3 2737.1 2742.5 2765.6 2779.5 2613.5 2804.4 2838.8 2840.0 2847.7 2758.6 2793.1 2828.5 2841.8 2848.7 2694.2 2714.4 2732.0 2735.4 2737.5 14699.6 14746.0 14930.2 14945.1 14996.9 7984.9 8289.9 8297.5 8307.8 8317.1 4971.3 5156.3 5467.5 5486.6 5658.2 1843.7 1854.8 1877.6 1890.6 1906.3 25196.0 25580.5 25583.4 25610.5 25633.4 6456.0 6520.2 6743.9 6809.1 6903.8 16967.0 16970.5 16997.0 17013.0 17013.7 7825.3 7958.9 7959.8 7966.0 7988.7 7318.2 7370.6 7417.1 7500.4 7512.4 5185.1 5313.3 5322.2 5356.9 5387.1 1381.2 1412.7 1609.5 1610.2 1627.3 891.4 909.6 934.6 935.2 957.0 8345.7 8545.1 9066.4 9079.5 9182.9 1025.2 1033.0 1038.2 1042.7 1058.2 5087.1 5146.1 5279.1 5314.1 5340.5 20197.4 20420.2 20739.5 20923.1 21298.9 20744.2 20835.6 20855.2 20923.8 20928.6 10831.2 10975.8 11230.9 11329.0 11351.5 8570.9 8585.8 8605.7 8726.2 8845.8 16861.0 17444.7 17532.6 17906.3 18042.0 16659.0 17123.8 17312.7 17345.9 17802.5 10488.0 10902.3 10933.3 10935.8 10937.2 13216.1 13333.3 13334.7 13335.9 13337.0 10407.1 10497.2 10633.6 10677.1 10802.1 10191.7 10238.1 10248.7 10305.6 10602.5 8317.5 8989.0 9049.1 9057.3 9126.9 6823.6 6867.4 6873.6 6894.1 6969.7 6578.9 6752.7 6795.2 6800.9 7004.8 13:12:36 INFO - PID 6933 | 13:12:36 INFO - PID 6933 | [#4] prototype.html Cycles:180 Average:5579.45 Median:6201.51 stddev:1602.74 (25.8%) stddev-sans-first:1560.70 13:12:36 INFO - PID 6933 | Values: 471.1 478.2 490.8 495.9 502.1 6448.9 6460.8 6464.2 6466.5 6493.8 6052.7 6129.8 6211.3 6283.7 6307.9 4960.3 4962.5 4997.4 5063.1 5077.8 4984.3 5030.2 5032.6 5042.5 5043.5 4743.2 4909.4 4917.5 4979.0 4992.3 4973.9 4983.5 4984.7 5003.1 5015.7 4379.1 4402.0 4429.9 4431.7 4451.2 6154.5 6161.7 6185.6 6191.7 6238.8 5675.2 5681.3 5715.3 5762.3 5798.5 6491.9 6581.4 6589.0 6616.8 6659.4 4801.9 4805.3 4805.9 4831.5 4837.8 7034.5 7053.6 7062.3 7109.7 7146.5 4890.2 4939.7 4965.6 4966.7 5007.4 4938.9 4981.8 4990.6 4997.7 4999.7 5723.4 5736.4 5764.3 5781.6 5790.6 6677.4 6730.4 6731.1 6735.3 6777.2 1654.2 1684.4 1694.9 1703.6 1752.4 1176.1 1191.3 1193.1 1196.7 1203.3 5753.3 5763.4 5818.2 5822.4 5916.9 6618.0 6622.8 6643.4 6667.7 6748.8 5820.2 5929.4 5949.6 5962.8 5972.7 3621.7 3627.8 3675.7 3675.9 3703.8 6508.5 6528.0 6540.5 6652.0 6712.4 6988.5 6993.3 7022.4 7075.4 7081.2 6554.2 6604.3 6630.7 6639.2 6707.8 6760.4 6783.7 6865.2 6873.8 6984.8 6790.1 6811.0 6906.2 6918.7 6926.8 6694.0 6696.7 6740.6 6741.3 6766.2 4938.1 4947.2 4950.5 4963.1 4998.9 6918.4 6956.9 6964.9 6976.5 6998.9 6630.9 6659.7 6734.9 6751.4 6760.7 6612.9 6693.7 6744.2 6775.0 6788.9 6249.1 6486.3 6498.6 6629.2 6640.2 6345.3 6567.7 6585.8 6648.8 6663.3 6419.8 6447.7 6448.7 6474.1 6501.8 13:12:36 INFO - PID 6933 | 13:12:36 INFO - PID 6933 | [#5] yui.html Cycles:180 Average:23952.15 Median:20357.99 stddev:32241.54 (158.4%) stddev-sans-first:32285.52 13:12:36 INFO - PID 6933 | Values: 898.2 901.6 918.8 920.5 924.1 19236.5 19906.6 19957.2 19976.9 20487.8 19908.4 20027.2 20114.5 20160.6 20341.4 961.5 970.1 971.9 972.2 974.0 944.0 993.6 996.9 998.3 1000.2 1045.1 1051.1 1060.0 1061.4 1063.2 1052.0 1052.0 1053.4 1054.0 1054.6 775.5 775.7 775.9 776.6 776.8 19945.4 19962.9 20092.8 20113.3 20374.6 20781.2 20956.5 21088.2 21185.4 21214.0 16925.3 17049.4 17170.5 17184.7 17295.7 3625.2 3638.8 3640.4 3641.7 3642.4 127175.0 127466.0 128142.0 129606.0 130715.0 16119.7 16137.8 16141.8 16528.5 16532.2 30097.5 30183.4 30231.8 30589.8 31133.0 7960.6 8384.5 8680.3 8699.0 8759.6 4363.3 4529.9 4536.0 4540.3 4553.3 5210.7 5302.8 5325.7 5713.0 5887.7 1112.8 1142.7 1154.0 1154.2 1156.4 691.5 710.3 718.0 721.2 731.2 11598.8 12524.9 12582.7 12649.8 13085.7 682.1 688.8 690.1 694.7 695.6 5846.1 5949.7 5969.3 6002.9 6058.2 108571.5 109722.5 109885.0 110164.0 111070.0 92616.5 95991.0 98155.5 100720.0 102091.5 81324.0 82643.6 83224.0 84267.2 86473.6 11529.8 12821.3 12938.4 13142.6 13334.8 38009.9 40055.0 40317.2 40358.2 40876.0 70349.0 71750.6 72145.0 72289.0 74264.3 16373.7 16642.4 16679.0 17798.8 18041.9 20432.4 20916.7 21168.9 21287.1 21312.4 18722.0 19294.0 19562.3 19603.2 19689.7 18393.7 18443.0 18756.9 18945.8 18975.9 17988.6 18773.8 19438.4 19492.3 19513.4 13405.1 13464.6 13798.2 13940.7 13943.9 14147.6 14408.8 14529.3 14690.5 15034.5 13:12:36 INFO - PID 6933 | -------- Summary: end -------- 13:12:36 INFO - PID 6933 | 13:12:37 INFO - TEST-INFO | 6933: exit 0 13:12:37 INFO - TEST-OK | dromaeo_css | took 1110859ms 13:12:37 INFO - TEST-START | kraken 13:12:37 INFO - Initialising browser for kraken test... 13:12:37 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:38233/getInfo.html -profile /tmp/tmpVU3laQ/profile 13:12:37 INFO - TEST-INFO | started process 7304 (/builds/slave/test/build/application/firefox/firefox http://localhost:38233/getInfo.html) 13:12:42 INFO - TEST-INFO | 7304: exit 0 13:12:42 INFO - Browser initialized. 13:12:42 INFO - Running cycle 1/1 for kraken test... 13:12:42 INFO - Using env: {'DISPLAY': ':0', 13:12:42 INFO - 'HOME': '/home/cltbld', 13:12:42 INFO - 'JSGC_DISABLE_POISONING': '1', 13:12:42 INFO - 'LANG': 'en_US.UTF-8', 13:12:42 INFO - 'LANGUAGE': 'en_US:en', 13:12:42 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 13:12:42 INFO - 'LOGNAME': 'cltbld', 13:12:42 INFO - 'MAIL': '/var/mail/cltbld', 13:12:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:12:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 13:12:42 INFO - 'MOZ_CRASHREPORTER': '1', 13:12:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:12:42 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 13:12:42 INFO - 'MOZ_NO_REMOTE': '1', 13:12:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:12:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:12:42 INFO - 'NO_EM_RESTART': '1', 13:12:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:12:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:12:42 INFO - 'PWD': '/builds/slave/test', 13:12:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:12:42 INFO - 'RUST_BACKTRACE': 'full', 13:12:42 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 13:12:42 INFO - 'SHELL': '/bin/bash', 13:12:42 INFO - 'SHLVL': '1', 13:12:42 INFO - 'TERM': 'linux', 13:12:42 INFO - 'TMOUT': '86400', 13:12:42 INFO - 'USER': 'cltbld', 13:12:42 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504036376.908797-1903792368', 13:12:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:12:42 INFO - '_': '/tools/buildbot/bin/python'} 13:12:42 INFO - TEST-INFO | started process 7486 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVU3laQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 13:12:43 INFO - PID 7486 | 13:12:43 INFO - PID 7486 | (/builds/slave/test/build/application/firefox/firefox:7538): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 13:12:43 INFO - PID 7486 | 13:12:54 INFO - PID 7486 | RSS: Main: 171053056 13:12:54 INFO - PID 7486 | 13:12:56 INFO - PID 7486 | Cycle 1(1): loaded http://localhost:38233/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:38233/tests/kraken/driver.html?testName=audio-beat-detection) 13:12:57 INFO - PID 7486 | RSS: Main: 175554560 13:12:57 INFO - PID 7486 | 13:13:00 INFO - PID 7486 | Cycle 1(1): loaded http://localhost:38233/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:38233/tests/kraken/driver.html?testName=audio-dft) 13:13:00 INFO - PID 7486 | RSS: Main: 176308224 13:13:00 INFO - PID 7486 | 13:13:03 INFO - PID 7486 | Cycle 1(1): loaded http://localhost:38233/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:38233/tests/kraken/driver.html?testName=audio-fft) 13:13:04 INFO - PID 7486 | RSS: Main: 170229760 13:13:04 INFO - PID 7486 | 13:13:06 INFO - PID 7486 | Cycle 1(1): loaded http://localhost:38233/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:38233/tests/kraken/driver.html?testName=audio-oscillator) 13:13:07 INFO - PID 7486 | RSS: Main: 171110400 13:13:07 INFO - PID 7486 | 13:13:09 INFO - PID 7486 | Cycle 1(1): loaded http://localhost:38233/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:38233/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:13:10 INFO - PID 7486 | RSS: Main: 171380736 13:13:10 INFO - PID 7486 | 13:13:13 INFO - PID 7486 | Cycle 1(1): loaded http://localhost:38233/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:38233/tests/kraken/driver.html?testName=imaging-darkroom) 13:13:13 INFO - PID 7486 | RSS: Main: 174108672 13:13:13 INFO - PID 7486 | 13:13:17 INFO - PID 7486 | Cycle 1(1): loaded http://localhost:38233/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:38233/tests/kraken/driver.html?testName=imaging-desaturate) 13:13:17 INFO - PID 7486 | RSS: Main: 173699072 13:13:17 INFO - PID 7486 | 13:13:20 INFO - PID 7486 | Cycle 1(1): loaded http://localhost:38233/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:38233/tests/kraken/driver.html?testName=json-parse-financial) 13:13:21 INFO - PID 7486 | RSS: Main: 173715456 13:13:21 INFO - PID 7486 | 13:13:22 INFO - PID 7486 | Cycle 1(1): loaded http://localhost:38233/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:38233/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:13:23 INFO - PID 7486 | RSS: Main: 173522944 13:13:23 INFO - PID 7486 | 13:13:25 INFO - PID 7486 | Cycle 1(1): loaded http://localhost:38233/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:38233/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:13:25 INFO - PID 7486 | RSS: Main: 173965312 13:13:25 INFO - PID 7486 | 13:13:27 INFO - PID 7486 | Cycle 1(1): loaded http://localhost:38233/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:38233/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:13:28 INFO - PID 7486 | RSS: Main: 170618880 13:13:28 INFO - PID 7486 | 13:13:31 INFO - PID 7486 | Cycle 1(1): loaded http://localhost:38233/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:38233/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:13:32 INFO - PID 7486 | RSS: Main: 170639360 13:13:32 INFO - PID 7486 | 13:13:34 INFO - PID 7486 | Cycle 1(1): loaded http://localhost:38233/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:38233/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:13:35 INFO - PID 7486 | RSS: Main: 170815488 13:13:35 INFO - PID 7486 | 13:13:37 INFO - PID 7486 | Cycle 1(1): loaded http://localhost:38233/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:38233/tests/kraken/driver.html?testName=ai-astar) 13:13:37 INFO - PID 7486 | RSS: Main: 172240896 13:13:37 INFO - PID 7486 | 13:13:37 INFO - PID 7486 | __start_tp_report 13:13:37 INFO - PID 7486 | _x_x_mozilla_page_load 13:13:37 INFO - PID 7486 | _x_x_mozilla_page_load_details 13:13:37 INFO - PID 7486 | |i|pagename|runs| 13:13:37 INFO - PID 7486 | |0;ai-astar;98;109;100;107;107;110;108;99;109;110 13:13:37 INFO - PID 7486 | |1;audio-beat-detection;146;121;149;179;118;152;141;117;176;121 13:13:37 INFO - PID 7486 | |2;audio-dft;164;163;164;163;163;162;164;166;168;163 13:13:37 INFO - PID 7486 | |3;audio-fft;76;74;74;174;75;76;74;73;149;75 13:13:37 INFO - PID 7486 | |4;audio-oscillator;80;78;134;80;79;79;125;78;80;130 13:13:37 INFO - PID 7486 | |5;imaging-gaussian-blur;117;114;115;114;115;114;114;116;114;114 13:13:37 INFO - PID 7486 | |6;imaging-darkroom;148;148;149;148;148;149;148;148;149;148 13:13:37 INFO - PID 7486 | |7;imaging-desaturate;93;94;94;94;94;95;94;94;95;93 13:13:37 INFO - PID 7486 | |8;json-parse-financial;53;52;52;52;52;52;53;53;52;52 13:13:37 INFO - PID 7486 | |9;json-stringify-tinderbox;39;38;37;37;44;38;37;38;39;39 13:13:37 INFO - PID 7486 | |10;stanford-crypto-aes;76;75;80;78;76;80;77;79;75;73 13:13:37 INFO - PID 7486 | |11;stanford-crypto-ccm;133;133;120;119;119;125;124;123;123;154 13:13:37 INFO - PID 7486 | |12;stanford-crypto-pbkdf2;179;171;167;175;168;172;171;169;170;171 13:13:37 INFO - PID 7486 | |13;stanford-crypto-sha256-iterative;64;64;65;66;64;67;78;65;67;67 13:13:37 INFO - PID 7486 | __end_tp_report 13:13:37 INFO - PID 7486 | __start_cc_report 13:13:37 INFO - PID 7486 | _x_x_mozilla_cycle_collect,964 13:13:37 INFO - PID 7486 | __end_cc_report 13:13:37 INFO - PID 7486 | __startTimestamp1504037617601__endTimestamp 13:13:37 INFO - PID 7486 | 13:13:37 INFO - PID 7486 | ------- Summary: start ------- 13:13:37 INFO - PID 7486 | Number of tests: 14 13:13:37 INFO - PID 7486 | 13:13:37 INFO - PID 7486 | [#0] ai-astar Cycles:10 Average:105.70 Median:109.50 stddev:4.76 (4.3%) stddev-sans-first:4.16 13:13:37 INFO - PID 7486 | Values: 98.0 109.0 100.0 107.0 107.0 110.0 108.0 99.0 109.0 110.0 13:13:37 INFO - PID 7486 | 13:13:37 INFO - PID 7486 | [#1] audio-beat-detection Cycles:10 Average:142.00 Median:147.50 stddev:23.03 (15.6%) stddev-sans-first:24.38 13:13:37 INFO - PID 7486 | Values: 146.0 121.0 149.0 179.0 118.0 152.0 141.0 117.0 176.0 121.0 13:13:37 INFO - PID 7486 | 13:13:37 INFO - PID 7486 | [#2] audio-dft Cycles:10 Average:164.00 Median:164.00 stddev:1.76 (1.1%) stddev-sans-first:1.87 13:13:37 INFO - PID 7486 | Values: 164.0 163.0 164.0 163.0 163.0 162.0 164.0 166.0 168.0 163.0 13:13:37 INFO - PID 7486 | 13:13:37 INFO - PID 7486 | [#3] audio-fft Cycles:10 Average:92.00 Median:74.50 stddev:37.11 (49.8%) stddev-sans-first:38.91 13:13:37 INFO - PID 7486 | Values: 76.0 74.0 74.0 174.0 75.0 76.0 74.0 73.0 149.0 75.0 13:13:37 INFO - PID 7486 | 13:13:37 INFO - PID 7486 | [#4] audio-oscillator Cycles:10 Average:94.30 Median:79.00 stddev:24.51 (31.0%) stddev-sans-first:25.44 13:13:37 INFO - PID 7486 | Values: 80.0 78.0 134.0 80.0 79.0 79.0 125.0 78.0 80.0 130.0 13:13:37 INFO - PID 7486 | 13:13:37 INFO - PID 7486 | [#5] imaging-gaussian-blur Cycles:10 Average:114.70 Median:114.50 stddev:1.06 (0.9%) stddev-sans-first:0.73 13:13:37 INFO - PID 7486 | Values: 117.0 114.0 115.0 114.0 115.0 114.0 114.0 116.0 114.0 114.0 13:13:37 INFO - PID 7486 | 13:13:37 INFO - PID 7486 | [#6] imaging-darkroom Cycles:10 Average:148.30 Median:148.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 13:13:37 INFO - PID 7486 | Values: 148.0 148.0 149.0 148.0 148.0 149.0 148.0 148.0 149.0 148.0 13:13:37 INFO - PID 7486 | 13:13:37 INFO - PID 7486 | [#7] imaging-desaturate Cycles:10 Average:94.00 Median:94.00 stddev:0.67 (0.7%) stddev-sans-first:0.60 13:13:37 INFO - PID 7486 | Values: 93.0 94.0 94.0 94.0 94.0 95.0 94.0 94.0 95.0 93.0 13:13:37 INFO - PID 7486 | 13:13:37 INFO - PID 7486 | [#8] json-parse-financial Cycles:10 Average:52.30 Median:52.00 stddev:0.48 (0.9%) stddev-sans-first:0.44 13:13:37 INFO - PID 7486 | Values: 53.0 52.0 52.0 52.0 52.0 52.0 53.0 53.0 52.0 52.0 13:13:37 INFO - PID 7486 | 13:13:37 INFO - PID 7486 | [#9] json-stringify-tinderbox Cycles:10 Average:38.60 Median:38.50 stddev:2.07 (5.4%) stddev-sans-first:2.19 13:13:37 INFO - PID 7486 | Values: 39.0 38.0 37.0 37.0 44.0 38.0 37.0 38.0 39.0 39.0 13:13:37 INFO - PID 7486 | 13:13:37 INFO - PID 7486 | [#10] stanford-crypto-aes Cycles:10 Average:76.90 Median:77.50 stddev:2.33 (3.0%) stddev-sans-first:2.45 13:13:37 INFO - PID 7486 | Values: 76.0 75.0 80.0 78.0 76.0 80.0 77.0 79.0 75.0 73.0 13:13:37 INFO - PID 7486 | 13:13:37 INFO - PID 7486 | [#11] stanford-crypto-ccm Cycles:10 Average:127.30 Median:124.50 stddev:10.66 (8.6%) stddev-sans-first:11.10 13:13:37 INFO - PID 7486 | Values: 133.0 133.0 120.0 119.0 119.0 125.0 124.0 123.0 123.0 154.0 13:13:37 INFO - PID 7486 | 13:13:37 INFO - PID 7486 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.30 Median:171.00 stddev:3.50 (2.0%) stddev-sans-first:2.35 13:13:37 INFO - PID 7486 | Values: 179.0 171.0 167.0 175.0 168.0 172.0 171.0 169.0 170.0 171.0 13:13:37 INFO - PID 7486 | 13:13:37 INFO - PID 7486 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.70 Median:66.50 stddev:4.16 (6.3%) stddev-sans-first:4.30 13:13:37 INFO - PID 7486 | Values: 64.0 64.0 65.0 66.0 64.0 67.0 78.0 65.0 67.0 67.0 13:13:37 INFO - PID 7486 | -------- Summary: end -------- 13:13:37 INFO - PID 7486 | 13:13:38 INFO - PID 7486 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 13:13:38 INFO - TEST-INFO | 7486: exit 0 13:13:38 INFO - TEST-OK | kraken | took 60777ms 13:13:38 INFO - SUITE-END | took 1171s 13:13:38 INFO - Completed test suite (00:19:32) 13:13:38 INFO - javascript benchmark 13:13:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8606.69711408437, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3308.9172770680743, 3315.386653469108, 3325.7671962962586, 3360.7479439042077, 3360.8823691170815, 6300.086487459315, 6375.840603984902, 6415.58298710584, 6444.097826304315, 6452.161219041518, 6204.472619827309, 6303.0545418187185, 6322.114903913456, 6340.144080549125, 6342.112104305401, 5844.0, 5851.79518716845, 5859.44335288147, 5871.178035075082, 5897.823065308047, 5731.018205678131, 5763.942360576382, 5850.648961062328, 5886.88226235475, 5959.284885813709, 5738.885222295552, 5795.681237531934, 5841.240638716961, 5873.060310350366, 5904.143899134601, 5822.9417705822825, 5831.620944638585, 5878.265216847894, 5891.440313170242, 5904.143899134644, 5680.6591604503865, 5740.397258287861, 5755.827325180251, 5762.279715035621, 5838.503727183194, 6284.4344009039405, 6305.3379395163765, 6347.460465860361, 6369.4585960193435, 6388.425041746219, 6754.966225168842, 6761.0196521504595, 6798.422134118606, 6848.554843935129, 6857.588544687334, 6830.0, 6868.896966545506, 6952.304769523058, 6959.965200173967, 6992.650367481657, 6304.621722696653, 6359.713812878385, 6379.106925030499, 6413.102165696806, 6424.678766061772, 6958.826029349307, 6962.617056061864, 6965.442764578822, 7043.8239044023285, 7069.681864316118, 6626.7017984190825, 6705.893527567994, 6720.9663951680905, 6727.495437842182, 6739.629320387327, 6842.692078856364, 6866.313368663194, 6931.480138989596, 6938.27148149437, 6941.548799328028, 6711.765088221987, 6758.831029224309, 6780.389764921082, 6821.249662537117, 6840.897386539206, 6636.104125942936, 6745.089412929192, 6765.391114799691, 6849.972504124421, 6902.827429314207, 5349.705766182819, 5383.488568585978, 5409.945900541023, 5415.783368665209, 5439.891202175934, 4672.742999135012, 4680.0, 4716.198246298138, 4721.3390125382475, 4739.668223224341, 5827.883442331215, 5894.970525147432, 5949.405059494016, 6036.15493830864, 6045.486133678599, 6805.591664500146, 6889.483288753263, 6898.1377327834025, 6924.065251191126, 6946.5137440378685, 5938.940610593925, 5942.58401911862, 6008.09878518217, 6051.971164901889, 6068.848278792977, 5804.680742559115, 5846.064629659234, 5910.970445147746, 5929.199558059693, 5947.0, 6427.935720642828, 6538.1827271591055, 6548.27968923418, 6649.767258145942, 6664.633445160563, 6917.377436030782, 6965.33829286217, 6967.79096627102, 6970.128733908262, 6972.0, 6516.609003459807, 6599.043138744951, 6668.6665666717445, 6687.164104486939, 6698.56459330142, 6475.902861456988, 6551.737930482727, 6719.294474080151, 6736.730530778714, 6839.452843772586, 6713.899291510534, 6789.049533065276, 6802.727890884309, 6805.183377994672, 6807.047013418027, 6463.353664633499, 6534.771283005072, 6572.671366431564, 6575.178102737158, 6644.767433139817, 6649.966750166218, 6671.0, 6684.231313399021, 6693.899591506229, 6717.026031225542, 6502.122213501117, 6521.119648847345, 6582.8354291143105, 6599.9010014848855, 6614.305497922649, 6577.835554111186, 6656.900146497709, 6676.49926255523, 6702.731890724316, 6718.193816742022, 6469.77355792545, 6500.739970401131, 6503.089567460464, 6558.1802274715665, 6560.803175904731], "value": 6226.446677658998, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [821.7863355527564, 860.8751730999005, 892.3129190523293, 893.1782759860926, 1151.0963893343726, 29718.405631887348, 30335.696643033563, 30336.54495182574, 40122.799386003106, 40748.185036299255, 40447.0, 40836.59163408365, 40882.0, 40887.0, 40913.0, 7672.309492145706, 7696.4227682924, 7837.059552853666, 7849.254320839511, 7860.803479912991, 7659.310662040415, 7760.883586746203, 7764.495307804975, 7777.8055548611455, 7783.610819459004, 7727.72952946647, 7791.493552919071, 7802.8439431211345, 7803.8049048773955, 7814.257645523695, 7750.922490775105, 7774.105977812511, 7782.610869456505, 7786.571738554378, 7792.064952205772, 7679.57762323072, 7725.806854828618, 7749.186335434754, 7751.263629955145, 7752.806179845493, 36462.8176859114, 36654.450183247274, 36671.63328366709, 36674.44988325177, 36699.0, 3575.0704816747834, 3602.6397360264027, 3602.873899413508, 3603.279344131158, 3604.6395360463744, 30811.537826932614, 30812.8459357704, 30897.0, 30945.845270773727, 30979.690203097907, 5022.096022715909, 5039.496050394968, 5060.1903695408555, 5101.0, 5123.718195499246, 188886.0, 188969.0, 189218.0, 189722.0, 190301.04849475803, 46846.76576617095, 46999.06001880012, 47041.764791175825, 47119.76440117846, 47165.0, 49168.0, 49189.50810491921, 51241.0, 51911.0, 51971.0, 15999.920000400156, 16056.197190140447, 16072.598185045234, 16082.839171608133, 16175.191240438166, 11518.36648984297, 11708.824367634492, 11714.824277635842, 11718.121140914294, 13212.207267563977, 15028.323725432381, 15105.47130850437, 15110.773338399933, 15187.240637968058, 15257.313420896086, 6113.663748493876, 6116.785912493041, 6120.571559990847, 6179.16581261533, 6247.531435142291, 1010.5048526222098, 1028.228828378716, 1041.0, 1042.3276986343917, 1045.3153184664056, 23567.7643223569, 23907.521849562912, 24205.21640011386, 24276.271711848673, 24413.38966525851, 1083.9891601084046, 1132.1226049811462, 1136.4261048170622, 1137.5336112194127, 1149.109440183864, 11902.464389102512, 12548.623541293775, 12589.685257868627, 12600.11799174049, 12662.430190641444, 224676.0, 225726.0, 226042.0, 226477.0, 226697.0, 48473.75763121162, 48606.0, 48657.5134248653, 48797.51202488001, 48837.0, 1435.1891181482429, 1446.026174272111, 1454.2583282525989, 1455.7015811758565, 1458.9781153282922, 22739.65890511677, 22848.086076556752, 22874.88562557243, 22894.198703045313, 23155.3053408398, 37107.07232319214, 37296.44055339118, 37388.252234954605, 37390.439143413416, 37401.81299093596, 204344.0, 204405.0, 204677.0, 204867.0, 204938.0, 33554.0, 33930.66069339374, 34931.82534087415, 36503.26993460063, 36637.450438242915, 43214.35178472388, 43598.782006089765, 43795.34306985334, 44188.33717494176, 44332.77833610812, 32772.67227327696, 32832.83583582067, 32996.84511042102, 33237.16907077342, 33370.499442508866, 31181.285029323604, 32368.83815580907, 32500.512492312162, 32954.17614559655, 33120.1719957003, 35273.47089793604, 36046.27907441784, 36126.63873361233, 36269.81865090658, 36618.45072323864, 31610.103393796446, 32129.196770080936, 32507.512387313735, 32531.512027319135, 32971.340573188885, 23115.190968316027, 24836.00655973741, 24837.62743558884, 24871.378215544755, 25031.374215644755], "value": 17167.74268939285, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [561.287165300069, 563.5688698145916, 571.3943220186602, 571.6277448588355, 580.5587753307485, 21653.783462165393, 22350.441238969015, 22354.10583576657, 22453.775462245412, 22498.775012249873, 25579.488410231785, 25993.48013039738, 26093.217203483924, 26158.346041348977, 26177.73822261777, 6323.24121105468, 6448.838779030515, 6457.225132984025, 6460.483161347104, 6463.773767918124, 5769.682667453289, 6297.559170858019, 6298.212723409574, 6309.6845157742155, 6420.967895160517, 6270.0, 6272.811815645515, 6284.088807122965, 6288.402601752826, 6289.402506761873, 6259.7496100156395, 6273.309935907047, 6295.622262664232, 6298.244210694723, 6301.432871041606, 5839.153322768175, 5854.0, 5854.590178687494, 5857.355690874, 5875.236219291507, 28901.855490722413, 29519.55720664192, 30012.099637010924, 30340.393192136253, 30408.695913040807, 1999.6400647883374, 2024.0284663361522, 2025.9290924817708, 2028.3407892435018, 2028.4725971247435, 19370.4188874334, 19844.0, 19938.0, 19954.0, 19992.10035548404, 2334.6498025296173, 2363.5272945410816, 2407.518496300747, 2420.7579242075826, 2452.276578409356, 130942.34528827391, 135721.0, 135808.32095839558, 135861.848289102, 135981.3200933989, 13633.590992270249, 14190.148591084568, 14336.21150836693, 14346.282685865874, 14510.347034383478, 22780.658290125662, 22829.885850570972, 22955.196568120034, 23014.884925575265, 23018.309450716504, 9990.650327238513, 10299.279050466395, 10311.401732731438, 10407.53166107527, 10593.152547796159, 8279.172082791672, 9842.901570984342, 9871.654492092886, 9886.357386769838, 9904.0, 7879.763607091796, 8946.37375383717, 8980.191782739585, 9023.729288121367, 9276.323322231927, 2571.2543362425063, 2595.5976823592373, 2607.6610040694586, 2710.105665130502, 2715.891364345404, 938.7981583959481, 943.6697155995485, 976.2531663277598, 988.5353883674661, 989.2580564576567, 19331.0, 19445.416637500897, 19851.503712407306, 19872.70190947137, 20220.595588088156, 1048.4128887822844, 1062.2776511971933, 1064.669952314785, 1067.9679609611724, 1069.1927594665976, 10870.18473614482, 10926.617568385227, 10978.286411383235, 11017.008469237648, 11047.171462140372, 534682.0, 549152.0, 550782.0, 553160.0, 556954.0, 113707.43146284382, 114871.0, 115165.0, 116417.0, 116453.0, 1186.0807873897797, 1224.2715584227371, 1228.520876858026, 1238.5603110895684, 1243.5274595653775, 17607.72030813543, 17698.380556680455, 17790.0, 17828.643427131643, 17900.0, 54397.18404223861, 55038.724806377315, 55186.448135518134, 55865.16202256888, 56362.15456768234, 72517.0, 72675.27324726686, 72884.0, 74126.62936685282, 74944.0, 12210.328431936328, 12745.10784245131, 12776.872231277941, 14074.366653500412, 14634.634134146732, 23975.640365394185, 24472.0, 24844.50310993806, 25379.238622840607, 25697.357566060997, 19276.518087047934, 19828.206871724968, 19838.702419463432, 20047.899760501103, 20105.6984145235, 17079.829201707824, 17143.0, 17947.820521795136, 17984.2806287747, 18286.0, 19813.306534271233, 19869.205231790565, 19945.501362465475, 20376.898115509328, 20399.082041308473, 20648.380548583566, 21209.15163393447, 21224.0, 21410.357689269345, 21483.570328593654, 18608.44174674762, 19207.8079219206, 19247.13387897576, 19253.133608987348, 19441.31955381555], "value": 12128.856369044795, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1323.7881938889777, 1406.9648258793536, 1418.2412409360993, 1423.402171088143, 1437.302908089577, 14299.356528956196, 15506.534803955885, 15513.301901414436, 15551.144687042208, 15555.455559055436, 15228.0, 15466.845331546681, 15475.613109672264, 15506.379744810194, 15514.146721930289, 2747.285705716518, 2811.63448751662, 2816.0284701777855, 2823.153054083777, 2824.4351129774022, 2640.326716687242, 2737.110439107298, 2742.492638861807, 2765.5575107982727, 2779.5135851226055, 2613.4642398308397, 2804.383035732136, 2838.843863587502, 2840.034388307975, 2847.7152284771564, 2758.600002999574, 2793.0922450203766, 2828.5474324108145, 2841.843698596587, 2848.672402673688, 2694.1513423271804, 2714.4028313770937, 2732.0, 2735.3571910600995, 2737.54830452975, 14699.55901322962, 14746.0, 14930.178840163786, 14945.103293802407, 14996.925015374962, 7984.9201507985335, 8289.917100829036, 8297.460665056813, 8307.83384332316, 8317.085120636724, 4971.279164521125, 5156.252343410222, 5467.45325467454, 5486.561075113981, 5658.207850900836, 1843.6773564626137, 1854.842338401224, 1877.5869308752053, 1890.5557194059386, 1906.323255244396, 25196.0, 25580.488390232276, 25583.360415989562, 25610.48779024409, 25633.359166021, 6455.9677201613695, 6520.18497687789, 6743.932560674429, 6809.114815074008, 6903.75836845708, 16967.0, 16970.490885273462, 16997.0, 17013.0, 17013.659726805396, 7825.256600622932, 7958.880616790752, 7959.8408031839035, 7965.960170199228, 7988.720394786154, 7318.158411782713, 7370.6314684266645, 7417.072865891763, 7500.399968002546, 7512.436567257478, 5185.144451165515, 5313.335833020872, 5322.2282768998275, 5356.91964620531, 5387.138057910715, 1381.1851007905184, 1412.7315809996069, 1609.5251900689325, 1610.2029495399852, 1627.3246602659763, 891.4250308550949, 909.6225066597289, 934.6027938126269, 935.209747763139, 957.0381766324799, 8345.749627511184, 8545.060043395222, 9066.365354425126, 9079.54602269897, 9182.86225706615, 1025.2259544044198, 1032.9586816527255, 1038.2213339995003, 1042.6819819954987, 1058.1746237934424, 5087.109755792795, 5146.125158723026, 5279.1289437243195, 5314.096603577401, 5340.519353258225, 20197.394078177676, 20420.183192672128, 20739.481512961996, 20923.05846236923, 21298.893505532684, 20744.170233190805, 20835.583288334452, 20855.165793368094, 20923.79076209249, 20928.581428371654, 10831.187660925461, 10975.847536008605, 11230.943845280884, 11328.999999999834, 11351.545938162546, 8570.914290857012, 8585.82828343442, 8605.741827745178, 8726.171013753683, 8845.823083538164, 16861.0, 17444.651106977537, 17532.561685957953, 17906.28374864991, 18042.0076895772, 16659.0, 17123.828761712222, 17312.740308895125, 17345.856914599648, 17802.46592602224, 10488.0, 10902.345859248471, 10933.343999359746, 10935.83596246041, 10937.179711521827, 13216.074874758675, 13333.266670333225, 13334.733305334034, 13335.86664133385, 13337.0, 10407.115395191493, 10497.16022718196, 10633.627823026158, 10677.14582833356, 10802.135829133807, 10191.694249172537, 10238.078572928473, 10248.692539223835, 10305.587776488857, 10602.522886469973, 8317.500949943023, 8989.0, 9049.140331668481, 9057.320700947323, 9126.863097053416, 6823.624700641512, 6867.381935625817, 6873.621950792754, 6894.103766510321, 6969.745445819801, 6578.868422631616, 6752.729890804313, 6795.150606174228, 6800.897986530305, 7004.754833580801], "value": 6613.066372813655, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [471.09784762180425, 478.2419864514744, 490.76933841094683, 495.91321518734225, 502.1137691973667, 6448.903266451001, 6460.773872914449, 6464.224293084831, 6466.450351720109, 6493.8376540586505, 6052.727627256773, 6129.785457508977, 6211.298085332872, 6283.717232724527, 6307.873842523159, 4960.305557221992, 4962.478939711333, 4997.4003119625695, 5063.139266324715, 5077.771500282496, 4984.3021976923255, 5030.220315851049, 5032.6477146599755, 5042.546170844624, 5043.546080852722, 4743.169945259583, 4909.410870695521, 4917.508249175072, 4978.950210497903, 4992.327570263966, 4973.925391119136, 4983.526564976321, 4984.700917944935, 5003.149464591028, 5015.7492125393765, 4379.1022840317655, 4402.0, 4429.889252768674, 4431.689781715281, 4451.198784218839, 6154.5076393888385, 6161.691915404257, 6185.628862268279, 6191.721372538246, 6238.812835614939, 5675.177099320616, 5681.261436013333, 5715.285414375712, 5762.250907382014, 5798.53562236048, 6491.93508064918, 6581.40034686301, 6588.957423198775, 6616.834579135512, 6659.40065394112, 4801.855944321641, 4805.303231031481, 4805.9094293982225, 4831.4926932671915, 4837.782299796518, 7034.472414568928, 7053.612051337174, 7062.329078737512, 7109.680064397115, 7146.499745017903, 4890.168671325883, 4939.703617782872, 4965.598564982998, 4966.652334336634, 5007.39911210657, 4938.9012219755405, 4981.800727970913, 4990.6257030722845, 4997.700137991732, 4999.700017998932, 5723.399043100415, 5736.4224838169, 5764.279465066867, 5781.624194427349, 5790.594658373873, 6677.399034086859, 6730.394264516217, 6731.124953755981, 6735.25912149673, 6777.152855893013, 1654.1729135432283, 1684.3683618643008, 1694.940677076321, 1703.5570751604423, 1752.4217008387202, 1176.100283283289, 1191.2733232728028, 1193.1051711216587, 1196.7486827766268, 1203.2720204276286, 5753.309602847601, 5763.423657634287, 5818.2436283282905, 5822.359540450547, 5916.940830591639, 6617.966910165418, 6622.761432683086, 6643.368879956396, 6667.6666166691475, 6748.763793267212, 5820.214271073435, 5929.436703513159, 5949.553783466257, 5962.821115366546, 5972.67065844321, 3621.728370372233, 3627.764195327295, 3675.650813172744, 3675.889723308305, 3703.777773333609, 6508.544401891917, 6527.967360163168, 6540.50946179029, 6652.0, 6712.39588437043, 6988.545744526589, 6993.2657071007825, 7022.438204943593, 7075.39859111971, 7081.150261968597, 6554.246261679968, 6604.282886449461, 6630.668466576748, 6639.203295604461, 6707.765228216989, 6760.391564759196, 6783.728650853911, 6865.210500792473, 6873.793786186422, 6984.79045628632, 6790.064831522992, 6811.0, 6906.240313565403, 6918.654067296515, 6926.792196234121, 6694.0, 6696.698648560729, 6740.595564265964, 6741.2921643226755, 6766.15423072116, 4938.135826230324, 4947.183714687037, 4950.45544990058, 4963.106640804545, 4998.875028124326, 6918.411934985583, 6956.860862782817, 6964.860702785814, 6976.4767642426, 6998.930010699828, 6630.933690663224, 6659.667016649245, 6734.898976515454, 6751.426128779109, 6760.695768690519, 6612.900806488003, 6693.732250709917, 6744.22441419243, 6775.0, 6788.86422271542, 6249.093881387083, 6486.318936511787, 6498.610083395011, 6629.171353580802, 6640.236372816995, 6345.318490599902, 6567.671616419065, 6585.769498067544, 6648.836453620694, 6663.300353462817, 6419.807405777834, 6447.677616119082, 6448.709808058555, 6474.125992990886, 6501.772437964649], "value": 5096.071558986916, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [898.2364989758705, 901.5897766516235, 918.7565295196772, 920.5259291464893, 924.1497822003756, 19236.519087022833, 19906.601867962632, 19957.201711931506, 19976.900115499422, 20487.795122048814, 19908.402747917586, 20027.198912043536, 20114.49713757157, 20160.59678806423, 20341.389758307236, 961.5240455974302, 970.0978090375947, 971.8785151856018, 972.2367941166186, 973.9853902191472, 944.0135058863469, 993.64228877604, 996.8604395384616, 998.3061772068415, 1000.164862339947, 1045.142982754142, 1051.0645525482328, 1059.973500662482, 1061.3843970497094, 1063.2291588598243, 1051.952662130202, 1052.0426411965113, 1053.4311471805215, 1053.984190237147, 1054.6150655010904, 775.464929198851, 775.7013549783318, 775.8991331126917, 776.646625785272, 776.7980325115454, 19945.401637950883, 19962.90018549898, 20092.79907200924, 20113.29603463872, 20374.592508149755, 20781.16875324985, 20956.476088097766, 21088.156473741034, 21185.364439066852, 21214.0, 16925.3229870805, 17049.40327088546, 17170.48488545333, 17184.656306873916, 17295.74056389155, 3625.23869987303, 3638.7998660073686, 3640.3993341098685, 3641.7086633069557, 3642.417213245868, 127175.0, 127466.0, 128142.0, 129605.99999999905, 130715.0, 16119.67760644804, 16137.838621613868, 16141.757873631905, 16528.504144875675, 16532.173391330387, 30097.548536771967, 30183.396332073673, 30231.848840755654, 30589.847050764605, 31133.0, 7960.641771120314, 8384.538850363166, 8680.30557555394, 8698.95650521756, 8759.562021898879, 4363.345498175236, 4529.8641040768825, 4535.977320113444, 4540.296254080623, 4553.2942393929, 5210.713410762369, 5302.840914772563, 5325.653832500875, 5713.0, 5887.735051922674, 1112.7663190729875, 1142.7485953090306, 1153.9653810385703, 1154.1978325064, 1156.4217891054473, 691.5435812363816, 710.2861624067779, 717.9676914538858, 721.1598487761804, 731.2139450091067, 11598.826017609743, 12524.937375313066, 12582.685432864251, 12649.810252846215, 13085.738285234242, 682.0621645237798, 688.8071340024801, 690.1097584116534, 694.6630884021347, 695.58265040975, 5846.064629659234, 5949.732262048132, 5969.313528944227, 6002.939970600238, 6058.182145410313, 108571.45714271536, 109722.45138774415, 109885.0, 110164.0, 111070.0, 92616.53691731498, 95991.0, 98155.50922245343, 100720.0, 102091.48954255182, 81324.0, 82643.58678206691, 83224.0, 84267.15732842716, 86473.5676321627, 11529.827052594386, 12821.294828784507, 12938.417771200508, 13142.60572182836, 13334.799978000532, 38009.87489474943, 40054.998625034605, 40317.193656126125, 40358.1928361437, 40876.0, 70349.0, 71750.64124679343, 72145.0, 72289.0, 74264.25735742574, 16373.672526549164, 16642.417515387388, 16679.0, 17798.822011779717, 18041.909790450965, 20432.387028389174, 20916.686249705963, 21168.894155529124, 21287.148514059263, 21312.36062918115, 18722.0, 19294.035298235314, 19562.315318963767, 19603.215871364984, 19689.704654430483, 18393.72409413833, 18443.0, 18756.906215468836, 18945.810541894403, 18975.90512047431, 17988.55028624295, 18773.812261877207, 19438.4168474946, 19492.31776887802, 19513.414597562096, 13405.128666636638, 13464.596062118153, 13798.17210967345, 13940.721185576434, 13943.860561394256, 14147.575572732423, 14408.783868242195, 14529.34617942174, 14690.485832995793, 15034.473793417177], "value": 9302.11550269174, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1488.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 109.0, 100.0, 107.0, 107.0, 110.0, 108.0, 99.0, 109.0, 110.0], "value": 105.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 121.0, 149.0, 179.0, 118.0, 152.0, 141.0, 117.0, 176.0, 121.0], "value": 142.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 163.0, 164.0, 163.0, 163.0, 162.0, 164.0, 166.0, 168.0, 163.0], "value": 164.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 74.0, 74.0, 174.0, 75.0, 76.0, 74.0, 73.0, 149.0, 75.0], "value": 92.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 78.0, 134.0, 80.0, 79.0, 79.0, 125.0, 78.0, 80.0, 130.0], "value": 94.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 114.0, 115.0, 114.0, 115.0, 114.0, 114.0, 116.0, 114.0, 114.0], "value": 114.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 149.0, 148.0, 148.0, 149.0, 148.0, 148.0, 149.0, 148.0], "value": 148.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 94.0, 94.0, 94.0, 95.0, 94.0, 94.0, 95.0, 93.0], "value": 94.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 52.0, 52.0, 52.0, 52.0, 52.0, 53.0, 53.0, 52.0, 52.0], "value": 52.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [39.0, 38.0, 37.0, 37.0, 44.0, 38.0, 37.0, 38.0, 39.0, 39.0], "value": 38.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 80.0, 78.0, 76.0, 80.0, 77.0, 79.0, 75.0, 73.0], "value": 76.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 133.0, 120.0, 119.0, 119.0, 125.0, 124.0, 123.0, 123.0, 154.0], "value": 127.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 171.0, 167.0, 175.0, 168.0, 172.0, 171.0, 169.0, 170.0, 171.0], "value": 171.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 65.0, 66.0, 64.0, 67.0, 78.0, 65.0, 67.0, 67.0], "value": 66.7, "unit": "score"}]}]} 13:13:38 INFO - Return code: 0 13:13:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:13:38 INFO - # TBPL SUCCESS # 13:13:38 INFO - Running post-action listener: _package_coverage_data 13:13:38 INFO - Running post-action listener: _resource_record_post_action 13:13:38 INFO - [mozharness: 2017-08-29 20:13:38.900808Z] Finished run-tests step (success) 13:13:38 INFO - Running post-run listener: _resource_record_post_run 13:13:38 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 16384; Write bytes: 399998976; Read time: 196; Write time: 256624 13:13:38 INFO - TinderboxPrint: CPU usage
13.1% 13:13:38 INFO - TinderboxPrint: I/O read bytes / time
16,384 / 196 13:13:38 INFO - TinderboxPrint: I/O write bytes / time
399,998,976 / 256,624 13:13:38 INFO - TinderboxPrint: CPU idle
8,236.5 (86.9%) 13:13:38 INFO - TinderboxPrint: CPU user
1,193.1 (12.6%) 13:13:38 INFO - TinderboxPrint: Swap in / out
0 / 0 13:13:38 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 4096; Write bytes: 114757632; Read time: 32; Write time: 116592 13:13:38 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 13:13:38 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 12288; Write bytes: 285241344; Read time: 164; Write time: 140032 13:13:39 INFO - Running post-run listener: _upload_blobber_files 13:13:39 INFO - Blob upload gear active. 13:13:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:13:39 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 13:13:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 13:13:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 13:13:39 INFO - (blobuploader) - INFO - Open directory for files ... 13:13:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 13:13:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:13:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:13:41 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 13:13:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:13:41 INFO - (blobuploader) - INFO - Done attempting. 13:13:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:13:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:13:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:13:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:13:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:13:44 INFO - (blobuploader) - INFO - Done attempting. 13:13:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 13:13:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:13:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:13:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 13:13:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:13:45 INFO - (blobuploader) - INFO - Done attempting. 13:13:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 13:13:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:13:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:13:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 13:13:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:13:46 INFO - (blobuploader) - INFO - Done attempting. 13:13:46 INFO - (blobuploader) - INFO - Iteration through files over. 13:13:46 INFO - Return code: 0 13:13:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:13:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:13:46 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7a90739a1cd3d0aa689558c4bf58f939b3c81b7a891c595a1ec7d2a00a067e287ce83313d01bfbdbbefbc1d1a57ba5d1c789d5f3274eb55d45a7364d8013c189", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/de0f6ad718c4b6d606fae85a41506234c600aa43cb11bf4864db8020d185a321064c4f5a451987c903d2aa4f19c6204350c9d207a35394ba59609a103b12e7fb", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4da4af48c3b924339f9f92c380952d8f9f7fbb73780ec123c1cf574c47238eb1141c53dc839468230ba3a5997568de7d8c7ffd7f474596cf294e87cab810795f", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b31f80f645660f71d88daf8c36659d184795af57ef588e417fcab091b3049797e8851dbd3522a9eb510c91b23a385134efa8250f7cfcbf711a096d8321b808a6"} 13:13:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:13:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:13:46 INFO - Contents: 13:13:46 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7a90739a1cd3d0aa689558c4bf58f939b3c81b7a891c595a1ec7d2a00a067e287ce83313d01bfbdbbefbc1d1a57ba5d1c789d5f3274eb55d45a7364d8013c189", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/de0f6ad718c4b6d606fae85a41506234c600aa43cb11bf4864db8020d185a321064c4f5a451987c903d2aa4f19c6204350c9d207a35394ba59609a103b12e7fb", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4da4af48c3b924339f9f92c380952d8f9f7fbb73780ec123c1cf574c47238eb1141c53dc839468230ba3a5997568de7d8c7ffd7f474596cf294e87cab810795f", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b31f80f645660f71d88daf8c36659d184795af57ef588e417fcab091b3049797e8851dbd3522a9eb510c91b23a385134efa8250f7cfcbf711a096d8321b808a6"} 13:13:46 INFO - Running post-run listener: copy_logs_to_upload_dir 13:13:46 INFO - Copying logs to upload dir... 13:13:46 INFO - mkdir: /builds/slave/test/build/upload/logs 13:13:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1247.322496 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2017-08-29 13:13:46.198143) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-29 13:13:46.201192) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1504036376.908797-1903792368 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7a90739a1cd3d0aa689558c4bf58f939b3c81b7a891c595a1ec7d2a00a067e287ce83313d01bfbdbbefbc1d1a57ba5d1c789d5f3274eb55d45a7364d8013c189", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/de0f6ad718c4b6d606fae85a41506234c600aa43cb11bf4864db8020d185a321064c4f5a451987c903d2aa4f19c6204350c9d207a35394ba59609a103b12e7fb", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4da4af48c3b924339f9f92c380952d8f9f7fbb73780ec123c1cf574c47238eb1141c53dc839468230ba3a5997568de7d8c7ffd7f474596cf294e87cab810795f", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b31f80f645660f71d88daf8c36659d184795af57ef588e417fcab091b3049797e8851dbd3522a9eb510c91b23a385134efa8250f7cfcbf711a096d8321b808a6"} build_url:https://queue.taskcluster.net/v1/task/I8xynoCOTwiPbft2MqBxhQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011242 build_url: 'https://queue.taskcluster.net/v1/task/I8xynoCOTwiPbft2MqBxhQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7a90739a1cd3d0aa689558c4bf58f939b3c81b7a891c595a1ec7d2a00a067e287ce83313d01bfbdbbefbc1d1a57ba5d1c789d5f3274eb55d45a7364d8013c189", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/de0f6ad718c4b6d606fae85a41506234c600aa43cb11bf4864db8020d185a321064c4f5a451987c903d2aa4f19c6204350c9d207a35394ba59609a103b12e7fb", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4da4af48c3b924339f9f92c380952d8f9f7fbb73780ec123c1cf574c47238eb1141c53dc839468230ba3a5997568de7d8c7ffd7f474596cf294e87cab810795f", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b31f80f645660f71d88daf8c36659d184795af57ef588e417fcab091b3049797e8851dbd3522a9eb510c91b23a385134efa8250f7cfcbf711a096d8321b808a6"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-29 13:13:46.227911) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-29 13:13:46.228228) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1504036376.908797-1903792368 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004498 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-29 13:13:46.313715) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-29 13:13:46.315331) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-29 13:13:46.315618) ========= ========= Total master_lag: 0.19 =========