builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-039 starttime: 1505654125.79 results: success (0) revision: 877d536658e644bd0d1662d608cc2ca3a9fa8d9f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-17 06:15:25.791723) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-17 06:15:25.792226) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-17 06:15:25.792569) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-17 06:15:26.057493) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-17 06:15:26.057934) ========= 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=5bd038846534b2fdfa68eeae000001e2-1505652212.867325-1465953002 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-17 06:15:26-- 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% 33.2M=0s 2017-09-17 06:15:26 (33.2 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.219969 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-17 06:15:26.334898) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-17 06:15:26.338281) ========= 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=5bd038846534b2fdfa68eeae000001e2-1505652212.867325-1465953002 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015380 ========= master_lag: 0.07 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-17 06:15:26.424195) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 12 secs) (at 2017-09-17 06:15:26.424853) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 877d536658e644bd0d1662d608cc2ca3a9fa8d9f --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 877d536658e644bd0d1662d608cc2ca3a9fa8d9f --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=5bd038846534b2fdfa68eeae000001e2-1505652212.867325-1465953002 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-17 06:15:26,534 truncating revision to first 12 chars 2017-09-17 06:15:26,534 Setting DEBUG logging. 2017-09-17 06:15:26,534 attempt 1/10 2017-09-17 06:15:26,534 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/877d536658e6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-17 06:15:26,918 attempt 1/10 2017-09-17 06:15:27,505 current task status: no status available at this point. state: STARTED 2017-09-17 06:15:27,505 sleeping for 11.00s (attempt 1/10) 2017-09-17 06:15:38,516 attempt 2/10 2017-09-17 06:15:38,886 current task status: Task completed! Check 's3_urls' for upload locations. state: SUCCESS 2017-09-17 06:15:39,053 unpacking tar archive at: mozilla-central-877d536658e6/testing/mozharness/ program finished with exit code 0 elapsedTime=12.858190 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 12 secs) (at 2017-09-17 06:15:39.322234) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-17 06:15:39.322562) ========= script_repo_revision: 877d536658e644bd0d1662d608cc2ca3a9fa8d9f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-17 06:15:39.322969) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-17 06:15:39.323291) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-17 06:15:39.336627) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2017-09-17 06:15:39.336964) ========= /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=5bd038846534b2fdfa68eeae000001e2-1505652212.867325-1465953002 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 06:15:39 INFO - MultiFileLogger online at 20170917 06:15:39 in /builds/slave/test 06:15:39 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 06:15:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:15:39 INFO - {'append_to_log': False, 06:15:39 INFO - 'base_work_dir': '/builds/slave/test', 06:15:39 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 06:15:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:15:39 INFO - 'branch': 'Firefox-Non-PGO', 06:15:39 INFO - 'buildbot_json_path': 'buildprops.json', 06:15:39 INFO - 'code_coverage': False, 06:15:39 INFO - 'config_files': ('talos/linux_config.py',), 06:15:39 INFO - 'default_actions': ('clobber', 06:15:39 INFO - 'read-buildbot-config', 06:15:39 INFO - 'download-and-extract', 06:15:39 INFO - 'populate-webroot', 06:15:39 INFO - 'create-virtualenv', 06:15:39 INFO - 'install', 06:15:39 INFO - 'setup-mitmproxy', 06:15:39 INFO - 'run-tests'), 06:15:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:15:39 INFO - 'disable_ccov_upload': False, 06:15:39 INFO - 'disable_stylo': False, 06:15:39 INFO - 'download_minidump_stackwalk': True, 06:15:39 INFO - 'download_symbols': 'ondemand', 06:15:39 INFO - 'enable_stylo': False, 06:15:39 INFO - 'enable_webrender': False, 06:15:39 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 06:15:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:15:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:15:39 INFO - 'gecko_profile': False, 06:15:39 INFO - 'gecko_profile_interval': 0, 06:15:39 INFO - 'installer_path': 'installer.exe', 06:15:39 INFO - 'log_level': 'info', 06:15:39 INFO - 'log_name': 'talos', 06:15:39 INFO - 'log_to_console': True, 06:15:39 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 06:15:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 06:15:39 INFO - 'opt_config_files': (), 06:15:39 INFO - 'pip_index': False, 06:15:39 INFO - 'suite': 'dromaeojs-e10s', 06:15:39 INFO - 'system_bits': '32', 06:15:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:15:39 INFO - 'title': 'talos-linux64-ix-039', 06:15:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:15:39 INFO - 'use_talos_json': True, 06:15:39 INFO - 'verify': 'False', 06:15:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:15:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:15:39 INFO - 'work_dir': 'build'} 06:15:39 INFO - [mozharness: 2017-09-17 13:15:39.570317Z] Running clobber step. 06:15:39 INFO - Running pre-action listener: _resource_record_pre_action 06:15:39 INFO - Running main action method: clobber 06:15:39 INFO - rmtree: /builds/slave/test/build 06:15:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:15:41 INFO - Running post-action listener: _resource_record_post_action 06:15:41 INFO - [mozharness: 2017-09-17 13:15:41.088396Z] Finished clobber step (success) 06:15:41 INFO - [mozharness: 2017-09-17 13:15:41.088493Z] Running read-buildbot-config step. 06:15:41 INFO - Running pre-action listener: _resource_record_pre_action 06:15:41 INFO - Running main action method: read_buildbot_config 06:15:41 INFO - Using buildbot properties: 06:15:41 INFO - { 06:15:41 INFO - "project": "", 06:15:41 INFO - "product": "firefox", 06:15:41 INFO - "who": "archaeopteryx@coole-files.de", 06:15:41 INFO - "installer_path": "public/build/target.tar.bz2", 06:15:41 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 06:15:41 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 06:15:41 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 06:15:41 INFO - "stage_platform": "linux64", 06:15:41 INFO - "basedir": "/builds/slave/test", 06:15:41 INFO - "buildnumber": 840, 06:15:41 INFO - "platform": "ubuntu64_hw", 06:15:41 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 06:15:41 INFO - "slavebuilddir": "test", 06:15:41 INFO - "taskId": "NDXSeTGPS1WlNO03lWDLLw", 06:15:41 INFO - "branch": "mozilla-central", 06:15:41 INFO - "script_repo_revision": "production", 06:15:41 INFO - "revision": "877d536658e644bd0d1662d608cc2ca3a9fa8d9f", 06:15:41 INFO - "slavename": "talos-linux64-ix-039", 06:15:41 INFO - "repo_path": "mozilla-central" 06:15:41 INFO - } 06:15:41 INFO - Finding installer, test and symbols from parent task. 06:15:41 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NDXSeTGPS1WlNO03lWDLLw'}, attempt #1 06:15:41 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NDXSeTGPS1WlNO03lWDLLw'}, attempt #1 06:15:42 INFO - Task dependencies: Wjb9bWClSh6PQEiCFFxuzg 06:15:42 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Wjb9bWClSh6PQEiCFFxuzg'}, attempt #1 06:15:42 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Wjb9bWClSh6PQEiCFFxuzg/artifacts/public/build/target.tar.bz2 06:15:42 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/Wjb9bWClSh6PQEiCFFxuzg/artifacts/public/build/target.test_packages.json 06:15:42 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Wjb9bWClSh6PQEiCFFxuzg/artifacts/public/build/target.crashreporter-symbols.zip 06:15:42 INFO - Running post-action listener: _resource_record_post_action 06:15:42 INFO - [mozharness: 2017-09-17 13:15:42.477822Z] Finished read-buildbot-config step (success) 06:15:42 INFO - [mozharness: 2017-09-17 13:15:42.478040Z] Running download-and-extract step. 06:15:42 INFO - Running pre-action listener: _resource_record_pre_action 06:15:42 INFO - Running main action method: download_and_extract 06:15:42 INFO - mkdir: /builds/slave/test/build/tests 06:15:42 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')]} 06:15:42 INFO - trying https://queue.taskcluster.net/v1/task/Wjb9bWClSh6PQEiCFFxuzg/artifacts/public/build/target.test_packages.json 06:15:42 INFO - Downloading https://queue.taskcluster.net/v1/task/Wjb9bWClSh6PQEiCFFxuzg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 06:15:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Wjb9bWClSh6PQEiCFFxuzg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 06:15:43 INFO - Downloaded 989 bytes. 06:15:43 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 06:15:43 INFO - Using the following test package requirements: 06:15:43 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 06:15:43 INFO - u'common': [u'target.common.tests.zip'], 06:15:43 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 06:15:43 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 06:15:43 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 06:15:43 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 06:15:43 INFO - u'mozbase': [u'target.common.tests.zip'], 06:15:43 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 06:15:43 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 06:15:43 INFO - u'web-platform': [u'target.common.tests.zip', 06:15:43 INFO - u'target.web-platform.tests.tar.gz'], 06:15:43 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 06:15:43 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 06:15:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Wjb9bWClSh6PQEiCFFxuzg/artifacts/public/build/target.common.tests.zip 06:15:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Wjb9bWClSh6PQEiCFFxuzg/artifacts/public/build/target.common.tests.zip'}, attempt #1 06:15:43 INFO - Fetch https://queue.taskcluster.net/v1/task/Wjb9bWClSh6PQEiCFFxuzg/artifacts/public/build/target.common.tests.zip into memory 06:15:44 INFO - Content-Length response header: 54731270 06:15:44 INFO - Bytes received: 54731270 06:15:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Wjb9bWClSh6PQEiCFFxuzg/artifacts/public/build/target.talos.tests.zip 06:15:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Wjb9bWClSh6PQEiCFFxuzg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 06:15:54 INFO - Fetch https://queue.taskcluster.net/v1/task/Wjb9bWClSh6PQEiCFFxuzg/artifacts/public/build/target.talos.tests.zip into memory 06:15:55 INFO - Content-Length response header: 14508748 06:15:55 INFO - Bytes received: 14508748 06:15: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')]} 06:15:55 INFO - trying https://queue.taskcluster.net/v1/task/Wjb9bWClSh6PQEiCFFxuzg/artifacts/public/build/target.tar.bz2 06:15:55 INFO - Downloading https://queue.taskcluster.net/v1/task/Wjb9bWClSh6PQEiCFFxuzg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 06:15:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Wjb9bWClSh6PQEiCFFxuzg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 06:15:57 INFO - Downloaded 61376949 bytes. 06:15:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Wjb9bWClSh6PQEiCFFxuzg/artifacts/public/build/target.tar.bz2 06:15:57 INFO - mkdir: /builds/slave/test/properties 06:15:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:15:57 INFO - Writing to file /builds/slave/test/properties/build_url 06:15:57 INFO - Contents: 06:15:57 INFO - build_url:https://queue.taskcluster.net/v1/task/Wjb9bWClSh6PQEiCFFxuzg/artifacts/public/build/target.tar.bz2 06:15:57 INFO - Running post-action listener: _resource_record_post_action 06:15:57 INFO - Running post-action listener: find_tests_for_verification 06:15:57 INFO - Running post-action listener: set_extra_try_arguments 06:15:57 INFO - [mozharness: 2017-09-17 13:15:57.772199Z] Finished download-and-extract step (success) 06:15:57 INFO - [mozharness: 2017-09-17 13:15:57.772339Z] Running populate-webroot step. 06:15:57 INFO - Running pre-action listener: _resource_record_pre_action 06:15:57 INFO - Running main action method: populate_webroot 06:15:57 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:15:57 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 06:15:57 INFO - 'tests': ['tresize', 06:15:57 INFO - 'tcanvasmark']}, 06:15:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:15:57 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 06:15:57 INFO - 'tests': ['dromaeo_css', 06:15:57 INFO - 'kraken']}, 06:15:57 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 06:15:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:15:57 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 06:15:57 INFO - 'talos_options': ['--disable-stylo'], 06:15:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:15:57 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 06:15:57 INFO - 'tests': ['damp', 'tps']}, 06:15:57 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 06:15:57 INFO - 'talos_options': ['--disable-stylo'], 06:15:57 INFO - 'tests': ['damp', 'tps']}, 06:15:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:15:57 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 06:15:57 INFO - 'tests': ['dromaeo_dom']}, 06:15:57 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 06:15:57 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 06:15:57 INFO - 'tests': ['basic_compositor_video', 06:15:57 INFO - 'glvideo']}, 06:15:57 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 06:15:57 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 06:15:57 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 06:15:57 INFO - 'talos_options': ['--disable-stylo'], 06:15:57 INFO - 'tests': ['ts_paint_webext', 06:15:57 INFO - 'tp5o_webext']}, 06:15:57 INFO - 'other-e10s': {'tests': ['a11yr', 06:15:57 INFO - 'ts_paint', 06:15:57 INFO - 'tpaint', 06:15:57 INFO - 'sessionrestore', 06:15:57 INFO - 'sessionrestore_many_windows', 06:15:57 INFO - 'sessionrestore_no_auto_restore', 06:15:57 INFO - 'tabpaint']}, 06:15:57 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 06:15:57 INFO - 'tests': ['a11yr', 06:15:57 INFO - 'ts_paint', 06:15:57 INFO - 'tpaint', 06:15:57 INFO - 'sessionrestore', 06:15:57 INFO - 'sessionrestore_many_windows', 06:15:57 INFO - 'sessionrestore_no_auto_restore', 06:15:57 INFO - 'tabpaint']}, 06:15:57 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 06:15:57 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 06:15:57 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 06:15:57 INFO - 'tests': ['perf_reftest_singletons']}, 06:15:57 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 06:15:57 INFO - 'tests': ['bloom_basic']}, 06:15:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:15:57 INFO - 'tsvgr_opacity', 06:15:57 INFO - 'tart', 06:15:57 INFO - 'tscrollx', 06:15:57 INFO - 'tsvg_static']}, 06:15:57 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 06:15:57 INFO - 'tests': ['tsvgx', 06:15:57 INFO - 'tsvgr_opacity', 06:15:57 INFO - 'tart', 06:15:57 INFO - 'tscrollx', 06:15:57 INFO - 'tsvg_static']}, 06:15:57 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 06:15:57 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 06:15:57 INFO - 'talos_options': ['--disable-stylo'], 06:15:57 INFO - 'tests': ['tp5o']}, 06:15:57 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 06:15:57 INFO - 'talos_options': ['--mitmproxy', 06:15:57 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 06:15:57 INFO - '--firstNonBlankPaint'], 06:15:57 INFO - 'tests': ['tp6_google', 06:15:57 INFO - 'tp6_youtube', 06:15:57 INFO - 'tp6_amazon', 06:15:57 INFO - 'tp6_facebook']}, 06:15:57 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 06:15:57 INFO - 'talos_options': ['--disable-stylo', 06:15:57 INFO - '--mitmproxy', 06:15:57 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 06:15:57 INFO - '--firstNonBlankPaint'], 06:15:57 INFO - 'tests': ['tp6_google', 06:15:57 INFO - 'tp6_youtube', 06:15:57 INFO - 'tp6_amazon', 06:15:57 INFO - 'tp6_facebook']}, 06:15:57 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 06:15:57 INFO - 'talos_options': ['--stylo-threads=1', 06:15:57 INFO - '--mitmproxy', 06:15:57 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 06:15:57 INFO - '--firstNonBlankPaint'], 06:15:57 INFO - 'tests': ['tp6_google', 06:15:57 INFO - 'tp6_youtube', 06:15:57 INFO - 'tp6_amazon', 06:15:57 INFO - 'tp6_facebook']}, 06:15:57 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 06:15:57 INFO - 'talos_options': ['--xperf_path', 06:15:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:15:57 INFO - 'tests': ['tp5n']}, 06:15:57 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 06:15:57 INFO - 'talos_options': ['--disable-stylo', 06:15:57 INFO - '--xperf_path', 06:15:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:15:57 INFO - 'tests': ['tp5n']}}} 06:15:57 INFO - Running post-action listener: _resource_record_post_action 06:15:57 INFO - [mozharness: 2017-09-17 13:15:57.788749Z] Finished populate-webroot step (success) 06:15:57 INFO - [mozharness: 2017-09-17 13:15:57.788911Z] Running create-virtualenv step. 06:15:57 INFO - Running pre-action listener: _resource_record_pre_action 06:15:57 INFO - Running main action method: create_virtualenv 06:15:57 INFO - Creating virtualenv /builds/slave/test/build/venv 06:15:57 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 06:15:57 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 06:15:57 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 06:15:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:15:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:15:58 INFO - Installing distribute.............................................................................................................................................................................................done. 06:15:58 INFO - Installing pip...............done. 06:15:58 INFO - Return code: 0 06:15:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 06:15:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 06:15:58 INFO - Reading from file tmpfile_stdout 06:15:58 INFO - Output received: 06:15:58 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 06:15:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:15:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:15:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:15:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:15:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:15:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14441f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x160da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17b0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17b4090>, '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 0x17b4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17aef90>, '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': '5bd038846534b2fdfa68eeae000001e2-1505652212.867325-1465953002', '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 06:15:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 06:15:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 06:15:58 INFO - Using env: {'DISPLAY': ':0', 06:15:58 INFO - 'HOME': '/home/cltbld', 06:15:58 INFO - 'LANG': 'en_US.UTF-8', 06:15:58 INFO - 'LANGUAGE': 'en_US:en', 06:15:58 INFO - 'LOGNAME': 'cltbld', 06:15:58 INFO - 'MAIL': '/var/mail/cltbld', 06:15:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:15:58 INFO - 'MOZ_NO_REMOTE': '1', 06:15:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:15:58 INFO - 'NO_EM_RESTART': '1', 06:15:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:15:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:15:58 INFO - 'PWD': '/builds/slave/test', 06:15:58 INFO - 'SHELL': '/bin/bash', 06:15:58 INFO - 'SHLVL': '1', 06:15:58 INFO - 'TERM': 'linux', 06:15:58 INFO - 'TMOUT': '86400', 06:15:58 INFO - 'USER': 'cltbld', 06:15:58 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1505652212.867325-1465953002', 06:15:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:15:58 INFO - '_': '/tools/buildbot/bin/python'} 06:16:00 INFO - Ignoring indexes: http://pypi.python.org/simple/ 06:16:00 INFO - Downloading/unpacking pip>=1.5 06:16:00 INFO - Running setup.py egg_info for package pip 06:16:00 INFO - warning: no files found matching 'pip/cacert.pem' 06:16:00 INFO - warning: no files found matching '*.html' under directory 'docs' 06:16:00 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 06:16:00 INFO - no previously-included directories found matching 'docs/_build/_sources' 06:16:00 INFO - Installing collected packages: pip 06:16:00 INFO - Found existing installation: pip 1.1 06:16:00 INFO - Uninstalling pip: 06:16:00 INFO - Successfully uninstalled pip 06:16:00 INFO - Running setup.py install for pip 06:16:00 INFO - warning: no files found matching 'pip/cacert.pem' 06:16:00 INFO - warning: no files found matching '*.html' under directory 'docs' 06:16:00 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 06:16:00 INFO - no previously-included directories found matching 'docs/_build/_sources' 06:16:00 INFO - Installing pip script to /builds/slave/test/build/venv/bin 06:16:00 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 06:16:00 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 06:16:00 INFO - Successfully installed pip 06:16:00 INFO - Cleaning up... 06:16:00 INFO - Return code: 0 06:16:00 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:16: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')]} 06:16:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:16: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')]} 06:16:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:16:00 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 0x14441f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x160da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17b0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17b4090>, '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 0x17b4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17aef90>, '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': '5bd038846534b2fdfa68eeae000001e2-1505652212.867325-1465953002', '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 06:16:00 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 06:16:00 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 06:16:00 INFO - Using env: {'DISPLAY': ':0', 06:16:00 INFO - 'HOME': '/home/cltbld', 06:16:00 INFO - 'LANG': 'en_US.UTF-8', 06:16:00 INFO - 'LANGUAGE': 'en_US:en', 06:16:00 INFO - 'LOGNAME': 'cltbld', 06:16:00 INFO - 'MAIL': '/var/mail/cltbld', 06:16:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:16:00 INFO - 'MOZ_NO_REMOTE': '1', 06:16:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:16:00 INFO - 'NO_EM_RESTART': '1', 06:16:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:16:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:16:00 INFO - 'PWD': '/builds/slave/test', 06:16:00 INFO - 'SHELL': '/bin/bash', 06:16:00 INFO - 'SHLVL': '1', 06:16:00 INFO - 'TERM': 'linux', 06:16:00 INFO - 'TMOUT': '86400', 06:16:00 INFO - 'USER': 'cltbld', 06:16:00 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1505652212.867325-1465953002', 06:16:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:16:00 INFO - '_': '/tools/buildbot/bin/python'} 06:16:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:16:00 INFO - Downloading/unpacking psutil>=3.1.1 06:16:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:16:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:16:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:16:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:16:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:16:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:16:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:16:01 INFO - Installing collected packages: psutil 06:16:01 INFO - Running setup.py install for psutil 06:16:01 INFO - building 'psutil._psutil_linux' extension 06:16:01 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 06:16:01 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 06:16:01 INFO - building 'psutil._psutil_posix' extension 06:16:01 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 06:16:01 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 06:16:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:16:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:16:02 INFO - Successfully installed psutil 06:16:02 INFO - Cleaning up... 06:16:02 INFO - Return code: 0 06:16:02 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:16:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:16:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:16:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:16:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:16:02 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 0x14441f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x160da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17b0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17b4090>, '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 0x17b4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17aef90>, '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': '5bd038846534b2fdfa68eeae000001e2-1505652212.867325-1465953002', '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 06:16:02 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 06:16:02 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 06:16:02 INFO - Using env: {'DISPLAY': ':0', 06:16:02 INFO - 'HOME': '/home/cltbld', 06:16:02 INFO - 'LANG': 'en_US.UTF-8', 06:16:02 INFO - 'LANGUAGE': 'en_US:en', 06:16:02 INFO - 'LOGNAME': 'cltbld', 06:16:02 INFO - 'MAIL': '/var/mail/cltbld', 06:16:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:16:02 INFO - 'MOZ_NO_REMOTE': '1', 06:16:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:16:02 INFO - 'NO_EM_RESTART': '1', 06:16:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:16:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:16:02 INFO - 'PWD': '/builds/slave/test', 06:16:02 INFO - 'SHELL': '/bin/bash', 06:16:02 INFO - 'SHLVL': '1', 06:16:02 INFO - 'TERM': 'linux', 06:16:02 INFO - 'TMOUT': '86400', 06:16:02 INFO - 'USER': 'cltbld', 06:16:02 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1505652212.867325-1465953002', 06:16:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:16:02 INFO - '_': '/tools/buildbot/bin/python'} 06:16:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:16:02 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:16:02 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 06:16:02 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 06:16:02 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 06:16:02 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 06:16:03 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:16:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:16:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:16:03 INFO - Installing collected packages: mozsystemmonitor 06:16:03 INFO - Running setup.py install for mozsystemmonitor 06:16:03 INFO - Successfully installed mozsystemmonitor 06:16:03 INFO - Cleaning up... 06:16:05 INFO - Return code: 0 06:16:05 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:16:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:16:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:16:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:16:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:16:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x14441f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x160da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17b0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17b4090>, '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 0x17b4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17aef90>, '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': '5bd038846534b2fdfa68eeae000001e2-1505652212.867325-1465953002', '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 06:16:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 06:16:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 06:16:05 INFO - Using env: {'DISPLAY': ':0', 06:16:05 INFO - 'HOME': '/home/cltbld', 06:16:05 INFO - 'LANG': 'en_US.UTF-8', 06:16:05 INFO - 'LANGUAGE': 'en_US:en', 06:16:05 INFO - 'LOGNAME': 'cltbld', 06:16:05 INFO - 'MAIL': '/var/mail/cltbld', 06:16:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:16:05 INFO - 'MOZ_NO_REMOTE': '1', 06:16:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:16:05 INFO - 'NO_EM_RESTART': '1', 06:16:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:16:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:16:05 INFO - 'PWD': '/builds/slave/test', 06:16:05 INFO - 'SHELL': '/bin/bash', 06:16:05 INFO - 'SHLVL': '1', 06:16:05 INFO - 'TERM': 'linux', 06:16:05 INFO - 'TMOUT': '86400', 06:16:05 INFO - 'USER': 'cltbld', 06:16:05 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1505652212.867325-1465953002', 06:16:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:16:05 INFO - '_': '/tools/buildbot/bin/python'} 06:16:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:16:05 INFO - Downloading/unpacking jsonschema==2.5.1 06:16:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:16:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:16:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:16:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:16:06 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:16:06 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:16: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 06:16: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 06:16: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 06:16: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 06:16:06 INFO - Downloading functools32-3.2.3-2.tar.gz 06:16:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:16:06 INFO - warning: no files found matching '*.txt' 06:16:06 INFO - no previously-included directories found matching 'build' 06:16:06 INFO - no previously-included directories found matching 'dist' 06:16:06 INFO - no previously-included directories found matching '.git*' 06:16:06 INFO - Installing collected packages: jsonschema, functools32 06:16:06 INFO - Running setup.py install for functools32 06:16:07 INFO - warning: no files found matching '*.txt' 06:16:07 INFO - no previously-included directories found matching 'build' 06:16:07 INFO - no previously-included directories found matching 'dist' 06:16:07 INFO - no previously-included directories found matching '.git*' 06:16:07 INFO - Successfully installed jsonschema functools32 06:16:07 INFO - Cleaning up... 06:16:07 INFO - Return code: 0 06:16:07 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:16:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:16:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:16:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:16:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:16:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x14441f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x160da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17b0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17b4090>, '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 0x17b4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17aef90>, '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': '5bd038846534b2fdfa68eeae000001e2-1505652212.867325-1465953002', '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 06:16:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 06:16:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 06:16:07 INFO - Using env: {'DISPLAY': ':0', 06:16:07 INFO - 'HOME': '/home/cltbld', 06:16:07 INFO - 'LANG': 'en_US.UTF-8', 06:16:07 INFO - 'LANGUAGE': 'en_US:en', 06:16:07 INFO - 'LOGNAME': 'cltbld', 06:16:07 INFO - 'MAIL': '/var/mail/cltbld', 06:16:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:16:07 INFO - 'MOZ_NO_REMOTE': '1', 06:16:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:16:07 INFO - 'NO_EM_RESTART': '1', 06:16:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:16:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:16:07 INFO - 'PWD': '/builds/slave/test', 06:16:07 INFO - 'SHELL': '/bin/bash', 06:16:07 INFO - 'SHLVL': '1', 06:16:07 INFO - 'TERM': 'linux', 06:16:07 INFO - 'TMOUT': '86400', 06:16:07 INFO - 'USER': 'cltbld', 06:16:07 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1505652212.867325-1465953002', 06:16:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:16:07 INFO - '_': '/tools/buildbot/bin/python'} 06:16:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:16:07 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:16:07 INFO - Cleaning up... 06:16:07 INFO - Return code: 0 06:16:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:16:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:16:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:16:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:16:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:16:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x14441f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x160da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17b0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17b4090>, '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 0x17b4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17aef90>, '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': '5bd038846534b2fdfa68eeae000001e2-1505652212.867325-1465953002', '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 06:16:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 06:16:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 06:16:07 INFO - Using env: {'DISPLAY': ':0', 06:16:07 INFO - 'HOME': '/home/cltbld', 06:16:07 INFO - 'LANG': 'en_US.UTF-8', 06:16:07 INFO - 'LANGUAGE': 'en_US:en', 06:16:07 INFO - 'LOGNAME': 'cltbld', 06:16:07 INFO - 'MAIL': '/var/mail/cltbld', 06:16:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:16:07 INFO - 'MOZ_NO_REMOTE': '1', 06:16:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:16:07 INFO - 'NO_EM_RESTART': '1', 06:16:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:16:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:16:07 INFO - 'PWD': '/builds/slave/test', 06:16:07 INFO - 'SHELL': '/bin/bash', 06:16:07 INFO - 'SHLVL': '1', 06:16:07 INFO - 'TERM': 'linux', 06:16:07 INFO - 'TMOUT': '86400', 06:16:07 INFO - 'USER': 'cltbld', 06:16:07 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1505652212.867325-1465953002', 06:16:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:16:07 INFO - '_': '/tools/buildbot/bin/python'} 06:16:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:16:07 INFO - Downloading/unpacking blobuploader==1.2.4 06:16:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:16:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:16:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:16:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:16:08 INFO - Downloading blobuploader-1.2.4.tar.gz 06:16:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:16:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:16:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:16:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:16:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:16:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:16:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:16:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:16:09 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 06:16:09 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 06:16:09 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 06:16:09 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 06:16:09 INFO - Downloading docopt-0.6.1.tar.gz 06:16:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:16:09 INFO - Installing collected packages: blobuploader, requests, docopt 06:16:09 INFO - Running setup.py install for blobuploader 06:16:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:16:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:16:09 INFO - Running setup.py install for requests 06:16:09 INFO - Running setup.py install for docopt 06:16:10 INFO - Successfully installed blobuploader requests docopt 06:16:10 INFO - Cleaning up... 06:16:10 INFO - Return code: 0 06:16:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:16:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:16:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:16:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:16:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:16:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14441f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x160da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17b0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17b4090>, '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 0x17b4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17aef90>, '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': '5bd038846534b2fdfa68eeae000001e2-1505652212.867325-1465953002', '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 06:16:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 06:16:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:16:10 INFO - Using env: {'DISPLAY': ':0', 06:16:10 INFO - 'HOME': '/home/cltbld', 06:16:10 INFO - 'LANG': 'en_US.UTF-8', 06:16:10 INFO - 'LANGUAGE': 'en_US:en', 06:16:10 INFO - 'LOGNAME': 'cltbld', 06:16:10 INFO - 'MAIL': '/var/mail/cltbld', 06:16:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:16:10 INFO - 'MOZ_NO_REMOTE': '1', 06:16:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:16:10 INFO - 'NO_EM_RESTART': '1', 06:16:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:16:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:16:10 INFO - 'PWD': '/builds/slave/test', 06:16:10 INFO - 'SHELL': '/bin/bash', 06:16:10 INFO - 'SHLVL': '1', 06:16:10 INFO - 'TERM': 'linux', 06:16:10 INFO - 'TMOUT': '86400', 06:16:10 INFO - 'USER': 'cltbld', 06:16:10 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1505652212.867325-1465953002', 06:16:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:16:10 INFO - '_': '/tools/buildbot/bin/python'} 06:16:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:16:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:16:10 INFO - Running setup.py (path:/tmp/pip-hEilu4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:16:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:16:10 INFO - Running setup.py (path:/tmp/pip-TlTSEJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:16:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:16:10 INFO - Running setup.py (path:/tmp/pip-yiJ7Vg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:16:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:16:10 INFO - Running setup.py (path:/tmp/pip-Ca9BIu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:16:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:16:10 INFO - Running setup.py (path:/tmp/pip-dFGITn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:16:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:16:10 INFO - Running setup.py (path:/tmp/pip-LFTFpA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:16:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:16:10 INFO - Running setup.py (path:/tmp/pip-ngq_zq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:16:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:16:10 INFO - Running setup.py (path:/tmp/pip-jtn9SY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:16:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:16:10 INFO - Running setup.py (path:/tmp/pip-0QG5sf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:16:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:16:10 INFO - Running setup.py (path:/tmp/pip-vVoKQK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:16:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:16:11 INFO - Running setup.py (path:/tmp/pip-OOaKHp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:16:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:16:11 INFO - Running setup.py (path:/tmp/pip-lnv2Ml-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:16:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:16:11 INFO - Running setup.py (path:/tmp/pip-iLneFw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:16:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:16:11 INFO - Running setup.py (path:/tmp/pip-d0TJz0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:16:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:16:11 INFO - Running setup.py (path:/tmp/pip-jgxaCl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:16:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:16:11 INFO - Running setup.py (path:/tmp/pip-Ct7tLe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:16:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:16:11 INFO - Running setup.py (path:/tmp/pip-R9F40I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:16:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:16:11 INFO - Running setup.py install for manifestparser 06:16:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:16:11 INFO - Running setup.py install for mozcrash 06:16:11 INFO - Running setup.py install for mozdebug 06:16:11 INFO - Running setup.py install for mozdevice 06:16:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:16:11 INFO - Running setup.py install for mozfile 06:16:12 INFO - Running setup.py install for mozhttpd 06:16:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:16:12 INFO - Running setup.py install for mozinfo 06:16:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:16:12 INFO - Running setup.py install for mozInstall 06:16:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:16:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:16:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:16:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:16:12 INFO - Running setup.py install for mozleak 06:16:12 INFO - Running setup.py install for mozlog 06:16:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:16:12 INFO - Running setup.py install for moznetwork 06:16:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:16:12 INFO - Running setup.py install for mozprocess 06:16:12 INFO - Running setup.py install for mozprofile 06:16:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:16:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:16:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:16:12 INFO - Running setup.py install for mozrunner 06:16:12 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:16:12 INFO - Running setup.py install for mozscreenshot 06:16:13 INFO - Running setup.py install for moztest 06:16:13 INFO - Running setup.py install for mozversion 06:16:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:16:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:16:13 INFO - Cleaning up... 06:16:13 INFO - Return code: 0 06:16:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:16:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:16:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:16:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:16:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:16:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14441f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x160da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17b0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17b4090>, '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 0x17b4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17aef90>, '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': '5bd038846534b2fdfa68eeae000001e2-1505652212.867325-1465953002', '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 06:16:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 06:16:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:16:13 INFO - Using env: {'DISPLAY': ':0', 06:16:13 INFO - 'HOME': '/home/cltbld', 06:16:13 INFO - 'LANG': 'en_US.UTF-8', 06:16:13 INFO - 'LANGUAGE': 'en_US:en', 06:16:13 INFO - 'LOGNAME': 'cltbld', 06:16:13 INFO - 'MAIL': '/var/mail/cltbld', 06:16:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:16:13 INFO - 'MOZ_NO_REMOTE': '1', 06:16:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:16:13 INFO - 'NO_EM_RESTART': '1', 06:16:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:16:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:16:13 INFO - 'PWD': '/builds/slave/test', 06:16:13 INFO - 'SHELL': '/bin/bash', 06:16:13 INFO - 'SHLVL': '1', 06:16:13 INFO - 'TERM': 'linux', 06:16:13 INFO - 'TMOUT': '86400', 06:16:13 INFO - 'USER': 'cltbld', 06:16:13 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1505652212.867325-1465953002', 06:16:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:16:13 INFO - '_': '/tools/buildbot/bin/python'} 06:16:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:16:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:16:13 INFO - Running setup.py (path:/tmp/pip-tcwg6o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:16:13 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 06:16:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:16:13 INFO - Running setup.py (path:/tmp/pip-ayOXzb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:16:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:16:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:16:13 INFO - Running setup.py (path:/tmp/pip-vMHfJi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:16:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 06:16:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:16:13 INFO - Running setup.py (path:/tmp/pip-yvk8LQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:16:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:16:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:16:13 INFO - Running setup.py (path:/tmp/pip-AYnWIR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:16:13 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)) 06:16:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:16:13 INFO - Running setup.py (path:/tmp/pip-V5K1nF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:16:13 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)) 06:16:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:16:13 INFO - Running setup.py (path:/tmp/pip-0NScmS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:16:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 06:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:16:14 INFO - Running setup.py (path:/tmp/pip-e1fzPa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:16:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 06:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:16:14 INFO - Running setup.py (path:/tmp/pip-kH4KvF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:16:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 06:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:16:14 INFO - Running setup.py (path:/tmp/pip-Dq_yd2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:16:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:16:14 INFO - Running setup.py (path:/tmp/pip-RCbnL0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:16:14 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 06:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:16:14 INFO - Running setup.py (path:/tmp/pip-sPoIv_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:16:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 06:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:16:14 INFO - Running setup.py (path:/tmp/pip-Su8kWS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:16:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 06:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:16:14 INFO - Running setup.py (path:/tmp/pip-wofcYX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:16:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 06:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:16:14 INFO - Running setup.py (path:/tmp/pip-eTtAO5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:16:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 06:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:16:14 INFO - Running setup.py (path:/tmp/pip-h0Xdaw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:16:14 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 06:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:16:14 INFO - Running setup.py (path:/tmp/pip-SghuBl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:16:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 06:16:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:16:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:16:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 06:16:14 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:16:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:16:14 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 06:16:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:16:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:16:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:16:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:16:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:16:15 INFO - Downloading blessings-1.6.tar.gz 06:16:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:16:15 INFO - Installing collected packages: blessings 06:16:15 INFO - Running setup.py install for blessings 06:16:16 INFO - Successfully installed blessings 06:16:16 INFO - Cleaning up... 06:16:16 INFO - Return code: 0 06:16:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:16:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:16:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:16:16 INFO - Reading from file tmpfile_stdout 06:16:16 INFO - Current package versions: 06:16:16 INFO - argparse == 1.2.1 06:16:16 INFO - blessings == 1.6 06:16:16 INFO - blobuploader == 1.2.4 06:16:16 INFO - docopt == 0.6.1 06:16:16 INFO - functools32 == 3.2.3-2 06:16:16 INFO - jsonschema == 2.5.1 06:16:16 INFO - manifestparser == 1.1 06:16:16 INFO - mozInstall == 1.13 06:16:16 INFO - mozcrash == 1.0 06:16:16 INFO - mozdebug == 0.1 06:16:16 INFO - mozdevice == 0.51 06:16:16 INFO - mozfile == 1.2 06:16:16 INFO - mozhttpd == 0.7 06:16:16 INFO - mozinfo == 0.10 06:16:16 INFO - mozleak == 0.1 06:16:16 INFO - mozlog == 3.5 06:16:16 INFO - moznetwork == 0.27 06:16:16 INFO - mozprocess == 0.25 06:16:16 INFO - mozprofile == 0.28 06:16:16 INFO - mozrunner == 6.13 06:16:16 INFO - mozscreenshot == 0.1 06:16:16 INFO - mozsystemmonitor == 0.3 06:16:16 INFO - moztest == 0.8 06:16:16 INFO - mozversion == 1.4 06:16:16 INFO - psutil == 3.1.1 06:16:16 INFO - requests == 1.2.3 06:16:16 INFO - wsgiref == 0.1.2 06:16:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:16:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:16:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:16:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:16:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:16:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14441f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x160da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17b0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17b4090>, '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 0x17b4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17aef90>, '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': '5bd038846534b2fdfa68eeae000001e2-1505652212.867325-1465953002', '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 06:16:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 06:16:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:16:16 INFO - Using env: {'DISPLAY': ':0', 06:16:16 INFO - 'HOME': '/home/cltbld', 06:16:16 INFO - 'LANG': 'en_US.UTF-8', 06:16:16 INFO - 'LANGUAGE': 'en_US:en', 06:16:16 INFO - 'LOGNAME': 'cltbld', 06:16:16 INFO - 'MAIL': '/var/mail/cltbld', 06:16:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:16:16 INFO - 'MOZ_NO_REMOTE': '1', 06:16:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:16:16 INFO - 'NO_EM_RESTART': '1', 06:16:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:16:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:16:16 INFO - 'PWD': '/builds/slave/test', 06:16:16 INFO - 'SHELL': '/bin/bash', 06:16:16 INFO - 'SHLVL': '1', 06:16:16 INFO - 'TERM': 'linux', 06:16:16 INFO - 'TMOUT': '86400', 06:16:16 INFO - 'USER': 'cltbld', 06:16:16 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1505652212.867325-1465953002', 06:16:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:16:16 INFO - '_': '/tools/buildbot/bin/python'} 06:16:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:16:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 06:16:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 06:16:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 06:16:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 06:16:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 06:16:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 06:16:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 06:16:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 06:16:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 06:16:16 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 06:16:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:16:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:16:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:16:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:16:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 06:16:17 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 06:16:17 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 06:16:17 INFO - Installing collected packages: simplejson 06:16:17 INFO - Running setup.py install for simplejson 06:16:17 INFO - building 'simplejson._speedups' extension 06:16:17 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 06:16:18 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 06:16:18 INFO - Successfully installed simplejson 06:16:18 INFO - Cleaning up... 06:16:18 INFO - Return code: 0 06:16:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:16:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:16:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:16:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:16:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:16:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14441f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x160da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17b0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17b4090>, '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 0x17b4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17aef90>, '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': '5bd038846534b2fdfa68eeae000001e2-1505652212.867325-1465953002', '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 06:16:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 06:16:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 06:16:18 INFO - Using env: {'DISPLAY': ':0', 06:16:18 INFO - 'HOME': '/home/cltbld', 06:16:18 INFO - 'LANG': 'en_US.UTF-8', 06:16:18 INFO - 'LANGUAGE': 'en_US:en', 06:16:18 INFO - 'LOGNAME': 'cltbld', 06:16:18 INFO - 'MAIL': '/var/mail/cltbld', 06:16:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:16:18 INFO - 'MOZ_NO_REMOTE': '1', 06:16:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:16:18 INFO - 'NO_EM_RESTART': '1', 06:16:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:16:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:16:18 INFO - 'PWD': '/builds/slave/test', 06:16:18 INFO - 'SHELL': '/bin/bash', 06:16:18 INFO - 'SHLVL': '1', 06:16:18 INFO - 'TERM': 'linux', 06:16:18 INFO - 'TMOUT': '86400', 06:16:18 INFO - 'USER': 'cltbld', 06:16:18 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1505652212.867325-1465953002', 06:16:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:16:18 INFO - '_': '/tools/buildbot/bin/python'} 06:16:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:16:18 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:16:18 INFO - Cleaning up... 06:16:18 INFO - Return code: 0 06:16:18 INFO - Running post-action listener: _resource_record_post_action 06:16:18 INFO - Running post-action listener: _start_resource_monitoring 06:16:19 INFO - Starting resource monitoring. 06:16:19 INFO - [mozharness: 2017-09-17 13:16:19.014881Z] Finished create-virtualenv step (success) 06:16:19 INFO - [mozharness: 2017-09-17 13:16:19.015391Z] Running install step. 06:16:19 INFO - Running pre-action listener: _resource_record_pre_action 06:16:19 INFO - Running main action method: install 06:16:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:16:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:16:19 INFO - Reading from file tmpfile_stdout 06:16:19 INFO - Detecting whether we're running mozinstall >=1.0... 06:16:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:16:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:16:19 INFO - Reading from file tmpfile_stdout 06:16:19 INFO - Output received: 06:16:19 INFO - Usage: mozinstall [options] installer 06:16:19 INFO - Options: 06:16:19 INFO - -h, --help show this help message and exit 06:16:19 INFO - -d DEST, --destination=DEST 06:16:19 INFO - Directory to install application into. [default: 06:16:19 INFO - "/builds/slave/test"] 06:16:19 INFO - --app=APP Application being installed. [default: firefox] 06:16:19 INFO - mkdir: /builds/slave/test/build/application 06:16:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 06:16:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 06:16:37 INFO - Reading from file tmpfile_stdout 06:16:37 INFO - Output received: 06:16:37 INFO - /builds/slave/test/build/application/firefox/firefox 06:16:37 INFO - Running post-action listener: _resource_record_post_action 06:16:37 INFO - [mozharness: 2017-09-17 13:16:37.148296Z] Finished install step (success) 06:16:37 INFO - [mozharness: 2017-09-17 13:16:37.148442Z] Running setup-mitmproxy step. 06:16:37 INFO - Running pre-action listener: _resource_record_pre_action 06:16:37 INFO - Running main action method: setup_mitmproxy 06:16:37 INFO - Skipping: mitmproxy is not required 06:16:37 INFO - Running post-action listener: _resource_record_post_action 06:16:37 INFO - [mozharness: 2017-09-17 13:16:37.149162Z] Finished setup-mitmproxy step (success) 06:16:37 INFO - [mozharness: 2017-09-17 13:16:37.149383Z] Running run-tests step. 06:16:37 INFO - Running pre-action listener: _resource_record_pre_action 06:16:37 INFO - Running pre-action listener: _set_gcov_prefix 06:16:37 INFO - Running main action method: run_tests 06:16:37 WARNING - Try message not found. 06:16:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:16:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:16:37 INFO - Python 2.7.3 06:16:37 INFO - Return code: 0 06:16:37 INFO - grabbing minidump binary from tooltool 06:16:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:16:37 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17b4090>, '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 0x17b4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17aef90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:16:37 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 06:16:37 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 06:16:37 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 06:16:37 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:16:37 INFO - Return code: 0 06:16:37 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 06:16:37 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:16:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:16:37 INFO - ENV: RUST_BACKTRACE is now full 06:16:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 06:16:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:16:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Wjb9bWClSh6PQEiCFFxuzg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-039', '--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 06:16:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Wjb9bWClSh6PQEiCFFxuzg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-039 --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 06:16:37 INFO - Using env: {'DISPLAY': ':0', 06:16:37 INFO - 'HOME': '/home/cltbld', 06:16:37 INFO - 'LANG': 'en_US.UTF-8', 06:16:37 INFO - 'LANGUAGE': 'en_US:en', 06:16:37 INFO - 'LOGNAME': 'cltbld', 06:16:37 INFO - 'MAIL': '/var/mail/cltbld', 06:16:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:16:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 06:16:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:16:37 INFO - 'MOZ_NO_REMOTE': '1', 06:16:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:16:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:16:37 INFO - 'NO_EM_RESTART': '1', 06:16:37 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:16:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:16:37 INFO - 'PWD': '/builds/slave/test', 06:16:37 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:16:37 INFO - 'RUST_BACKTRACE': 'full', 06:16:37 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 06:16:37 INFO - 'SHELL': '/bin/bash', 06:16:37 INFO - 'SHLVL': '1', 06:16:37 INFO - 'TERM': 'linux', 06:16:37 INFO - 'TMOUT': '86400', 06:16:37 INFO - 'USER': 'cltbld', 06:16:37 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1505652212.867325-1465953002', 06:16:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:16:37 INFO - '_': '/tools/buildbot/bin/python'} 06:16:37 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Wjb9bWClSh6PQEiCFFxuzg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-039', '--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 06:16:37 INFO - mozversion application_buildid: 20170917122706 06:16:37 INFO - mozversion application_changeset: 877d536658e644bd0d1662d608cc2ca3a9fa8d9f 06:16:37 INFO - mozversion application_display_name: Nightly 06:16:37 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:16:37 INFO - mozversion application_name: Firefox 06:16:37 INFO - mozversion application_remotingname: firefox 06:16:37 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 06:16:37 INFO - mozversion application_vendor: Mozilla 06:16:37 INFO - mozversion application_version: 57.0a1 06:16:37 INFO - mozversion platform_buildid: 20170917122706 06:16:37 INFO - mozversion platform_changeset: 877d536658e644bd0d1662d608cc2ca3a9fa8d9f 06:16:37 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 06:16:37 INFO - mozversion platform_version: 57.0a1 06:16:37 INFO - using testdate: 1505654197 06:16:37 INFO - actual date: 1505654197 06:16:37 INFO - starting webserver on 'localhost:60522' 06:16:37 INFO - SUITE-START | Running 2 tests 06:16:37 INFO - TEST-START | dromaeo_css 06:16:37 INFO - Initialising browser for dromaeo_css test... 06:16:37 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:60522/getInfo.html -profile /tmp/tmpXBbqiA/profile 06:16:37 INFO - TEST-INFO | started process 1471 (/builds/slave/test/build/application/firefox/firefox http://localhost:60522/getInfo.html) 06:16:43 INFO - TEST-INFO | 1471: exit 0 06:16:43 INFO - Browser initialized. 06:16:43 INFO - Running cycle 1/1 for dromaeo_css test... 06:16:43 INFO - Using env: {'DISPLAY': ':0', 06:16:43 INFO - 'HOME': '/home/cltbld', 06:16:43 INFO - 'JSGC_DISABLE_POISONING': '1', 06:16:43 INFO - 'LANG': 'en_US.UTF-8', 06:16:43 INFO - 'LANGUAGE': 'en_US:en', 06:16:43 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 06:16:43 INFO - 'LOGNAME': 'cltbld', 06:16:43 INFO - 'MAIL': '/var/mail/cltbld', 06:16:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:16:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 06:16:43 INFO - 'MOZ_CRASHREPORTER': '1', 06:16:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:16:43 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 06:16:43 INFO - 'MOZ_NO_REMOTE': '1', 06:16:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:16:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:16:43 INFO - 'NO_EM_RESTART': '1', 06:16:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:16:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:16:43 INFO - 'PWD': '/builds/slave/test', 06:16:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:16:43 INFO - 'RUST_BACKTRACE': 'full', 06:16:43 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 06:16:43 INFO - 'SHELL': '/bin/bash', 06:16:43 INFO - 'SHLVL': '1', 06:16:43 INFO - 'TERM': 'linux', 06:16:43 INFO - 'TMOUT': '86400', 06:16:43 INFO - 'USER': 'cltbld', 06:16:43 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1505652212.867325-1465953002', 06:16:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:16:43 INFO - '_': '/tools/buildbot/bin/python'} 06:16:43 INFO - TEST-INFO | started process 1704 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXBbqiA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 06:16:44 INFO - PID 1704 | 06:16:44 INFO - PID 1704 | (/builds/slave/test/build/application/firefox/firefox:1754): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 06:16:44 INFO - PID 1704 | 06:16:54 INFO - PID 1704 | RSS: Main: 157679616 06:16:54 INFO - PID 1704 | 06:19:51 INFO - PID 1704 | Cycle 1(1): loaded http://localhost:60522/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60522/tests/dromaeo/cssquery-ext.html) 06:19:52 INFO - PID 1704 | RSS: Main: 156971008 06:19:52 INFO - PID 1704 | 06:22:54 INFO - PID 1704 | Cycle 1(1): loaded http://localhost:60522/tests/dromaeo/cssquery-ext.html (next: http://localhost:60522/tests/dromaeo/cssquery-jquery.html) 06:22:55 INFO - PID 1704 | RSS: Main: 156692480 06:22:55 INFO - PID 1704 | 06:25:56 INFO - PID 1704 | Cycle 1(1): loaded http://localhost:60522/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60522/tests/dromaeo/cssquery-mootools.html) 06:25:57 INFO - PID 1704 | RSS: Main: 158236672 06:25:57 INFO - PID 1704 | 06:28:59 INFO - PID 1704 | Cycle 1(1): loaded http://localhost:60522/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60522/tests/dromaeo/cssquery-prototype.html) 06:28:59 INFO - PID 1704 | RSS: Main: 157216768 06:28:59 INFO - PID 1704 | 06:32:03 INFO - PID 1704 | Cycle 1(1): loaded http://localhost:60522/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60522/tests/dromaeo/cssquery-yui.html) 06:32:03 INFO - PID 1704 | RSS: Main: 157147136 06:32:03 INFO - PID 1704 | 06:35:05 INFO - PID 1704 | Cycle 1(1): loaded http://localhost:60522/tests/dromaeo/cssquery-yui.html (next: http://localhost:60522/tests/dromaeo/cssquery-dojo.html) 06:35:05 INFO - PID 1704 | RSS: Main: 161218560 06:35:05 INFO - PID 1704 | 06:35:05 INFO - PID 1704 | __start_tp_report 06:35:05 INFO - PID 1704 | _x_x_mozilla_page_load 06:35:05 INFO - PID 1704 | _x_x_mozilla_page_load_details 06:35:05 INFO - PID 1704 | |i|pagename|runs| 06:35:05 INFO - PID 1704 | |0;dojo.html;3150.952735708964;3152.605924259466;3152.8581213845373;3171.2706077602165;3179.507176387661;6114.663693496856;6222.7199776010075;6231.719572619231;6301.810945671631;6365.713542890564;6230.595011324261;6231.345708700589;6245.781397651083;6251.874962500748;6283.937160628392;5758.510526605222;5776.249087618614;5849.268841394825;5849.502792262641;5863.58954873159;5707.543396528268;5806.65160090396;5828.096645020008;5860.589758716891;5893.027650437694;97.35334423948923;5326.414094449627;5681.914771278434;5790.739416726257;5874.5006674432725;5722.141678748221;5736.024875771129;5812.389699081577;5817.127430885401;5826.533877289807;5672.716364181816;5691.4877661010705;5763.049096899007;5776.220210271644;5803.767849286023;6179.38206179383;6187.752489900398;6246.312905580428;6304.590201636879;6314.463270622003;6631.635260060695;6662.766803161866;6741.5617984831315;6787.457003439713;6793.252742198354;6604.702788374535;6635.6682165892;6644.501662375341;6645.269020407752;6755.7973260802255;6117.418845209742;6157.230346206724;6186.288576813679;6193.163922870445;6193.721282542297;6832.145981752331;6901.240863505011;6966.790996270019;6972.372486476242;6987.406070483964;6533.1180290661105;6609.570377925419;6617.867642647217;6619.801405957829;6628.966855165693;6640.136782218281;6641.833954151185;6743.426808721216;6744.865102697919;6755.459563235027;6494.740210391531;6570.704318305688;6631.834204144935;6635.336466353423;6727.529072964846;6599.637019963852;6643.069970204176;6653.002049692488;6728.596284222962;6735.966320168368;5271;5318.8936221276135;5347.705876176848;5349.973250133802;5363.222332761728;4590.875235567308;4595.6783025187915;4624;4630.560096790799;4633.027064316531;5726.599138060295;5770.13447982806;5798.246227990418;5856;5865.67738774363;6623.9006414903815;6624.96687516569;6637.103990961158;6653.201615806134;6659.833504162338;5818.4472475115645;5828.329742079715;5833.416658334133;5833.4749872511675;5885.823425297162;5760.164776107441;5821.53427725781;5831.679257640785;5837.387074357216;5840.3575606683235;6511.088447617338;6522.641254731036;6618.768343107968;6621.304762999912;6633.9668301658185;6596.3073877244115;6693.564918280491;6707.765228216989;6719.361660642231;6814.863702725917;6485.092087107903;6491.091247225487;6493.350664933469;6495.350464953467;6523.3150519194805;6663.433608143168;6681.33186681328;6702.698378572878;6714.9328506716265;6723.462123030048;6761.661916904233;6769.627670478171;6813.863722725617;6846.281140480178;6870.2442731298515;6409.7115629795835;6505.31694172124;6514.413702766774;6604.7027883746305;6613.867722645424;6409;6415;6420.6147631142285;6440.9033864493;6444.194475690539;6262.561620686691;6347.0479428086155;6360.77737279193;6368.42684158428;6443.452306553995;6553.311902250195;6564.113844630913;6581.14445122131;6605.405513503808;6614.6361950093205;6302.432781049728;6304.432601065926;6370.076338930921;6402.679866006774;6418.1656384669695 06:35:05 INFO - PID 1704 | |1;ext.html;839.4249938791928;855.5636625321084;860.8106216632343;861.1861790607878;870.3385427075423;38356.424653630216;42317.788411057976;42382.36426453606;42517.36223956643;42532;38797.41803872944;39202.411963820494;39213;39226.41160382597;39235.41146882799;6923.446124310069;7063.6115013674225;7068.752593659223;7071.257517960617;7074.893876591856;6922.965385173067;6987.2663370346145;6994.300569943016;7008.0539127217935;7018.3683468487825;6971.477139214554;7689.653965571562;7795.064592248909;7919.485233459835;7925.484843485184;7775.961120194391;7808.49244799092;7842;7851.057873055212;7868.449208555404;7511.849763004764;7681.3470854977395;7688.846223075507;7740.9225907740765;7777.377809775261;37844.81077594622;38181;38331.80834095839;38382.424263636065;38419.61580384188;3570.4822800694014;3619.6380361963593;3621.3300539400034;3635.654612811779;3642.3261696586214;31282.37435251305;31298.37403251945;31374.843125784453;31541.053768386984;31562.210944726565;5246.527812496894;5261.342332208474;5264.763085661116;5281.788728450896;5293.57651387888;189472;189878;191096;191381;191458;40946.38580421296;41417.378739318934;46133.07733845304;46291;47523.28715069277;50156;50263.74868125636;50377.74811125921;50392.49607503951;50428.24357634639;15382.153981530899;15420.228988550753;15557.844421555867;15570.766438503431;15581.844181558039;12820.615381538382;12837.101402901893;12839.422225999853;12844.550440734529;12973.481060757653;13532.729345413036;13632;13741.519046833513;13881.514147004806;13929.467758546552;5608.635438696471;5651.576131790132;5654.349749778746;5666.206731057655;5714.342850572238;1013.3312014070679;1016.8576399304103;1017.4759998600726;1021.897810218987;1026.8151732688189;22783.202587909345;22877.542449150922;23247.651285230735;23310;23502.294931152093;1102.239454776202;1114.0920150077661;1129.768397478515;1139.715071232192;1143.17691262291;12317;12359.443825027894;12386.690332741753;12430.192037517532;12454.128211025141;225686;227309;227681;228207;228997;52538.47461525412;52708.73645631747;52752.47247527552;52781.47218527842;52845;1413.3216056293038;1434.8923830712738;1438.0149597525728;1447.8334991476115;1448.543708731725;22562.774372256066;22827.20104796324;22902.54194916059;23123.537529249657;23347;37925.62074379331;38037.23925521419;38142;38196.23607527778;38269;198416;198846;199652;199708;200246;30194.24514387158;30715.385692286476;33532.664673352956;33611.3277734439;34101.82949085338;41517.37723934204;42812.14375712406;42966.35550466808;43125.78437107794;43250.78374608233;32041.6795832048;32332.35335293328;32368.67631323751;32504.83747581247;32959.67040329662;31291.84354078215;31582.842085789423;31782.6821731789;31792.18493780761;31923;35361.64638353584;35551.28897421985;35564.822175888956;35641.46537801883;35730.46404303885;30908.69091309058;31125.844370778002;31266.687333126378;31485.37029259356;31926.521102183022;23113.19103831358;23365;23557.646635300825;23836.88081559581;23936.401589959693 06:35:05 INFO - PID 1704 | |2;jquery.html;554.4787899374587;557.7406505974724;566.9064604340284;569.8346880629113;572.7508533787801;21893.562128757436;23003.539929201426;23071.76928230717;23078.76921230787;23078.88460557699;23339;24208.636870446957;24429.38926526838;24454.877725611346;24652.876735616297;6465.547411681184;6658.966705166467;6662.633555154464;6665.933340666604;6670.7665231716655;6446.1619989401315;6574.652196299747;6584.473242140642;6588.3411658833975;6619.735210591571;6239.37606239377;6543.705533251015;6571;6578.342165783407;6587.637679927626;6594.3075977022445;6630;6646.900296495556;6649.76725814599;6649.833754156137;5897.14491398749;6165.630062196237;6166.290876549209;6168;6184.165137706397;27936.441271174666;28477.288067798265;28486.002989895253;28513.572296415572;28524.572131418045;2055.0341339570527;2079.9688004679942;2084.8436367272366;2086.050846864673;2089.279198676454;18959;19149.329773458;19496.512587185432;19500.70748938767;19512.902435487875;2421.4672771990136;2497.076081849709;2514.7108082570558;2520.357308886222;2520.8991640334366;118639.4068029663;123281.38359308236;125245;129261.7073829259;133940;20307.492312692302;20333.89833050825;21281.36155915325;21345.893270533546;21820.01759436965;41486.37770433446;41618.37572436416;41655.375169372484;41678.58321416807;41708;11347.375894325725;11400.657980260605;11470.48382822775;11546.249493782878;11725.355105469282;9575;9634.13292803637;9685.111403276294;9726.36778609388;9827.901720982842;10993.450327483593;11092.33445993243;11194.186499805162;11395.917387848142;11396.917292857166;3876.89049607417;4098.262312783668;4161.459010328698;4176.665866730655;4245.363195520635;955.1594596754811;976.4531862157214;986.659602437158;993.1707024634367;996.1532697207316;18968.430947071334;19260.518487037934;19300.32448864311;19454.124564394635;19620.3132890351;1027.5427434791595;1038.065740833241;1038.7143535527791;1044.3472829481575;1044.4412239451856;10596.099331556905;10641.627543036111;10677.145828333714;10792.082672972729;10963.56145754161;423919;426320;458562;463090;464259;106396;113721.43139284251;118056.40971795085;118744;119316.4034179841;1029.120102312527;1075.0593230922943;1153.6135394642888;1180.9232399893979;1208.6253261488966;20195.192192312144;20347.491312717302;20394.184232630527;20431.59136817285;20451;57533.75373514659;58136.4186358131;58415.70792146012;58757;59363.406365935785;70644;71240.64379678069;71396.64301678458;71663.28336716566;72837.27162728304;19875.701864472332;20368;20475.488112796702;20625.69061464049;20666.79333206709;21587.784122159206;21610.675839862728;22698.773012269667;23110.884445577663;23409.648855266845;17993.550161246072;18123.36568220106;18338.449846504092;18340;18542.536436589195;16463.753043704113;16697.914635549136;16729.91635041817;16937.745933810755;17000.744988825423;17526.82473175303;18273.269069237613;18633.53416164607;18731.625367492303;18921.337753178574;19903.104360303532;19994;19996.100175491847;20041.29855455052;20233.797662023193;17943.37198198109;18040.188191531674;18235;18332.17505212242;18454.35409760652 06:35:05 INFO - PID 1704 | |3;mootools.html;1274.7896597061483;1354.7561438940993;1355.356205802244;1361.7412691588597;1362.182690385768;14863.554093377203;15538.766918496218;15559.533214003599;15564;15574.065556066644;15177.468788592401;15200.16399098049;15252.237388130578;15278.847211527882;15353.769693454578;2771.1825011621568;2916.9854150729325;2920.956185657217;2921.693222211669;2922.1817890990555;2829.533127034047;2868.885244590214;2870.0672281508487;2870.39721658452;2871.267826704198;2777.8333300001964;2904.2013446302226;2904.288449329917;2906.5785461108126;2907.0987993722015;2847.7009913959046;2856.11460447262;2875.0368626510144;2886.653601567815;2887.927801804951;2719.225020869043;2836.489431902257;2950.6164198654037;2959.9852000739857;2964.7479964203067;15015.924920375437;15172.089674619558;15189.772153417707;15215.31531081104;15245.23773811305;8055.556944368057;8387.0354909185;8388.245057944814;8393.874091888627;8395.580220988988;5378.91931621026;5499.312585926759;5632.74652640628;5709.628874123168;5716.1425786131995;1894.4506093232947;1917.966955027675;1919.673655478572;1920.6734855074671;1980.7722111957166;26291.605625915625;26613.73386266132;26675.866620666773;26751;26761.73238267612;6723.529352945345;6747.932520674731;6926.2034865990045;7050.15398152225;7111.751088711871;17118.657626847395;17138.31446742116;17147.399841005754;17156;17182.140892955304;7800.804979875548;7898.802529936797;7906.248906353887;7914.643841027168;7916.247956444128;7187.209406965229;7257.673404696801;7319.34125928669;7334.9266507335315;7340.376068034277;5093.9745301274;5252.816151434681;5273.709945952933;5338.439463856239;5347.786088556492;1430.1133297355707;1467.9339429725687;1557.704036233112;1568.9215539223221;1594.0515393340943;875.5097145598485;896.6727144592177;918.4122161816442;923.4459324405437;926.9953650231706;8506.489610623383;8647.827043459221;8744.69393571222;8813.911860881437;8828.558572071372;717.8564287142488;721.5382155420535;724.4168444402202;740.1488288468197;803.9227435236811;5347;5501.312335958005;5517.36550296721;5603.635763675348;5632.915506267409;20980.685289720666;21046;21918.78081219199;21956.231531896625;22029.889850550644;20895.37313880586;21021.579568408546;21580.676289855666;21813;21817.890910545346;10800.675979720621;11066.778664426827;11161.330320180816;11353.602623908124;11470.311781292981;8363.163683631832;8375.95812020936;8398.664053437795;8434.198751118634;8438.282745966662;17986.550336241176;18504.074796260404;18654.81345186585;18756.906215468836;18857.622847543247;18751.624967500848;18781.906090470005;18785.624287514445;18977.43067707971;19007.71488427647;10981.61564345244;11039.558417663204;11169.218154729091;11199.048080912888;11218.495167717309;13526.661833453849;13976.580702578938;14007.08953917992;14025.438982440588;14036.64908377258;11340.659780206606;11360.715982100513;11535.480903359537;11721.88278117242;11936.164468487123;10816.837747433638;11148.832767508657;11300.717482063013;11354.602588909349;11454.14093942941;9899.257555683496;10008.39949603026;10011.849822252818;10013.899861001297;10032.849507257251;7139.535930164523;7194.71221151169;7242.637868106679;7288.817779555554;7324.377427918686;7343.2289609590225;7468.290512401313;7471.402287816853;7477.177510473734;7525.360344370571 06:35:05 INFO - PID 1704 | |4;prototype.html;498.66127860335024;503.87384196321227;525.7213676751323;529.351544358161;533.9439358867319;6251.781187658433;6278.466330361914;6308.116863639083;6313.6211827290335;6353;6161.507079433646;6234.158388617535;6235.781747638834;6279.843003924904;6299.244090709121;5172.948270517284;5175.146100893348;5215.739213039333;5215.921761173567;5232.215167724834;5149.871253218662;5150.381954165506;5155.639105262633;5158.767855446514;5207.359681700271;5147.588192944574;5184.611154163453;5197.48025197481;5220.1468478690085;5247.160454327309;5124.564412024983;5167.147420675584;5189.2475591039465;5246.239295302198;5250.947490525104;4718.882027949295;4791.880202994918;4828.46886842447;4837.516248375135;4855.271709243606;6034.340556346996;6078.39216078393;6106.01554494791;6119.847003824895;6184.319724830265;5936.347001829795;5962.403759624046;5969.462748352626;6006.657403612595;6018.368071352489;6531.346865313478;6607.603543787388;6623.4701223901975;6643.7674681385915;6646.169228846395;5088.1350170471105;5293.708846013467;5302.973485132549;5309.893802123975;5328.307320048408;6718.227403848571;6877.862442751117;6882.483813713991;6930.61881596512;6946.027556142144;5443.346798384219;5459.3994660587305;5481.698506582096;5486.670799751948;5510.8071217507195;5405.594580406407;5479.808206712746;5531.170324451365;5538.833834984957;5572.359178694502;5988.251468566429;6014.458698717136;6025.66858822769;6053.7578496860515;6057.939420605826;6658.1702776320835;6688.227619680789;6698.39714425704;6714.1271634688155;6725.798226053226;1896.8482521398255;1908.6278175755594;1912.1586501939103;1916.8658193926292;1921.932732354389;1371.5131128449257;1434.870861622438;1442.35094207607;1443.0475885915246;1443.2752860332012;5725.59920805548;5728.684922329312;5755;5762.481376676035;5789.507891829242;6560.8359791005605;6587.50593705474;6656.567323123982;6686.632235227109;6753.29090445506;5575.804846830341;5665.178549110356;5675.971620141873;5680.062789639664;5716.5140963017775;3469.913252168716;3499.3001399720156;3572.4641303804638;3579.2483578448296;3581.068922080278;6402.1753800166725;6467.773627923;6501.4148726613885;6520.510961677893;6558.147440832661;6822.454203663695;6851.623160726208;6883.621400823003;6950.270221626757;6964.616946067914;6344.397282258178;6439.581427207217;6464.806055818333;6471.708773105222;6471.870562588721;6572.638504882278;6629.966850165718;6634.734610615521;6638.701258443285;6669;6574.4411725002;6582.3746744059235;6640.601563906181;6650.4347130492515;6693.397594216543;6501.902471462838;6511.544191906711;6517.576357536745;6551.377619126175;6603.966980165068;5375.274337964324;5449.700266485382;5466.867921510535;5473.397926228191;5478.397376288525;6483.546151769331;6633.170853643294;6634.369734875179;6711.429528490133;6724.93275067243;6318.715657795318;6446.548741588043;6487.513436492188;6503.869922601616;6531.510136739668;6397.936020639921;6420.2616699078335;6448.097266382616;6554.705038273384;6557.540972131905;6320.462760665395;6322.8735425290315;6436.806895793134;6438.452731517874;6444.323346048597;6370.968145159244;6431.678416079271;6488.545801793829;6544.181977252843;6567.343265673395;6276.717547710456;6277.309495955533;6291.339409361951;6333.8733225336155;6363.059266923633 06:35:05 INFO - PID 1704 | |5;yui.html;904.2720609909024;906.5829718329568;914.3964983111147;922.0410772796292;936.7892224249542;19573;20177.19291228351;20398.184072637076;20488.8975555122;20500.282490112848;18014.909925450353;18095.819041809576;18268.908655456737;18284.908575457102;18304.81695183048;946.7301818981609;948.4783369146942;956.3544607390019;959.1176117971448;960.0879164793439;959.2949182350967;961.0629636104812;968.2012339819677;968.4867020479122;969.1423090564865;988.1650005745134;1016.2581315639587;1025.7794574166553;1026.712520494259;1032.4682788363998;1015.7206768138747;1015.7308313296983;1020.5458570935863;1020.9744756381076;1022.4938655365604;727.7853033355173;732.318943382654;734.3757805865025;737.3584981066452;740.7074205688723;20413;20499.590008199903;20642.48393790152;20655.793442065537;20674;20186.19255229789;20308.18767249323;21078.3676489704;21138.788612113836;21325.146994120216;16760.748588771177;16965.236564354633;17117.657646846994;17197.828021719746;17295.22171502273;3632.5640923089136;3638.126849556114;3658.9268214635827;3664.175560498893;3664.798436086014;103816.2910919945;137252;141579;141669;144187;17824.287028518713;17850.19674114668;17951;18023.72964405535;18064.81935180658;31330.68669313278;32134.678653213636;32213.51679724806;32220.83889580584;32273.35453290921;9313.301502387212;9521.857172142423;9533.475658838692;9584.424934503952;9643.855342169873;4307.440032795779;4423.623991960655;4427.136708341907;4432.756198409054;4435.622972047412;5013.423456302572;5040.294358789773;5043.87390315245;5101.234814777813;5110.821121260738;888.595688961521;1094.5731164845718;1096.714854137914;1096.9122470202365;1102.6306187427142;694.7047504810436;701.1761180612759;702.2521015118882;718.8598223346502;728.883378659412;10983.560657573607;11334.829977550344;11361.829572556418;12384.380780960915;12756.936215318863;656.4584218020057;670.2225418514499;676.7022510095542;679.7383007542132;686.3959715450471;5686.658800471985;5691.146328050825;5709.600327977085;5752.395998420189;5841.912371314434;117272;117905.41047294709;120368;121574.39212803879;122938;100992;105079.47460262649;109407;110041.44979275213;110974;94724;94780;94794.52602736941;97189.5140524307;99494;12540.874591254336;12670;12718.427670755023;12727.618171454871;12738.617841464771;44024.779876100416;44237.336439952785;44640;44652;45375.773121135506;55536.72231638816;55705.442945571645;55761.1635825471;56952.145717815096;57134.71432642976;17215.483535493957;17486.30054797794;17509.124543773014;17518.299268029645;17537;20432.591348172657;20508.282210122576;20536.07587658529;20549.58900822005;20627.4843128923;18788.906055469633;18809;18958.43124706261;19101.808981910002;19328.516787080436;18400.07999599988;18602;18925.43223703291;19256.518587035436;19274.518137046685;18174.909125454287;18409.53976150607;18429;18738.812611873705;18776.7183492245;12922.483100675867;13006.934965325112;13152.145110567782;13378.799318010433;13533.120347177402;11658.94170529142;11820.0543956485;11823.645290641294;11962.641120766391;12110.212836165621 06:35:05 INFO - PID 1704 | __end_tp_report 06:35:05 INFO - PID 1704 | __start_cc_report 06:35:05 INFO - PID 1704 | _x_x_mozilla_cycle_collect,284 06:35:05 INFO - PID 1704 | __end_cc_report 06:35:05 INFO - PID 1704 | __startTimestamp1505655305613__endTimestamp 06:35:05 INFO - PID 1704 | 06:35:05 INFO - PID 1704 | ------- Summary: start ------- 06:35:05 INFO - PID 1704 | Number of tests: 6 06:35:05 INFO - PID 1704 | 06:35:05 INFO - PID 1704 | [#0] dojo.html Cycles:165 Average:6130.02 Median:6409.00 stddev:868.29 (13.5%) stddev-sans-first:838.91 06:35:05 INFO - PID 1704 | Values: 3151.0 3152.6 3152.9 3171.3 3179.5 6114.7 6222.7 6231.7 6301.8 6365.7 6230.6 6231.3 6245.8 6251.9 6283.9 5758.5 5776.2 5849.3 5849.5 5863.6 5707.5 5806.7 5828.1 5860.6 5893.0 97.4 5326.4 5681.9 5790.7 5874.5 5722.1 5736.0 5812.4 5817.1 5826.5 5672.7 5691.5 5763.0 5776.2 5803.8 6179.4 6187.8 6246.3 6304.6 6314.5 6631.6 6662.8 6741.6 6787.5 6793.3 6604.7 6635.7 6644.5 6645.3 6755.8 6117.4 6157.2 6186.3 6193.2 6193.7 6832.1 6901.2 6966.8 6972.4 6987.4 6533.1 6609.6 6617.9 6619.8 6629.0 6640.1 6641.8 6743.4 6744.9 6755.5 6494.7 6570.7 6631.8 6635.3 6727.5 6599.6 6643.1 6653.0 6728.6 6736.0 5271.0 5318.9 5347.7 5350.0 5363.2 4590.9 4595.7 4624.0 4630.6 4633.0 5726.6 5770.1 5798.2 5856.0 5865.7 6623.9 6625.0 6637.1 6653.2 6659.8 5818.4 5828.3 5833.4 5833.5 5885.8 5760.2 5821.5 5831.7 5837.4 5840.4 6511.1 6522.6 6618.8 6621.3 6634.0 6596.3 6693.6 6707.8 6719.4 6814.9 6485.1 6491.1 6493.4 6495.4 6523.3 6663.4 6681.3 6702.7 6714.9 6723.5 6761.7 6769.6 6813.9 6846.3 6870.2 6409.7 6505.3 6514.4 6604.7 6613.9 6409.0 6415.0 6420.6 6440.9 6444.2 6262.6 6347.0 6360.8 6368.4 6443.5 6553.3 6564.1 6581.1 6605.4 6614.6 6302.4 6304.4 6370.1 6402.7 6418.2 06:35:05 INFO - PID 1704 | 06:35:05 INFO - PID 1704 | [#1] ext.html Cycles:180 Average:37226.79 Median:35456.47 stddev:53475.23 (150.8%) stddev-sans-first:53555.45 06:35:05 INFO - PID 1704 | Values: 839.4 855.6 860.8 861.2 870.3 38356.4 42317.8 42382.4 42517.4 42532.0 38797.4 39202.4 39213.0 39226.4 39235.4 6923.4 7063.6 7068.8 7071.3 7074.9 6923.0 6987.3 6994.3 7008.1 7018.4 6971.5 7689.7 7795.1 7919.5 7925.5 7776.0 7808.5 7842.0 7851.1 7868.4 7511.8 7681.3 7688.8 7740.9 7777.4 37844.8 38181.0 38331.8 38382.4 38419.6 3570.5 3619.6 3621.3 3635.7 3642.3 31282.4 31298.4 31374.8 31541.1 31562.2 5246.5 5261.3 5264.8 5281.8 5293.6 189472.0 189878.0 191096.0 191381.0 191458.0 40946.4 41417.4 46133.1 46291.0 47523.3 50156.0 50263.7 50377.7 50392.5 50428.2 15382.2 15420.2 15557.8 15570.8 15581.8 12820.6 12837.1 12839.4 12844.6 12973.5 13532.7 13632.0 13741.5 13881.5 13929.5 5608.6 5651.6 5654.3 5666.2 5714.3 1013.3 1016.9 1017.5 1021.9 1026.8 22783.2 22877.5 23247.7 23310.0 23502.3 1102.2 1114.1 1129.8 1139.7 1143.2 12317.0 12359.4 12386.7 12430.2 12454.1 225686.0 227309.0 227681.0 228207.0 228997.0 52538.5 52708.7 52752.5 52781.5 52845.0 1413.3 1434.9 1438.0 1447.8 1448.5 22562.8 22827.2 22902.5 23123.5 23347.0 37925.6 38037.2 38142.0 38196.2 38269.0 198416.0 198846.0 199652.0 199708.0 200246.0 30194.2 30715.4 33532.7 33611.3 34101.8 41517.4 42812.1 42966.4 43125.8 43250.8 32041.7 32332.4 32368.7 32504.8 32959.7 31291.8 31582.8 31782.7 31792.2 31923.0 35361.6 35551.3 35564.8 35641.5 35730.5 30908.7 31125.8 31266.7 31485.4 31926.5 23113.2 23365.0 23557.6 23836.9 23936.4 06:35:05 INFO - PID 1704 | 06:35:05 INFO - PID 1704 | [#2] jquery.html Cycles:180 Average:34393.96 Median:21715.35 stddev:75504.11 (347.7%) stddev-sans-first:75673.17 06:35:05 INFO - PID 1704 | Values: 554.5 557.7 566.9 569.8 572.8 21893.6 23003.5 23071.8 23078.8 23078.9 23339.0 24208.6 24429.4 24454.9 24652.9 6465.5 6659.0 6662.6 6665.9 6670.8 6446.2 6574.7 6584.5 6588.3 6619.7 6239.4 6543.7 6571.0 6578.3 6587.6 6594.3 6630.0 6646.9 6649.8 6649.8 5897.1 6165.6 6166.3 6168.0 6184.2 27936.4 28477.3 28486.0 28513.6 28524.6 2055.0 2080.0 2084.8 2086.1 2089.3 18959.0 19149.3 19496.5 19500.7 19512.9 2421.5 2497.1 2514.7 2520.4 2520.9 118639.4 123281.4 125245.0 129261.7 133940.0 20307.5 20333.9 21281.4 21345.9 21820.0 41486.4 41618.4 41655.4 41678.6 41708.0 11347.4 11400.7 11470.5 11546.2 11725.4 9575.0 9634.1 9685.1 9726.4 9827.9 10993.5 11092.3 11194.2 11395.9 11396.9 3876.9 4098.3 4161.5 4176.7 4245.4 955.2 976.5 986.7 993.2 996.2 18968.4 19260.5 19300.3 19454.1 19620.3 1027.5 1038.1 1038.7 1044.3 1044.4 10596.1 10641.6 10677.1 10792.1 10963.6 423919.0 426320.0 458562.0 463090.0 464259.0 106396.0 113721.4 118056.4 118744.0 119316.4 1029.1 1075.1 1153.6 1180.9 1208.6 20195.2 20347.5 20394.2 20431.6 20451.0 57533.8 58136.4 58415.7 58757.0 59363.4 70644.0 71240.6 71396.6 71663.3 72837.3 19875.7 20368.0 20475.5 20625.7 20666.8 21587.8 21610.7 22698.8 23110.9 23409.6 17993.6 18123.4 18338.4 18340.0 18542.5 16463.8 16697.9 16729.9 16937.7 17000.7 17526.8 18273.3 18633.5 18731.6 18921.3 19903.1 19994.0 19996.1 20041.3 20233.8 17943.4 18040.2 18235.0 18332.2 18454.4 06:35:05 INFO - PID 1704 | 06:35:05 INFO - PID 1704 | [#3] mootools.html Cycles:180 Average:9401.21 Median:2774.51 stddev:6634.52 (239.1%) stddev-sans-first:6625.04 06:35:05 INFO - PID 1704 | Values: 1274.8 1354.8 1355.4 1361.7 1362.2 14863.6 15538.8 15559.5 15564.0 15574.1 15177.5 15200.2 15252.2 15278.8 15353.8 2771.2 2917.0 2921.0 2921.7 2922.2 2829.5 2868.9 2870.1 2870.4 2871.3 2777.8 2904.2 2904.3 2906.6 2907.1 2847.7 2856.1 2875.0 2886.7 2887.9 2719.2 2836.5 2950.6 2960.0 2964.7 15015.9 15172.1 15189.8 15215.3 15245.2 8055.6 8387.0 8388.2 8393.9 8395.6 5378.9 5499.3 5632.7 5709.6 5716.1 1894.5 1918.0 1919.7 1920.7 1980.8 26291.6 26613.7 26675.9 26751.0 26761.7 6723.5 6747.9 6926.2 7050.2 7111.8 17118.7 17138.3 17147.4 17156.0 17182.1 7800.8 7898.8 7906.2 7914.6 7916.2 7187.2 7257.7 7319.3 7334.9 7340.4 5094.0 5252.8 5273.7 5338.4 5347.8 1430.1 1467.9 1557.7 1568.9 1594.1 875.5 896.7 918.4 923.4 927.0 8506.5 8647.8 8744.7 8813.9 8828.6 717.9 721.5 724.4 740.1 803.9 5347.0 5501.3 5517.4 5603.6 5632.9 20980.7 21046.0 21918.8 21956.2 22029.9 20895.4 21021.6 21580.7 21813.0 21817.9 10800.7 11066.8 11161.3 11353.6 11470.3 8363.2 8376.0 8398.7 8434.2 8438.3 17986.6 18504.1 18654.8 18756.9 18857.6 18751.6 18781.9 18785.6 18977.4 19007.7 10981.6 11039.6 11169.2 11199.0 11218.5 13526.7 13976.6 14007.1 14025.4 14036.6 11340.7 11360.7 11535.5 11721.9 11936.2 10816.8 11148.8 11300.7 11354.6 11454.1 9899.3 10008.4 10011.8 10013.9 10032.8 7139.5 7194.7 7242.6 7288.8 7324.4 7343.2 7468.3 7471.4 7477.2 7525.4 06:35:05 INFO - PID 1704 | 06:35:05 INFO - PID 1704 | [#4] prototype.html Cycles:180 Average:5609.09 Median:6209.24 stddev:1492.37 (24.0%) stddev-sans-first:1446.43 06:35:05 INFO - PID 1704 | Values: 498.7 503.9 525.7 529.4 533.9 6251.8 6278.5 6308.1 6313.6 6353.0 6161.5 6234.2 6235.8 6279.8 6299.2 5172.9 5175.1 5215.7 5215.9 5232.2 5149.9 5150.4 5155.6 5158.8 5207.4 5147.6 5184.6 5197.5 5220.1 5247.2 5124.6 5167.1 5189.2 5246.2 5250.9 4718.9 4791.9 4828.5 4837.5 4855.3 6034.3 6078.4 6106.0 6119.8 6184.3 5936.3 5962.4 5969.5 6006.7 6018.4 6531.3 6607.6 6623.5 6643.8 6646.2 5088.1 5293.7 5303.0 5309.9 5328.3 6718.2 6877.9 6882.5 6930.6 6946.0 5443.3 5459.4 5481.7 5486.7 5510.8 5405.6 5479.8 5531.2 5538.8 5572.4 5988.3 6014.5 6025.7 6053.8 6057.9 6658.2 6688.2 6698.4 6714.1 6725.8 1896.8 1908.6 1912.2 1916.9 1921.9 1371.5 1434.9 1442.4 1443.0 1443.3 5725.6 5728.7 5755.0 5762.5 5789.5 6560.8 6587.5 6656.6 6686.6 6753.3 5575.8 5665.2 5676.0 5680.1 5716.5 3469.9 3499.3 3572.5 3579.2 3581.1 6402.2 6467.8 6501.4 6520.5 6558.1 6822.5 6851.6 6883.6 6950.3 6964.6 6344.4 6439.6 6464.8 6471.7 6471.9 6572.6 6630.0 6634.7 6638.7 6669.0 6574.4 6582.4 6640.6 6650.4 6693.4 6501.9 6511.5 6517.6 6551.4 6604.0 5375.3 5449.7 5466.9 5473.4 5478.4 6483.5 6633.2 6634.4 6711.4 6724.9 6318.7 6446.5 6487.5 6503.9 6531.5 6397.9 6420.3 6448.1 6554.7 6557.5 6320.5 6322.9 6436.8 6438.5 6444.3 6371.0 6431.7 6488.5 6544.2 6567.3 6276.7 6277.3 6291.3 6333.9 6363.1 06:35:05 INFO - PID 1704 | 06:35:05 INFO - PID 1704 | [#5] yui.html Cycles:180 Average:24588.31 Median:20494.24 stddev:34457.99 (168.1%) stddev-sans-first:34508.76 06:35:05 INFO - PID 1704 | Values: 904.3 906.6 914.4 922.0 936.8 19573.0 20177.2 20398.2 20488.9 20500.3 18014.9 18095.8 18268.9 18284.9 18304.8 946.7 948.5 956.4 959.1 960.1 959.3 961.1 968.2 968.5 969.1 988.2 1016.3 1025.8 1026.7 1032.5 1015.7 1015.7 1020.5 1021.0 1022.5 727.8 732.3 734.4 737.4 740.7 20413.0 20499.6 20642.5 20655.8 20674.0 20186.2 20308.2 21078.4 21138.8 21325.1 16760.7 16965.2 17117.7 17197.8 17295.2 3632.6 3638.1 3658.9 3664.2 3664.8 103816.3 137252.0 141579.0 141669.0 144187.0 17824.3 17850.2 17951.0 18023.7 18064.8 31330.7 32134.7 32213.5 32220.8 32273.4 9313.3 9521.9 9533.5 9584.4 9643.9 4307.4 4423.6 4427.1 4432.8 4435.6 5013.4 5040.3 5043.9 5101.2 5110.8 888.6 1094.6 1096.7 1096.9 1102.6 694.7 701.2 702.3 718.9 728.9 10983.6 11334.8 11361.8 12384.4 12756.9 656.5 670.2 676.7 679.7 686.4 5686.7 5691.1 5709.6 5752.4 5841.9 117272.0 117905.4 120368.0 121574.4 122938.0 100992.0 105079.5 109407.0 110041.4 110974.0 94724.0 94780.0 94794.5 97189.5 99494.0 12540.9 12670.0 12718.4 12727.6 12738.6 44024.8 44237.3 44640.0 44652.0 45375.8 55536.7 55705.4 55761.2 56952.1 57134.7 17215.5 17486.3 17509.1 17518.3 17537.0 20432.6 20508.3 20536.1 20549.6 20627.5 18788.9 18809.0 18958.4 19101.8 19328.5 18400.1 18602.0 18925.4 19256.5 19274.5 18174.9 18409.5 18429.0 18738.8 18776.7 12922.5 13006.9 13152.1 13378.8 13533.1 11658.9 11820.1 11823.6 11962.6 12110.2 06:35:05 INFO - PID 1704 | -------- Summary: end -------- 06:35:05 INFO - PID 1704 | 06:35:06 INFO - TEST-INFO | 1704: exit 0 06:35:06 INFO - TEST-OK | dromaeo_css | took 1108834ms 06:35:06 INFO - TEST-START | kraken 06:35:06 INFO - Initialising browser for kraken test... 06:35:06 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:60522/getInfo.html -profile /tmp/tmppvOUwn/profile 06:35:06 INFO - TEST-INFO | started process 2085 (/builds/slave/test/build/application/firefox/firefox http://localhost:60522/getInfo.html) 06:35:11 INFO - TEST-INFO | 2085: exit 0 06:35:12 INFO - Browser initialized. 06:35:12 INFO - Running cycle 1/1 for kraken test... 06:35:12 INFO - Using env: {'DISPLAY': ':0', 06:35:12 INFO - 'HOME': '/home/cltbld', 06:35:12 INFO - 'JSGC_DISABLE_POISONING': '1', 06:35:12 INFO - 'LANG': 'en_US.UTF-8', 06:35:12 INFO - 'LANGUAGE': 'en_US:en', 06:35:12 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 06:35:12 INFO - 'LOGNAME': 'cltbld', 06:35:12 INFO - 'MAIL': '/var/mail/cltbld', 06:35:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:35:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 06:35:12 INFO - 'MOZ_CRASHREPORTER': '1', 06:35:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:35:12 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 06:35:12 INFO - 'MOZ_NO_REMOTE': '1', 06:35:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:35:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:35:12 INFO - 'NO_EM_RESTART': '1', 06:35:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:35:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:35:12 INFO - 'PWD': '/builds/slave/test', 06:35:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:35:12 INFO - 'RUST_BACKTRACE': 'full', 06:35:12 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 06:35:12 INFO - 'SHELL': '/bin/bash', 06:35:12 INFO - 'SHLVL': '1', 06:35:12 INFO - 'TERM': 'linux', 06:35:12 INFO - 'TMOUT': '86400', 06:35:12 INFO - 'USER': 'cltbld', 06:35:12 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1505652212.867325-1465953002', 06:35:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:35:12 INFO - '_': '/tools/buildbot/bin/python'} 06:35:12 INFO - TEST-INFO | started process 2320 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppvOUwn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 06:35:12 INFO - PID 2320 | 06:35:12 INFO - PID 2320 | (/builds/slave/test/build/application/firefox/firefox:2369): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 06:35:12 INFO - PID 2320 | 06:35:23 INFO - PID 2320 | RSS: Main: 155250688 06:35:23 INFO - PID 2320 | 06:35:25 INFO - PID 2320 | Cycle 1(1): loaded http://localhost:60522/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60522/tests/kraken/driver.html?testName=audio-beat-detection) 06:35:26 INFO - PID 2320 | RSS: Main: 161599488 06:35:26 INFO - PID 2320 | 06:35:29 INFO - PID 2320 | Cycle 1(1): loaded http://localhost:60522/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60522/tests/kraken/driver.html?testName=audio-dft) 06:35:30 INFO - PID 2320 | RSS: Main: 161681408 06:35:30 INFO - PID 2320 | 06:35:33 INFO - PID 2320 | Cycle 1(1): loaded http://localhost:60522/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60522/tests/kraken/driver.html?testName=audio-fft) 06:35:33 INFO - PID 2320 | RSS: Main: 156749824 06:35:33 INFO - PID 2320 | 06:35:35 INFO - PID 2320 | Cycle 1(1): loaded http://localhost:60522/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60522/tests/kraken/driver.html?testName=audio-oscillator) 06:35:36 INFO - PID 2320 | RSS: Main: 157241344 06:35:36 INFO - PID 2320 | 06:35:38 INFO - PID 2320 | Cycle 1(1): loaded http://localhost:60522/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60522/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:35:39 INFO - PID 2320 | RSS: Main: 158646272 06:35:39 INFO - PID 2320 | 06:35:42 INFO - PID 2320 | Cycle 1(1): loaded http://localhost:60522/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60522/tests/kraken/driver.html?testName=imaging-darkroom) 06:35:42 INFO - PID 2320 | RSS: Main: 160411648 06:35:42 INFO - PID 2320 | 06:35:46 INFO - PID 2320 | Cycle 1(1): loaded http://localhost:60522/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60522/tests/kraken/driver.html?testName=imaging-desaturate) 06:35:47 INFO - PID 2320 | RSS: Main: 160624640 06:35:47 INFO - PID 2320 | 06:35:50 INFO - PID 2320 | Cycle 1(1): loaded http://localhost:60522/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60522/tests/kraken/driver.html?testName=json-parse-financial) 06:35:50 INFO - PID 2320 | RSS: Main: 160661504 06:35:50 INFO - PID 2320 | 06:35:52 INFO - PID 2320 | Cycle 1(1): loaded http://localhost:60522/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60522/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:35:52 INFO - PID 2320 | RSS: Main: 160530432 06:35:52 INFO - PID 2320 | 06:35:54 INFO - PID 2320 | Cycle 1(1): loaded http://localhost:60522/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60522/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:35:55 INFO - PID 2320 | RSS: Main: 160698368 06:35:55 INFO - PID 2320 | 06:35:57 INFO - PID 2320 | Cycle 1(1): loaded http://localhost:60522/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60522/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:35:58 INFO - PID 2320 | RSS: Main: 157458432 06:35:58 INFO - PID 2320 | 06:36:00 INFO - PID 2320 | Cycle 1(1): loaded http://localhost:60522/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60522/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:36:01 INFO - PID 2320 | RSS: Main: 157917184 06:36:01 INFO - PID 2320 | 06:36:04 INFO - PID 2320 | Cycle 1(1): loaded http://localhost:60522/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60522/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:36:04 INFO - PID 2320 | RSS: Main: 158007296 06:36:04 INFO - PID 2320 | 06:36:06 INFO - PID 2320 | Cycle 1(1): loaded http://localhost:60522/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60522/tests/kraken/driver.html?testName=ai-astar) 06:36:06 INFO - PID 2320 | RSS: Main: 158965760 06:36:06 INFO - PID 2320 | 06:36:06 INFO - PID 2320 | __start_tp_report 06:36:06 INFO - PID 2320 | _x_x_mozilla_page_load 06:36:06 INFO - PID 2320 | _x_x_mozilla_page_load_details 06:36:06 INFO - PID 2320 | |i|pagename|runs| 06:36:06 INFO - PID 2320 | |0;ai-astar;109;107;106;107;106;107;108;108;106;108 06:36:06 INFO - PID 2320 | |1;audio-beat-detection;117;121;117;115;119;115;115;117;141;122 06:36:06 INFO - PID 2320 | |2;audio-dft;164;161;164;163;162;163;164;164;167;169 06:36:06 INFO - PID 2320 | |3;audio-fft;73;74;75;73;73;74;75;74;74;73 06:36:06 INFO - PID 2320 | |4;audio-oscillator;79;79;79;79;79;79;79;79;78;86 06:36:06 INFO - PID 2320 | |5;imaging-gaussian-blur;111;113;113;114;114;114;114;114;114;114 06:36:06 INFO - PID 2320 | |6;imaging-darkroom;150;149;148;149;149;149;148;151;152;150 06:36:06 INFO - PID 2320 | |7;imaging-desaturate;94;94;95;94;94;94;95;101;95;95 06:36:06 INFO - PID 2320 | |8;json-parse-financial;64;54;58;55;55;55;56;54;55;56 06:36:06 INFO - PID 2320 | |9;json-stringify-tinderbox;38;38;38;39;38;40;38;38;38;39 06:36:06 INFO - PID 2320 | |10;stanford-crypto-aes;75;74;78;78;78;76;75;75;73;76 06:36:06 INFO - PID 2320 | |11;stanford-crypto-ccm;111;126;112;121;114;116;111;113;120;127 06:36:06 INFO - PID 2320 | |12;stanford-crypto-pbkdf2;170;171;178;173;170;173;169;173;165;165 06:36:06 INFO - PID 2320 | |13;stanford-crypto-sha256-iterative;68;65;67;67;63;67;65;63;64;72 06:36:06 INFO - PID 2320 | __end_tp_report 06:36:06 INFO - PID 2320 | __start_cc_report 06:36:06 INFO - PID 2320 | _x_x_mozilla_cycle_collect,779 06:36:06 INFO - PID 2320 | __end_cc_report 06:36:06 INFO - PID 2320 | __startTimestamp1505655366819__endTimestamp 06:36:06 INFO - PID 2320 | 06:36:06 INFO - PID 2320 | ------- Summary: start ------- 06:36:06 INFO - PID 2320 | Number of tests: 14 06:36:06 INFO - PID 2320 | 06:36:06 INFO - PID 2320 | [#0] ai-astar Cycles:10 Average:107.20 Median:107.50 stddev:1.03 (1.0%) stddev-sans-first:0.87 06:36:06 INFO - PID 2320 | Values: 109.0 107.0 106.0 107.0 106.0 107.0 108.0 108.0 106.0 108.0 06:36:06 INFO - PID 2320 | 06:36:06 INFO - PID 2320 | [#1] audio-beat-detection Cycles:10 Average:119.90 Median:118.00 stddev:7.81 (6.6%) stddev-sans-first:8.21 06:36:06 INFO - PID 2320 | Values: 117.0 121.0 117.0 115.0 119.0 115.0 115.0 117.0 141.0 122.0 06:36:06 INFO - PID 2320 | 06:36:06 INFO - PID 2320 | [#2] audio-dft Cycles:10 Average:164.10 Median:164.00 stddev:2.33 (1.4%) stddev-sans-first:2.47 06:36:06 INFO - PID 2320 | Values: 164.0 161.0 164.0 163.0 162.0 163.0 164.0 164.0 167.0 169.0 06:36:06 INFO - PID 2320 | 06:36:06 INFO - PID 2320 | [#3] audio-fft Cycles:10 Average:73.80 Median:74.00 stddev:0.79 (1.1%) stddev-sans-first:0.78 06:36:06 INFO - PID 2320 | Values: 73.0 74.0 75.0 73.0 73.0 74.0 75.0 74.0 74.0 73.0 06:36:06 INFO - PID 2320 | 06:36:06 INFO - PID 2320 | [#4] audio-oscillator Cycles:10 Average:79.60 Median:79.00 stddev:2.27 (2.9%) stddev-sans-first:2.40 06:36:06 INFO - PID 2320 | Values: 79.0 79.0 79.0 79.0 79.0 79.0 79.0 79.0 78.0 86.0 06:36:06 INFO - PID 2320 | 06:36:06 INFO - PID 2320 | [#5] imaging-gaussian-blur Cycles:10 Average:113.50 Median:114.00 stddev:0.97 (0.9%) stddev-sans-first:0.44 06:36:06 INFO - PID 2320 | Values: 111.0 113.0 113.0 114.0 114.0 114.0 114.0 114.0 114.0 114.0 06:36:06 INFO - PID 2320 | 06:36:06 INFO - PID 2320 | [#6] imaging-darkroom Cycles:10 Average:149.50 Median:149.50 stddev:1.27 (0.8%) stddev-sans-first:1.33 06:36:06 INFO - PID 2320 | Values: 150.0 149.0 148.0 149.0 149.0 149.0 148.0 151.0 152.0 150.0 06:36:06 INFO - PID 2320 | 06:36:06 INFO - PID 2320 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:94.50 stddev:2.13 (2.3%) stddev-sans-first:2.22 06:36:06 INFO - PID 2320 | Values: 94.0 94.0 95.0 94.0 94.0 94.0 95.0 101.0 95.0 95.0 06:36:06 INFO - PID 2320 | 06:36:06 INFO - PID 2320 | [#8] json-parse-financial Cycles:10 Average:56.20 Median:55.50 stddev:2.97 (5.4%) stddev-sans-first:1.22 06:36:06 INFO - PID 2320 | Values: 64.0 54.0 58.0 55.0 55.0 55.0 56.0 54.0 55.0 56.0 06:36:06 INFO - PID 2320 | 06:36:06 INFO - PID 2320 | [#9] json-stringify-tinderbox Cycles:10 Average:38.40 Median:38.00 stddev:0.70 (1.8%) stddev-sans-first:0.73 06:36:06 INFO - PID 2320 | Values: 38.0 38.0 38.0 39.0 38.0 40.0 38.0 38.0 38.0 39.0 06:36:06 INFO - PID 2320 | 06:36:06 INFO - PID 2320 | [#10] stanford-crypto-aes Cycles:10 Average:75.80 Median:76.00 stddev:1.75 (2.3%) stddev-sans-first:1.83 06:36:06 INFO - PID 2320 | Values: 75.0 74.0 78.0 78.0 78.0 76.0 75.0 75.0 73.0 76.0 06:36:06 INFO - PID 2320 | 06:36:06 INFO - PID 2320 | [#11] stanford-crypto-ccm Cycles:10 Average:117.10 Median:118.00 stddev:6.05 (5.1%) stddev-sans-first:6.00 06:36:06 INFO - PID 2320 | Values: 111.0 126.0 112.0 121.0 114.0 116.0 111.0 113.0 120.0 127.0 06:36:06 INFO - PID 2320 | 06:36:06 INFO - PID 2320 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.70 Median:172.00 stddev:3.92 (2.3%) stddev-sans-first:4.15 06:36:06 INFO - PID 2320 | Values: 170.0 171.0 178.0 173.0 170.0 173.0 169.0 173.0 165.0 165.0 06:36:06 INFO - PID 2320 | 06:36:06 INFO - PID 2320 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.10 Median:67.00 stddev:2.73 (4.1%) stddev-sans-first:2.80 06:36:06 INFO - PID 2320 | Values: 68.0 65.0 67.0 67.0 63.0 67.0 65.0 63.0 64.0 72.0 06:36:06 INFO - PID 2320 | -------- Summary: end -------- 06:36:06 INFO - PID 2320 | 06:36:07 INFO - PID 2320 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 06:36:07 INFO - TEST-INFO | 2320: exit 0 06:36:07 INFO - TEST-OK | kraken | took 61138ms 06:36:07 INFO - SUITE-END | took 1169s 06:36:07 INFO - Completed test suite (00:19:30) 06:36:07 INFO - javascript benchmark 06:36:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8654.953448905146, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3150.952735708964, 3152.605924259466, 3152.8581213845373, 3171.2706077602165, 3179.507176387661, 6114.663693496856, 6222.7199776010075, 6231.719572619231, 6301.810945671631, 6365.713542890564, 6230.595011324261, 6231.345708700589, 6245.781397651083, 6251.874962500748, 6283.937160628392, 5758.510526605222, 5776.249087618614, 5849.268841394825, 5849.502792262641, 5863.58954873159, 5707.543396528268, 5806.65160090396, 5828.096645020008, 5860.589758716891, 5893.027650437694, 97.35334423948923, 5326.414094449627, 5681.914771278434, 5790.739416726257, 5874.5006674432725, 5722.141678748221, 5736.024875771129, 5812.389699081577, 5817.127430885401, 5826.533877289807, 5672.716364181816, 5691.4877661010705, 5763.049096899007, 5776.220210271644, 5803.767849286023, 6179.38206179383, 6187.752489900398, 6246.312905580428, 6304.590201636879, 6314.463270622003, 6631.635260060695, 6662.766803161866, 6741.5617984831315, 6787.457003439713, 6793.252742198354, 6604.702788374535, 6635.6682165892, 6644.501662375341, 6645.269020407752, 6755.7973260802255, 6117.418845209742, 6157.230346206724, 6186.288576813679, 6193.163922870445, 6193.721282542297, 6832.145981752331, 6901.240863505011, 6966.790996270019, 6972.372486476242, 6987.406070483964, 6533.1180290661105, 6609.570377925419, 6617.867642647217, 6619.801405957829, 6628.966855165693, 6640.136782218281, 6641.833954151185, 6743.426808721216, 6744.865102697919, 6755.459563235027, 6494.740210391531, 6570.704318305688, 6631.834204144935, 6635.336466353423, 6727.529072964846, 6599.637019963852, 6643.069970204176, 6653.002049692488, 6728.596284222962, 6735.966320168368, 5271.0, 5318.8936221276135, 5347.705876176848, 5349.973250133802, 5363.222332761728, 4590.875235567308, 4595.6783025187915, 4624.0, 4630.560096790799, 4633.027064316531, 5726.599138060295, 5770.13447982806, 5798.246227990418, 5856.0, 5865.67738774363, 6623.9006414903815, 6624.96687516569, 6637.103990961158, 6653.201615806134, 6659.833504162338, 5818.4472475115645, 5828.329742079715, 5833.416658334133, 5833.4749872511675, 5885.823425297162, 5760.164776107441, 5821.53427725781, 5831.679257640785, 5837.387074357216, 5840.3575606683235, 6511.088447617338, 6522.641254731036, 6618.768343107968, 6621.304762999912, 6633.9668301658185, 6596.3073877244115, 6693.564918280491, 6707.765228216989, 6719.361660642231, 6814.863702725917, 6485.092087107903, 6491.091247225487, 6493.350664933469, 6495.350464953467, 6523.3150519194805, 6663.433608143168, 6681.33186681328, 6702.698378572878, 6714.9328506716265, 6723.462123030048, 6761.661916904233, 6769.627670478171, 6813.863722725617, 6846.281140480178, 6870.2442731298515, 6409.7115629795835, 6505.31694172124, 6514.413702766774, 6604.7027883746305, 6613.867722645424, 6409.0, 6415.0, 6420.6147631142285, 6440.9033864493, 6444.194475690539, 6262.561620686691, 6347.0479428086155, 6360.77737279193, 6368.42684158428, 6443.452306553995, 6553.311902250195, 6564.113844630913, 6581.14445122131, 6605.405513503808, 6614.6361950093205, 6302.432781049728, 6304.432601065926, 6370.076338930921, 6402.679866006774, 6418.1656384669695], "value": 6068.476796829227, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [839.4249938791928, 855.5636625321084, 860.8106216632343, 861.1861790607878, 870.3385427075423, 38356.424653630216, 42317.788411057976, 42382.36426453606, 42517.36223956643, 42532.0, 38797.41803872944, 39202.411963820494, 39213.0, 39226.41160382597, 39235.41146882799, 6923.446124310069, 7063.6115013674225, 7068.752593659223, 7071.257517960617, 7074.893876591856, 6922.965385173067, 6987.2663370346145, 6994.300569943016, 7008.0539127217935, 7018.3683468487825, 6971.477139214554, 7689.653965571562, 7795.064592248909, 7919.485233459835, 7925.484843485184, 7775.961120194391, 7808.49244799092, 7842.0, 7851.057873055212, 7868.449208555404, 7511.849763004764, 7681.3470854977395, 7688.846223075507, 7740.9225907740765, 7777.377809775261, 37844.81077594622, 38181.0, 38331.80834095839, 38382.424263636065, 38419.61580384188, 3570.4822800694014, 3619.6380361963593, 3621.3300539400034, 3635.654612811779, 3642.3261696586214, 31282.37435251305, 31298.37403251945, 31374.843125784453, 31541.053768386984, 31562.210944726565, 5246.527812496894, 5261.342332208474, 5264.763085661116, 5281.788728450896, 5293.57651387888, 189472.0, 189878.0, 191096.0, 191381.0, 191458.0, 40946.38580421296, 41417.378739318934, 46133.07733845304, 46291.0, 47523.28715069277, 50156.0, 50263.74868125636, 50377.74811125921, 50392.49607503951, 50428.24357634639, 15382.153981530899, 15420.228988550753, 15557.844421555867, 15570.766438503431, 15581.844181558039, 12820.615381538382, 12837.101402901893, 12839.422225999853, 12844.550440734529, 12973.481060757653, 13532.729345413036, 13632.0, 13741.519046833513, 13881.514147004806, 13929.467758546552, 5608.635438696471, 5651.576131790132, 5654.349749778746, 5666.206731057655, 5714.342850572238, 1013.3312014070679, 1016.8576399304103, 1017.4759998600726, 1021.897810218987, 1026.8151732688189, 22783.202587909345, 22877.542449150922, 23247.651285230735, 23310.0, 23502.294931152093, 1102.239454776202, 1114.0920150077661, 1129.768397478515, 1139.715071232192, 1143.17691262291, 12317.0, 12359.443825027894, 12386.690332741753, 12430.192037517532, 12454.128211025141, 225686.0, 227309.0, 227681.0, 228207.0, 228997.0, 52538.47461525412, 52708.73645631747, 52752.47247527552, 52781.47218527842, 52845.0, 1413.3216056293038, 1434.8923830712738, 1438.0149597525728, 1447.8334991476115, 1448.543708731725, 22562.774372256066, 22827.20104796324, 22902.54194916059, 23123.537529249657, 23347.0, 37925.62074379331, 38037.23925521419, 38142.0, 38196.23607527778, 38269.0, 198416.0, 198846.0, 199652.0, 199708.0, 200246.0, 30194.24514387158, 30715.385692286476, 33532.664673352956, 33611.3277734439, 34101.82949085338, 41517.37723934204, 42812.14375712406, 42966.35550466808, 43125.78437107794, 43250.78374608233, 32041.6795832048, 32332.35335293328, 32368.67631323751, 32504.83747581247, 32959.67040329662, 31291.84354078215, 31582.842085789423, 31782.6821731789, 31792.18493780761, 31923.0, 35361.64638353584, 35551.28897421985, 35564.822175888956, 35641.46537801883, 35730.46404303885, 30908.69091309058, 31125.844370778002, 31266.687333126378, 31485.37029259356, 31926.521102183022, 23113.19103831358, 23365.0, 23557.646635300825, 23836.88081559581, 23936.401589959693], "value": 16950.418557966495, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [554.4787899374587, 557.7406505974724, 566.9064604340284, 569.8346880629113, 572.7508533787801, 21893.562128757436, 23003.539929201426, 23071.76928230717, 23078.76921230787, 23078.88460557699, 23339.0, 24208.636870446957, 24429.38926526838, 24454.877725611346, 24652.876735616297, 6465.547411681184, 6658.966705166467, 6662.633555154464, 6665.933340666604, 6670.7665231716655, 6446.1619989401315, 6574.652196299747, 6584.473242140642, 6588.3411658833975, 6619.735210591571, 6239.37606239377, 6543.705533251015, 6571.0, 6578.342165783407, 6587.637679927626, 6594.3075977022445, 6630.0, 6646.900296495556, 6649.76725814599, 6649.833754156137, 5897.14491398749, 6165.630062196237, 6166.290876549209, 6168.0, 6184.165137706397, 27936.441271174666, 28477.288067798265, 28486.002989895253, 28513.572296415572, 28524.572131418045, 2055.0341339570527, 2079.9688004679942, 2084.8436367272366, 2086.050846864673, 2089.279198676454, 18959.0, 19149.329773458, 19496.512587185432, 19500.70748938767, 19512.902435487875, 2421.4672771990136, 2497.076081849709, 2514.7108082570558, 2520.357308886222, 2520.8991640334366, 118639.4068029663, 123281.38359308236, 125245.0, 129261.7073829259, 133940.0, 20307.492312692302, 20333.89833050825, 21281.36155915325, 21345.893270533546, 21820.01759436965, 41486.37770433446, 41618.37572436416, 41655.375169372484, 41678.58321416807, 41708.0, 11347.375894325725, 11400.657980260605, 11470.48382822775, 11546.249493782878, 11725.355105469282, 9575.0, 9634.13292803637, 9685.111403276294, 9726.36778609388, 9827.901720982842, 10993.450327483593, 11092.33445993243, 11194.186499805162, 11395.917387848142, 11396.917292857166, 3876.89049607417, 4098.262312783668, 4161.459010328698, 4176.665866730655, 4245.363195520635, 955.1594596754811, 976.4531862157214, 986.659602437158, 993.1707024634367, 996.1532697207316, 18968.430947071334, 19260.518487037934, 19300.32448864311, 19454.124564394635, 19620.3132890351, 1027.5427434791595, 1038.065740833241, 1038.7143535527791, 1044.3472829481575, 1044.4412239451856, 10596.099331556905, 10641.627543036111, 10677.145828333714, 10792.082672972729, 10963.56145754161, 423919.0, 426320.0, 458562.0, 463090.0, 464259.0, 106396.0, 113721.43139284251, 118056.40971795085, 118744.0, 119316.4034179841, 1029.120102312527, 1075.0593230922943, 1153.6135394642888, 1180.9232399893979, 1208.6253261488966, 20195.192192312144, 20347.491312717302, 20394.184232630527, 20431.59136817285, 20451.0, 57533.75373514659, 58136.4186358131, 58415.70792146012, 58757.0, 59363.406365935785, 70644.0, 71240.64379678069, 71396.64301678458, 71663.28336716566, 72837.27162728304, 19875.701864472332, 20368.0, 20475.488112796702, 20625.69061464049, 20666.79333206709, 21587.784122159206, 21610.675839862728, 22698.773012269667, 23110.884445577663, 23409.648855266845, 17993.550161246072, 18123.36568220106, 18338.449846504092, 18340.0, 18542.536436589195, 16463.753043704113, 16697.914635549136, 16729.91635041817, 16937.745933810755, 17000.744988825423, 17526.82473175303, 18273.269069237613, 18633.53416164607, 18731.625367492303, 18921.337753178574, 19903.104360303532, 19994.0, 19996.100175491847, 20041.29855455052, 20233.797662023193, 17943.37198198109, 18040.188191531674, 18235.0, 18332.17505212242, 18454.35409760652], "value": 12717.540562774191, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1274.7896597061483, 1354.7561438940993, 1355.356205802244, 1361.7412691588597, 1362.182690385768, 14863.554093377203, 15538.766918496218, 15559.533214003599, 15564.0, 15574.065556066644, 15177.468788592401, 15200.16399098049, 15252.237388130578, 15278.847211527882, 15353.769693454578, 2771.1825011621568, 2916.9854150729325, 2920.956185657217, 2921.693222211669, 2922.1817890990555, 2829.533127034047, 2868.885244590214, 2870.0672281508487, 2870.39721658452, 2871.267826704198, 2777.8333300001964, 2904.2013446302226, 2904.288449329917, 2906.5785461108126, 2907.0987993722015, 2847.7009913959046, 2856.11460447262, 2875.0368626510144, 2886.653601567815, 2887.927801804951, 2719.225020869043, 2836.489431902257, 2950.6164198654037, 2959.9852000739857, 2964.7479964203067, 15015.924920375437, 15172.089674619558, 15189.772153417707, 15215.31531081104, 15245.23773811305, 8055.556944368057, 8387.0354909185, 8388.245057944814, 8393.874091888627, 8395.580220988988, 5378.91931621026, 5499.312585926759, 5632.74652640628, 5709.628874123168, 5716.1425786131995, 1894.4506093232947, 1917.966955027675, 1919.673655478572, 1920.6734855074671, 1980.7722111957166, 26291.605625915625, 26613.73386266132, 26675.866620666773, 26751.0, 26761.73238267612, 6723.529352945345, 6747.932520674731, 6926.2034865990045, 7050.15398152225, 7111.751088711871, 17118.657626847395, 17138.31446742116, 17147.399841005754, 17156.0, 17182.140892955304, 7800.804979875548, 7898.802529936797, 7906.248906353887, 7914.643841027168, 7916.247956444128, 7187.209406965229, 7257.673404696801, 7319.34125928669, 7334.9266507335315, 7340.376068034277, 5093.9745301274, 5252.816151434681, 5273.709945952933, 5338.439463856239, 5347.786088556492, 1430.1133297355707, 1467.9339429725687, 1557.704036233112, 1568.9215539223221, 1594.0515393340943, 875.5097145598485, 896.6727144592177, 918.4122161816442, 923.4459324405437, 926.9953650231706, 8506.489610623383, 8647.827043459221, 8744.69393571222, 8813.911860881437, 8828.558572071372, 717.8564287142488, 721.5382155420535, 724.4168444402202, 740.1488288468197, 803.9227435236811, 5347.0, 5501.312335958005, 5517.36550296721, 5603.635763675348, 5632.915506267409, 20980.685289720666, 21046.0, 21918.78081219199, 21956.231531896625, 22029.889850550644, 20895.37313880586, 21021.579568408546, 21580.676289855666, 21813.0, 21817.890910545346, 10800.675979720621, 11066.778664426827, 11161.330320180816, 11353.602623908124, 11470.311781292981, 8363.163683631832, 8375.95812020936, 8398.664053437795, 8434.198751118634, 8438.282745966662, 17986.550336241176, 18504.074796260404, 18654.81345186585, 18756.906215468836, 18857.622847543247, 18751.624967500848, 18781.906090470005, 18785.624287514445, 18977.43067707971, 19007.71488427647, 10981.61564345244, 11039.558417663204, 11169.218154729091, 11199.048080912888, 11218.495167717309, 13526.661833453849, 13976.580702578938, 14007.08953917992, 14025.438982440588, 14036.64908377258, 11340.659780206606, 11360.715982100513, 11535.480903359537, 11721.88278117242, 11936.164468487123, 10816.837747433638, 11148.832767508657, 11300.717482063013, 11354.602588909349, 11454.14093942941, 9899.257555683496, 10008.39949603026, 10011.849822252818, 10013.899861001297, 10032.849507257251, 7139.535930164523, 7194.71221151169, 7242.637868106679, 7288.817779555554, 7324.377427918686, 7343.2289609590225, 7468.290512401313, 7471.402287816853, 7477.177510473734, 7525.360344370571], "value": 6734.303359908418, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [498.66127860335024, 503.87384196321227, 525.7213676751323, 529.351544358161, 533.9439358867319, 6251.781187658433, 6278.466330361914, 6308.116863639083, 6313.6211827290335, 6353.0, 6161.507079433646, 6234.158388617535, 6235.781747638834, 6279.843003924904, 6299.244090709121, 5172.948270517284, 5175.146100893348, 5215.739213039333, 5215.921761173567, 5232.215167724834, 5149.871253218662, 5150.381954165506, 5155.639105262633, 5158.767855446514, 5207.359681700271, 5147.588192944574, 5184.611154163453, 5197.48025197481, 5220.1468478690085, 5247.160454327309, 5124.564412024983, 5167.147420675584, 5189.2475591039465, 5246.239295302198, 5250.947490525104, 4718.882027949295, 4791.880202994918, 4828.46886842447, 4837.516248375135, 4855.271709243606, 6034.340556346996, 6078.39216078393, 6106.01554494791, 6119.847003824895, 6184.319724830265, 5936.347001829795, 5962.403759624046, 5969.462748352626, 6006.657403612595, 6018.368071352489, 6531.346865313478, 6607.603543787388, 6623.4701223901975, 6643.7674681385915, 6646.169228846395, 5088.1350170471105, 5293.708846013467, 5302.973485132549, 5309.893802123975, 5328.307320048408, 6718.227403848571, 6877.862442751117, 6882.483813713991, 6930.61881596512, 6946.027556142144, 5443.346798384219, 5459.3994660587305, 5481.698506582096, 5486.670799751948, 5510.8071217507195, 5405.594580406407, 5479.808206712746, 5531.170324451365, 5538.833834984957, 5572.359178694502, 5988.251468566429, 6014.458698717136, 6025.66858822769, 6053.7578496860515, 6057.939420605826, 6658.1702776320835, 6688.227619680789, 6698.39714425704, 6714.1271634688155, 6725.798226053226, 1896.8482521398255, 1908.6278175755594, 1912.1586501939103, 1916.8658193926292, 1921.932732354389, 1371.5131128449257, 1434.870861622438, 1442.35094207607, 1443.0475885915246, 1443.2752860332012, 5725.59920805548, 5728.684922329312, 5755.0, 5762.481376676035, 5789.507891829242, 6560.8359791005605, 6587.50593705474, 6656.567323123982, 6686.632235227109, 6753.29090445506, 5575.804846830341, 5665.178549110356, 5675.971620141873, 5680.062789639664, 5716.5140963017775, 3469.913252168716, 3499.3001399720156, 3572.4641303804638, 3579.2483578448296, 3581.068922080278, 6402.1753800166725, 6467.773627923, 6501.4148726613885, 6520.510961677893, 6558.147440832661, 6822.454203663695, 6851.623160726208, 6883.621400823003, 6950.270221626757, 6964.616946067914, 6344.397282258178, 6439.581427207217, 6464.806055818333, 6471.708773105222, 6471.870562588721, 6572.638504882278, 6629.966850165718, 6634.734610615521, 6638.701258443285, 6669.0, 6574.4411725002, 6582.3746744059235, 6640.601563906181, 6650.4347130492515, 6693.397594216543, 6501.902471462838, 6511.544191906711, 6517.576357536745, 6551.377619126175, 6603.966980165068, 5375.274337964324, 5449.700266485382, 5466.867921510535, 5473.397926228191, 5478.397376288525, 6483.546151769331, 6633.170853643294, 6634.369734875179, 6711.429528490133, 6724.93275067243, 6318.715657795318, 6446.548741588043, 6487.513436492188, 6503.869922601616, 6531.510136739668, 6397.936020639921, 6420.2616699078335, 6448.097266382616, 6554.705038273384, 6557.540972131905, 6320.462760665395, 6322.8735425290315, 6436.806895793134, 6438.452731517874, 6444.323346048597, 6370.968145159244, 6431.678416079271, 6488.545801793829, 6544.181977252843, 6567.343265673395, 6276.717547710456, 6277.309495955533, 6291.339409361951, 6333.8733225336155, 6363.059266923633], "value": 5179.393730816013, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [904.2720609909024, 906.5829718329568, 914.3964983111147, 922.0410772796292, 936.7892224249542, 19573.0, 20177.19291228351, 20398.184072637076, 20488.8975555122, 20500.282490112848, 18014.909925450353, 18095.819041809576, 18268.908655456737, 18284.908575457102, 18304.81695183048, 946.7301818981609, 948.4783369146942, 956.3544607390019, 959.1176117971448, 960.0879164793439, 959.2949182350967, 961.0629636104812, 968.2012339819677, 968.4867020479122, 969.1423090564865, 988.1650005745134, 1016.2581315639587, 1025.7794574166553, 1026.712520494259, 1032.4682788363998, 1015.7206768138747, 1015.7308313296983, 1020.5458570935863, 1020.9744756381076, 1022.4938655365604, 727.7853033355173, 732.318943382654, 734.3757805865025, 737.3584981066452, 740.7074205688723, 20413.0, 20499.590008199903, 20642.48393790152, 20655.793442065537, 20674.0, 20186.19255229789, 20308.18767249323, 21078.3676489704, 21138.788612113836, 21325.146994120216, 16760.748588771177, 16965.236564354633, 17117.657646846994, 17197.828021719746, 17295.22171502273, 3632.5640923089136, 3638.126849556114, 3658.9268214635827, 3664.175560498893, 3664.798436086014, 103816.2910919945, 137252.0, 141579.0, 141669.0, 144187.0, 17824.287028518713, 17850.19674114668, 17951.0, 18023.72964405535, 18064.81935180658, 31330.68669313278, 32134.678653213636, 32213.51679724806, 32220.83889580584, 32273.35453290921, 9313.301502387212, 9521.857172142423, 9533.475658838692, 9584.424934503952, 9643.855342169873, 4307.440032795779, 4423.623991960655, 4427.136708341907, 4432.756198409054, 4435.622972047412, 5013.423456302572, 5040.294358789773, 5043.87390315245, 5101.234814777813, 5110.821121260738, 888.595688961521, 1094.5731164845718, 1096.714854137914, 1096.9122470202365, 1102.6306187427142, 694.7047504810436, 701.1761180612759, 702.2521015118882, 718.8598223346502, 728.883378659412, 10983.560657573607, 11334.829977550344, 11361.829572556418, 12384.380780960915, 12756.936215318863, 656.4584218020057, 670.2225418514499, 676.7022510095542, 679.7383007542132, 686.3959715450471, 5686.658800471985, 5691.146328050825, 5709.600327977085, 5752.395998420189, 5841.912371314434, 117272.0, 117905.41047294709, 120368.0, 121574.39212803879, 122938.0, 100992.0, 105079.47460262649, 109407.0, 110041.44979275213, 110974.0, 94724.0, 94780.0, 94794.52602736941, 97189.5140524307, 99494.0, 12540.874591254336, 12670.0, 12718.427670755023, 12727.618171454871, 12738.617841464771, 44024.779876100416, 44237.336439952785, 44640.0, 44652.0, 45375.773121135506, 55536.72231638816, 55705.442945571645, 55761.1635825471, 56952.145717815096, 57134.71432642976, 17215.483535493957, 17486.30054797794, 17509.124543773014, 17518.299268029645, 17537.0, 20432.591348172657, 20508.282210122576, 20536.07587658529, 20549.58900822005, 20627.4843128923, 18788.906055469633, 18809.0, 18958.43124706261, 19101.808981910002, 19328.516787080436, 18400.07999599988, 18602.0, 18925.43223703291, 19256.518587035436, 19274.518137046685, 18174.909125454287, 18409.53976150607, 18429.0, 18738.812611873705, 18776.7183492245, 12922.483100675867, 13006.934965325112, 13152.145110567782, 13378.799318010433, 13533.120347177402, 11658.94170529142, 11820.0543956485, 11823.645290641294, 11962.641120766391, 12110.212836165621], "value": 9211.446107866397, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1427.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 107.0, 106.0, 107.0, 106.0, 107.0, 108.0, 108.0, 106.0, 108.0], "value": 107.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 121.0, 117.0, 115.0, 119.0, 115.0, 115.0, 117.0, 141.0, 122.0], "value": 119.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 161.0, 164.0, 163.0, 162.0, 163.0, 164.0, 164.0, 167.0, 169.0], "value": 164.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 75.0, 73.0, 73.0, 74.0, 75.0, 74.0, 74.0, 73.0], "value": 73.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 79.0, 79.0, 79.0, 79.0, 79.0, 79.0, 78.0, 86.0], "value": 79.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 113.0, 113.0, 114.0, 114.0, 114.0, 114.0, 114.0, 114.0, 114.0], "value": 113.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 149.0, 148.0, 149.0, 149.0, 149.0, 148.0, 151.0, 152.0, 150.0], "value": 149.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 94.0, 94.0, 94.0, 95.0, 101.0, 95.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 54.0, 58.0, 55.0, 55.0, 55.0, 56.0, 54.0, 55.0, 56.0], "value": 56.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 38.0, 38.0, 39.0, 38.0, 40.0, 38.0, 38.0, 38.0, 39.0], "value": 38.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 78.0, 78.0, 78.0, 76.0, 75.0, 75.0, 73.0, 76.0], "value": 75.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 126.0, 112.0, 121.0, 114.0, 116.0, 111.0, 113.0, 120.0, 127.0], "value": 117.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 171.0, 178.0, 173.0, 170.0, 173.0, 169.0, 173.0, 165.0, 165.0], "value": 170.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 65.0, 67.0, 67.0, 63.0, 67.0, 65.0, 63.0, 64.0, 72.0], "value": 66.1, "unit": "score"}]}]} 06:36:08 INFO - Return code: 0 06:36:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:36:08 INFO - # TBPL SUCCESS # 06:36:08 INFO - Running post-action listener: _package_coverage_data 06:36:08 INFO - Running post-action listener: _resource_record_post_action 06:36:08 INFO - [mozharness: 2017-09-17 13:36:08.093200Z] Finished run-tests step (success) 06:36:08 INFO - Running post-run listener: _resource_record_post_run 06:36:08 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 4096; Write bytes: 338214912; Read time: 16; Write time: 356812 06:36:08 INFO - TinderboxPrint: CPU usage
13.1% 06:36:08 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 06:36:08 INFO - TinderboxPrint: I/O write bytes / time
338,214,912 / 356,812 06:36:08 INFO - TinderboxPrint: CPU idle
8,236.3 (86.9%) 06:36:08 INFO - TinderboxPrint: CPU user
1,191.5 (12.6%) 06:36:08 INFO - TinderboxPrint: Swap in / out
0 / 0 06:36:08 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 13447168; Read time: 0; Write time: 4332 06:36:08 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 06:36:08 INFO - run-tests - Wall time: 1171s; CPU: 13.0%; Read bytes: 0; Write bytes: 324358144; Read time: 0; Write time: 352460 06:36:08 INFO - Running post-run listener: _upload_blobber_files 06:36:08 INFO - Blob upload gear active. 06:36:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:36:08 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 06:36:08 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'] 06:36:08 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 06:36:09 INFO - (blobuploader) - INFO - Open directory for files ... 06:36:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 06:36:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:36:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:36:10 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 06:36:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:36:10 INFO - (blobuploader) - INFO - Done attempting. 06:36:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:36:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:36:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:36:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:36:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:36:17 INFO - (blobuploader) - INFO - Done attempting. 06:36:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:36:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:36:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:36:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:36:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:36:18 INFO - (blobuploader) - INFO - Done attempting. 06:36:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:36:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:36:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:36:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:36:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:36:19 INFO - (blobuploader) - INFO - Done attempting. 06:36:19 INFO - (blobuploader) - INFO - Iteration through files over. 06:36:19 INFO - Return code: 0 06:36:19 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:36:19 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:36:19 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/98c03cfb706133bcaa02b6ec21ffd45864a794877cfd4ce392b0b1897dd6b1430f1acbfe735daae04dc6c67fa8557934f15af46ae46d1d7520cb1424cf2d809e", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6e4cd488640210d846397c3cd6b3ddf09a02038ee3544a1bf034cb47e829e3cb21fee766daf01e594df4b748128f042cf11f89a03b30ae03622a03970ff63098", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2a3c57fa7a58082c090fb5f22d26dc82e64997d0ceb985380d72bb83576e8b8bd3653d724b911b6727e36f8834c95f6f7d8e1d90b4050902ede85a6c00c6e34b", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5642f0002bdc013c7141b1627b1912bc6dce1d12234aada71717272bf02ca26ba581125df186494a930f81b2d63ec0915f12d8225dd96f9679f8b48a2b4690e7"} 06:36:19 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:36:19 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:36:19 INFO - Contents: 06:36:19 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/98c03cfb706133bcaa02b6ec21ffd45864a794877cfd4ce392b0b1897dd6b1430f1acbfe735daae04dc6c67fa8557934f15af46ae46d1d7520cb1424cf2d809e", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6e4cd488640210d846397c3cd6b3ddf09a02038ee3544a1bf034cb47e829e3cb21fee766daf01e594df4b748128f042cf11f89a03b30ae03622a03970ff63098", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2a3c57fa7a58082c090fb5f22d26dc82e64997d0ceb985380d72bb83576e8b8bd3653d724b911b6727e36f8834c95f6f7d8e1d90b4050902ede85a6c00c6e34b", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5642f0002bdc013c7141b1627b1912bc6dce1d12234aada71717272bf02ca26ba581125df186494a930f81b2d63ec0915f12d8225dd96f9679f8b48a2b4690e7"} 06:36:19 INFO - Running post-run listener: copy_logs_to_upload_dir 06:36:19 INFO - Copying logs to upload dir... 06:36:19 INFO - mkdir: /builds/slave/test/build/upload/logs 06:36:19 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.929093 ========= master_lag: 0.18 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2017-09-17 06:36:19.444850) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-17 06:36:19.453504) ========= 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=5bd038846534b2fdfa68eeae000001e2-1505652212.867325-1465953002 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/98c03cfb706133bcaa02b6ec21ffd45864a794877cfd4ce392b0b1897dd6b1430f1acbfe735daae04dc6c67fa8557934f15af46ae46d1d7520cb1424cf2d809e", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6e4cd488640210d846397c3cd6b3ddf09a02038ee3544a1bf034cb47e829e3cb21fee766daf01e594df4b748128f042cf11f89a03b30ae03622a03970ff63098", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2a3c57fa7a58082c090fb5f22d26dc82e64997d0ceb985380d72bb83576e8b8bd3653d724b911b6727e36f8834c95f6f7d8e1d90b4050902ede85a6c00c6e34b", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5642f0002bdc013c7141b1627b1912bc6dce1d12234aada71717272bf02ca26ba581125df186494a930f81b2d63ec0915f12d8225dd96f9679f8b48a2b4690e7"} build_url:https://queue.taskcluster.net/v1/task/Wjb9bWClSh6PQEiCFFxuzg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011215 build_url: 'https://queue.taskcluster.net/v1/task/Wjb9bWClSh6PQEiCFFxuzg/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/98c03cfb706133bcaa02b6ec21ffd45864a794877cfd4ce392b0b1897dd6b1430f1acbfe735daae04dc6c67fa8557934f15af46ae46d1d7520cb1424cf2d809e", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6e4cd488640210d846397c3cd6b3ddf09a02038ee3544a1bf034cb47e829e3cb21fee766daf01e594df4b748128f042cf11f89a03b30ae03622a03970ff63098", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2a3c57fa7a58082c090fb5f22d26dc82e64997d0ceb985380d72bb83576e8b8bd3653d724b911b6727e36f8834c95f6f7d8e1d90b4050902ede85a6c00c6e34b", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5642f0002bdc013c7141b1627b1912bc6dce1d12234aada71717272bf02ca26ba581125df186494a930f81b2d63ec0915f12d8225dd96f9679f8b48a2b4690e7"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-17 06:36:19.481459) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-17 06:36:19.481776) ========= 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=5bd038846534b2fdfa68eeae000001e2-1505652212.867325-1465953002 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004513 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-17 06:36:19.565936) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-17 06:36:19.568287) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-17 06:36:19.568628) ========= ========= Total master_lag: 0.44 =========