builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-087 starttime: 1504306216.7 results: success (0) revision: 73e8f351b28ffc30e8077be21ce7298f4648ca5a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-01 15:50:16.702134) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-01 15:50:16.702606) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-01 15:50:16.702901) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-01 15:50:16.716183) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 15:50:16.716482) ========= 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=ca619d3d7d62c99d832f7b8a000001c4-1504306215.232531-1880746267 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-01 15:50:16-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.4M=0s 2017-09-01 15:50:16 (46.4 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.080562 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 15:50:16.815944) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 15:50:16.816281) ========= 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=ca619d3d7d62c99d832f7b8a000001c4-1504306215.232531-1880746267 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016244 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 15:50:16.866528) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 15:50:16.866880) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 73e8f351b28ffc30e8077be21ce7298f4648ca5a --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 73e8f351b28ffc30e8077be21ce7298f4648ca5a --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=ca619d3d7d62c99d832f7b8a000001c4-1504306215.232531-1880746267 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-01 15:50:16,919 truncating revision to first 12 chars 2017-09-01 15:50:16,919 Setting DEBUG logging. 2017-09-01 15:50:16,919 attempt 1/10 2017-09-01 15:50:16,919 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/73e8f351b28f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-01 15:50:17,355 unpacking tar archive at: mozilla-central-73e8f351b28f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.827229 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 15:50:17.712480) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-01 15:50:17.712865) ========= script_repo_revision: 73e8f351b28ffc30e8077be21ce7298f4648ca5a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-01 15:50:17.713254) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-01 15:50:17.713528) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-01 15:50:17.728315) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2017-09-01 15:50:17.728668) ========= /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=ca619d3d7d62c99d832f7b8a000001c4-1504306215.232531-1880746267 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:50:17 INFO - MultiFileLogger online at 20170901 15:50:17 in /builds/slave/test 15:50:17 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 15:50:17 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:50:17 INFO - {'append_to_log': False, 15:50:17 INFO - 'base_work_dir': '/builds/slave/test', 15:50:17 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 15:50:17 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:50:17 INFO - 'branch': 'Firefox-Non-PGO', 15:50:17 INFO - 'buildbot_json_path': 'buildprops.json', 15:50:17 INFO - 'code_coverage': False, 15:50:17 INFO - 'config_files': ('talos/linux_config.py',), 15:50:17 INFO - 'default_actions': ('clobber', 15:50:17 INFO - 'read-buildbot-config', 15:50:17 INFO - 'download-and-extract', 15:50:17 INFO - 'populate-webroot', 15:50:17 INFO - 'create-virtualenv', 15:50:17 INFO - 'install', 15:50:17 INFO - 'setup-mitmproxy', 15:50:17 INFO - 'run-tests'), 15:50:17 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:50:17 INFO - 'disable_ccov_upload': False, 15:50:17 INFO - 'disable_stylo': False, 15:50:17 INFO - 'download_minidump_stackwalk': True, 15:50:17 INFO - 'download_symbols': 'ondemand', 15:50:17 INFO - 'enable_stylo': False, 15:50:17 INFO - 'enable_webrender': False, 15:50:17 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 15:50:17 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:50:17 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:50:17 INFO - 'gecko_profile': False, 15:50:17 INFO - 'gecko_profile_interval': 0, 15:50:17 INFO - 'installer_path': 'installer.exe', 15:50:17 INFO - 'log_level': 'info', 15:50:17 INFO - 'log_name': 'talos', 15:50:17 INFO - 'log_to_console': True, 15:50:17 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:50:17 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:50:17 INFO - 'opt_config_files': (), 15:50:17 INFO - 'pip_index': False, 15:50:17 INFO - 'suite': 'dromaeojs-e10s', 15:50:17 INFO - 'system_bits': '32', 15:50:17 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:50:17 INFO - 'title': 'talos-linux64-ix-087', 15:50:17 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:50:17 INFO - 'use_talos_json': True, 15:50:17 INFO - 'verify': 'False', 15:50:17 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:50:17 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:50:17 INFO - 'work_dir': 'build'} 15:50:17 INFO - [mozharness: 2017-09-01 22:50:17.924054Z] Running clobber step. 15:50:17 INFO - Running pre-action listener: _resource_record_pre_action 15:50:17 INFO - Running main action method: clobber 15:50:17 INFO - rmtree: /builds/slave/test/build 15:50:17 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:50:19 INFO - Running post-action listener: _resource_record_post_action 15:50:19 INFO - [mozharness: 2017-09-01 22:50:19.508993Z] Finished clobber step (success) 15:50:19 INFO - [mozharness: 2017-09-01 22:50:19.509091Z] Running read-buildbot-config step. 15:50:19 INFO - Running pre-action listener: _resource_record_pre_action 15:50:19 INFO - Running main action method: read_buildbot_config 15:50:19 INFO - Using buildbot properties: 15:50:19 INFO - { 15:50:19 INFO - "project": "", 15:50:19 INFO - "product": "firefox", 15:50:19 INFO - "who": "kwierso@gmail.com", 15:50:19 INFO - "installer_path": "public/build/target.tar.bz2", 15:50:19 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 15:50:19 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 15:50:19 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 15:50:19 INFO - "stage_platform": "linux64", 15:50:19 INFO - "basedir": "/builds/slave/test", 15:50:19 INFO - "buildnumber": 1109, 15:50:19 INFO - "platform": "ubuntu64_hw", 15:50:19 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 15:50:19 INFO - "slavebuilddir": "test", 15:50:19 INFO - "taskId": "QevYWLNwTaCe2ybms_8XbQ", 15:50:19 INFO - "branch": "mozilla-central", 15:50:19 INFO - "script_repo_revision": "production", 15:50:19 INFO - "revision": "73e8f351b28ffc30e8077be21ce7298f4648ca5a", 15:50:19 INFO - "slavename": "talos-linux64-ix-087", 15:50:19 INFO - "repo_path": "mozilla-central" 15:50:19 INFO - } 15:50:19 INFO - Finding installer, test and symbols from parent task. 15:50:19 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QevYWLNwTaCe2ybms_8XbQ'}, attempt #1 15:50:19 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QevYWLNwTaCe2ybms_8XbQ'}, attempt #1 15:50:20 INFO - Task dependencies: B3zBBhhZS1Sz7X0NYgNFnw 15:50:20 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/B3zBBhhZS1Sz7X0NYgNFnw'}, attempt #1 15:50:20 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/B3zBBhhZS1Sz7X0NYgNFnw/artifacts/public/build/target.tar.bz2 15:50:20 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/B3zBBhhZS1Sz7X0NYgNFnw/artifacts/public/build/target.test_packages.json 15:50:20 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/B3zBBhhZS1Sz7X0NYgNFnw/artifacts/public/build/target.crashreporter-symbols.zip 15:50:20 INFO - Running post-action listener: _resource_record_post_action 15:50:20 INFO - [mozharness: 2017-09-01 22:50:20.923847Z] Finished read-buildbot-config step (success) 15:50:20 INFO - [mozharness: 2017-09-01 22:50:20.924064Z] Running download-and-extract step. 15:50:20 INFO - Running pre-action listener: _resource_record_pre_action 15:50:20 INFO - Running main action method: download_and_extract 15:50:20 INFO - mkdir: /builds/slave/test/build/tests 15:50:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:50:20 INFO - trying https://queue.taskcluster.net/v1/task/B3zBBhhZS1Sz7X0NYgNFnw/artifacts/public/build/target.test_packages.json 15:50:20 INFO - Downloading https://queue.taskcluster.net/v1/task/B3zBBhhZS1Sz7X0NYgNFnw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 15:50:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/B3zBBhhZS1Sz7X0NYgNFnw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 15:50:21 INFO - Downloaded 989 bytes. 15:50:21 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 15:50:21 INFO - Using the following test package requirements: 15:50:21 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 15:50:21 INFO - u'common': [u'target.common.tests.zip'], 15:50:21 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 15:50:21 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 15:50:21 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 15:50:21 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 15:50:21 INFO - u'mozbase': [u'target.common.tests.zip'], 15:50:21 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 15:50:21 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 15:50:21 INFO - u'web-platform': [u'target.common.tests.zip', 15:50:21 INFO - u'target.web-platform.tests.tar.gz'], 15:50:21 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 15:50:21 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 15:50:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/B3zBBhhZS1Sz7X0NYgNFnw/artifacts/public/build/target.common.tests.zip 15:50:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/B3zBBhhZS1Sz7X0NYgNFnw/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:50:21 INFO - Fetch https://queue.taskcluster.net/v1/task/B3zBBhhZS1Sz7X0NYgNFnw/artifacts/public/build/target.common.tests.zip into memory 15:50:22 INFO - Content-Length response header: 54957611 15:50:22 INFO - Bytes received: 54957611 15:50:33 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 15:50:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/B3zBBhhZS1Sz7X0NYgNFnw/artifacts/public/build/target.common.tests.zip 15:50:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/B3zBBhhZS1Sz7X0NYgNFnw/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:50:33 INFO - Fetch https://queue.taskcluster.net/v1/task/B3zBBhhZS1Sz7X0NYgNFnw/artifacts/public/build/target.common.tests.zip into memory 15:50:34 INFO - Content-Length response header: 54957611 15:50:34 INFO - Bytes received: 54957611 15:50:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/B3zBBhhZS1Sz7X0NYgNFnw/artifacts/public/build/target.talos.tests.zip 15:50:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/B3zBBhhZS1Sz7X0NYgNFnw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 15:50:47 INFO - Fetch https://queue.taskcluster.net/v1/task/B3zBBhhZS1Sz7X0NYgNFnw/artifacts/public/build/target.talos.tests.zip into memory 15:50:48 INFO - Content-Length response header: 14496650 15:50:48 INFO - Bytes received: 14496650 15:50:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:50:48 INFO - trying https://queue.taskcluster.net/v1/task/B3zBBhhZS1Sz7X0NYgNFnw/artifacts/public/build/target.tar.bz2 15:50:48 INFO - Downloading https://queue.taskcluster.net/v1/task/B3zBBhhZS1Sz7X0NYgNFnw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 15:50:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/B3zBBhhZS1Sz7X0NYgNFnw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 15:50:50 INFO - Downloaded 62528630 bytes. 15:50:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/B3zBBhhZS1Sz7X0NYgNFnw/artifacts/public/build/target.tar.bz2 15:50:50 INFO - mkdir: /builds/slave/test/properties 15:50:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:50:50 INFO - Writing to file /builds/slave/test/properties/build_url 15:50:50 INFO - Contents: 15:50:50 INFO - build_url:https://queue.taskcluster.net/v1/task/B3zBBhhZS1Sz7X0NYgNFnw/artifacts/public/build/target.tar.bz2 15:50:50 INFO - Running post-action listener: _resource_record_post_action 15:50:50 INFO - Running post-action listener: find_tests_for_verification 15:50:50 INFO - Running post-action listener: set_extra_try_arguments 15:50:50 INFO - [mozharness: 2017-09-01 22:50:50.074583Z] Finished download-and-extract step (success) 15:50:50 INFO - [mozharness: 2017-09-01 22:50:50.074681Z] Running populate-webroot step. 15:50:50 INFO - Running pre-action listener: _resource_record_pre_action 15:50:50 INFO - Running main action method: populate_webroot 15:50:50 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:50:50 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:50:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:50:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:50:50 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:50:50 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:50:50 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:50:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:50:50 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:50:50 INFO - 'talos_options': ['--enable-stylo'], 15:50:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:50:50 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:50:50 INFO - 'tests': ['damp', 'tps']}, 15:50:50 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:50:50 INFO - 'talos_options': ['--enable-stylo'], 15:50:50 INFO - 'tests': ['damp', 'tps']}, 15:50:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:50:50 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 15:50:50 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:50:50 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 15:50:50 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 15:50:50 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 15:50:50 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:50:50 INFO - 'talos_options': ['--enable-stylo'], 15:50:50 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 15:50:50 INFO - 'other-e10s': {'tests': ['a11yr', 15:50:50 INFO - 'ts_paint', 15:50:50 INFO - 'tpaint', 15:50:50 INFO - 'sessionrestore', 15:50:50 INFO - 'sessionrestore_many_windows', 15:50:50 INFO - 'sessionrestore_no_auto_restore', 15:50:50 INFO - 'tabpaint']}, 15:50:50 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:50:50 INFO - 'tests': ['a11yr', 15:50:50 INFO - 'ts_paint', 15:50:50 INFO - 'tpaint', 15:50:50 INFO - 'sessionrestore', 15:50:50 INFO - 'sessionrestore_many_windows', 15:50:50 INFO - 'sessionrestore_no_auto_restore', 15:50:50 INFO - 'tabpaint']}, 15:50:50 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 15:50:50 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 15:50:50 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:50:50 INFO - 'tests': ['bloom_basic_singleton']}, 15:50:50 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:50:50 INFO - 'tests': ['bloom_basic']}, 15:50:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:50:50 INFO - 'tsvgr_opacity', 15:50:50 INFO - 'tart', 15:50:50 INFO - 'tscrollx', 15:50:50 INFO - 'tsvg_static']}, 15:50:50 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:50:50 INFO - 'tests': ['tsvgx', 15:50:50 INFO - 'tsvgr_opacity', 15:50:50 INFO - 'tart', 15:50:50 INFO - 'tscrollx', 15:50:50 INFO - 'tsvg_static']}, 15:50:50 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:50:50 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:50:50 INFO - 'talos_options': ['--enable-stylo'], 15:50:50 INFO - 'tests': ['tp5o']}, 15:50:50 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:50:50 INFO - 'talos_options': ['--mitmproxy', 15:50:50 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:50:50 INFO - '--firstNonBlankPaint'], 15:50:50 INFO - 'tests': ['tp6_google', 15:50:50 INFO - 'tp6_youtube', 15:50:50 INFO - 'tp6_amazon', 15:50:50 INFO - 'tp6_facebook']}, 15:50:50 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:50:50 INFO - 'talos_options': ['--enable-stylo', 15:50:50 INFO - '--mitmproxy', 15:50:50 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:50:50 INFO - '--firstNonBlankPaint'], 15:50:50 INFO - 'tests': ['tp6_google', 15:50:50 INFO - 'tp6_youtube', 15:50:50 INFO - 'tp6_amazon', 15:50:50 INFO - 'tp6_facebook']}, 15:50:50 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:50:50 INFO - 'talos_options': ['--enable-stylo', 15:50:50 INFO - '--stylo-threads=1', 15:50:50 INFO - '--mitmproxy', 15:50:50 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:50:50 INFO - '--firstNonBlankPaint'], 15:50:50 INFO - 'tests': ['tp6_google', 15:50:50 INFO - 'tp6_youtube', 15:50:50 INFO - 'tp6_amazon', 15:50:50 INFO - 'tp6_facebook']}, 15:50:50 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:50:50 INFO - 'talos_options': ['--xperf_path', 15:50:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:50:50 INFO - 'tests': ['tp5n']}, 15:50:50 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:50:50 INFO - 'talos_options': ['--enable-stylo', 15:50:50 INFO - '--xperf_path', 15:50:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:50:50 INFO - 'tests': ['tp5n']}}} 15:50:50 INFO - Running post-action listener: _resource_record_post_action 15:50:50 INFO - [mozharness: 2017-09-01 22:50:50.088983Z] Finished populate-webroot step (success) 15:50:50 INFO - [mozharness: 2017-09-01 22:50:50.089072Z] Running create-virtualenv step. 15:50:50 INFO - Running pre-action listener: _resource_record_pre_action 15:50:50 INFO - Running main action method: create_virtualenv 15:50:50 INFO - Creating virtualenv /builds/slave/test/build/venv 15:50:50 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:50:50 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 15:50:50 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 15:50:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:50:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:50:50 INFO - Installing distribute.............................................................................................................................................................................................done. 15:50:50 INFO - Installing pip...............done. 15:50:51 INFO - Return code: 0 15:50:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:50:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:50:51 INFO - Reading from file tmpfile_stdout 15:50:51 INFO - Output received: 15:50:51 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 15:50:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:50:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:50:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:50:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d9a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f63a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2106a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x210a090>, '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 0x210ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2104f90>, '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': 'ca619d3d7d62c99d832f7b8a000001c4-1504306215.232531-1880746267', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:50:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:50:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:50:51 INFO - Using env: {'DISPLAY': ':0', 15:50:51 INFO - 'HOME': '/home/cltbld', 15:50:51 INFO - 'LANG': 'en_US.UTF-8', 15:50:51 INFO - 'LANGUAGE': 'en_US:en', 15:50:51 INFO - 'LOGNAME': 'cltbld', 15:50:51 INFO - 'MAIL': '/var/mail/cltbld', 15:50:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:51 INFO - 'MOZ_NO_REMOTE': '1', 15:50:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:50:51 INFO - 'NO_EM_RESTART': '1', 15:50:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:50:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:51 INFO - 'PWD': '/builds/slave/test', 15:50:51 INFO - 'SHELL': '/bin/bash', 15:50:51 INFO - 'SHLVL': '1', 15:50:51 INFO - 'TERM': 'linux', 15:50:51 INFO - 'TMOUT': '86400', 15:50:51 INFO - 'USER': 'cltbld', 15:50:51 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1504306215.232531-1880746267', 15:50:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:51 INFO - '_': '/tools/buildbot/bin/python'} 15:50:52 INFO - Ignoring indexes: http://pypi.python.org/simple/ 15:50:52 INFO - Downloading/unpacking pip>=1.5 15:50:52 INFO - Running setup.py egg_info for package pip 15:50:52 INFO - warning: no files found matching 'pip/cacert.pem' 15:50:52 INFO - warning: no files found matching '*.html' under directory 'docs' 15:50:52 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:50:52 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:50:52 INFO - Installing collected packages: pip 15:50:52 INFO - Found existing installation: pip 1.1 15:50:52 INFO - Uninstalling pip: 15:50:52 INFO - Successfully uninstalled pip 15:50:52 INFO - Running setup.py install for pip 15:50:52 INFO - warning: no files found matching 'pip/cacert.pem' 15:50:52 INFO - warning: no files found matching '*.html' under directory 'docs' 15:50:52 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:50:52 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:50:52 INFO - Installing pip script to /builds/slave/test/build/venv/bin 15:50:52 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 15:50:52 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 15:50:52 INFO - Successfully installed pip 15:50:52 INFO - Cleaning up... 15:50:52 INFO - Return code: 0 15:50:52 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:50:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:50:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:50:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1d9a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f63a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2106a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x210a090>, '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 0x210ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2104f90>, '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': 'ca619d3d7d62c99d832f7b8a000001c4-1504306215.232531-1880746267', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:50:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:50:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:50:52 INFO - Using env: {'DISPLAY': ':0', 15:50:52 INFO - 'HOME': '/home/cltbld', 15:50:52 INFO - 'LANG': 'en_US.UTF-8', 15:50:52 INFO - 'LANGUAGE': 'en_US:en', 15:50:52 INFO - 'LOGNAME': 'cltbld', 15:50:52 INFO - 'MAIL': '/var/mail/cltbld', 15:50:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:52 INFO - 'MOZ_NO_REMOTE': '1', 15:50:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:50:52 INFO - 'NO_EM_RESTART': '1', 15:50:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:50:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:52 INFO - 'PWD': '/builds/slave/test', 15:50:52 INFO - 'SHELL': '/bin/bash', 15:50:52 INFO - 'SHLVL': '1', 15:50:52 INFO - 'TERM': 'linux', 15:50:52 INFO - 'TMOUT': '86400', 15:50:52 INFO - 'USER': 'cltbld', 15:50:52 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1504306215.232531-1880746267', 15:50:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:52 INFO - '_': '/tools/buildbot/bin/python'} 15:50:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:50:52 INFO - Downloading/unpacking psutil>=3.1.1 15:50:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:50:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:50:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:50:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:50:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:50:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:50:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:50:53 INFO - Installing collected packages: psutil 15:50:53 INFO - Running setup.py install for psutil 15:50:53 INFO - building 'psutil._psutil_linux' extension 15:50:53 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 15:50:53 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 15:50:53 INFO - building 'psutil._psutil_posix' extension 15:50:53 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 15:50:54 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 15:50:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:50:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:50:54 INFO - Successfully installed psutil 15:50:54 INFO - Cleaning up... 15:50:54 INFO - Return code: 0 15:50:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:50:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:50:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:50:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d9a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f63a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2106a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x210a090>, '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 0x210ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2104f90>, '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': 'ca619d3d7d62c99d832f7b8a000001c4-1504306215.232531-1880746267', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:50:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:50:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:50:54 INFO - Using env: {'DISPLAY': ':0', 15:50:54 INFO - 'HOME': '/home/cltbld', 15:50:54 INFO - 'LANG': 'en_US.UTF-8', 15:50:54 INFO - 'LANGUAGE': 'en_US:en', 15:50:54 INFO - 'LOGNAME': 'cltbld', 15:50:54 INFO - 'MAIL': '/var/mail/cltbld', 15:50:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:54 INFO - 'MOZ_NO_REMOTE': '1', 15:50:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:50:54 INFO - 'NO_EM_RESTART': '1', 15:50:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:50:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:54 INFO - 'PWD': '/builds/slave/test', 15:50:54 INFO - 'SHELL': '/bin/bash', 15:50:54 INFO - 'SHLVL': '1', 15:50:54 INFO - 'TERM': 'linux', 15:50:54 INFO - 'TMOUT': '86400', 15:50:54 INFO - 'USER': 'cltbld', 15:50:54 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1504306215.232531-1880746267', 15:50:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:54 INFO - '_': '/tools/buildbot/bin/python'} 15:50:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:50:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:50:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:50:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:50:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:50:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:50:55 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:50:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:50:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:50:55 INFO - Installing collected packages: mozsystemmonitor 15:50:55 INFO - Running setup.py install for mozsystemmonitor 15:50:55 INFO - Successfully installed mozsystemmonitor 15:50:55 INFO - Cleaning up... 15:50:55 INFO - Return code: 0 15:50:55 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:50:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:50:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:50:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1d9a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f63a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2106a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x210a090>, '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 0x210ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2104f90>, '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': 'ca619d3d7d62c99d832f7b8a000001c4-1504306215.232531-1880746267', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:50:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:50:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:50:55 INFO - Using env: {'DISPLAY': ':0', 15:50:55 INFO - 'HOME': '/home/cltbld', 15:50:55 INFO - 'LANG': 'en_US.UTF-8', 15:50:55 INFO - 'LANGUAGE': 'en_US:en', 15:50:55 INFO - 'LOGNAME': 'cltbld', 15:50:55 INFO - 'MAIL': '/var/mail/cltbld', 15:50:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:55 INFO - 'MOZ_NO_REMOTE': '1', 15:50:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:50:55 INFO - 'NO_EM_RESTART': '1', 15:50:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:50:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:55 INFO - 'PWD': '/builds/slave/test', 15:50:55 INFO - 'SHELL': '/bin/bash', 15:50:55 INFO - 'SHLVL': '1', 15:50:55 INFO - 'TERM': 'linux', 15:50:55 INFO - 'TMOUT': '86400', 15:50:55 INFO - 'USER': 'cltbld', 15:50:55 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1504306215.232531-1880746267', 15:50:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:55 INFO - '_': '/tools/buildbot/bin/python'} 15:50:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:50:55 INFO - Downloading/unpacking jsonschema==2.5.1 15:50:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:50:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:50:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:50:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:50:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:50:57 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:50:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:50:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:50:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:50:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:50:57 INFO - Downloading functools32-3.2.3-2.tar.gz 15:50:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:50:57 INFO - warning: no files found matching '*.txt' 15:50:57 INFO - no previously-included directories found matching 'build' 15:50:57 INFO - no previously-included directories found matching 'dist' 15:50:57 INFO - no previously-included directories found matching '.git*' 15:50:57 INFO - Installing collected packages: jsonschema, functools32 15:50:57 INFO - Running setup.py install for functools32 15:50:57 INFO - warning: no files found matching '*.txt' 15:50:57 INFO - no previously-included directories found matching 'build' 15:50:57 INFO - no previously-included directories found matching 'dist' 15:50:57 INFO - no previously-included directories found matching '.git*' 15:50:57 INFO - Successfully installed jsonschema functools32 15:50:57 INFO - Cleaning up... 15:50:57 INFO - Return code: 0 15:50:57 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:50:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:50:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:50:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1d9a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f63a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2106a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x210a090>, '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 0x210ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2104f90>, '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': 'ca619d3d7d62c99d832f7b8a000001c4-1504306215.232531-1880746267', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:50:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 15:50:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 15:50:57 INFO - Using env: {'DISPLAY': ':0', 15:50:57 INFO - 'HOME': '/home/cltbld', 15:50:57 INFO - 'LANG': 'en_US.UTF-8', 15:50:57 INFO - 'LANGUAGE': 'en_US:en', 15:50:57 INFO - 'LOGNAME': 'cltbld', 15:50:57 INFO - 'MAIL': '/var/mail/cltbld', 15:50:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:57 INFO - 'MOZ_NO_REMOTE': '1', 15:50:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:50:57 INFO - 'NO_EM_RESTART': '1', 15:50:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:50:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:57 INFO - 'PWD': '/builds/slave/test', 15:50:57 INFO - 'SHELL': '/bin/bash', 15:50:57 INFO - 'SHLVL': '1', 15:50:57 INFO - 'TERM': 'linux', 15:50:57 INFO - 'TMOUT': '86400', 15:50:57 INFO - 'USER': 'cltbld', 15:50:57 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1504306215.232531-1880746267', 15:50:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:57 INFO - '_': '/tools/buildbot/bin/python'} 15:50:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:50:57 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:50:57 INFO - Cleaning up... 15:50:57 INFO - Return code: 0 15:50:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:50:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:50:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:50:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1d9a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f63a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2106a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x210a090>, '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 0x210ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2104f90>, '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': 'ca619d3d7d62c99d832f7b8a000001c4-1504306215.232531-1880746267', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:50:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:50:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:50:57 INFO - Using env: {'DISPLAY': ':0', 15:50:57 INFO - 'HOME': '/home/cltbld', 15:50:57 INFO - 'LANG': 'en_US.UTF-8', 15:50:57 INFO - 'LANGUAGE': 'en_US:en', 15:50:57 INFO - 'LOGNAME': 'cltbld', 15:50:57 INFO - 'MAIL': '/var/mail/cltbld', 15:50:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:57 INFO - 'MOZ_NO_REMOTE': '1', 15:50:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:50:57 INFO - 'NO_EM_RESTART': '1', 15:50:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:50:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:57 INFO - 'PWD': '/builds/slave/test', 15:50:57 INFO - 'SHELL': '/bin/bash', 15:50:57 INFO - 'SHLVL': '1', 15:50:57 INFO - 'TERM': 'linux', 15:50:57 INFO - 'TMOUT': '86400', 15:50:57 INFO - 'USER': 'cltbld', 15:50:57 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1504306215.232531-1880746267', 15:50:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:57 INFO - '_': '/tools/buildbot/bin/python'} 15:50:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:50:57 INFO - Downloading/unpacking blobuploader==1.2.4 15:50:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:50:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:50:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:50:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:50:58 INFO - Downloading blobuploader-1.2.4.tar.gz 15:50:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:50:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:50:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:50:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:50:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:50:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:50:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:50:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:50:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:50:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:50:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:50:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:50:59 INFO - Downloading docopt-0.6.1.tar.gz 15:50:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:50:59 INFO - Installing collected packages: blobuploader, requests, docopt 15:50:59 INFO - Running setup.py install for blobuploader 15:50:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:50:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:50:59 INFO - Running setup.py install for requests 15:50:59 INFO - Running setup.py install for docopt 15:51:00 INFO - Successfully installed blobuploader requests docopt 15:51:00 INFO - Cleaning up... 15:51:00 INFO - Return code: 0 15:51:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:51:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:00 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 0x1d9a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f63a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2106a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x210a090>, '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 0x210ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2104f90>, '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': 'ca619d3d7d62c99d832f7b8a000001c4-1504306215.232531-1880746267', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:51:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:51:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:51:00 INFO - Using env: {'DISPLAY': ':0', 15:51:00 INFO - 'HOME': '/home/cltbld', 15:51:00 INFO - 'LANG': 'en_US.UTF-8', 15:51:00 INFO - 'LANGUAGE': 'en_US:en', 15:51:00 INFO - 'LOGNAME': 'cltbld', 15:51:00 INFO - 'MAIL': '/var/mail/cltbld', 15:51:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:00 INFO - 'MOZ_NO_REMOTE': '1', 15:51:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:51:00 INFO - 'NO_EM_RESTART': '1', 15:51:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:51:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:00 INFO - 'PWD': '/builds/slave/test', 15:51:00 INFO - 'SHELL': '/bin/bash', 15:51:00 INFO - 'SHLVL': '1', 15:51:00 INFO - 'TERM': 'linux', 15:51:00 INFO - 'TMOUT': '86400', 15:51:00 INFO - 'USER': 'cltbld', 15:51:00 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1504306215.232531-1880746267', 15:51:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:00 INFO - '_': '/tools/buildbot/bin/python'} 15:51:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:51:00 INFO - Running setup.py (path:/tmp/pip-YFyoyq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:51:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:51:00 INFO - Running setup.py (path:/tmp/pip-UetPVV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:51:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:51:00 INFO - Running setup.py (path:/tmp/pip-C6oF1A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:51:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:51:00 INFO - Running setup.py (path:/tmp/pip-2f5vLj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:51:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:51:00 INFO - Running setup.py (path:/tmp/pip-z827OU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:51:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:51:00 INFO - Running setup.py (path:/tmp/pip-nIbR6_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:51:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:51:00 INFO - Running setup.py (path:/tmp/pip-_1iFO3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:51:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:51:00 INFO - Running setup.py (path:/tmp/pip-EIDLuh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:51:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:51:00 INFO - Running setup.py (path:/tmp/pip-QZiXOw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:51:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:51:00 INFO - Running setup.py (path:/tmp/pip-EJ5vrY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:51:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:51:01 INFO - Running setup.py (path:/tmp/pip-RHx6gJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:51:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:51:01 INFO - Running setup.py (path:/tmp/pip-QXXldi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:51:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:51:01 INFO - Running setup.py (path:/tmp/pip-uDJIQE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:51:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:51:01 INFO - Running setup.py (path:/tmp/pip-388sRz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:51:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:51:01 INFO - Running setup.py (path:/tmp/pip-GJ3Awv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:51:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:51:01 INFO - Running setup.py (path:/tmp/pip-IxNJeg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:51:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:51:01 INFO - Running setup.py (path:/tmp/pip-b0LYz8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:51:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:51:01 INFO - Running setup.py install for manifestparser 15:51:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:51:01 INFO - Running setup.py install for mozcrash 15:51:01 INFO - Running setup.py install for mozdebug 15:51:01 INFO - Running setup.py install for mozdevice 15:51:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:51:01 INFO - Running setup.py install for mozfile 15:51:02 INFO - Running setup.py install for mozhttpd 15:51:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:51:02 INFO - Running setup.py install for mozinfo 15:51:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:51:02 INFO - Running setup.py install for mozInstall 15:51:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:51:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:51:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:51:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:51:02 INFO - Running setup.py install for mozleak 15:51:02 INFO - Running setup.py install for mozlog 15:51:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:51:02 INFO - Running setup.py install for moznetwork 15:51:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:51:02 INFO - Running setup.py install for mozprocess 15:51:02 INFO - Running setup.py install for mozprofile 15:51:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:51:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:51:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:51:02 INFO - Running setup.py install for mozrunner 15:51:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:51:02 INFO - Running setup.py install for mozscreenshot 15:51:03 INFO - Running setup.py install for moztest 15:51:03 INFO - Running setup.py install for mozversion 15:51:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:51:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:51:03 INFO - Cleaning up... 15:51:03 INFO - Return code: 0 15:51:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:51:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:03 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 0x1d9a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f63a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2106a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x210a090>, '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 0x210ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2104f90>, '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': 'ca619d3d7d62c99d832f7b8a000001c4-1504306215.232531-1880746267', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:51:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:51:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:51:03 INFO - Using env: {'DISPLAY': ':0', 15:51:03 INFO - 'HOME': '/home/cltbld', 15:51:03 INFO - 'LANG': 'en_US.UTF-8', 15:51:03 INFO - 'LANGUAGE': 'en_US:en', 15:51:03 INFO - 'LOGNAME': 'cltbld', 15:51:03 INFO - 'MAIL': '/var/mail/cltbld', 15:51:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:03 INFO - 'MOZ_NO_REMOTE': '1', 15:51:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:51:03 INFO - 'NO_EM_RESTART': '1', 15:51:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:51:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:03 INFO - 'PWD': '/builds/slave/test', 15:51:03 INFO - 'SHELL': '/bin/bash', 15:51:03 INFO - 'SHLVL': '1', 15:51:03 INFO - 'TERM': 'linux', 15:51:03 INFO - 'TMOUT': '86400', 15:51:03 INFO - 'USER': 'cltbld', 15:51:03 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1504306215.232531-1880746267', 15:51:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:03 INFO - '_': '/tools/buildbot/bin/python'} 15:51:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:51:03 INFO - Running setup.py (path:/tmp/pip-bmSjcn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:51:03 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 15:51:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:51:03 INFO - Running setup.py (path:/tmp/pip-9UCbSz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:51:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:51:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:51:03 INFO - Running setup.py (path:/tmp/pip-3GUz2L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:51:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:51:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:51:03 INFO - Running setup.py (path:/tmp/pip-WEonS_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:51:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:51:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:51:03 INFO - Running setup.py (path:/tmp/pip-jIgr1S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:51:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 15:51:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:51:03 INFO - Running setup.py (path:/tmp/pip-HAjSAt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:51:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 15:51:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:51:03 INFO - Running setup.py (path:/tmp/pip-UJEmtU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:51:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 15:51:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:51:03 INFO - Running setup.py (path:/tmp/pip-Cywuwn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:51:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:51:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:51:04 INFO - Running setup.py (path:/tmp/pip-zhE1pF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:51:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 15:51:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:51:04 INFO - Running setup.py (path:/tmp/pip-Rfh59Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:51:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:51:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:51:04 INFO - Running setup.py (path:/tmp/pip-gRZB8x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:51:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 15:51:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:51:04 INFO - Running setup.py (path:/tmp/pip-qZvN9G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:51:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 15:51:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:51:04 INFO - Running setup.py (path:/tmp/pip-BWNSom-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:51:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 15:51:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:51:04 INFO - Running setup.py (path:/tmp/pip-H4ihTc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:51:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 15:51:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:51:04 INFO - Running setup.py (path:/tmp/pip-0oTjYc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:51:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 15:51:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:51:04 INFO - Running setup.py (path:/tmp/pip-TVxeRt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:51:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 15:51:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:51:04 INFO - Running setup.py (path:/tmp/pip-lKIggt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:51:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 15:51:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:51:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:51:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:51:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:51:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:51:04 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:51:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:51:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:05 INFO - Downloading blessings-1.6.tar.gz 15:51:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:51:05 INFO - Installing collected packages: blessings 15:51:05 INFO - Running setup.py install for blessings 15:51:05 INFO - Successfully installed blessings 15:51:05 INFO - Cleaning up... 15:51:06 INFO - Return code: 0 15:51:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:51:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:51:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:51:06 INFO - Reading from file tmpfile_stdout 15:51:06 INFO - Current package versions: 15:51:06 INFO - argparse == 1.2.1 15:51:06 INFO - blessings == 1.6 15:51:06 INFO - blobuploader == 1.2.4 15:51:06 INFO - docopt == 0.6.1 15:51:06 INFO - functools32 == 3.2.3-2 15:51:06 INFO - jsonschema == 2.5.1 15:51:06 INFO - manifestparser == 1.1 15:51:06 INFO - mozInstall == 1.13 15:51:06 INFO - mozcrash == 1.0 15:51:06 INFO - mozdebug == 0.1 15:51:06 INFO - mozdevice == 0.51 15:51:06 INFO - mozfile == 1.2 15:51:06 INFO - mozhttpd == 0.7 15:51:06 INFO - mozinfo == 0.10 15:51:06 INFO - mozleak == 0.1 15:51:06 INFO - mozlog == 3.5 15:51:06 INFO - moznetwork == 0.27 15:51:06 INFO - mozprocess == 0.25 15:51:06 INFO - mozprofile == 0.28 15:51:06 INFO - mozrunner == 6.13 15:51:06 INFO - mozscreenshot == 0.1 15:51:06 INFO - mozsystemmonitor == 0.3 15:51:06 INFO - moztest == 0.8 15:51:06 INFO - mozversion == 1.4 15:51:06 INFO - psutil == 3.1.1 15:51:06 INFO - requests == 1.2.3 15:51:06 INFO - wsgiref == 0.1.2 15:51:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:51: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')]} 15:51:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51: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')]} 15:51:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:06 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 0x1d9a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f63a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2106a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x210a090>, '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 0x210ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2104f90>, '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': 'ca619d3d7d62c99d832f7b8a000001c4-1504306215.232531-1880746267', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:51:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 15:51:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:51:06 INFO - Using env: {'DISPLAY': ':0', 15:51:06 INFO - 'HOME': '/home/cltbld', 15:51:06 INFO - 'LANG': 'en_US.UTF-8', 15:51:06 INFO - 'LANGUAGE': 'en_US:en', 15:51:06 INFO - 'LOGNAME': 'cltbld', 15:51:06 INFO - 'MAIL': '/var/mail/cltbld', 15:51:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:06 INFO - 'MOZ_NO_REMOTE': '1', 15:51:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:51:06 INFO - 'NO_EM_RESTART': '1', 15:51:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:51:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:06 INFO - 'PWD': '/builds/slave/test', 15:51:06 INFO - 'SHELL': '/bin/bash', 15:51:06 INFO - 'SHLVL': '1', 15:51:06 INFO - 'TERM': 'linux', 15:51:06 INFO - 'TMOUT': '86400', 15:51:06 INFO - 'USER': 'cltbld', 15:51:06 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1504306215.232531-1880746267', 15:51:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:06 INFO - '_': '/tools/buildbot/bin/python'} 15:51:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:51:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 15:51:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 15:51:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:51:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 15:51:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 15:51:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 15:51:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 15:51:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 15:51:06 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 15:51:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 15:51:07 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:51:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:51:07 INFO - Installing collected packages: simplejson 15:51:07 INFO - Running setup.py install for simplejson 15:51:07 INFO - building 'simplejson._speedups' extension 15:51:07 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 15:51:08 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 15:51:08 INFO - Successfully installed simplejson 15:51:08 INFO - Cleaning up... 15:51:08 INFO - Return code: 0 15:51:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:51:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:08 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 0x1d9a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f63a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2106a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x210a090>, '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 0x210ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2104f90>, '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': 'ca619d3d7d62c99d832f7b8a000001c4-1504306215.232531-1880746267', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:51:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:51:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:51:08 INFO - Using env: {'DISPLAY': ':0', 15:51:08 INFO - 'HOME': '/home/cltbld', 15:51:08 INFO - 'LANG': 'en_US.UTF-8', 15:51:08 INFO - 'LANGUAGE': 'en_US:en', 15:51:08 INFO - 'LOGNAME': 'cltbld', 15:51:08 INFO - 'MAIL': '/var/mail/cltbld', 15:51:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:08 INFO - 'MOZ_NO_REMOTE': '1', 15:51:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:51:08 INFO - 'NO_EM_RESTART': '1', 15:51:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:51:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:08 INFO - 'PWD': '/builds/slave/test', 15:51:08 INFO - 'SHELL': '/bin/bash', 15:51:08 INFO - 'SHLVL': '1', 15:51:08 INFO - 'TERM': 'linux', 15:51:08 INFO - 'TMOUT': '86400', 15:51:08 INFO - 'USER': 'cltbld', 15:51:08 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1504306215.232531-1880746267', 15:51:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:08 INFO - '_': '/tools/buildbot/bin/python'} 15:51:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:51:08 INFO - Cleaning up... 15:51:08 INFO - Return code: 0 15:51:08 INFO - Running post-action listener: _resource_record_post_action 15:51:08 INFO - Running post-action listener: _start_resource_monitoring 15:51:08 INFO - Starting resource monitoring. 15:51:08 INFO - [mozharness: 2017-09-01 22:51:08.981111Z] Finished create-virtualenv step (success) 15:51:08 INFO - [mozharness: 2017-09-01 22:51:08.981578Z] Running install step. 15:51:08 INFO - Running pre-action listener: _resource_record_pre_action 15:51:08 INFO - Running main action method: install 15:51:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:51:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:51:09 INFO - Reading from file tmpfile_stdout 15:51:09 INFO - Detecting whether we're running mozinstall >=1.0... 15:51:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:51:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:51:09 INFO - Reading from file tmpfile_stdout 15:51:09 INFO - Output received: 15:51:09 INFO - Usage: mozinstall [options] installer 15:51:09 INFO - Options: 15:51:09 INFO - -h, --help show this help message and exit 15:51:09 INFO - -d DEST, --destination=DEST 15:51:09 INFO - Directory to install application into. [default: 15:51:09 INFO - "/builds/slave/test"] 15:51:09 INFO - --app=APP Application being installed. [default: firefox] 15:51:09 INFO - mkdir: /builds/slave/test/build/application 15:51:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 15:51:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 15:51:25 INFO - Reading from file tmpfile_stdout 15:51:25 INFO - Output received: 15:51:25 INFO - /builds/slave/test/build/application/firefox/firefox 15:51:25 INFO - Running post-action listener: _resource_record_post_action 15:51:25 INFO - [mozharness: 2017-09-01 22:51:25.887909Z] Finished install step (success) 15:51:25 INFO - [mozharness: 2017-09-01 22:51:25.888055Z] Running setup-mitmproxy step. 15:51:25 INFO - Running pre-action listener: _resource_record_pre_action 15:51:25 INFO - Running main action method: setup_mitmproxy 15:51:25 INFO - Skipping: mitmproxy is not required 15:51:25 INFO - Running post-action listener: _resource_record_post_action 15:51:25 INFO - [mozharness: 2017-09-01 22:51:25.888598Z] Finished setup-mitmproxy step (success) 15:51:25 INFO - [mozharness: 2017-09-01 22:51:25.888701Z] Running run-tests step. 15:51:25 INFO - Running pre-action listener: _resource_record_pre_action 15:51:25 INFO - Running pre-action listener: _set_gcov_prefix 15:51:25 INFO - Running main action method: run_tests 15:51:25 WARNING - Try message not found. 15:51:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:51:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:51:25 INFO - Python 2.7.3 15:51:25 INFO - Return code: 0 15:51:25 INFO - grabbing minidump binary from tooltool 15:51:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:25 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 0x210a090>, '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 0x210ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2104f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:51:25 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 15:51:25 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 15:51:25 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 15:51:25 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:51:26 INFO - Return code: 0 15:51:26 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:51:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:51:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:51:26 INFO - ENV: RUST_BACKTRACE is now full 15:51:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:51:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:51:26 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/B3zBBhhZS1Sz7X0NYgNFnw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-087', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 15:51:26 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/B3zBBhhZS1Sz7X0NYgNFnw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-087 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 15:51:26 INFO - Using env: {'DISPLAY': ':0', 15:51:26 INFO - 'HOME': '/home/cltbld', 15:51:26 INFO - 'LANG': 'en_US.UTF-8', 15:51:26 INFO - 'LANGUAGE': 'en_US:en', 15:51:26 INFO - 'LOGNAME': 'cltbld', 15:51:26 INFO - 'MAIL': '/var/mail/cltbld', 15:51:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:51:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:51:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:26 INFO - 'MOZ_NO_REMOTE': '1', 15:51:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:51:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:51:26 INFO - 'NO_EM_RESTART': '1', 15:51:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:51:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:26 INFO - 'PWD': '/builds/slave/test', 15:51:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:51:26 INFO - 'RUST_BACKTRACE': 'full', 15:51:26 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:51:26 INFO - 'SHELL': '/bin/bash', 15:51:26 INFO - 'SHLVL': '1', 15:51:26 INFO - 'TERM': 'linux', 15:51:26 INFO - 'TMOUT': '86400', 15:51:26 INFO - 'USER': 'cltbld', 15:51:26 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1504306215.232531-1880746267', 15:51:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:26 INFO - '_': '/tools/buildbot/bin/python'} 15:51:26 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/B3zBBhhZS1Sz7X0NYgNFnw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-087', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 15:51:26 INFO - mozversion application_buildid: 20170901215936 15:51:26 INFO - mozversion application_changeset: 73e8f351b28ffc30e8077be21ce7298f4648ca5a 15:51:26 INFO - mozversion application_display_name: Nightly 15:51:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:51:26 INFO - mozversion application_name: Firefox 15:51:26 INFO - mozversion application_remotingname: firefox 15:51:26 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:51:26 INFO - mozversion application_vendor: Mozilla 15:51:26 INFO - mozversion application_version: 57.0a1 15:51:26 INFO - mozversion platform_buildid: 20170901215936 15:51:26 INFO - mozversion platform_changeset: 73e8f351b28ffc30e8077be21ce7298f4648ca5a 15:51:26 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:51:26 INFO - mozversion platform_version: 57.0a1 15:51:26 INFO - using testdate: 1504306286 15:51:26 INFO - actual date: 1504306286 15:51:26 INFO - starting webserver on 'localhost:47738' 15:51:26 INFO - SUITE-START | Running 2 tests 15:51:26 INFO - TEST-START | dromaeo_css 15:51:26 INFO - Initialising browser for dromaeo_css test... 15:51:26 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:47738/getInfo.html -profile /tmp/tmpcVDIzl/profile 15:51:26 INFO - TEST-INFO | started process 7833 (/builds/slave/test/build/application/firefox/firefox http://localhost:47738/getInfo.html) 15:51:31 INFO - TEST-INFO | 7833: exit 0 15:51:32 INFO - Browser initialized. 15:51:32 INFO - Running cycle 1/1 for dromaeo_css test... 15:51:32 INFO - Using env: {'DISPLAY': ':0', 15:51:32 INFO - 'HOME': '/home/cltbld', 15:51:32 INFO - 'JSGC_DISABLE_POISONING': '1', 15:51:32 INFO - 'LANG': 'en_US.UTF-8', 15:51:32 INFO - 'LANGUAGE': 'en_US:en', 15:51:32 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 15:51:32 INFO - 'LOGNAME': 'cltbld', 15:51:32 INFO - 'MAIL': '/var/mail/cltbld', 15:51:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:51:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:51:32 INFO - 'MOZ_CRASHREPORTER': '1', 15:51:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:32 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 15:51:32 INFO - 'MOZ_NO_REMOTE': '1', 15:51:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:51:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:51:32 INFO - 'NO_EM_RESTART': '1', 15:51:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:51:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:32 INFO - 'PWD': '/builds/slave/test', 15:51:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:51:32 INFO - 'RUST_BACKTRACE': 'full', 15:51:32 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:51:32 INFO - 'SHELL': '/bin/bash', 15:51:32 INFO - 'SHLVL': '1', 15:51:32 INFO - 'TERM': 'linux', 15:51:32 INFO - 'TMOUT': '86400', 15:51:32 INFO - 'USER': 'cltbld', 15:51:32 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1504306215.232531-1880746267', 15:51:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:32 INFO - '_': '/tools/buildbot/bin/python'} 15:51:32 INFO - TEST-INFO | started process 8018 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpcVDIzl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 15:51:32 INFO - PID 8018 | 15:51:32 INFO - PID 8018 | (/builds/slave/test/build/application/firefox/firefox:8070): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 15:51:32 INFO - PID 8018 | 15:51:43 INFO - PID 8018 | RSS: Main: 170115072 15:51:43 INFO - PID 8018 | 15:54:41 INFO - PID 8018 | Cycle 1(1): loaded http://localhost:47738/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47738/tests/dromaeo/cssquery-ext.html) 15:54:43 INFO - PID 8018 | RSS: Main: 172064768 15:54:43 INFO - PID 8018 | 15:57:45 INFO - PID 8018 | Cycle 1(1): loaded http://localhost:47738/tests/dromaeo/cssquery-ext.html (next: http://localhost:47738/tests/dromaeo/cssquery-jquery.html) 15:57:45 INFO - PID 8018 | RSS: Main: 168108032 15:57:45 INFO - PID 8018 | 16:00:47 INFO - PID 8018 | Cycle 1(1): loaded http://localhost:47738/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47738/tests/dromaeo/cssquery-mootools.html) 16:00:48 INFO - PID 8018 | RSS: Main: 170606592 16:00:48 INFO - PID 8018 | 16:03:49 INFO - PID 8018 | Cycle 1(1): loaded http://localhost:47738/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47738/tests/dromaeo/cssquery-prototype.html) 16:03:50 INFO - PID 8018 | RSS: Main: 168820736 16:03:50 INFO - PID 8018 | 16:06:54 INFO - PID 8018 | Cycle 1(1): loaded http://localhost:47738/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47738/tests/dromaeo/cssquery-yui.html) 16:06:54 INFO - PID 8018 | RSS: Main: 168648704 16:06:54 INFO - PID 8018 | 16:09:56 INFO - PID 8018 | Cycle 1(1): loaded http://localhost:47738/tests/dromaeo/cssquery-yui.html (next: http://localhost:47738/tests/dromaeo/cssquery-dojo.html) 16:09:56 INFO - PID 8018 | RSS: Main: 172998656 16:09:56 INFO - PID 8018 | 16:09:56 INFO - PID 8018 | __start_tp_report 16:09:56 INFO - PID 8018 | _x_x_mozilla_page_load 16:09:56 INFO - PID 8018 | _x_x_mozilla_page_load_details 16:09:56 INFO - PID 8018 | |i|pagename|runs| 16:09:56 INFO - PID 8018 | |0;dojo.html;3264.0860559043463;3294.094124115866;3313.0557791029555;3313.9337213255735;3349.933001339973;6403.13557669715;6405.5516113872045;6445.1943507061615;6454.612723236609;6479.125318082058;6315.684215789215;6416.903746443807;6436.098946147543;6443.29123796382;6450.483961283097;5910.645361278315;5913.438223368795;5964.612300200484;5999.460048595647;6041.939580604204;5857.3849745776715;5946.078357854518;5957.672328021957;5986.700664966756;6054.666993315366;5711.942880571182;5775.942240577582;5780.595358324919;5788.42115788422;5809.651420914759;5824.155497452888;5835.445632664932;5843.2695913010875;5854.063349864044;5953.58324917256;5855.736491857877;5886.146508756249;5920.437558431942;5949.851253718648;5956.374580669054;6384.840378990516;6427.517936154761;6481.48148148147;6500.902486462707;6556.9016464752585;6728.39444449997;6848.178218613748;6927.168739751262;7009.859802803915;7012.0183174355125;7058.470614703918;7067;7070.717171313193;7073.257307982639;7077.07997960267;6444.838879028015;6526.738930442777;6542.705578248942;6550.836229094215;6573.375529324707;7172.49792514519;7211.567305961554;7258.891116633255;7265.491415600963;7276.199618042011;6543.312952139957;6675.632840193739;6676.165479315086;6849.794506164823;6932.930670693228;6849;6867.690953907087;6891;6892.965535172292;6902.309769023058;6850.965745171242;6873.243943166248;6879.2432832388395;6888;6921.757738479129;6411.166548348685;6903.447724182053;7012.9298707013295;7017.964910175417;7020.7893763187185;5457.754401051962;5510.448955104457;5528.917066244009;5534.501894829405;5538.196961440569;4777.4267087949665;4816.783244753994;4835.226363781778;4836.008618233253;4843.467218605951;5884.941150588525;6010.2186715726675;6026.246719160105;6045.494876993327;6120.418560236771;6924.688389022506;6931.133608298963;6942.271061538466;6956.5478243913985;6958.373746362851;5961.225040744675;5972.820815375546;5975.760969561169;5978.491828194652;6018.398160183947;5560.099740733632;5955.880882382328;5968.403159684084;5980.641161530322;6070.544709146831;6740.898886516707;6743.932560674429;6811.489138314646;6845.383915447534;6868.862622747617;6813.04617353561;6947.548409353376;6978.267281935527;6979;7092.184398793998;6530.151080359523;6571.507136964651;6574.934250657432;6582;6591.736330546724;6734;6736.292689267556;6773.221079575912;6841.555298905555;6860.965695171492;6875.140607424072;6893.276205998499;6930.549514281555;6936;7082.256363081802;6723.966380168068;6782.898256526058;6784.59292442455;6790.932090679227;6802.217744959195;6689.899651505329;6707.966460167863;6717.193936727624;6801.455883529206;6813.727450901908;6349.936500634934;6529.575577587441;6554.180727409074;6602.306757790363;6720.529562930548;6574.967125164535;6631.966840165961;6676.599404035774;6690.698918548775;6691.397774200345;5634.887302254014;6588.604683718992;6614.603123812587;6686.53194276396;6688.565243259172 16:09:56 INFO - PID 8018 | |1;ext.html;800.76777734457;834.1158372125551;852.4033176776258;885.2033170146867;887.2236792806299;38937.61062389375;39482.60517394832;39616.801915990414;39640;39746.60253397465;36502.08744781382;36917;36917.81541092297;36978.260434791286;37012.44481332782;6846.418054465376;6931.965340173292;6949.166100067999;6959.44324454045;6967.37293643572;6883.690233939485;6964.686589103477;6965.23382427935;6966.02475653409;6966.721331146748;7428.33145016951;7614.428917831157;7622.085349758009;7624.6568904399155;7625.123110842269;7511.098668159829;7548.8112797179965;7557.395408367317;7557.395408367317;7577.621118944058;7227.494075414723;7391.815204619874;7430.331270185709;7431;7434.1822399536;36248.63751362506;36532.634673653454;36550.17249137533;36600.45099323512;36613.81693091544;3517.8768743094124;3548.627394123606;3565.358235517606;3566.7681600696133;3573.0352804742834;29719.702802972126;29730.40539189226;29742.85128574365;29838.55242171369;29889;5108.310378098946;5112.821051263188;5124.359455068116;5134.486551344836;5167.173252279617;182541;184924;187686;193504;198319.0084049585;45967.31049034267;46464.3030354545;46693.533064669595;46750.29874551884;46801.765991170505;48808.26787598189;48839.5116048835;48848.26727599089;48859.267110993365;48903;14755.778663319845;14817;14893.255337233095;14942.477013304482;15019.098854068572;12180.269183848997;12186.08604354677;12198.75602487963;12199.878001220051;12207;13938.721225575431;13967.441302347996;13995.930020349833;14044.508442204473;14160.150390976369;5683.914741278884;5708.94477987924;5709.457601527848;5777.537796976232;5810.099434587646;1019.3781793106199;1019.7246743379246;1029.120102312527;1031.6234574380446;1037.7457522906784;22518.549629007328;22759.431014224447;22799.544009120058;22947.196848110238;23194.420139496648;1106.3417266726285;1117.1174771930084;1124.3478782306406;1131.932084074958;1136.6874109619755;11990.9208171265;12163.452644631012;12189.81932678608;12236.571719989757;12257.658539508797;219917;219953;220009;220773;220939;49917;50239.24641130386;50333.496665033614;50377.49622503728;50382.74808625934;1453.1862157192006;1466;1469.1478942213491;1470.8749756270622;1473.395907677847;21767.673484898056;21821.23625673094;22781.658275125556;27114;27240.455190896468;47158;47431.288530672755;47533;47564;47776.5222347786;195427;196463;198632;198766;199988;27779.30551736142;33062.5040624386;34410.13974650654;34538.481922770676;34602;41807.581924181584;43190.35214471848;43640.56359436365;43738;44064.33903491386;32218.67781322157;32274.19314517062;32381.676183238807;32448.188795279362;32873;31283.687163128987;31722.36555268928;31813;32117.51823722599;32837.17907052343;36066;36125.27749445049;36360.63639363572;36373.45439818458;36478;30807.69192308048;30934.69065309318;31303.53044704377;32143.839280803448;32185.35629287448;23786.16748413797;29725.851370743872;30102.24744381408;30377.69622303749;30857.537136943414 16:09:56 INFO - PID 8018 | |2;jquery.html;564.9858753531162;567.701956472852;567.9999999999998;578.2482772395886;591.6095377051146;21696.457588560294;22387.664185037218;22413;22471.101155953744;22788.430289242737;25831.741682583168;26368.868155659242;26392.208233752972;26428.47143057138;26493.602595961074;6536.803895883119;6669.766558170465;6691.966540167292;6693.866122677544;6696.899546506782;6447;6528.608283503005;6604.537682362236;6725.66371681414;6777.491688123386;6730.4279136273235;6734.1245638066985;6737.326267373248;6751.527393082486;6757.155355580552;6710.027046078316;6725.764598239087;6738.52830301879;6741.730330786762;6741.764038258638;6245.313015568284;6246.187995560594;6251.3123556408755;6263.655498947602;6280.214973128359;28755;29349.413011739645;29927;30190;30404;2048.774634790172;2088.2169186555043;2091.0381224636685;2091.215794077221;2096.2139197800825;19305.42083737476;19860.10629521675;19880.602387952305;19880.70178947317;19960.3013894513;2390.7609239075955;2441.816863735227;2491.95016099677;2548.5540030494776;2557.105013245368;122072.38963805213;124211.3789431056;125491.37254313761;127049.36475317564;127364;14160.787588186186;14810.555683329518;14831.146106736658;15035.42128076558;15232.695346093016;22732.31803045911;22743.203987860346;22748.20381286647;22751.08995640189;22781.202657906895;9878.209743220525;10129.544170512345;10134.797304053878;10147.543360548792;10187.185025197967;7678.61606919663;9229;9291.72124836256;9361.157495825408;9365.48489833052;7896.605169741605;9153.221976132103;9193.402428842104;9341.90658093424;9377.578008989612;2726.5092283388785;2751.6285301484045;2755.7678280592786;2762.9171124865884;2838.929026774306;973.7176218896583;989.9851502227473;990.9950450247702;995.9153471954965;999.980000399988;19356.612867742566;19423.223071076998;19434.319798807257;19912;19982.800171998384;1040.5681642118586;1041.8697662792151;1068.423051552153;1071.9517621707041;1072.5012869015945;10724.517396717167;10945.398003109905;10998.175136864767;11075;11092.500837462172;440293;448393;454918;456814;459621;108843;110822;110845;113136;113691.43154284176;1157.5659127826896;1169.5321871251397;1192.3978390912534;1193.1886317304316;1197.4910662968205;17321.74017389715;17505.649887002444;17818.91090544539;17886.731699024785;17893.463196104138;53826.461735382145;54604.726976364866;54973.725131374085;56134.71932640474;57253.427465726476;66357;67160.6641966787;68573.65713171402;68643.65678171578;69084.6545767268;11778.234414763014;13473.797893031417;13728.519501817389;14249.50126745559;14524.782128268296;22240.77759222387;22360.664590030836;22500.77499224987;22530.436739081655;22646.773532264466;17613.82386176122;17700.203490842687;17768.378106766202;17830.197641106442;17833.554161146076;15920.681586367977;16320.18399080065;16380.099094549438;16633.50099497017;16996.215397383097;18049.368272110412;18224.36214732478;18241.45275641733;18331;18376.540586485444;14022.649433764238;14030.368633411317;14094.083884547472;14133.787993179905;14138.929305353407;17719.379821706178;17828.91085544564;17836.643267134845;17867.28530858751;17917.910410448385 16:09:56 INFO - PID 8018 | |3;mootools.html;1304.654266619346;1370.9245991470466;1371.2389623758816;1379.3448112146732;1383.7094210215855;14694.191819449927;15013.174275414847;15344.539663810076;15363.078215307076;15364.539063828075;15756.606084847885;16033.038017718945;16078.437254696088;16108.838911610881;16117.113558754263;2833.178378270304;2875.9712402876016;2876.525373313401;2877.4101309231555;2878.5106531889523;2739.575365818301;2922.517784565544;2927.780416468773;2934.6038284831593;2939.4121175764826;2751.408447183855;2800.495910736067;2804.887804487818;2827.8303301801857;2828.900988465404;2773.5700966350246;2790.6651201855807;2800.5519116941296;2804.4110736745306;2808.382155925693;2713.2538551898188;2713.7557619814115;2725.5639097744465;2726.5228584997744;2728.8499132547704;15109.773353399707;15225;15234.847651523565;15245.923770381189;15261.694766104616;7953.840923181562;8130.065042520127;8179;8194.139615340364;8240.62917168729;5204.557612602934;5383.300170977787;5420.186971954238;5531.7234138293325;5539.9169012464845;1922.3367938061347;1930.806919308072;1945.805419458057;1952.0532541717255;1973.4770285874256;26923.46153076947;27092.864535677392;27190.592141118097;27296.727032729617;27342.179734607995;6713.295104014106;6939.375456208965;6966.9651651741415;7087.149542054884;7163.140423149255;17276.13619319029;17370.739438908426;17436.215370308368;17439.73840392395;17446.912765436093;7925.960370198112;8161.387895907831;8165.183481651788;8166.469179503432;8166.632501537445;7442.776716698508;7559.470837041465;7592.8861067084035;7621.809454763676;7625.504342217738;5155.871103222375;5231.843044708665;5277.577793776565;5308.416074231832;5346.43862394443;1529.4188208480928;1537.2390666620095;1541.46819347325;1552.4566401759519;1575.039226072095;850.5067061104545;880.1374652840252;888.9955550222207;900.3652425040331;901.8466860633708;7430.591317477591;7439.590822504814;7468.5145465544565;7473.177950425449;7491.438142139361;698.8252936765808;988.8714467119229;1025.733309339562;1030.8247597908373;1035.1304903880775;5288.7355632219005;5331.706756128373;5503.944960550343;5738.713064346766;5850.3564607893095;22057.558848822933;22328.106875725112;22392.10431582751;22570;22595.43511412195;21108.683369749466;22037;22302.776972230393;22314.55370892573;22352.21767238139;11389.886101138882;11611.361375124281;11619.99999999983;11676.70808229801;11712.121590880719;8126.228008339198;8914;8966.820663586823;9200.447973121634;9261.490618015805;18691.158897849902;18731.37760713474;18789;19076.61846763029;19098.809011909703;19325;19404.320848770225;19516.90241548783;19539.12073956704;19581.7140468643;13765;14000.43998240059;14100.717985640435;14113.153210807384;14118.364673589507;14079.859201407855;14127.64680882986;14201.644958876112;14270.928645356707;14280.357383917957;11197.72005699864;11229.88770112321;11246.887531124912;11391.715207119889;11488.655340339803;11420.714482138013;11559.537618495166;11571.074314054687;11761.764764704487;11839;9407.764805879908;9457.148856602938;9574.808503829745;9634.807303854024;9723.02769723022;6722.193336799615;6737.427318677967;6774.86450270982;6812.114425124702;6826.146731658543;6745.831354216184;6802.693878775566;6809.489288303298;6856.9314306856295;6894.896576551456 16:09:56 INFO - PID 8018 | |4;prototype.html;508.43402320851783;520.5315216305326;526.1397614899638;529.8542900702306;541.5748637319703;6500.28496865345;6531.869362612757;6545.476361891055;6547.821392149412;6550.705218265178;6375.203099612549;6388.872222555546;6409.775657851976;6498.675066246692;6513.218413790351;5323.1749078892835;5341.706206158659;5346.358436987567;5347.518723314901;5360.16917377807;4842.685225460352;5025.522575355335;5243.79024839006;5249.790008399678;5306.7346632668405;4984.57631101355;5075.111855425304;5082.847514574569;5093.134167191568;5137.280780690688;5082.440931497532;5093.185090385539;5103.846884593431;5104.566111880457;5159.329287192679;4372.475302963665;4467.776611169428;4522.751248681321;4567.177907976563;4573.931391029138;6207.81376558704;6344.238691357021;6355.841103972438;6359.8092057238355;6362.427381535684;5740.650947027445;5763.6541807491685;5769.855753606152;5819.0689489681445;5826.912596311059;6614.570052946543;6641.734330626769;6690.665466726644;6765.019072234548;6770.289119642465;4938.555530002317;4973.303737476756;4992.976439829826;4999.925001124986;5009.499050094962;6764.797056088325;6903.447724182053;6985.6507174641065;6987.7204911803465;7084.0164753869;5102.642815002914;5114.360704911886;5114.616403769732;5137.0753264412015;5137.765072193608;5182.4299327073995;5195.402528709171;5198.451355064319;5215.217717342429;5216.686998780086;5808.564357673192;5813.244278243886;5816;5816.621919575214;5838.737256823453;6748.595084294958;6776.6950487227205;6779.220389655155;6786.355296246848;6819.5908245505425;1794.3091909614632;1804.7653805005266;1810.9637807243782;1811.2664370929795;1813.6463389639157;1171.1509155862102;1230.0467137967967;1250.5435516036764;1251.224240970586;1256.221142891395;5796;5829.446202610745;5869.8239052828485;5882.176495290662;5991.580589358703;6710.328967103251;6821.624810635462;6853;6894.551854129466;6930.8613827724175;5870.178175055496;5878.794242201588;5935.732892019783;5941.574022234632;5950.553708471882;3603.3513967486106;3646.835892384849;3654.780713157166;3678.4482327651067;3710.0910276982313;6442.355764423613;6502.902456463157;6603;6629.326324940905;6777.423918966844;6856.314368563204;7002.75490357835;7103;7116.608586527687;7155.821104472429;6629.668516574248;6662.56693314933;6673;6707.765228217087;6727.831804204934;6844.589324640538;6876.209235937931;6947.687354068978;6959.130108736408;7015.789526314219;6829.8634027318185;6843.452523798183;6873;6909.723611055501;6930.306969303029;6665.600063996176;6685.799426017227;6732.629705366252;6772.11962444879;6772.5936443813525;5105.744712764421;5117.0342984187555;5128.153854613947;5163.225516172604;5166.147585648477;6604.438622717123;6725.730970761115;6914.308569143247;6949.861002780017;7029.824254393681;6576.901346479711;6660.200775906922;6681.33186681328;6694.732210711517;6768.492363072691;6603.339666033358;6643.302453242533;6645.966770166118;6657.1345725055435;6667.499937504804;6407.423331900151;6596.901046484402;6668.633225172662;6678.899816502853;6682.565633233824;6706.362895524917;6716.294789047275;6762.734483947933;6795.3204679531655;6861.4510839133745;6529.477641788741;6538.201994451564;6566.737330506918;6629.834254143685;6635 16:09:56 INFO - PID 8018 | |5;yui.html;885.3802338363147;887.8712586674932;904.8597467392557;906.0893801729261;910.5265262063729;18416.171272292744;19504;19528.41414757558;19587;19607.019649017544;15964.920175399136;16058.518244452656;16414.671706565892;19649;19777;937.2689302344165;950.686273529737;953.5565961827749;954.4225743425231;956.4596003258172;1001.8597396364515;1006.0543089495887;1007.0332480818387;1007.2294694558632;1007.5717819926539;924.685606893656;975.8438649816031;1034.0125180452649;1037.9532921018535;1038.1435315864405;1031.5512751952924;1031.9019693129142;1033.0186322993186;1033.261218228966;1033.333499892565;750.740994356946;751.2600088912417;751.3200553499038;751.6504825256283;751.8158051277472;18726.250949961985;18841.717374239397;19062;19273.61452770951;20240.696389554167;20776.48058798527;20941.26705565313;21042.158313667434;21400.14399424021;21424.92875356226;15663.373465061384;15752.842471575366;15770.526884193492;15778.684426311524;15815.446459373981;3683.0608194910396;3697.3899306614376;3697.704183665327;3698.9630103698887;3699.445083237509;117965;124078;124767;125037.37481312627;126275;15702.68594628101;16022.679546409006;16076.196190190678;16152.030878147114;16164.595885102966;29918.55122173169;30061.849690751405;30710;30765;30769.538456923165;8415.116412776693;8451.577421129043;8466.492010479391;8509.531975741394;8510.191531804592;3939.3106206413763;4055.858044968471;4062.6749860011128;4063.0248740302113;4078.4290199372113;5008.4240312364545;5034.899302014012;5082.440931497532;5125.923111153335;5133.8716532087;992.4392718114386;1003.6738060130486;1027.1474676018886;1029.6602121299952;1031.2781053262745;638.9904151437732;654.3750718064238;660.6993817812884;664.1034603285623;674.2515807453723;11121.499532521057;11198.832017519744;11217.60738374153;11788.23376480526;13682.863171368357;695.8538706871614;714.3963350968423;718.5150023733959;721.2354903801987;721.5670597641373;5497.285352904177;5547.528460080857;5801.651900885876;5901.812493149613;5913.615614985012;105632;106219;106617;107836.46081769698;109990;94788.52605737065;95042.524787377;96520;97392.51303743436;98988.50505747425;73285.26714732891;82338;84439.5778021106;87398.56300718583;87652.5617371909;12691.873081269068;12920.935395322964;13043.804342935053;13097.148685335424;13126.934365328494;38068.61931380651;39467.40798887962;39518.60481395264;39536;41586.168276634904;49349.506504935925;50342;50563.49436505588;50711;51344.74327628338;17628.73556896673;17766.733498997783;18048.097595120453;18062.277508900024;18164;21586.35240942774;22037.889810551485;22279.331620051424;22619.208327708453;22815.201467948544;18903.338383156522;19125.808741912402;19192.904035479733;19838.40484785459;19842.702359464332;17264.741028884324;17338.219780109674;17339.653206935538;17456.389026384524;18799.90600046991;18609.720854186926;19003.714944276122;19270.90364548168;19297.903510482356;19587.51031224231;12097.2136811107;12216.633500994983;12509.249445033327;12568.183068100545;13023.153495022792;14357.784633230718;14374.496892609126;14383.311760031636;14394.784078239045;14521.186061094922 16:09:56 INFO - PID 8018 | __end_tp_report 16:09:56 INFO - PID 8018 | __start_cc_report 16:09:56 INFO - PID 8018 | _x_x_mozilla_cycle_collect,362 16:09:56 INFO - PID 8018 | __end_cc_report 16:09:56 INFO - PID 8018 | __startTimestamp1504307396799__endTimestamp 16:09:56 INFO - PID 8018 | 16:09:56 INFO - PID 8018 | ------- Summary: start ------- 16:09:56 INFO - PID 8018 | Number of tests: 6 16:09:56 INFO - PID 8018 | 16:09:56 INFO - PID 8018 | [#0] dojo.html Cycles:165 Average:6352.58 Median:6573.38 stddev:757.30 (11.5%) stddev-sans-first:719.82 16:09:56 INFO - PID 8018 | Values: 3264.1 3294.1 3313.1 3313.9 3349.9 6403.1 6405.6 6445.2 6454.6 6479.1 6315.7 6416.9 6436.1 6443.3 6450.5 5910.6 5913.4 5964.6 5999.5 6041.9 5857.4 5946.1 5957.7 5986.7 6054.7 5711.9 5775.9 5780.6 5788.4 5809.7 5824.2 5835.4 5843.3 5854.1 5953.6 5855.7 5886.1 5920.4 5949.9 5956.4 6384.8 6427.5 6481.5 6500.9 6556.9 6728.4 6848.2 6927.2 7009.9 7012.0 7058.5 7067.0 7070.7 7073.3 7077.1 6444.8 6526.7 6542.7 6550.8 6573.4 7172.5 7211.6 7258.9 7265.5 7276.2 6543.3 6675.6 6676.2 6849.8 6932.9 6849.0 6867.7 6891.0 6893.0 6902.3 6851.0 6873.2 6879.2 6888.0 6921.8 6411.2 6903.4 7012.9 7018.0 7020.8 5457.8 5510.4 5528.9 5534.5 5538.2 4777.4 4816.8 4835.2 4836.0 4843.5 5884.9 6010.2 6026.2 6045.5 6120.4 6924.7 6931.1 6942.3 6956.5 6958.4 5961.2 5972.8 5975.8 5978.5 6018.4 5560.1 5955.9 5968.4 5980.6 6070.5 6740.9 6743.9 6811.5 6845.4 6868.9 6813.0 6947.5 6978.3 6979.0 7092.2 6530.2 6571.5 6574.9 6582.0 6591.7 6734.0 6736.3 6773.2 6841.6 6861.0 6875.1 6893.3 6930.5 6936.0 7082.3 6724.0 6782.9 6784.6 6790.9 6802.2 6689.9 6708.0 6717.2 6801.5 6813.7 6349.9 6529.6 6554.2 6602.3 6720.5 6575.0 6632.0 6676.6 6690.7 6691.4 5634.9 6588.6 6614.6 6686.5 6688.6 16:09:56 INFO - PID 8018 | 16:09:56 INFO - PID 8018 | [#1] ext.html Cycles:180 Average:37115.91 Median:3533.25 stddev:52541.34 (1487.1%) stddev-sans-first:52617.97 16:09:56 INFO - PID 8018 | Values: 800.8 834.1 852.4 885.2 887.2 38937.6 39482.6 39616.8 39640.0 39746.6 36502.1 36917.0 36917.8 36978.3 37012.4 6846.4 6932.0 6949.2 6959.4 6967.4 6883.7 6964.7 6965.2 6966.0 6966.7 7428.3 7614.4 7622.1 7624.7 7625.1 7511.1 7548.8 7557.4 7557.4 7577.6 7227.5 7391.8 7430.3 7431.0 7434.2 36248.6 36532.6 36550.2 36600.5 36613.8 3517.9 3548.6 3565.4 3566.8 3573.0 29719.7 29730.4 29742.9 29838.6 29889.0 5108.3 5112.8 5124.4 5134.5 5167.2 182541.0 184924.0 187686.0 193504.0 198319.0 45967.3 46464.3 46693.5 46750.3 46801.8 48808.3 48839.5 48848.3 48859.3 48903.0 14755.8 14817.0 14893.3 14942.5 15019.1 12180.3 12186.1 12198.8 12199.9 12207.0 13938.7 13967.4 13995.9 14044.5 14160.2 5683.9 5708.9 5709.5 5777.5 5810.1 1019.4 1019.7 1029.1 1031.6 1037.7 22518.5 22759.4 22799.5 22947.2 23194.4 1106.3 1117.1 1124.3 1131.9 1136.7 11990.9 12163.5 12189.8 12236.6 12257.7 219917.0 219953.0 220009.0 220773.0 220939.0 49917.0 50239.2 50333.5 50377.5 50382.7 1453.2 1466.0 1469.1 1470.9 1473.4 21767.7 21821.2 22781.7 27114.0 27240.5 47158.0 47431.3 47533.0 47564.0 47776.5 195427.0 196463.0 198632.0 198766.0 199988.0 27779.3 33062.5 34410.1 34538.5 34602.0 41807.6 43190.4 43640.6 43738.0 44064.3 32218.7 32274.2 32381.7 32448.2 32873.0 31283.7 31722.4 31813.0 32117.5 32837.2 36066.0 36125.3 36360.6 36373.5 36478.0 30807.7 30934.7 31303.5 32143.8 32185.4 23786.2 29725.9 30102.2 30377.7 30857.5 16:09:56 INFO - PID 8018 | 16:09:56 INFO - PID 8018 | [#2] jquery.html Cycles:180 Average:33017.25 Median:22515.61 stddev:76144.34 (338.2%) stddev-sans-first:76318.96 16:09:56 INFO - PID 8018 | Values: 565.0 567.7 568.0 578.2 591.6 21696.5 22387.7 22413.0 22471.1 22788.4 25831.7 26368.9 26392.2 26428.5 26493.6 6536.8 6669.8 6692.0 6693.9 6696.9 6447.0 6528.6 6604.5 6725.7 6777.5 6730.4 6734.1 6737.3 6751.5 6757.2 6710.0 6725.8 6738.5 6741.7 6741.8 6245.3 6246.2 6251.3 6263.7 6280.2 28755.0 29349.4 29927.0 30190.0 30404.0 2048.8 2088.2 2091.0 2091.2 2096.2 19305.4 19860.1 19880.6 19880.7 19960.3 2390.8 2441.8 2492.0 2548.6 2557.1 122072.4 124211.4 125491.4 127049.4 127364.0 14160.8 14810.6 14831.1 15035.4 15232.7 22732.3 22743.2 22748.2 22751.1 22781.2 9878.2 10129.5 10134.8 10147.5 10187.2 7678.6 9229.0 9291.7 9361.2 9365.5 7896.6 9153.2 9193.4 9341.9 9377.6 2726.5 2751.6 2755.8 2762.9 2838.9 973.7 990.0 991.0 995.9 1000.0 19356.6 19423.2 19434.3 19912.0 19982.8 1040.6 1041.9 1068.4 1072.0 1072.5 10724.5 10945.4 10998.2 11075.0 11092.5 440293.0 448393.0 454918.0 456814.0 459621.0 108843.0 110822.0 110845.0 113136.0 113691.4 1157.6 1169.5 1192.4 1193.2 1197.5 17321.7 17505.6 17818.9 17886.7 17893.5 53826.5 54604.7 54973.7 56134.7 57253.4 66357.0 67160.7 68573.7 68643.7 69084.7 11778.2 13473.8 13728.5 14249.5 14524.8 22240.8 22360.7 22500.8 22530.4 22646.8 17613.8 17700.2 17768.4 17830.2 17833.6 15920.7 16320.2 16380.1 16633.5 16996.2 18049.4 18224.4 18241.5 18331.0 18376.5 14022.6 14030.4 14094.1 14133.8 14138.9 17719.4 17828.9 17836.6 17867.3 17917.9 16:09:56 INFO - PID 8018 | 16:09:56 INFO - PID 8018 | [#3] mootools.html Cycles:180 Average:9549.40 Median:15012.79 stddev:6866.39 (45.7%) stddev-sans-first:6857.71 16:09:56 INFO - PID 8018 | Values: 1304.7 1370.9 1371.2 1379.3 1383.7 14694.2 15013.2 15344.5 15363.1 15364.5 15756.6 16033.0 16078.4 16108.8 16117.1 2833.2 2876.0 2876.5 2877.4 2878.5 2739.6 2922.5 2927.8 2934.6 2939.4 2751.4 2800.5 2804.9 2827.8 2828.9 2773.6 2790.7 2800.6 2804.4 2808.4 2713.3 2713.8 2725.6 2726.5 2728.8 15109.8 15225.0 15234.8 15245.9 15261.7 7953.8 8130.1 8179.0 8194.1 8240.6 5204.6 5383.3 5420.2 5531.7 5539.9 1922.3 1930.8 1945.8 1952.1 1973.5 26923.5 27092.9 27190.6 27296.7 27342.2 6713.3 6939.4 6967.0 7087.1 7163.1 17276.1 17370.7 17436.2 17439.7 17446.9 7926.0 8161.4 8165.2 8166.5 8166.6 7442.8 7559.5 7592.9 7621.8 7625.5 5155.9 5231.8 5277.6 5308.4 5346.4 1529.4 1537.2 1541.5 1552.5 1575.0 850.5 880.1 889.0 900.4 901.8 7430.6 7439.6 7468.5 7473.2 7491.4 698.8 988.9 1025.7 1030.8 1035.1 5288.7 5331.7 5503.9 5738.7 5850.4 22057.6 22328.1 22392.1 22570.0 22595.4 21108.7 22037.0 22302.8 22314.6 22352.2 11389.9 11611.4 11620.0 11676.7 11712.1 8126.2 8914.0 8966.8 9200.4 9261.5 18691.2 18731.4 18789.0 19076.6 19098.8 19325.0 19404.3 19516.9 19539.1 19581.7 13765.0 14000.4 14100.7 14113.2 14118.4 14079.9 14127.6 14201.6 14270.9 14280.4 11197.7 11229.9 11246.9 11391.7 11488.7 11420.7 11559.5 11571.1 11761.8 11839.0 9407.8 9457.1 9574.8 9634.8 9723.0 6722.2 6737.4 6774.9 6812.1 6826.1 6745.8 6802.7 6809.5 6856.9 6894.9 16:09:56 INFO - PID 8018 | 16:09:56 INFO - PID 8018 | [#4] prototype.html Cycles:180 Average:5659.77 Median:6382.04 stddev:1588.74 (24.9%) stddev-sans-first:1545.43 16:09:56 INFO - PID 8018 | Values: 508.4 520.5 526.1 529.9 541.6 6500.3 6531.9 6545.5 6547.8 6550.7 6375.2 6388.9 6409.8 6498.7 6513.2 5323.2 5341.7 5346.4 5347.5 5360.2 4842.7 5025.5 5243.8 5249.8 5306.7 4984.6 5075.1 5082.8 5093.1 5137.3 5082.4 5093.2 5103.8 5104.6 5159.3 4372.5 4467.8 4522.8 4567.2 4573.9 6207.8 6344.2 6355.8 6359.8 6362.4 5740.7 5763.7 5769.9 5819.1 5826.9 6614.6 6641.7 6690.7 6765.0 6770.3 4938.6 4973.3 4993.0 4999.9 5009.5 6764.8 6903.4 6985.7 6987.7 7084.0 5102.6 5114.4 5114.6 5137.1 5137.8 5182.4 5195.4 5198.5 5215.2 5216.7 5808.6 5813.2 5816.0 5816.6 5838.7 6748.6 6776.7 6779.2 6786.4 6819.6 1794.3 1804.8 1811.0 1811.3 1813.6 1171.2 1230.0 1250.5 1251.2 1256.2 5796.0 5829.4 5869.8 5882.2 5991.6 6710.3 6821.6 6853.0 6894.6 6930.9 5870.2 5878.8 5935.7 5941.6 5950.6 3603.4 3646.8 3654.8 3678.4 3710.1 6442.4 6502.9 6603.0 6629.3 6777.4 6856.3 7002.8 7103.0 7116.6 7155.8 6629.7 6662.6 6673.0 6707.8 6727.8 6844.6 6876.2 6947.7 6959.1 7015.8 6829.9 6843.5 6873.0 6909.7 6930.3 6665.6 6685.8 6732.6 6772.1 6772.6 5105.7 5117.0 5128.2 5163.2 5166.1 6604.4 6725.7 6914.3 6949.9 7029.8 6576.9 6660.2 6681.3 6694.7 6768.5 6603.3 6643.3 6646.0 6657.1 6667.5 6407.4 6596.9 6668.6 6678.9 6682.6 6706.4 6716.3 6762.7 6795.3 6861.5 6529.5 6538.2 6566.7 6629.8 6635.0 16:09:56 INFO - PID 8018 | 16:09:56 INFO - PID 8018 | [#5] yui.html Cycles:180 Average:22864.21 Median:20041.70 stddev:30811.75 (153.7%) stddev-sans-first:30853.98 16:09:56 INFO - PID 8018 | Values: 885.4 887.9 904.9 906.1 910.5 18416.2 19504.0 19528.4 19587.0 19607.0 15964.9 16058.5 16414.7 19649.0 19777.0 937.3 950.7 953.6 954.4 956.5 1001.9 1006.1 1007.0 1007.2 1007.6 924.7 975.8 1034.0 1038.0 1038.1 1031.6 1031.9 1033.0 1033.3 1033.3 750.7 751.3 751.3 751.7 751.8 18726.3 18841.7 19062.0 19273.6 20240.7 20776.5 20941.3 21042.2 21400.1 21424.9 15663.4 15752.8 15770.5 15778.7 15815.4 3683.1 3697.4 3697.7 3699.0 3699.4 117965.0 124078.0 124767.0 125037.4 126275.0 15702.7 16022.7 16076.2 16152.0 16164.6 29918.6 30061.8 30710.0 30765.0 30769.5 8415.1 8451.6 8466.5 8509.5 8510.2 3939.3 4055.9 4062.7 4063.0 4078.4 5008.4 5034.9 5082.4 5125.9 5133.9 992.4 1003.7 1027.1 1029.7 1031.3 639.0 654.4 660.7 664.1 674.3 11121.5 11198.8 11217.6 11788.2 13682.9 695.9 714.4 718.5 721.2 721.6 5497.3 5547.5 5801.7 5901.8 5913.6 105632.0 106219.0 106617.0 107836.5 109990.0 94788.5 95042.5 96520.0 97392.5 98988.5 73285.3 82338.0 84439.6 87398.6 87652.6 12691.9 12920.9 13043.8 13097.1 13126.9 38068.6 39467.4 39518.6 39536.0 41586.2 49349.5 50342.0 50563.5 50711.0 51344.7 17628.7 17766.7 18048.1 18062.3 18164.0 21586.4 22037.9 22279.3 22619.2 22815.2 18903.3 19125.8 19192.9 19838.4 19842.7 17264.7 17338.2 17339.7 17456.4 18799.9 18609.7 19003.7 19270.9 19297.9 19587.5 12097.2 12216.6 12509.2 12568.2 13023.2 14357.8 14374.5 14383.3 14394.8 14521.2 16:09:56 INFO - PID 8018 | -------- Summary: end -------- 16:09:56 INFO - PID 8018 | 16:09:57 INFO - TEST-INFO | 8018: exit 0 16:09:57 INFO - TEST-OK | dromaeo_css | took 1111299ms 16:09:57 INFO - TEST-START | kraken 16:09:57 INFO - Initialising browser for kraken test... 16:09:57 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:47738/getInfo.html -profile /tmp/tmpnGTvWY/profile 16:09:57 INFO - TEST-INFO | started process 8388 (/builds/slave/test/build/application/firefox/firefox http://localhost:47738/getInfo.html) 16:10:03 INFO - TEST-INFO | 8388: exit 0 16:10:03 INFO - Browser initialized. 16:10:03 INFO - Running cycle 1/1 for kraken test... 16:10:03 INFO - Using env: {'DISPLAY': ':0', 16:10:03 INFO - 'HOME': '/home/cltbld', 16:10:03 INFO - 'JSGC_DISABLE_POISONING': '1', 16:10:03 INFO - 'LANG': 'en_US.UTF-8', 16:10:03 INFO - 'LANGUAGE': 'en_US:en', 16:10:03 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:10:03 INFO - 'LOGNAME': 'cltbld', 16:10:03 INFO - 'MAIL': '/var/mail/cltbld', 16:10:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:10:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:10:03 INFO - 'MOZ_CRASHREPORTER': '1', 16:10:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:03 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:10:03 INFO - 'MOZ_NO_REMOTE': '1', 16:10:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:10:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:10:03 INFO - 'NO_EM_RESTART': '1', 16:10:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:10:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:03 INFO - 'PWD': '/builds/slave/test', 16:10:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:10:03 INFO - 'RUST_BACKTRACE': 'full', 16:10:03 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:10:03 INFO - 'SHELL': '/bin/bash', 16:10:03 INFO - 'SHLVL': '1', 16:10:03 INFO - 'TERM': 'linux', 16:10:03 INFO - 'TMOUT': '86400', 16:10:03 INFO - 'USER': 'cltbld', 16:10:03 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1504306215.232531-1880746267', 16:10:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:03 INFO - '_': '/tools/buildbot/bin/python'} 16:10:03 INFO - TEST-INFO | started process 8573 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnGTvWY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:10:04 INFO - PID 8573 | 16:10:04 INFO - PID 8573 | (/builds/slave/test/build/application/firefox/firefox:8625): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:10:04 INFO - PID 8573 | 16:10:14 INFO - PID 8573 | RSS: Main: 168267776 16:10:14 INFO - PID 8573 | 16:10:17 INFO - PID 8573 | Cycle 1(1): loaded http://localhost:47738/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47738/tests/kraken/driver.html?testName=audio-beat-detection) 16:10:18 INFO - PID 8573 | RSS: Main: 173023232 16:10:18 INFO - PID 8573 | 16:10:20 INFO - PID 8573 | Cycle 1(1): loaded http://localhost:47738/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47738/tests/kraken/driver.html?testName=audio-dft) 16:10:21 INFO - PID 8573 | RSS: Main: 177025024 16:10:21 INFO - PID 8573 | 16:10:24 INFO - PID 8573 | Cycle 1(1): loaded http://localhost:47738/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47738/tests/kraken/driver.html?testName=audio-fft) 16:10:25 INFO - PID 8573 | RSS: Main: 170184704 16:10:25 INFO - PID 8573 | 16:10:27 INFO - PID 8573 | Cycle 1(1): loaded http://localhost:47738/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47738/tests/kraken/driver.html?testName=audio-oscillator) 16:10:27 INFO - PID 8573 | RSS: Main: 171966464 16:10:27 INFO - PID 8573 | 16:10:29 INFO - PID 8573 | Cycle 1(1): loaded http://localhost:47738/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47738/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:10:30 INFO - PID 8573 | RSS: Main: 171671552 16:10:30 INFO - PID 8573 | 16:10:33 INFO - PID 8573 | Cycle 1(1): loaded http://localhost:47738/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47738/tests/kraken/driver.html?testName=imaging-darkroom) 16:10:34 INFO - PID 8573 | RSS: Main: 173178880 16:10:34 INFO - PID 8573 | 16:10:37 INFO - PID 8573 | Cycle 1(1): loaded http://localhost:47738/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47738/tests/kraken/driver.html?testName=imaging-desaturate) 16:10:38 INFO - PID 8573 | RSS: Main: 173387776 16:10:38 INFO - PID 8573 | 16:10:41 INFO - PID 8573 | Cycle 1(1): loaded http://localhost:47738/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47738/tests/kraken/driver.html?testName=json-parse-financial) 16:10:41 INFO - PID 8573 | RSS: Main: 173039616 16:10:41 INFO - PID 8573 | 16:10:43 INFO - PID 8573 | Cycle 1(1): loaded http://localhost:47738/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47738/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:10:44 INFO - PID 8573 | RSS: Main: 172920832 16:10:44 INFO - PID 8573 | 16:10:45 INFO - PID 8573 | Cycle 1(1): loaded http://localhost:47738/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47738/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:10:46 INFO - PID 8573 | RSS: Main: 172933120 16:10:46 INFO - PID 8573 | 16:10:48 INFO - PID 8573 | Cycle 1(1): loaded http://localhost:47738/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47738/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:10:49 INFO - PID 8573 | RSS: Main: 170143744 16:10:49 INFO - PID 8573 | 16:10:51 INFO - PID 8573 | Cycle 1(1): loaded http://localhost:47738/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47738/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:10:52 INFO - PID 8573 | RSS: Main: 170119168 16:10:52 INFO - PID 8573 | 16:10:55 INFO - PID 8573 | Cycle 1(1): loaded http://localhost:47738/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47738/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:10:56 INFO - PID 8573 | RSS: Main: 170483712 16:10:56 INFO - PID 8573 | 16:10:57 INFO - PID 8573 | Cycle 1(1): loaded http://localhost:47738/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47738/tests/kraken/driver.html?testName=ai-astar) 16:10:58 INFO - PID 8573 | RSS: Main: 171782144 16:10:58 INFO - PID 8573 | 16:10:58 INFO - PID 8573 | __start_tp_report 16:10:58 INFO - PID 8573 | _x_x_mozilla_page_load 16:10:58 INFO - PID 8573 | _x_x_mozilla_page_load_details 16:10:58 INFO - PID 8573 | |i|pagename|runs| 16:10:58 INFO - PID 8573 | |0;ai-astar;98;107;99;106;107;112;111;98;109;109 16:10:58 INFO - PID 8573 | |1;audio-beat-detection;144;115;138;169;114;148;140;119;177;118 16:10:58 INFO - PID 8573 | |2;audio-dft;165;164;161;161;162;166;161;170;164;162 16:10:58 INFO - PID 8573 | |3;audio-fft;74;76;74;172;75;75;74;74;158;76 16:10:58 INFO - PID 8573 | |4;audio-oscillator;78;79;136;80;80;80;134;79;78;127 16:10:58 INFO - PID 8573 | |5;imaging-gaussian-blur;114;114;114;114;115;116;114;114;115;114 16:10:58 INFO - PID 8573 | |6;imaging-darkroom;147;148;148;148;148;147;148;148;148;148 16:10:58 INFO - PID 8573 | |7;imaging-desaturate;94;94;93;95;94;94;96;94;94;94 16:10:58 INFO - PID 8573 | |8;json-parse-financial;54;54;54;54;58;57;55;54;53;55 16:10:58 INFO - PID 8573 | |9;json-stringify-tinderbox;44;39;38;38;38;38;38;38;39;38 16:10:58 INFO - PID 8573 | |10;stanford-crypto-aes;75;76;75;75;77;75;77;74;75;79 16:10:58 INFO - PID 8573 | |11;stanford-crypto-ccm;131;123;130;119;122;129;119;124;119;162 16:10:58 INFO - PID 8573 | |12;stanford-crypto-pbkdf2;175;172;175;171;171;171;176;174;175;174 16:10:58 INFO - PID 8573 | |13;stanford-crypto-sha256-iterative;65;64;66;73;64;66;67;64;63;65 16:10:58 INFO - PID 8573 | __end_tp_report 16:10:58 INFO - PID 8573 | __start_cc_report 16:10:58 INFO - PID 8573 | _x_x_mozilla_cycle_collect,938 16:10:58 INFO - PID 8573 | __end_cc_report 16:10:58 INFO - PID 8573 | __startTimestamp1504307458132__endTimestamp 16:10:58 INFO - PID 8573 | 16:10:58 INFO - PID 8573 | ------- Summary: start ------- 16:10:58 INFO - PID 8573 | Number of tests: 14 16:10:58 INFO - PID 8573 | 16:10:58 INFO - PID 8573 | [#0] ai-astar Cycles:10 Average:105.60 Median:111.50 stddev:5.34 (4.8%) stddev-sans-first:4.90 16:10:58 INFO - PID 8573 | Values: 98.0 107.0 99.0 106.0 107.0 112.0 111.0 98.0 109.0 109.0 16:10:58 INFO - PID 8573 | 16:10:58 INFO - PID 8573 | [#1] audio-beat-detection Cycles:10 Average:138.20 Median:142.00 stddev:22.33 (15.7%) stddev-sans-first:23.59 16:10:58 INFO - PID 8573 | Values: 144.0 115.0 138.0 169.0 114.0 148.0 140.0 119.0 177.0 118.0 16:10:58 INFO - PID 8573 | 16:10:58 INFO - PID 8573 | [#2] audio-dft Cycles:10 Average:163.60 Median:164.00 stddev:2.88 (1.8%) stddev-sans-first:3.00 16:10:58 INFO - PID 8573 | Values: 165.0 164.0 161.0 161.0 162.0 166.0 161.0 170.0 164.0 162.0 16:10:58 INFO - PID 8573 | 16:10:58 INFO - PID 8573 | [#3] audio-fft Cycles:10 Average:92.80 Median:74.50 stddev:38.20 (51.3%) stddev-sans-first:39.91 16:10:58 INFO - PID 8573 | Values: 74.0 76.0 74.0 172.0 75.0 75.0 74.0 74.0 158.0 76.0 16:10:58 INFO - PID 8573 | 16:10:58 INFO - PID 8573 | [#4] audio-oscillator Cycles:10 Average:95.10 Median:79.00 stddev:25.80 (32.7%) stddev-sans-first:26.61 16:10:58 INFO - PID 8573 | Values: 78.0 79.0 136.0 80.0 80.0 80.0 134.0 79.0 78.0 127.0 16:10:58 INFO - PID 8573 | 16:10:58 INFO - PID 8573 | [#5] imaging-gaussian-blur Cycles:10 Average:114.40 Median:114.00 stddev:0.70 (0.6%) stddev-sans-first:0.73 16:10:58 INFO - PID 8573 | Values: 114.0 114.0 114.0 114.0 115.0 116.0 114.0 114.0 115.0 114.0 16:10:58 INFO - PID 8573 | 16:10:58 INFO - PID 8573 | [#6] imaging-darkroom Cycles:10 Average:147.80 Median:148.00 stddev:0.42 (0.3%) stddev-sans-first:0.33 16:10:58 INFO - PID 8573 | Values: 147.0 148.0 148.0 148.0 148.0 147.0 148.0 148.0 148.0 148.0 16:10:58 INFO - PID 8573 | 16:10:58 INFO - PID 8573 | [#7] imaging-desaturate Cycles:10 Average:94.20 Median:94.00 stddev:0.79 (0.8%) stddev-sans-first:0.83 16:10:58 INFO - PID 8573 | Values: 94.0 94.0 93.0 95.0 94.0 94.0 96.0 94.0 94.0 94.0 16:10:58 INFO - PID 8573 | 16:10:58 INFO - PID 8573 | [#8] json-parse-financial Cycles:10 Average:54.80 Median:54.50 stddev:1.55 (2.8%) stddev-sans-first:1.62 16:10:58 INFO - PID 8573 | Values: 54.0 54.0 54.0 54.0 58.0 57.0 55.0 54.0 53.0 55.0 16:10:58 INFO - PID 8573 | 16:10:58 INFO - PID 8573 | [#9] json-stringify-tinderbox Cycles:10 Average:38.80 Median:38.00 stddev:1.87 (4.9%) stddev-sans-first:0.44 16:10:58 INFO - PID 8573 | Values: 44.0 39.0 38.0 38.0 38.0 38.0 38.0 38.0 39.0 38.0 16:10:58 INFO - PID 8573 | 16:10:58 INFO - PID 8573 | [#10] stanford-crypto-aes Cycles:10 Average:75.80 Median:75.50 stddev:1.48 (2.0%) stddev-sans-first:1.54 16:10:58 INFO - PID 8573 | Values: 75.0 76.0 75.0 75.0 77.0 75.0 77.0 74.0 75.0 79.0 16:10:58 INFO - PID 8573 | 16:10:58 INFO - PID 8573 | [#11] stanford-crypto-ccm Cycles:10 Average:127.80 Median:126.50 stddev:12.87 (10.2%) stddev-sans-first:13.59 16:10:58 INFO - PID 8573 | Values: 131.0 123.0 130.0 119.0 122.0 129.0 119.0 124.0 119.0 162.0 16:10:58 INFO - PID 8573 | 16:10:58 INFO - PID 8573 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.40 Median:174.50 stddev:1.96 (1.1%) stddev-sans-first:1.99 16:10:58 INFO - PID 8573 | Values: 175.0 172.0 175.0 171.0 171.0 171.0 176.0 174.0 175.0 174.0 16:10:58 INFO - PID 8573 | 16:10:58 INFO - PID 8573 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.70 Median:65.50 stddev:2.83 (4.3%) stddev-sans-first:2.99 16:10:58 INFO - PID 8573 | Values: 65.0 64.0 66.0 73.0 64.0 66.0 67.0 64.0 63.0 65.0 16:10:58 INFO - PID 8573 | -------- Summary: end -------- 16:10:58 INFO - PID 8573 | 16:10:58 INFO - PID 8573 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 16:10:58 INFO - TEST-INFO | 8573: exit 0 16:10:58 INFO - TEST-OK | kraken | took 61286ms 16:10:58 INFO - SUITE-END | took 1172s 16:10:59 INFO - Completed test suite (00:19:33) 16:10:59 INFO - javascript benchmark 16:10:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8585.359870963088, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3264.0860559043463, 3294.094124115866, 3313.0557791029555, 3313.9337213255735, 3349.933001339973, 6403.13557669715, 6405.5516113872045, 6445.1943507061615, 6454.612723236609, 6479.125318082058, 6315.684215789215, 6416.903746443807, 6436.098946147543, 6443.29123796382, 6450.483961283097, 5910.645361278315, 5913.438223368795, 5964.612300200484, 5999.460048595647, 6041.939580604204, 5857.3849745776715, 5946.078357854518, 5957.672328021957, 5986.700664966756, 6054.666993315366, 5711.942880571182, 5775.942240577582, 5780.595358324919, 5788.42115788422, 5809.651420914759, 5824.155497452888, 5835.445632664932, 5843.2695913010875, 5854.063349864044, 5953.58324917256, 5855.736491857877, 5886.146508756249, 5920.437558431942, 5949.851253718648, 5956.374580669054, 6384.840378990516, 6427.517936154761, 6481.48148148147, 6500.902486462707, 6556.9016464752585, 6728.39444449997, 6848.178218613748, 6927.168739751262, 7009.859802803915, 7012.0183174355125, 7058.470614703918, 7067.0, 7070.717171313193, 7073.257307982639, 7077.07997960267, 6444.838879028015, 6526.738930442777, 6542.705578248942, 6550.836229094215, 6573.375529324707, 7172.49792514519, 7211.567305961554, 7258.891116633255, 7265.491415600963, 7276.199618042011, 6543.312952139957, 6675.632840193739, 6676.165479315086, 6849.794506164823, 6932.930670693228, 6849.0, 6867.690953907087, 6891.0, 6892.965535172292, 6902.309769023058, 6850.965745171242, 6873.243943166248, 6879.2432832388395, 6888.0, 6921.757738479129, 6411.166548348685, 6903.447724182053, 7012.9298707013295, 7017.964910175417, 7020.7893763187185, 5457.754401051962, 5510.448955104457, 5528.917066244009, 5534.501894829405, 5538.196961440569, 4777.4267087949665, 4816.783244753994, 4835.226363781778, 4836.008618233253, 4843.467218605951, 5884.941150588525, 6010.2186715726675, 6026.246719160105, 6045.494876993327, 6120.418560236771, 6924.688389022506, 6931.133608298963, 6942.271061538466, 6956.5478243913985, 6958.373746362851, 5961.225040744675, 5972.820815375546, 5975.760969561169, 5978.491828194652, 6018.398160183947, 5560.099740733632, 5955.880882382328, 5968.403159684084, 5980.641161530322, 6070.544709146831, 6740.898886516707, 6743.932560674429, 6811.489138314646, 6845.383915447534, 6868.862622747617, 6813.04617353561, 6947.548409353376, 6978.267281935527, 6979.0, 7092.184398793998, 6530.151080359523, 6571.507136964651, 6574.934250657432, 6582.0, 6591.736330546724, 6734.0, 6736.292689267556, 6773.221079575912, 6841.555298905555, 6860.965695171492, 6875.140607424072, 6893.276205998499, 6930.549514281555, 6936.0, 7082.256363081802, 6723.966380168068, 6782.898256526058, 6784.59292442455, 6790.932090679227, 6802.217744959195, 6689.899651505329, 6707.966460167863, 6717.193936727624, 6801.455883529206, 6813.727450901908, 6349.936500634934, 6529.575577587441, 6554.180727409074, 6602.306757790363, 6720.529562930548, 6574.967125164535, 6631.966840165961, 6676.599404035774, 6690.698918548775, 6691.397774200345, 5634.887302254014, 6588.604683718992, 6614.603123812587, 6686.53194276396, 6688.565243259172], "value": 6296.750817538272, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [800.76777734457, 834.1158372125551, 852.4033176776258, 885.2033170146867, 887.2236792806299, 38937.61062389375, 39482.60517394832, 39616.801915990414, 39640.0, 39746.60253397465, 36502.08744781382, 36917.0, 36917.81541092297, 36978.260434791286, 37012.44481332782, 6846.418054465376, 6931.965340173292, 6949.166100067999, 6959.44324454045, 6967.37293643572, 6883.690233939485, 6964.686589103477, 6965.23382427935, 6966.02475653409, 6966.721331146748, 7428.33145016951, 7614.428917831157, 7622.085349758009, 7624.6568904399155, 7625.123110842269, 7511.098668159829, 7548.8112797179965, 7557.395408367317, 7557.395408367317, 7577.621118944058, 7227.494075414723, 7391.815204619874, 7430.331270185709, 7431.0, 7434.1822399536, 36248.63751362506, 36532.634673653454, 36550.17249137533, 36600.45099323512, 36613.81693091544, 3517.8768743094124, 3548.627394123606, 3565.358235517606, 3566.7681600696133, 3573.0352804742834, 29719.702802972126, 29730.40539189226, 29742.85128574365, 29838.55242171369, 29889.0, 5108.310378098946, 5112.821051263188, 5124.359455068116, 5134.486551344836, 5167.173252279617, 182541.0, 184924.0, 187686.0, 193504.0, 198319.0084049585, 45967.31049034267, 46464.3030354545, 46693.533064669595, 46750.29874551884, 46801.765991170505, 48808.26787598189, 48839.5116048835, 48848.26727599089, 48859.267110993365, 48903.0, 14755.778663319845, 14817.0, 14893.255337233095, 14942.477013304482, 15019.098854068572, 12180.269183848997, 12186.08604354677, 12198.75602487963, 12199.878001220051, 12207.0, 13938.721225575431, 13967.441302347996, 13995.930020349833, 14044.508442204473, 14160.150390976369, 5683.914741278884, 5708.94477987924, 5709.457601527848, 5777.537796976232, 5810.099434587646, 1019.3781793106199, 1019.7246743379246, 1029.120102312527, 1031.6234574380446, 1037.7457522906784, 22518.549629007328, 22759.431014224447, 22799.544009120058, 22947.196848110238, 23194.420139496648, 1106.3417266726285, 1117.1174771930084, 1124.3478782306406, 1131.932084074958, 1136.6874109619755, 11990.9208171265, 12163.452644631012, 12189.81932678608, 12236.571719989757, 12257.658539508797, 219917.0, 219953.0, 220009.0, 220773.0, 220939.0, 49917.0, 50239.24641130386, 50333.496665033614, 50377.49622503728, 50382.74808625934, 1453.1862157192006, 1466.0, 1469.1478942213491, 1470.8749756270622, 1473.395907677847, 21767.673484898056, 21821.23625673094, 22781.658275125556, 27114.0, 27240.455190896468, 47158.0, 47431.288530672755, 47533.0, 47564.0, 47776.5222347786, 195427.0, 196463.0, 198632.0, 198766.0, 199988.0, 27779.30551736142, 33062.5040624386, 34410.13974650654, 34538.481922770676, 34602.0, 41807.581924181584, 43190.35214471848, 43640.56359436365, 43738.0, 44064.33903491386, 32218.67781322157, 32274.19314517062, 32381.676183238807, 32448.188795279362, 32873.0, 31283.687163128987, 31722.36555268928, 31813.0, 32117.51823722599, 32837.17907052343, 36066.0, 36125.27749445049, 36360.63639363572, 36373.45439818458, 36478.0, 30807.69192308048, 30934.69065309318, 31303.53044704377, 32143.839280803448, 32185.35629287448, 23786.16748413797, 29725.851370743872, 30102.24744381408, 30377.69622303749, 30857.537136943414], "value": 16961.353347005985, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [564.9858753531162, 567.701956472852, 567.9999999999998, 578.2482772395886, 591.6095377051146, 21696.457588560294, 22387.664185037218, 22413.0, 22471.101155953744, 22788.430289242737, 25831.741682583168, 26368.868155659242, 26392.208233752972, 26428.47143057138, 26493.602595961074, 6536.803895883119, 6669.766558170465, 6691.966540167292, 6693.866122677544, 6696.899546506782, 6447.0, 6528.608283503005, 6604.537682362236, 6725.66371681414, 6777.491688123386, 6730.4279136273235, 6734.1245638066985, 6737.326267373248, 6751.527393082486, 6757.155355580552, 6710.027046078316, 6725.764598239087, 6738.52830301879, 6741.730330786762, 6741.764038258638, 6245.313015568284, 6246.187995560594, 6251.3123556408755, 6263.655498947602, 6280.214973128359, 28755.0, 29349.413011739645, 29927.0, 30190.0, 30404.0, 2048.774634790172, 2088.2169186555043, 2091.0381224636685, 2091.215794077221, 2096.2139197800825, 19305.42083737476, 19860.10629521675, 19880.602387952305, 19880.70178947317, 19960.3013894513, 2390.7609239075955, 2441.816863735227, 2491.95016099677, 2548.5540030494776, 2557.105013245368, 122072.38963805213, 124211.3789431056, 125491.37254313761, 127049.36475317564, 127364.0, 14160.787588186186, 14810.555683329518, 14831.146106736658, 15035.42128076558, 15232.695346093016, 22732.31803045911, 22743.203987860346, 22748.20381286647, 22751.08995640189, 22781.202657906895, 9878.209743220525, 10129.544170512345, 10134.797304053878, 10147.543360548792, 10187.185025197967, 7678.61606919663, 9229.0, 9291.72124836256, 9361.157495825408, 9365.48489833052, 7896.605169741605, 9153.221976132103, 9193.402428842104, 9341.90658093424, 9377.578008989612, 2726.5092283388785, 2751.6285301484045, 2755.7678280592786, 2762.9171124865884, 2838.929026774306, 973.7176218896583, 989.9851502227473, 990.9950450247702, 995.9153471954965, 999.980000399988, 19356.612867742566, 19423.223071076998, 19434.319798807257, 19912.0, 19982.800171998384, 1040.5681642118586, 1041.8697662792151, 1068.423051552153, 1071.9517621707041, 1072.5012869015945, 10724.517396717167, 10945.398003109905, 10998.175136864767, 11075.0, 11092.500837462172, 440293.0, 448393.0, 454918.0, 456814.0, 459621.0, 108843.0, 110822.0, 110845.0, 113136.0, 113691.43154284176, 1157.5659127826896, 1169.5321871251397, 1192.3978390912534, 1193.1886317304316, 1197.4910662968205, 17321.74017389715, 17505.649887002444, 17818.91090544539, 17886.731699024785, 17893.463196104138, 53826.461735382145, 54604.726976364866, 54973.725131374085, 56134.71932640474, 57253.427465726476, 66357.0, 67160.6641966787, 68573.65713171402, 68643.65678171578, 69084.6545767268, 11778.234414763014, 13473.797893031417, 13728.519501817389, 14249.50126745559, 14524.782128268296, 22240.77759222387, 22360.664590030836, 22500.77499224987, 22530.436739081655, 22646.773532264466, 17613.82386176122, 17700.203490842687, 17768.378106766202, 17830.197641106442, 17833.554161146076, 15920.681586367977, 16320.18399080065, 16380.099094549438, 16633.50099497017, 16996.215397383097, 18049.368272110412, 18224.36214732478, 18241.45275641733, 18331.0, 18376.540586485444, 14022.649433764238, 14030.368633411317, 14094.083884547472, 14133.787993179905, 14138.929305353407, 17719.379821706178, 17828.91085544564, 17836.643267134845, 17867.28530858751, 17917.910410448385], "value": 11848.08968427889, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1304.654266619346, 1370.9245991470466, 1371.2389623758816, 1379.3448112146732, 1383.7094210215855, 14694.191819449927, 15013.174275414847, 15344.539663810076, 15363.078215307076, 15364.539063828075, 15756.606084847885, 16033.038017718945, 16078.437254696088, 16108.838911610881, 16117.113558754263, 2833.178378270304, 2875.9712402876016, 2876.525373313401, 2877.4101309231555, 2878.5106531889523, 2739.575365818301, 2922.517784565544, 2927.780416468773, 2934.6038284831593, 2939.4121175764826, 2751.408447183855, 2800.495910736067, 2804.887804487818, 2827.8303301801857, 2828.900988465404, 2773.5700966350246, 2790.6651201855807, 2800.5519116941296, 2804.4110736745306, 2808.382155925693, 2713.2538551898188, 2713.7557619814115, 2725.5639097744465, 2726.5228584997744, 2728.8499132547704, 15109.773353399707, 15225.0, 15234.847651523565, 15245.923770381189, 15261.694766104616, 7953.840923181562, 8130.065042520127, 8179.0, 8194.139615340364, 8240.62917168729, 5204.557612602934, 5383.300170977787, 5420.186971954238, 5531.7234138293325, 5539.9169012464845, 1922.3367938061347, 1930.806919308072, 1945.805419458057, 1952.0532541717255, 1973.4770285874256, 26923.46153076947, 27092.864535677392, 27190.592141118097, 27296.727032729617, 27342.179734607995, 6713.295104014106, 6939.375456208965, 6966.9651651741415, 7087.149542054884, 7163.140423149255, 17276.13619319029, 17370.739438908426, 17436.215370308368, 17439.73840392395, 17446.912765436093, 7925.960370198112, 8161.387895907831, 8165.183481651788, 8166.469179503432, 8166.632501537445, 7442.776716698508, 7559.470837041465, 7592.8861067084035, 7621.809454763676, 7625.504342217738, 5155.871103222375, 5231.843044708665, 5277.577793776565, 5308.416074231832, 5346.43862394443, 1529.4188208480928, 1537.2390666620095, 1541.46819347325, 1552.4566401759519, 1575.039226072095, 850.5067061104545, 880.1374652840252, 888.9955550222207, 900.3652425040331, 901.8466860633708, 7430.591317477591, 7439.590822504814, 7468.5145465544565, 7473.177950425449, 7491.438142139361, 698.8252936765808, 988.8714467119229, 1025.733309339562, 1030.8247597908373, 1035.1304903880775, 5288.7355632219005, 5331.706756128373, 5503.944960550343, 5738.713064346766, 5850.3564607893095, 22057.558848822933, 22328.106875725112, 22392.10431582751, 22570.0, 22595.43511412195, 21108.683369749466, 22037.0, 22302.776972230393, 22314.55370892573, 22352.21767238139, 11389.886101138882, 11611.361375124281, 11619.99999999983, 11676.70808229801, 11712.121590880719, 8126.228008339198, 8914.0, 8966.820663586823, 9200.447973121634, 9261.490618015805, 18691.158897849902, 18731.37760713474, 18789.0, 19076.61846763029, 19098.809011909703, 19325.0, 19404.320848770225, 19516.90241548783, 19539.12073956704, 19581.7140468643, 13765.0, 14000.43998240059, 14100.717985640435, 14113.153210807384, 14118.364673589507, 14079.859201407855, 14127.64680882986, 14201.644958876112, 14270.928645356707, 14280.357383917957, 11197.72005699864, 11229.88770112321, 11246.887531124912, 11391.715207119889, 11488.655340339803, 11420.714482138013, 11559.537618495166, 11571.074314054687, 11761.764764704487, 11839.0, 9407.764805879908, 9457.148856602938, 9574.808503829745, 9634.807303854024, 9723.02769723022, 6722.193336799615, 6737.427318677967, 6774.86450270982, 6812.114425124702, 6826.146731658543, 6745.831354216184, 6802.693878775566, 6809.489288303298, 6856.9314306856295, 6894.896576551456], "value": 6804.110460664823, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [508.43402320851783, 520.5315216305326, 526.1397614899638, 529.8542900702306, 541.5748637319703, 6500.28496865345, 6531.869362612757, 6545.476361891055, 6547.821392149412, 6550.705218265178, 6375.203099612549, 6388.872222555546, 6409.775657851976, 6498.675066246692, 6513.218413790351, 5323.1749078892835, 5341.706206158659, 5346.358436987567, 5347.518723314901, 5360.16917377807, 4842.685225460352, 5025.522575355335, 5243.79024839006, 5249.790008399678, 5306.7346632668405, 4984.57631101355, 5075.111855425304, 5082.847514574569, 5093.134167191568, 5137.280780690688, 5082.440931497532, 5093.185090385539, 5103.846884593431, 5104.566111880457, 5159.329287192679, 4372.475302963665, 4467.776611169428, 4522.751248681321, 4567.177907976563, 4573.931391029138, 6207.81376558704, 6344.238691357021, 6355.841103972438, 6359.8092057238355, 6362.427381535684, 5740.650947027445, 5763.6541807491685, 5769.855753606152, 5819.0689489681445, 5826.912596311059, 6614.570052946543, 6641.734330626769, 6690.665466726644, 6765.019072234548, 6770.289119642465, 4938.555530002317, 4973.303737476756, 4992.976439829826, 4999.925001124986, 5009.499050094962, 6764.797056088325, 6903.447724182053, 6985.6507174641065, 6987.7204911803465, 7084.0164753869, 5102.642815002914, 5114.360704911886, 5114.616403769732, 5137.0753264412015, 5137.765072193608, 5182.4299327073995, 5195.402528709171, 5198.451355064319, 5215.217717342429, 5216.686998780086, 5808.564357673192, 5813.244278243886, 5816.0, 5816.621919575214, 5838.737256823453, 6748.595084294958, 6776.6950487227205, 6779.220389655155, 6786.355296246848, 6819.5908245505425, 1794.3091909614632, 1804.7653805005266, 1810.9637807243782, 1811.2664370929795, 1813.6463389639157, 1171.1509155862102, 1230.0467137967967, 1250.5435516036764, 1251.224240970586, 1256.221142891395, 5796.0, 5829.446202610745, 5869.8239052828485, 5882.176495290662, 5991.580589358703, 6710.328967103251, 6821.624810635462, 6853.0, 6894.551854129466, 6930.8613827724175, 5870.178175055496, 5878.794242201588, 5935.732892019783, 5941.574022234632, 5950.553708471882, 3603.3513967486106, 3646.835892384849, 3654.780713157166, 3678.4482327651067, 3710.0910276982313, 6442.355764423613, 6502.902456463157, 6603.0, 6629.326324940905, 6777.423918966844, 6856.314368563204, 7002.75490357835, 7103.0, 7116.608586527687, 7155.821104472429, 6629.668516574248, 6662.56693314933, 6673.0, 6707.765228217087, 6727.831804204934, 6844.589324640538, 6876.209235937931, 6947.687354068978, 6959.130108736408, 7015.789526314219, 6829.8634027318185, 6843.452523798183, 6873.0, 6909.723611055501, 6930.306969303029, 6665.600063996176, 6685.799426017227, 6732.629705366252, 6772.11962444879, 6772.5936443813525, 5105.744712764421, 5117.0342984187555, 5128.153854613947, 5163.225516172604, 5166.147585648477, 6604.438622717123, 6725.730970761115, 6914.308569143247, 6949.861002780017, 7029.824254393681, 6576.901346479711, 6660.200775906922, 6681.33186681328, 6694.732210711517, 6768.492363072691, 6603.339666033358, 6643.302453242533, 6645.966770166118, 6657.1345725055435, 6667.499937504804, 6407.423331900151, 6596.901046484402, 6668.633225172662, 6678.899816502853, 6682.565633233824, 6706.362895524917, 6716.294789047275, 6762.734483947933, 6795.3204679531655, 6861.4510839133745, 6529.477641788741, 6538.201994451564, 6566.737330506918, 6629.834254143685, 6635.0], "value": 5190.203251272246, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [885.3802338363147, 887.8712586674932, 904.8597467392557, 906.0893801729261, 910.5265262063729, 18416.171272292744, 19504.0, 19528.41414757558, 19587.0, 19607.019649017544, 15964.920175399136, 16058.518244452656, 16414.671706565892, 19649.0, 19777.0, 937.2689302344165, 950.686273529737, 953.5565961827749, 954.4225743425231, 956.4596003258172, 1001.8597396364515, 1006.0543089495887, 1007.0332480818387, 1007.2294694558632, 1007.5717819926539, 924.685606893656, 975.8438649816031, 1034.0125180452649, 1037.9532921018535, 1038.1435315864405, 1031.5512751952924, 1031.9019693129142, 1033.0186322993186, 1033.261218228966, 1033.333499892565, 750.740994356946, 751.2600088912417, 751.3200553499038, 751.6504825256283, 751.8158051277472, 18726.250949961985, 18841.717374239397, 19062.0, 19273.61452770951, 20240.696389554167, 20776.48058798527, 20941.26705565313, 21042.158313667434, 21400.14399424021, 21424.92875356226, 15663.373465061384, 15752.842471575366, 15770.526884193492, 15778.684426311524, 15815.446459373981, 3683.0608194910396, 3697.3899306614376, 3697.704183665327, 3698.9630103698887, 3699.445083237509, 117965.0, 124078.0, 124767.0, 125037.37481312627, 126275.0, 15702.68594628101, 16022.679546409006, 16076.196190190678, 16152.030878147114, 16164.595885102966, 29918.55122173169, 30061.849690751405, 30710.0, 30765.0, 30769.538456923165, 8415.116412776693, 8451.577421129043, 8466.492010479391, 8509.531975741394, 8510.191531804592, 3939.3106206413763, 4055.858044968471, 4062.6749860011128, 4063.0248740302113, 4078.4290199372113, 5008.4240312364545, 5034.899302014012, 5082.440931497532, 5125.923111153335, 5133.8716532087, 992.4392718114386, 1003.6738060130486, 1027.1474676018886, 1029.6602121299952, 1031.2781053262745, 638.9904151437732, 654.3750718064238, 660.6993817812884, 664.1034603285623, 674.2515807453723, 11121.499532521057, 11198.832017519744, 11217.60738374153, 11788.23376480526, 13682.863171368357, 695.8538706871614, 714.3963350968423, 718.5150023733959, 721.2354903801987, 721.5670597641373, 5497.285352904177, 5547.528460080857, 5801.651900885876, 5901.812493149613, 5913.615614985012, 105632.0, 106219.0, 106617.0, 107836.46081769698, 109990.0, 94788.52605737065, 95042.524787377, 96520.0, 97392.51303743436, 98988.50505747425, 73285.26714732891, 82338.0, 84439.5778021106, 87398.56300718583, 87652.5617371909, 12691.873081269068, 12920.935395322964, 13043.804342935053, 13097.148685335424, 13126.934365328494, 38068.61931380651, 39467.40798887962, 39518.60481395264, 39536.0, 41586.168276634904, 49349.506504935925, 50342.0, 50563.49436505588, 50711.0, 51344.74327628338, 17628.73556896673, 17766.733498997783, 18048.097595120453, 18062.277508900024, 18164.0, 21586.35240942774, 22037.889810551485, 22279.331620051424, 22619.208327708453, 22815.201467948544, 18903.338383156522, 19125.808741912402, 19192.904035479733, 19838.40484785459, 19842.702359464332, 17264.741028884324, 17338.219780109674, 17339.653206935538, 17456.389026384524, 18799.90600046991, 18609.720854186926, 19003.714944276122, 19270.90364548168, 19297.903510482356, 19587.51031224231, 12097.2136811107, 12216.633500994983, 12509.249445033327, 12568.183068100545, 13023.153495022792, 14357.784633230718, 14374.496892609126, 14383.311760031636, 14394.784078239045, 14521.186061094922], "value": 8960.789572435275, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1488.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 107.0, 99.0, 106.0, 107.0, 112.0, 111.0, 98.0, 109.0, 109.0], "value": 105.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 115.0, 138.0, 169.0, 114.0, 148.0, 140.0, 119.0, 177.0, 118.0], "value": 138.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 164.0, 161.0, 161.0, 162.0, 166.0, 161.0, 170.0, 164.0, 162.0], "value": 163.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 74.0, 172.0, 75.0, 75.0, 74.0, 74.0, 158.0, 76.0], "value": 92.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 79.0, 136.0, 80.0, 80.0, 80.0, 134.0, 79.0, 78.0, 127.0], "value": 95.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 114.0, 114.0, 115.0, 116.0, 114.0, 114.0, 115.0, 114.0], "value": 114.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 148.0, 148.0, 148.0, 148.0, 147.0, 148.0, 148.0, 148.0, 148.0], "value": 147.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 93.0, 95.0, 94.0, 94.0, 96.0, 94.0, 94.0, 94.0], "value": 94.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 54.0, 54.0, 58.0, 57.0, 55.0, 54.0, 53.0, 55.0], "value": 54.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [44.0, 39.0, 38.0, 38.0, 38.0, 38.0, 38.0, 38.0, 39.0, 38.0], "value": 38.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 75.0, 77.0, 75.0, 77.0, 74.0, 75.0, 79.0], "value": 75.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 123.0, 130.0, 119.0, 122.0, 129.0, 119.0, 124.0, 119.0, 162.0], "value": 127.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 172.0, 175.0, 171.0, 171.0, 171.0, 176.0, 174.0, 175.0, 174.0], "value": 173.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 66.0, 73.0, 64.0, 66.0, 67.0, 64.0, 63.0, 65.0], "value": 65.7, "unit": "score"}]}]} 16:10:59 INFO - Return code: 0 16:10:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:10:59 INFO - # TBPL SUCCESS # 16:10:59 INFO - Running post-action listener: _package_coverage_data 16:10:59 INFO - Running post-action listener: _resource_record_post_action 16:10:59 INFO - [mozharness: 2017-09-01 23:10:59.429944Z] Finished run-tests step (success) 16:10:59 INFO - Running post-run listener: _resource_record_post_run 16:10:59 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 4096; Write bytes: 402636800; Read time: 16; Write time: 269616 16:10:59 INFO - TinderboxPrint: CPU usage
13.1% 16:10:59 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 16:10:59 INFO - TinderboxPrint: I/O write bytes / time
402,636,800 / 269,616 16:10:59 INFO - TinderboxPrint: CPU idle
8,243.8 (86.9%) 16:10:59 INFO - TinderboxPrint: CPU user
1,192.8 (12.6%) 16:10:59 INFO - TinderboxPrint: Swap in / out
0 / 0 16:10:59 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 116756480; Read time: 0; Write time: 84804 16:10:59 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 16:10:59 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 4096; Write bytes: 285880320; Read time: 16; Write time: 184812 16:11:00 INFO - Running post-run listener: _upload_blobber_files 16:11:00 INFO - Blob upload gear active. 16:11:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:11:00 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:11:00 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'] 16:11:00 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 16:11:00 INFO - (blobuploader) - INFO - Open directory for files ... 16:11:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:11:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:11:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:11:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:11:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:11:01 INFO - (blobuploader) - INFO - Done attempting. 16:11:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:11:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:11:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:11:02 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:11:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:11:02 INFO - (blobuploader) - INFO - Done attempting. 16:11:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:11:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:11:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:11:12 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:11:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:11:12 INFO - (blobuploader) - INFO - Done attempting. 16:11:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:11:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:11:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:11:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:11:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:11:13 INFO - (blobuploader) - INFO - Done attempting. 16:11:13 INFO - (blobuploader) - INFO - Iteration through files over. 16:11:13 INFO - Return code: 0 16:11:13 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:11:13 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:11:13 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d37db19f4aef21d84682035a75e36477cd58ae6ce54ee7ca39704866fd664322c8d1967137621edeadb85bb74a78b21edab0e8730548bd0827c0477293fa45e1", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f9eb5dacf520573777889aea600e4aaa988be6a72d21a194d837b44a566a4a24450153066dcc725d0778a17e0a256b1059ffe149255a1487710dec2e9cae8b28", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/32dbf8fa9526b8da0d2971247d88ae4c1e8813bb2ee89d87a7dc69f6deedfc8699bcfdf24ffa306469a02fae6da208164dc5442132527603f874c99382852be2", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0ef4fd637d5fcea19f96c69612605d0df0829993c54a976d835e9218907411a85f0ce1263bc9ffa6a018f238a1b3a098d6cbdc1a7371b0496ccf2f5dd643f37e"} 16:11:13 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:11:13 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:11:13 INFO - Contents: 16:11:13 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d37db19f4aef21d84682035a75e36477cd58ae6ce54ee7ca39704866fd664322c8d1967137621edeadb85bb74a78b21edab0e8730548bd0827c0477293fa45e1", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f9eb5dacf520573777889aea600e4aaa988be6a72d21a194d837b44a566a4a24450153066dcc725d0778a17e0a256b1059ffe149255a1487710dec2e9cae8b28", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/32dbf8fa9526b8da0d2971247d88ae4c1e8813bb2ee89d87a7dc69f6deedfc8699bcfdf24ffa306469a02fae6da208164dc5442132527603f874c99382852be2", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0ef4fd637d5fcea19f96c69612605d0df0829993c54a976d835e9218907411a85f0ce1263bc9ffa6a018f238a1b3a098d6cbdc1a7371b0496ccf2f5dd643f37e"} 16:11:13 INFO - Running post-run listener: copy_logs_to_upload_dir 16:11:13 INFO - Copying logs to upload dir... 16:11:13 INFO - mkdir: /builds/slave/test/build/upload/logs 16:11:13 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1256.155159 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2017-09-01 16:11:13.919033) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-01 16:11:13.922475) ========= 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=ca619d3d7d62c99d832f7b8a000001c4-1504306215.232531-1880746267 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d37db19f4aef21d84682035a75e36477cd58ae6ce54ee7ca39704866fd664322c8d1967137621edeadb85bb74a78b21edab0e8730548bd0827c0477293fa45e1", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f9eb5dacf520573777889aea600e4aaa988be6a72d21a194d837b44a566a4a24450153066dcc725d0778a17e0a256b1059ffe149255a1487710dec2e9cae8b28", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/32dbf8fa9526b8da0d2971247d88ae4c1e8813bb2ee89d87a7dc69f6deedfc8699bcfdf24ffa306469a02fae6da208164dc5442132527603f874c99382852be2", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0ef4fd637d5fcea19f96c69612605d0df0829993c54a976d835e9218907411a85f0ce1263bc9ffa6a018f238a1b3a098d6cbdc1a7371b0496ccf2f5dd643f37e"} build_url:https://queue.taskcluster.net/v1/task/B3zBBhhZS1Sz7X0NYgNFnw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011367 build_url: 'https://queue.taskcluster.net/v1/task/B3zBBhhZS1Sz7X0NYgNFnw/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d37db19f4aef21d84682035a75e36477cd58ae6ce54ee7ca39704866fd664322c8d1967137621edeadb85bb74a78b21edab0e8730548bd0827c0477293fa45e1", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f9eb5dacf520573777889aea600e4aaa988be6a72d21a194d837b44a566a4a24450153066dcc725d0778a17e0a256b1059ffe149255a1487710dec2e9cae8b28", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/32dbf8fa9526b8da0d2971247d88ae4c1e8813bb2ee89d87a7dc69f6deedfc8699bcfdf24ffa306469a02fae6da208164dc5442132527603f874c99382852be2", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0ef4fd637d5fcea19f96c69612605d0df0829993c54a976d835e9218907411a85f0ce1263bc9ffa6a018f238a1b3a098d6cbdc1a7371b0496ccf2f5dd643f37e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-01 16:11:13.954936) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 16:11:13.955264) ========= 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=ca619d3d7d62c99d832f7b8a000001c4-1504306215.232531-1880746267 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004523 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 16:11:14.039990) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-01 16:11:14.040334) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-01 16:11:14.040627) ========= ========= Total master_lag: 0.21 =========